{"level":"warn","ts":"2026-05-08T10:19:40.949265Z","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:19:40.949367Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.133:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.133:2380","--initial-cluster=instance=https://199.19.213.133:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.133:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.133: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:19:40.949436Z","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:19:40.949447Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.133:2380"]} {"level":"info","ts":"2026-05-08T10:19:40.949468Z","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:19:40.950196Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.133:2379"]} {"level":"info","ts":"2026-05-08T10:19:40.950273Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.133:2380"],"listen-peer-urls":["https://199.19.213.133:2380"],"advertise-client-urls":["https://199.19.213.133:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.133:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.133: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:19:40.954069Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.601623ms"} {"level":"info","ts":"2026-05-08T10:19:40.96025Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"21b354698260dd82","cluster-id":"b778216e1db811d5"} {"level":"info","ts":"2026-05-08T10:19:40.960308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T10:19:40.960326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 became follower at term 0"} {"level":"info","ts":"2026-05-08T10:19:40.960339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 21b354698260dd82 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-08T10:19:40.960346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 became follower at term 1"} {"level":"info","ts":"2026-05-08T10:19:40.960382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 switched to configuration voters=(2428377436218514818)"} {"level":"warn","ts":"2026-05-08T10:19:40.966301Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T10:19:40.968758Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T10:19:40.969911Z","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:19:40.973521Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"21b354698260dd82","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-08T10:19:40.973811Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"21b354698260dd82","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-08T10:19:40.974123Z","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:19:40.974251Z","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:19:40.974401Z","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:19:40.979754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 switched to configuration voters=(2428377436218514818)"} {"level":"info","ts":"2026-05-08T10:19:40.979956Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b778216e1db811d5","local-member-id":"21b354698260dd82","added-peer-id":"21b354698260dd82","added-peer-peer-urls":["https://199.19.213.133:2380"]} {"level":"info","ts":"2026-05-08T10:19:40.98106Z","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:19:40.981378Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"21b354698260dd82","initial-advertise-peer-urls":["https://199.19.213.133:2380"],"listen-peer-urls":["https://199.19.213.133:2380"],"advertise-client-urls":["https://199.19.213.133:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.133:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-08T10:19:40.981417Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T10:19:40.981562Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.133:2380"} {"level":"info","ts":"2026-05-08T10:19:40.981583Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.133:2380"} {"level":"info","ts":"2026-05-08T10:19:41.460946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T10:19:41.461053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T10:19:41.461093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 received MsgPreVoteResp from 21b354698260dd82 at term 1"} {"level":"info","ts":"2026-05-08T10:19:41.461127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 became candidate at term 2"} {"level":"info","ts":"2026-05-08T10:19:41.461147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 received MsgVoteResp from 21b354698260dd82 at term 2"} {"level":"info","ts":"2026-05-08T10:19:41.461163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21b354698260dd82 became leader at term 2"} {"level":"info","ts":"2026-05-08T10:19:41.46119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 21b354698260dd82 elected leader 21b354698260dd82 at term 2"} {"level":"info","ts":"2026-05-08T10:19:41.462197Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"21b354698260dd82","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.133:2379]}","request-path":"/0/members/21b354698260dd82/attributes","cluster-id":"b778216e1db811d5","publish-timeout":"7s"} {"level":"info","ts":"2026-05-08T10:19:41.462485Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T10:19:41.463037Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:19:41.463123Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T10:19:41.463739Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T10:19:41.463875Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T10:19:41.465006Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b778216e1db811d5","local-member-id":"21b354698260dd82","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:19:41.465114Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:19:41.465155Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T10:19:41.466482Z","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:19:41.466861Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.133:2379"} {"level":"info","ts":"2026-05-08T10:20:20.605678Z","caller":"traceutil/trace.go:171","msg":"trace[645273340] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"296.947293ms","start":"2026-05-08T10:20:20.308693Z","end":"2026-05-08T10:20:20.60564Z","steps":["trace[645273340] 'process raft request' (duration: 287.81832ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:20:20.606081Z","caller":"traceutil/trace.go:171","msg":"trace[1242556344] transaction","detail":"{read_only:false; number_of_response:1; response_revision:536; }","duration":"274.712511ms","start":"2026-05-08T10:20:20.331344Z","end":"2026-05-08T10:20:20.606056Z","steps":["trace[1242556344] 'process raft request' (duration: 274.183745ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:20:20.606151Z","caller":"traceutil/trace.go:171","msg":"trace[328093290] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"258.831641ms","start":"2026-05-08T10:20:20.347261Z","end":"2026-05-08T10:20:20.606092Z","steps":["trace[328093290] 'process raft request' (duration: 258.413775ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:20:27.140159Z","caller":"traceutil/trace.go:171","msg":"trace[710998409] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"162.597255ms","start":"2026-05-08T10:20:26.977527Z","end":"2026-05-08T10:20:27.140124Z","steps":["trace[710998409] 'read index received' (duration: 161.737646ms)","trace[710998409] 'applied index is now lower than readState.Index' (duration: 858.759µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:20:27.14029Z","caller":"traceutil/trace.go:171","msg":"trace[1897173404] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"166.71016ms","start":"2026-05-08T10:20:26.973534Z","end":"2026-05-08T10:20:27.140244Z","steps":["trace[1897173404] 'process raft request' (duration: 166.031403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:20:27.140416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.825417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-z6t4b\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-08T10:20:27.140483Z","caller":"traceutil/trace.go:171","msg":"trace[352789878] range","detail":"{range_begin:/registry/pods/kube-system/cilium-z6t4b; range_end:; response_count:1; response_revision:586; }","duration":"162.968749ms","start":"2026-05-08T10:20:26.9775Z","end":"2026-05-08T10:20:27.140469Z","steps":["trace[352789878] 'agreement among raft nodes before linearized reading' (duration: 162.747876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:20:27.806087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.234918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:20:27.806329Z","caller":"traceutil/trace.go:171","msg":"trace[352822726] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"647.068998ms","start":"2026-05-08T10:20:27.159216Z","end":"2026-05-08T10:20:27.806285Z","steps":["trace[352822726] 'process raft request' (duration: 36.809718ms)","trace[352822726] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/pods/kube-system/cilium-z6t4b; req_size:17130; } (duration: 608.40971ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:20:27.806636Z","caller":"traceutil/trace.go:171","msg":"trace[318950788] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"612.202061ms","start":"2026-05-08T10:20:27.194409Z","end":"2026-05-08T10:20:27.806611Z","steps":["trace[318950788] 'process raft request' (duration: 611.829367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:20:27.806702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:20:27.159193Z","time spent":"647.404981ms","remote":"127.0.0.1:50154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:20:27.806814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:20:27.194397Z","time spent":"612.300622ms","remote":"127.0.0.1:50302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:20:27.806865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.239329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-08T10:20:27.806425Z","caller":"traceutil/trace.go:171","msg":"trace[370514573] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"645.776304ms","start":"2026-05-08T10:20:27.160602Z","end":"2026-05-08T10:20:27.806379Z","steps":["trace[370514573] 'read index received' (duration: 35.454433ms)","trace[370514573] 'applied index is now lower than readState.Index' (duration: 610.317281ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:20:27.80696Z","caller":"traceutil/trace.go:171","msg":"trace[755856446] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"646.390691ms","start":"2026-05-08T10:20:27.160541Z","end":"2026-05-08T10:20:27.806931Z","steps":["trace[755856446] 'agreement among raft nodes before linearized reading' (duration: 646.053756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:20:27.807006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:20:27.160525Z","time spent":"646.472192ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T10:20:27.828126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.424032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-08T10:20:27.828205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.575428ms","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:20:27.828243Z","caller":"traceutil/trace.go:171","msg":"trace[1181260402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"235.603714ms","start":"2026-05-08T10:20:27.592616Z","end":"2026-05-08T10:20:27.828219Z","steps":["trace[1181260402] 'agreement among raft nodes before linearized reading' (duration: 235.372561ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:20:27.828269Z","caller":"traceutil/trace.go:171","msg":"trace[2072150685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"111.704529ms","start":"2026-05-08T10:20:27.716546Z","end":"2026-05-08T10:20:27.82825Z","steps":["trace[2072150685] 'agreement among raft nodes before linearized reading' (duration: 111.440216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:02.606956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.584479ms","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:22:02.60697Z","caller":"traceutil/trace.go:171","msg":"trace[994090272] linearizableReadLoop","detail":"{readStateIndex:1882; appliedIndex:1881; }","duration":"246.200423ms","start":"2026-05-08T10:22:02.36035Z","end":"2026-05-08T10:22:02.606551Z","steps":["trace[994090272] 'read index received' (duration: 245.908729ms)","trace[994090272] 'applied index is now lower than readState.Index' (duration: 290.214µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:22:02.607067Z","caller":"traceutil/trace.go:171","msg":"trace[859651273] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1824; }","duration":"246.752052ms","start":"2026-05-08T10:22:02.36029Z","end":"2026-05-08T10:22:02.607042Z","steps":["trace[859651273] 'agreement among raft nodes before linearized reading' (duration: 246.450576ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:22:02.60712Z","caller":"traceutil/trace.go:171","msg":"trace[1267198729] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"304.774014ms","start":"2026-05-08T10:22:02.301849Z","end":"2026-05-08T10:22:02.606623Z","steps":["trace[1267198729] 'process raft request' (duration: 304.47367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:02.607249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:22:02.30181Z","time spent":"305.336973ms","remote":"127.0.0.1:50242","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:22:02.607496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.996177ms","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:22:02.607531Z","caller":"traceutil/trace.go:171","msg":"trace[1204200256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1824; }","duration":"135.075938ms","start":"2026-05-08T10:22:02.472444Z","end":"2026-05-08T10:22:02.60752Z","steps":["trace[1204200256] 'agreement among raft nodes before linearized reading' (duration: 134.972697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:02.607669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.504989ms","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:22:02.607695Z","caller":"traceutil/trace.go:171","msg":"trace[947732536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1824; }","duration":"134.62299ms","start":"2026-05-08T10:22:02.473064Z","end":"2026-05-08T10:22:02.607687Z","steps":["trace[947732536] 'agreement among raft nodes before linearized reading' (duration: 134.546749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:02.608126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.506889ms","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:22:02.608271Z","caller":"traceutil/trace.go:171","msg":"trace[478215725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1824; }","duration":"135.025937ms","start":"2026-05-08T10:22:02.473211Z","end":"2026-05-08T10:22:02.608237Z","steps":["trace[478215725] 'agreement among raft nodes before linearized reading' (duration: 134.349296ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:22:02.995005Z","caller":"traceutil/trace.go:171","msg":"trace[1874539622] linearizableReadLoop","detail":"{readStateIndex:1893; appliedIndex:1892; }","duration":"109.225824ms","start":"2026-05-08T10:22:02.88575Z","end":"2026-05-08T10:22:02.994976Z","steps":["trace[1874539622] 'read index received' (duration: 104.897643ms)","trace[1874539622] 'applied index is now lower than readState.Index' (duration: 4.326941ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:22:02.995099Z","caller":"traceutil/trace.go:171","msg":"trace[1674172018] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"121.855051ms","start":"2026-05-08T10:22:02.873212Z","end":"2026-05-08T10:22:02.995067Z","steps":["trace[1674172018] 'process raft request' (duration: 117.50231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:02.995535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.65267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-05-08T10:22:02.995705Z","caller":"traceutil/trace.go:171","msg":"trace[1099511994] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1835; }","duration":"109.959666ms","start":"2026-05-08T10:22:02.885699Z","end":"2026-05-08T10:22:02.995658Z","steps":["trace[1099511994] 'agreement among raft nodes before linearized reading' (duration: 109.558429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:02.99552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.170267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-05-08T10:22:02.996296Z","caller":"traceutil/trace.go:171","msg":"trace[1455641607] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1835; }","duration":"105.982961ms","start":"2026-05-08T10:22:02.890272Z","end":"2026-05-08T10:22:02.996255Z","steps":["trace[1455641607] 'agreement among raft nodes before linearized reading' (duration: 105.020195ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:22:11.50963Z","caller":"traceutil/trace.go:171","msg":"trace[151617945] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"145.005983ms","start":"2026-05-08T10:22:11.364593Z","end":"2026-05-08T10:22:11.509599Z","steps":["trace[151617945] 'process raft request' (duration: 144.520315ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:22:11.52238Z","caller":"traceutil/trace.go:171","msg":"trace[659711020] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"121.079404ms","start":"2026-05-08T10:22:11.401282Z","end":"2026-05-08T10:22:11.522362Z","steps":["trace[659711020] 'process raft request' (duration: 120.546055ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:22:35.230819Z","caller":"traceutil/trace.go:171","msg":"trace[235422088] linearizableReadLoop","detail":"{readStateIndex:2165; appliedIndex:2164; }","duration":"277.300556ms","start":"2026-05-08T10:22:34.953488Z","end":"2026-05-08T10:22:35.230789Z","steps":["trace[235422088] 'read index received' (duration: 277.068333ms)","trace[235422088] 'applied index is now lower than readState.Index' (duration: 230.603µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:22:35.230885Z","caller":"traceutil/trace.go:171","msg":"trace[1818610682] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"277.662061ms","start":"2026-05-08T10:22:34.953183Z","end":"2026-05-08T10:22:35.230845Z","steps":["trace[1818610682] 'process raft request' (duration: 277.330136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:35.231092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.573299ms","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:22:35.231141Z","caller":"traceutil/trace.go:171","msg":"trace[1128561209] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2095; }","duration":"277.6749ms","start":"2026-05-08T10:22:34.953454Z","end":"2026-05-08T10:22:35.231129Z","steps":["trace[1128561209] 'agreement among raft nodes before linearized reading' (duration: 277.480378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:35.73203Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15961493782686478598,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T10:22:35.942094Z","caller":"traceutil/trace.go:171","msg":"trace[552287951] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"987.603336ms","start":"2026-05-08T10:22:34.954458Z","end":"2026-05-08T10:22:35.942062Z","steps":["trace[552287951] 'process raft request' (duration: 987.366043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:35.942291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:22:34.95444Z","time spent":"987.741569ms","remote":"127.0.0.1:50242","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:22:35.942389Z","caller":"traceutil/trace.go:171","msg":"trace[670420226] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"836.325824ms","start":"2026-05-08T10:22:35.106038Z","end":"2026-05-08T10:22:35.942364Z","steps":["trace[670420226] 'process raft request' (duration: 836.202633ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:22:35.94239Z","caller":"traceutil/trace.go:171","msg":"trace[1455822863] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"984.38162ms","start":"2026-05-08T10:22:34.957956Z","end":"2026-05-08T10:22:35.942338Z","steps":["trace[1455822863] 'process raft request' (duration: 984.059906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:35.942622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:22:34.957946Z","time spent":"984.547242ms","remote":"127.0.0.1:50242","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:22:35.942705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:22:35.106011Z","time spent":"836.447166ms","remote":"127.0.0.1:50242","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:22:35.965246Z","caller":"traceutil/trace.go:171","msg":"trace[860373691] linearizableReadLoop","detail":"{readStateIndex:2169; appliedIndex:2165; }","duration":"734.323361ms","start":"2026-05-08T10:22:35.230903Z","end":"2026-05-08T10:22:35.965227Z","steps":["trace[860373691] 'read index received' (duration: 711.008262ms)","trace[860373691] 'applied index is now lower than readState.Index' (duration: 23.313799ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:22:35.965314Z","caller":"traceutil/trace.go:171","msg":"trace[191545949] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"729.286247ms","start":"2026-05-08T10:22:35.235982Z","end":"2026-05-08T10:22:35.965268Z","steps":["trace[191545949] 'process raft request' (duration: 728.640307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:35.965517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008292217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"warn","ts":"2026-05-08T10:22:35.965528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:22:35.235953Z","time spent":"729.46493ms","remote":"127.0.0.1:50242","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:22:35.965559Z","caller":"traceutil/trace.go:171","msg":"trace[1383545197] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2099; }","duration":"1.008358548s","start":"2026-05-08T10:22:34.957189Z","end":"2026-05-08T10:22:35.965547Z","steps":["trace[1383545197] 'agreement among raft nodes before linearized reading' (duration: 1.008139805s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:35.965593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:22:34.95718Z","time spent":"1.008406989s","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":547,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-05-08T10:22:35.965655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.141903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.133\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-08T10:22:35.965753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.994298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-08T10:22:35.96579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.469759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-05-08T10:22:35.965797Z","caller":"traceutil/trace.go:171","msg":"trace[766798576] range","detail":"{range_begin:/registry/masterleases/199.19.213.133; range_end:; response_count:1; response_revision:2099; }","duration":"933.336096ms","start":"2026-05-08T10:22:35.032413Z","end":"2026-05-08T10:22:35.96575Z","steps":["trace[766798576] 'agreement among raft nodes before linearized reading' (duration: 932.92936ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:22:35.96583Z","caller":"traceutil/trace.go:171","msg":"trace[1809543326] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2099; }","duration":"192.572541ms","start":"2026-05-08T10:22:35.773244Z","end":"2026-05-08T10:22:35.965817Z","steps":["trace[1809543326] 'agreement among raft nodes before linearized reading' (duration: 192.461819ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:22:35.965827Z","caller":"traceutil/trace.go:171","msg":"trace[1051249570] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2099; }","duration":"233.136921ms","start":"2026-05-08T10:22:35.732675Z","end":"2026-05-08T10:22:35.965812Z","steps":["trace[1051249570] 'agreement among raft nodes before linearized reading' (duration: 232.875347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:35.96581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.033024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-05-08T10:22:35.965851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:22:35.032397Z","time spent":"933.447047ms","remote":"127.0.0.1:49986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.133\" "} {"level":"info","ts":"2026-05-08T10:22:35.965907Z","caller":"traceutil/trace.go:171","msg":"trace[488503732] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2099; }","duration":"718.191797ms","start":"2026-05-08T10:22:35.24769Z","end":"2026-05-08T10:22:35.965882Z","steps":["trace[488503732] 'agreement among raft nodes before linearized reading' (duration: 717.807671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:35.965965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:22:35.247674Z","time spent":"718.282158ms","remote":"127.0.0.1:37544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4614,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-08T10:22:35.966181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.680799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-05-08T10:22:35.966797Z","caller":"traceutil/trace.go:171","msg":"trace[792614110] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2099; }","duration":"584.35792ms","start":"2026-05-08T10:22:35.382416Z","end":"2026-05-08T10:22:35.966774Z","steps":["trace[792614110] 'agreement among raft nodes before linearized reading' (duration: 583.184263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:35.967464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:22:35.382399Z","time spent":"585.043089ms","remote":"127.0.0.1:50090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":682,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-05-08T10:22:36.271675Z","caller":"traceutil/trace.go:171","msg":"trace[1052244614] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"299.556575ms","start":"2026-05-08T10:22:35.972085Z","end":"2026-05-08T10:22:36.271642Z","steps":["trace[1052244614] 'process raft request' (duration: 299.499124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:36.271779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:22:35.967266Z","time spent":"304.503037ms","remote":"127.0.0.1:49986","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:22:36.271886Z","caller":"traceutil/trace.go:171","msg":"trace[1889201554] linearizableReadLoop","detail":"{readStateIndex:2170; appliedIndex:2169; }","duration":"302.261605ms","start":"2026-05-08T10:22:35.969469Z","end":"2026-05-08T10:22:36.271731Z","steps":["trace[1889201554] 'read index received' (duration: 235.594387ms)","trace[1889201554] 'applied index is now lower than readState.Index' (duration: 66.663878ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:22:36.272111Z","caller":"traceutil/trace.go:171","msg":"trace[451191369] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"300.793603ms","start":"2026-05-08T10:22:35.971261Z","end":"2026-05-08T10:22:36.272055Z","steps":["trace[451191369] 'process raft request' (duration: 300.149063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:36.272227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.039345ms","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:36.27227Z","caller":"traceutil/trace.go:171","msg":"trace[2014332132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2101; }","duration":"243.136956ms","start":"2026-05-08T10:22:36.029117Z","end":"2026-05-08T10:22:36.272254Z","steps":["trace[2014332132] 'agreement among raft nodes before linearized reading' (duration: 243.030375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:36.27235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:22:35.971245Z","time spent":"300.975736ms","remote":"127.0.0.1:50242","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:22:36.272348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.836459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"warn","ts":"2026-05-08T10:22:36.272455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.895273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-05-08T10:22:36.272671Z","caller":"traceutil/trace.go:171","msg":"trace[1529371081] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2101; }","duration":"303.200357ms","start":"2026-05-08T10:22:35.969448Z","end":"2026-05-08T10:22:36.272649Z","steps":["trace[1529371081] 'agreement among raft nodes before linearized reading' (duration: 302.608639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:36.27361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:22:35.96943Z","time spent":"304.130741ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":575,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-08T10:22:36.272438Z","caller":"traceutil/trace.go:171","msg":"trace[1428136429] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2101; }","duration":"302.030511ms","start":"2026-05-08T10:22:35.970391Z","end":"2026-05-08T10:22:36.272421Z","steps":["trace[1428136429] 'agreement among raft nodes before linearized reading' (duration: 301.694117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:22:36.273886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:22:35.970375Z","time spent":"303.485572ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":590,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-05-08T10:23:36.182357Z","caller":"traceutil/trace.go:171","msg":"trace[646309862] linearizableReadLoop","detail":"{readStateIndex:2708; appliedIndex:2707; }","duration":"164.415121ms","start":"2026-05-08T10:23:36.01791Z","end":"2026-05-08T10:23:36.182325Z","steps":["trace[646309862] 'read index received' (duration: 163.573ms)","trace[646309862] 'applied index is now lower than readState.Index' (duration: 840.461µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:23:36.182674Z","caller":"traceutil/trace.go:171","msg":"trace[162881167] transaction","detail":"{read_only:false; number_of_response:0; response_revision:2609; }","duration":"415.505851ms","start":"2026-05-08T10:23:35.767126Z","end":"2026-05-08T10:23:36.182631Z","steps":["trace[162881167] 'process raft request' (duration: 414.456708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:23:36.182741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.336781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:23:36.182814Z","caller":"traceutil/trace.go:171","msg":"trace[339861889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2609; }","duration":"154.469103ms","start":"2026-05-08T10:23:36.028332Z","end":"2026-05-08T10:23:36.182801Z","steps":["trace[339861889] 'agreement among raft nodes before linearized reading' (duration: 154.333862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:23:36.182905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:23:35.767101Z","time spent":"415.707024ms","remote":"127.0.0.1:50468","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T10:23:36.182688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.742695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:23:36.183652Z","caller":"traceutil/trace.go:171","msg":"trace[396988745] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:2609; }","duration":"165.160331ms","start":"2026-05-08T10:23:36.017848Z","end":"2026-05-08T10:23:36.183008Z","steps":["trace[396988745] 'agreement among raft nodes before linearized reading' (duration: 164.702075ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:23:58.557471Z","caller":"traceutil/trace.go:171","msg":"trace[1499743840] linearizableReadLoop","detail":"{readStateIndex:2876; appliedIndex:2875; }","duration":"126.259913ms","start":"2026-05-08T10:23:58.431184Z","end":"2026-05-08T10:23:58.557444Z","steps":["trace[1499743840] 'read index received' (duration: 126.122411ms)","trace[1499743840] 'applied index is now lower than readState.Index' (duration: 136.322µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:23:58.557576Z","caller":"traceutil/trace.go:171","msg":"trace[537839364] transaction","detail":"{read_only:false; response_revision:2768; number_of_response:1; }","duration":"252.404894ms","start":"2026-05-08T10:23:58.305137Z","end":"2026-05-08T10:23:58.557542Z","steps":["trace[537839364] 'process raft request' (duration: 252.152421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:23:58.557761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.559636ms","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:58.557827Z","caller":"traceutil/trace.go:171","msg":"trace[1987547711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2768; }","duration":"126.686668ms","start":"2026-05-08T10:23:58.431124Z","end":"2026-05-08T10:23:58.55781Z","steps":["trace[1987547711] 'agreement among raft nodes before linearized reading' (duration: 126.482846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:23:58.557918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.762846ms","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:23:58.557966Z","caller":"traceutil/trace.go:171","msg":"trace[446911288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2768; }","duration":"121.853658ms","start":"2026-05-08T10:23:58.436101Z","end":"2026-05-08T10:23:58.557955Z","steps":["trace[446911288] 'agreement among raft nodes before linearized reading' (duration: 121.663526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:23:58.558166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.823437ms","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:23:58.558337Z","caller":"traceutil/trace.go:171","msg":"trace[1262716777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2768; }","duration":"122.05529ms","start":"2026-05-08T10:23:58.436237Z","end":"2026-05-08T10:23:58.558292Z","steps":["trace[1262716777] 'agreement among raft nodes before linearized reading' (duration: 121.664615ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:23:58.772552Z","caller":"traceutil/trace.go:171","msg":"trace[1339045166] transaction","detail":"{read_only:false; response_revision:2769; number_of_response:1; }","duration":"209.83439ms","start":"2026-05-08T10:23:58.562679Z","end":"2026-05-08T10:23:58.772513Z","steps":["trace[1339045166] 'process raft request' (duration: 110.837679ms)","trace[1339045166] 'compare' (duration: 98.835899ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:23:58.819808Z","caller":"traceutil/trace.go:171","msg":"trace[79727494] transaction","detail":"{read_only:false; response_revision:2770; number_of_response:1; }","duration":"256.471655ms","start":"2026-05-08T10:23:58.563309Z","end":"2026-05-08T10:23:58.819781Z","steps":["trace[79727494] 'process raft request' (duration: 256.303073ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:23:58.819991Z","caller":"traceutil/trace.go:171","msg":"trace[1276796394] transaction","detail":"{read_only:false; response_revision:2771; number_of_response:1; }","duration":"256.404135ms","start":"2026-05-08T10:23:58.563567Z","end":"2026-05-08T10:23:58.819971Z","steps":["trace[1276796394] 'process raft request' (duration: 256.173862ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:09.550301Z","caller":"traceutil/trace.go:171","msg":"trace[571711182] transaction","detail":"{read_only:false; response_revision:2839; number_of_response:1; }","duration":"117.303826ms","start":"2026-05-08T10:24:09.432967Z","end":"2026-05-08T10:24:09.550271Z","steps":["trace[571711182] 'process raft request' (duration: 117.093664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:09.924564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.644433ms","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:24:09.924714Z","caller":"traceutil/trace.go:171","msg":"trace[1441325300] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2839; }","duration":"180.817176ms","start":"2026-05-08T10:24:09.743874Z","end":"2026-05-08T10:24:09.924691Z","steps":["trace[1441325300] 'range keys from in-memory index tree' (duration: 180.620983ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:09.946957Z","caller":"traceutil/trace.go:171","msg":"trace[1690510480] linearizableReadLoop","detail":"{readStateIndex:2952; appliedIndex:2950; }","duration":"433.928749ms","start":"2026-05-08T10:24:09.513008Z","end":"2026-05-08T10:24:09.946937Z","steps":["trace[1690510480] 'read index received' (duration: 37.182541ms)","trace[1690510480] 'applied index is now lower than readState.Index' (duration: 396.745398ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:24:09.947074Z","caller":"traceutil/trace.go:171","msg":"trace[1266876567] transaction","detail":"{read_only:false; response_revision:2840; number_of_response:1; }","duration":"512.539026ms","start":"2026-05-08T10:24:09.434482Z","end":"2026-05-08T10:24:09.947021Z","steps":["trace[1266876567] 'process raft request' (duration: 512.232252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:09.94721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.756568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-05-08T10:24:09.947258Z","caller":"traceutil/trace.go:171","msg":"trace[688049294] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2840; }","duration":"392.865439ms","start":"2026-05-08T10:24:09.554379Z","end":"2026-05-08T10:24:09.947244Z","steps":["trace[688049294] 'agreement among raft nodes before linearized reading' (duration: 392.726138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:09.947288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:24:09.554359Z","time spent":"392.92131ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":590,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-05-08T10:24:09.947346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.224451ms","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:24:09.947351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:24:09.434462Z","time spent":"512.745789ms","remote":"127.0.0.1:50090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:24:09.947373Z","caller":"traceutil/trace.go:171","msg":"trace[1104910321] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2840; }","duration":"276.297623ms","start":"2026-05-08T10:24:09.671066Z","end":"2026-05-08T10:24:09.947364Z","steps":["trace[1104910321] 'agreement among raft nodes before linearized reading' (duration: 276.135291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:09.947537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.718688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18ad900fa8fe60b0\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-05-08T10:24:09.947621Z","caller":"traceutil/trace.go:171","msg":"trace[1217808833] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18ad900fa8fe60b0; range_end:; response_count:1; response_revision:2840; }","duration":"128.89964ms","start":"2026-05-08T10:24:09.818699Z","end":"2026-05-08T10:24:09.947599Z","steps":["trace[1217808833] 'agreement among raft nodes before linearized reading' (duration: 128.442265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:09.947691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.680919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-05-08T10:24:09.947723Z","caller":"traceutil/trace.go:171","msg":"trace[970076304] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2840; }","duration":"434.70911ms","start":"2026-05-08T10:24:09.513002Z","end":"2026-05-08T10:24:09.947711Z","steps":["trace[970076304] 'agreement among raft nodes before linearized reading' (duration: 434.045301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:09.947744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:24:09.512952Z","time spent":"434.78666ms","remote":"127.0.0.1:50168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1319,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-05-08T10:24:09.949716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.056447ms","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:24:09.949791Z","caller":"traceutil/trace.go:171","msg":"trace[1073293488] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2840; }","duration":"160.160388ms","start":"2026-05-08T10:24:09.789615Z","end":"2026-05-08T10:24:09.949775Z","steps":["trace[1073293488] 'agreement among raft nodes before linearized reading' (duration: 157.508826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:10.601048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.940883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:24:10.601245Z","caller":"traceutil/trace.go:171","msg":"trace[591004596] transaction","detail":"{read_only:false; response_revision:2841; number_of_response:1; }","duration":"649.162697ms","start":"2026-05-08T10:24:09.952044Z","end":"2026-05-08T10:24:10.601207Z","steps":["trace[591004596] 'process raft request' (duration: 252.968391ms)","trace[591004596] 'compare' (duration: 395.824661ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:24:10.601348Z","caller":"traceutil/trace.go:171","msg":"trace[1979309948] transaction","detail":"{read_only:false; response_revision:2844; number_of_response:1; }","duration":"646.569274ms","start":"2026-05-08T10:24:09.954761Z","end":"2026-05-08T10:24:10.60133Z","steps":["trace[1979309948] 'process raft request' (duration: 646.528004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:10.601414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:24:09.95202Z","time spent":"649.298308ms","remote":"127.0.0.1:50052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":764,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:24:10.601432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:24:09.954748Z","time spent":"646.649295ms","remote":"127.0.0.1:50242","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:24:10.601553Z","caller":"traceutil/trace.go:171","msg":"trace[1331184507] linearizableReadLoop","detail":"{readStateIndex:2953; appliedIndex:2952; }","duration":"649.43298ms","start":"2026-05-08T10:24:09.95211Z","end":"2026-05-08T10:24:10.601543Z","steps":["trace[1331184507] 'read index received' (duration: 252.92025ms)","trace[1331184507] 'applied index is now lower than readState.Index' (duration: 396.51169ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:24:10.602147Z","caller":"traceutil/trace.go:171","msg":"trace[978026435] transaction","detail":"{read_only:false; response_revision:2843; number_of_response:1; }","duration":"647.553506ms","start":"2026-05-08T10:24:09.95457Z","end":"2026-05-08T10:24:10.602124Z","steps":["trace[978026435] 'process raft request' (duration: 646.682865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:10.602269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:24:09.954555Z","time spent":"647.637976ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":552,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:24:10.602179Z","caller":"traceutil/trace.go:171","msg":"trace[1005691618] transaction","detail":"{read_only:false; response_revision:2842; number_of_response:1; }","duration":"647.623686ms","start":"2026-05-08T10:24:09.954511Z","end":"2026-05-08T10:24:10.602134Z","steps":["trace[1005691618] 'process raft request' (duration: 646.679015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:10.602319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.199839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1375"} {"level":"info","ts":"2026-05-08T10:24:10.602371Z","caller":"traceutil/trace.go:171","msg":"trace[1391210995] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2844; }","duration":"650.25694ms","start":"2026-05-08T10:24:09.952102Z","end":"2026-05-08T10:24:10.602359Z","steps":["trace[1391210995] 'agreement among raft nodes before linearized reading' (duration: 650.022517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:10.602412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:24:09.952055Z","time spent":"650.349291ms","remote":"127.0.0.1:50168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1398,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "} {"level":"warn","ts":"2026-05-08T10:24:10.602615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:24:09.954496Z","time spent":"647.83338ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:24:11.067061Z","caller":"traceutil/trace.go:171","msg":"trace[1776883852] linearizableReadLoop","detail":"{readStateIndex:2957; appliedIndex:2956; }","duration":"465.450866ms","start":"2026-05-08T10:24:10.601581Z","end":"2026-05-08T10:24:11.067031Z","steps":["trace[1776883852] 'read index received' (duration: 388.586243ms)","trace[1776883852] 'applied index is now lower than readState.Index' (duration: 76.862993ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:24:11.067172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.013292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T10:24:11.067249Z","caller":"traceutil/trace.go:171","msg":"trace[538675470] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2844; }","duration":"856.198615ms","start":"2026-05-08T10:24:10.211029Z","end":"2026-05-08T10:24:11.067228Z","steps":["trace[538675470] 'agreement among raft nodes before linearized reading' (duration: 856.025602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:11.067265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093749094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-08T10:24:11.067311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:24:10.210999Z","time spent":"856.301406ms","remote":"127.0.0.1:50010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":52,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-05-08T10:24:11.067356Z","caller":"traceutil/trace.go:171","msg":"trace[1701602397] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:2844; }","duration":"1.093878915s","start":"2026-05-08T10:24:09.973454Z","end":"2026-05-08T10:24:11.067332Z","steps":["trace[1701602397] 'agreement among raft nodes before linearized reading' (duration: 1.093649391s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:11.06741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:24:09.973446Z","time spent":"1.093953556s","remote":"127.0.0.1:59462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-05-08T10:24:11.067445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112711258s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-05-08T10:24:11.067525Z","caller":"traceutil/trace.go:171","msg":"trace[1669894028] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2844; }","duration":"1.11284573s","start":"2026-05-08T10:24:09.954656Z","end":"2026-05-08T10:24:11.067502Z","steps":["trace[1669894028] 'agreement among raft nodes before linearized reading' (duration: 1.112525836s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:11.067574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:24:09.954612Z","time spent":"1.112949231s","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":575,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-08T10:24:11.067772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.718349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-05-08T10:24:11.067816Z","caller":"traceutil/trace.go:171","msg":"trace[882738827] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2845; }","duration":"461.786611ms","start":"2026-05-08T10:24:10.606017Z","end":"2026-05-08T10:24:11.067804Z","steps":["trace[882738827] 'agreement among raft nodes before linearized reading' (duration: 461.648779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:11.067849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:24:10.605996Z","time spent":"461.843891ms","remote":"127.0.0.1:50168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1266,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"info","ts":"2026-05-08T10:24:11.068364Z","caller":"traceutil/trace.go:171","msg":"trace[1348503698] transaction","detail":"{read_only:false; response_revision:2845; number_of_response:1; }","duration":"466.802992ms","start":"2026-05-08T10:24:10.601546Z","end":"2026-05-08T10:24:11.068349Z","steps":["trace[1348503698] 'process raft request' (duration: 466.001613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:11.068473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:24:10.601487Z","time spent":"466.917454ms","remote":"127.0.0.1:50242","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:24:11.068792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.620958ms","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:11.068833Z","caller":"traceutil/trace.go:171","msg":"trace[614484627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2845; }","duration":"183.699129ms","start":"2026-05-08T10:24:10.885124Z","end":"2026-05-08T10:24:11.068823Z","steps":["trace[614484627] 'agreement among raft nodes before linearized reading' (duration: 183.592988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:11.068925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.999235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-08T10:24:11.069095Z","caller":"traceutil/trace.go:171","msg":"trace[743082694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2845; }","duration":"185.34026ms","start":"2026-05-08T10:24:10.883738Z","end":"2026-05-08T10:24:11.069079Z","steps":["trace[743082694] 'agreement among raft nodes before linearized reading' (duration: 184.53553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:11.069026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.238546ms","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:11.069322Z","caller":"traceutil/trace.go:171","msg":"trace[1496890373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2845; }","duration":"234.615681ms","start":"2026-05-08T10:24:10.834671Z","end":"2026-05-08T10:24:11.069287Z","steps":["trace[1496890373] 'agreement among raft nodes before linearized reading' (duration: 233.712679ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:25.417284Z","caller":"traceutil/trace.go:171","msg":"trace[522927450] transaction","detail":"{read_only:false; response_revision:2940; number_of_response:1; }","duration":"122.985191ms","start":"2026-05-08T10:24:25.294274Z","end":"2026-05-08T10:24:25.417259Z","steps":["trace[522927450] 'process raft request' (duration: 122.814749ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:25.418053Z","caller":"traceutil/trace.go:171","msg":"trace[1642581153] transaction","detail":"{read_only:false; response_revision:2942; number_of_response:1; }","duration":"123.142572ms","start":"2026-05-08T10:24:25.294874Z","end":"2026-05-08T10:24:25.418016Z","steps":["trace[1642581153] 'process raft request' (duration: 123.020351ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:25.418224Z","caller":"traceutil/trace.go:171","msg":"trace[57544366] linearizableReadLoop","detail":"{readStateIndex:3062; appliedIndex:3058; }","duration":"117.586804ms","start":"2026-05-08T10:24:25.300612Z","end":"2026-05-08T10:24:25.418199Z","steps":["trace[57544366] 'read index received' (duration: 116.481752ms)","trace[57544366] 'applied index is now lower than readState.Index' (duration: 1.103682ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:24:25.418344Z","caller":"traceutil/trace.go:171","msg":"trace[66221766] transaction","detail":"{read_only:false; response_revision:2941; number_of_response:1; }","duration":"123.645678ms","start":"2026-05-08T10:24:25.294684Z","end":"2026-05-08T10:24:25.418329Z","steps":["trace[66221766] 'process raft request' (duration: 123.066471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:24:25.418504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.868779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-05-08T10:24:25.418546Z","caller":"traceutil/trace.go:171","msg":"trace[293787676] transaction","detail":"{read_only:false; response_revision:2943; number_of_response:1; }","duration":"123.613349ms","start":"2026-05-08T10:24:25.294919Z","end":"2026-05-08T10:24:25.418532Z","steps":["trace[293787676] 'process raft request' (duration: 123.024281ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:25.41856Z","caller":"traceutil/trace.go:171","msg":"trace[2030012723] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2943; }","duration":"117.961079ms","start":"2026-05-08T10:24:25.300579Z","end":"2026-05-08T10:24:25.41854Z","steps":["trace[2030012723] 'agreement among raft nodes before linearized reading' (duration: 117.738236ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:24:37.596769Z","caller":"traceutil/trace.go:171","msg":"trace[1401172545] transaction","detail":"{read_only:false; response_revision:3020; number_of_response:1; }","duration":"100.125972ms","start":"2026-05-08T10:24:37.496622Z","end":"2026-05-08T10:24:37.596748Z","steps":["trace[1401172545] 'process raft request' (duration: 44.685455ms)","trace[1401172545] 'compare' (duration: 55.159292ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:25:17.755176Z","caller":"traceutil/trace.go:171","msg":"trace[802045836] linearizableReadLoop","detail":"{readStateIndex:3519; appliedIndex:3518; }","duration":"120.921963ms","start":"2026-05-08T10:25:17.63423Z","end":"2026-05-08T10:25:17.755152Z","steps":["trace[802045836] 'read index received' (duration: 120.78493ms)","trace[802045836] 'applied index is now lower than readState.Index' (duration: 136.113µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:25:17.755212Z","caller":"traceutil/trace.go:171","msg":"trace[1814877809] transaction","detail":"{read_only:false; response_revision:3377; number_of_response:1; }","duration":"141.13612ms","start":"2026-05-08T10:25:17.614048Z","end":"2026-05-08T10:25:17.755184Z","steps":["trace[1814877809] 'process raft request' (duration: 140.963275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:17.755476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.198771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-05-08T10:25:17.755579Z","caller":"traceutil/trace.go:171","msg":"trace[1391979836] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3377; }","duration":"121.353364ms","start":"2026-05-08T10:25:17.634202Z","end":"2026-05-08T10:25:17.755555Z","steps":["trace[1391979836] 'agreement among raft nodes before linearized reading' (duration: 121.044297ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:17.876181Z","caller":"traceutil/trace.go:171","msg":"trace[1330325518] linearizableReadLoop","detail":"{readStateIndex:3521; appliedIndex:3519; }","duration":"110.329671ms","start":"2026-05-08T10:25:17.765807Z","end":"2026-05-08T10:25:17.876136Z","steps":["trace[1330325518] 'read index received' (duration: 36.485153ms)","trace[1330325518] 'applied index is now lower than readState.Index' (duration: 73.843208ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:25:17.876562Z","caller":"traceutil/trace.go:171","msg":"trace[94484360] transaction","detail":"{read_only:false; response_revision:3379; number_of_response:1; }","duration":"113.616413ms","start":"2026-05-08T10:25:17.762702Z","end":"2026-05-08T10:25:17.876318Z","steps":["trace[94484360] 'process raft request' (duration: 106.166489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:17.877133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.282812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-cookie-secret\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:25:17.877233Z","caller":"traceutil/trace.go:171","msg":"trace[885117003] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-cookie-secret; range_end:; response_count:0; response_revision:3379; }","duration":"111.422506ms","start":"2026-05-08T10:25:17.765788Z","end":"2026-05-08T10:25:17.87721Z","steps":["trace[885117003] 'agreement among raft nodes before linearized reading' (duration: 111.045307ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:18.457305Z","caller":"traceutil/trace.go:171","msg":"trace[665096776] transaction","detail":"{read_only:false; response_revision:3388; number_of_response:1; }","duration":"171.963107ms","start":"2026-05-08T10:25:18.285281Z","end":"2026-05-08T10:25:18.457244Z","steps":["trace[665096776] 'process raft request' (duration: 171.61492ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:19.218893Z","caller":"traceutil/trace.go:171","msg":"trace[1006917712] transaction","detail":"{read_only:false; response_revision:3390; number_of_response:1; }","duration":"111.660575ms","start":"2026-05-08T10:25:19.107201Z","end":"2026-05-08T10:25:19.218861Z","steps":["trace[1006917712] 'process raft request' (duration: 111.41698ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:50.399414Z","caller":"traceutil/trace.go:171","msg":"trace[1296898140] transaction","detail":"{read_only:false; response_revision:3669; number_of_response:1; }","duration":"156.861585ms","start":"2026-05-08T10:25:50.242499Z","end":"2026-05-08T10:25:50.39936Z","steps":["trace[1296898140] 'process raft request' (duration: 156.535207ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:25:54.619296Z","caller":"traceutil/trace.go:171","msg":"trace[1959775309] linearizableReadLoop","detail":"{readStateIndex:3857; appliedIndex:3856; }","duration":"101.282181ms","start":"2026-05-08T10:25:54.517987Z","end":"2026-05-08T10:25:54.619269Z","steps":["trace[1959775309] 'read index received' (duration: 101.007796ms)","trace[1959775309] 'applied index is now lower than readState.Index' (duration: 273.055µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:25:54.619424Z","caller":"traceutil/trace.go:171","msg":"trace[1570282870] transaction","detail":"{read_only:false; response_revision:3696; number_of_response:1; }","duration":"102.949225ms","start":"2026-05-08T10:25:54.516433Z","end":"2026-05-08T10:25:54.619382Z","steps":["trace[1570282870] 'process raft request' (duration: 102.653848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:25:54.619898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.875113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"info","ts":"2026-05-08T10:25:54.619963Z","caller":"traceutil/trace.go:171","msg":"trace[1799589956] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:3696; }","duration":"101.986996ms","start":"2026-05-08T10:25:54.517956Z","end":"2026-05-08T10:25:54.619943Z","steps":["trace[1799589956] 'agreement among raft nodes before linearized reading' (duration: 101.460776ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:06.74911Z","caller":"traceutil/trace.go:171","msg":"trace[1971423192] transaction","detail":"{read_only:false; number_of_response:0; response_revision:3786; }","duration":"141.011842ms","start":"2026-05-08T10:26:06.608073Z","end":"2026-05-08T10:26:06.749084Z","steps":["trace[1971423192] 'process raft request' (duration: 140.853649ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:11.480219Z","caller":"traceutil/trace.go:171","msg":"trace[1759408954] transaction","detail":"{read_only:false; response_revision:3817; number_of_response:1; }","duration":"130.481188ms","start":"2026-05-08T10:26:11.349714Z","end":"2026-05-08T10:26:11.480195Z","steps":["trace[1759408954] 'process raft request' (duration: 125.853741ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:11.480326Z","caller":"traceutil/trace.go:171","msg":"trace[407964041] transaction","detail":"{read_only:false; response_revision:3818; number_of_response:1; }","duration":"127.279958ms","start":"2026-05-08T10:26:11.353032Z","end":"2026-05-08T10:26:11.480312Z","steps":["trace[407964041] 'process raft request' (duration: 127.094275ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:18.403801Z","caller":"traceutil/trace.go:171","msg":"trace[2072100216] transaction","detail":"{read_only:false; response_revision:3859; number_of_response:1; }","duration":"105.004654ms","start":"2026-05-08T10:26:18.298768Z","end":"2026-05-08T10:26:18.403772Z","steps":["trace[2072100216] 'process raft request' (duration: 104.842601ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:18.441016Z","caller":"traceutil/trace.go:171","msg":"trace[843975782] linearizableReadLoop","detail":"{readStateIndex:4042; appliedIndex:4039; }","duration":"128.837757ms","start":"2026-05-08T10:26:18.312145Z","end":"2026-05-08T10:26:18.440982Z","steps":["trace[843975782] 'read index received' (duration: 91.428021ms)","trace[843975782] 'applied index is now lower than readState.Index' (duration: 37.408436ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:18.44109Z","caller":"traceutil/trace.go:171","msg":"trace[795735121] transaction","detail":"{read_only:false; response_revision:3860; number_of_response:1; }","duration":"138.146779ms","start":"2026-05-08T10:26:18.302901Z","end":"2026-05-08T10:26:18.441048Z","steps":["trace[795735121] 'process raft request' (duration: 137.349005ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:18.44142Z","caller":"traceutil/trace.go:171","msg":"trace[1478259734] transaction","detail":"{read_only:false; response_revision:3861; number_of_response:1; }","duration":"138.396214ms","start":"2026-05-08T10:26:18.303002Z","end":"2026-05-08T10:26:18.441398Z","steps":["trace[1478259734] 'process raft request' (duration: 137.930615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:18.441491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.305446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-08T10:26:18.442484Z","caller":"traceutil/trace.go:171","msg":"trace[1378964092] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3861; }","duration":"129.465689ms","start":"2026-05-08T10:26:18.312101Z","end":"2026-05-08T10:26:18.441567Z","steps":["trace[1378964092] 'agreement among raft nodes before linearized reading' (duration: 129.02703ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:21.237551Z","caller":"traceutil/trace.go:171","msg":"trace[220605452] transaction","detail":"{read_only:false; response_revision:3875; number_of_response:1; }","duration":"106.446697ms","start":"2026-05-08T10:26:21.131074Z","end":"2026-05-08T10:26:21.237521Z","steps":["trace[220605452] 'process raft request' (duration: 106.280404ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:21.81754Z","caller":"traceutil/trace.go:171","msg":"trace[282189244] linearizableReadLoop","detail":"{readStateIndex:4062; appliedIndex:4061; }","duration":"179.985136ms","start":"2026-05-08T10:26:21.637522Z","end":"2026-05-08T10:26:21.817507Z","steps":["trace[282189244] 'read index received' (duration: 149.463961ms)","trace[282189244] 'applied index is now lower than readState.Index' (duration: 30.519515ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:21.81756Z","caller":"traceutil/trace.go:171","msg":"trace[709831772] transaction","detail":"{read_only:false; response_revision:3880; number_of_response:1; }","duration":"183.424489ms","start":"2026-05-08T10:26:21.6341Z","end":"2026-05-08T10:26:21.817524Z","steps":["trace[709831772] 'process raft request' (duration: 152.926705ms)","trace[709831772] 'compare' (duration: 30.205929ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:26:21.817821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.281511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-05-08T10:26:21.817913Z","caller":"traceutil/trace.go:171","msg":"trace[787527748] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3880; }","duration":"180.413183ms","start":"2026-05-08T10:26:21.637479Z","end":"2026-05-08T10:26:21.817892Z","steps":["trace[787527748] 'agreement among raft nodes before linearized reading' (duration: 180.186589ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:31.969075Z","caller":"traceutil/trace.go:171","msg":"trace[1101939928] transaction","detail":"{read_only:false; response_revision:4297; number_of_response:1; }","duration":"123.153165ms","start":"2026-05-08T10:26:31.845894Z","end":"2026-05-08T10:26:31.969047Z","steps":["trace[1101939928] 'process raft request' (duration: 113.186615ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:33.398911Z","caller":"traceutil/trace.go:171","msg":"trace[1932221047] linearizableReadLoop","detail":"{readStateIndex:4525; appliedIndex:4524; }","duration":"304.501746ms","start":"2026-05-08T10:26:33.09438Z","end":"2026-05-08T10:26:33.398881Z","steps":["trace[1932221047] 'read index received' (duration: 304.248701ms)","trace[1932221047] 'applied index is now lower than readState.Index' (duration: 251.705µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:33.398979Z","caller":"traceutil/trace.go:171","msg":"trace[531083670] transaction","detail":"{read_only:false; response_revision:4331; number_of_response:1; }","duration":"353.749482ms","start":"2026-05-08T10:26:33.045176Z","end":"2026-05-08T10:26:33.398925Z","steps":["trace[531083670] 'process raft request' (duration: 353.471876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:33.399422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.994695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/grafana-tls-9bwwn\" ","response":"range_response_count:1 size:2412"} {"level":"warn","ts":"2026-05-08T10:26:33.399505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:33.045155Z","time spent":"354.195349ms","remote":"127.0.0.1:50052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":666,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:26:33.399614Z","caller":"traceutil/trace.go:171","msg":"trace[1460227286] range","detail":"{range_begin:/registry/secrets/monitoring/grafana-tls-9bwwn; range_end:; response_count:1; response_revision:4331; }","duration":"305.25834ms","start":"2026-05-08T10:26:33.094297Z","end":"2026-05-08T10:26:33.399555Z","steps":["trace[1460227286] 'agreement among raft nodes before linearized reading' (duration: 304.821672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:33.399784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:33.094286Z","time spent":"305.481923ms","remote":"127.0.0.1:50074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":2435,"request content":"key:\"/registry/secrets/monitoring/grafana-tls-9bwwn\" "} {"level":"info","ts":"2026-05-08T10:26:33.542481Z","caller":"traceutil/trace.go:171","msg":"trace[9375373] linearizableReadLoop","detail":"{readStateIndex:4527; appliedIndex:4525; }","duration":"143.442049ms","start":"2026-05-08T10:26:33.39902Z","end":"2026-05-08T10:26:33.542462Z","steps":["trace[9375373] 'read index received' (duration: 143.170704ms)","trace[9375373] 'applied index is now lower than readState.Index' (duration: 270.645µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:26:33.542842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.679663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:26:33.542921Z","caller":"traceutil/trace.go:171","msg":"trace[1303496219] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:4333; }","duration":"237.836076ms","start":"2026-05-08T10:26:33.305073Z","end":"2026-05-08T10:26:33.542909Z","steps":["trace[1303496219] 'agreement among raft nodes before linearized reading' (duration: 237.587962ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:33.542999Z","caller":"traceutil/trace.go:171","msg":"trace[240511753] transaction","detail":"{read_only:false; response_revision:4332; number_of_response:1; }","duration":"385.598903ms","start":"2026-05-08T10:26:33.157385Z","end":"2026-05-08T10:26:33.542983Z","steps":["trace[240511753] 'process raft request' (duration: 384.701508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:33.542922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.033552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/grafana-tls\" ","response":"range_response_count:1 size:3051"} {"level":"warn","ts":"2026-05-08T10:26:33.543105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:33.157328Z","time spent":"385.711845ms","remote":"127.0.0.1:59446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3036,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:26:33.543158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.392332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-05-08T10:26:33.543269Z","caller":"traceutil/trace.go:171","msg":"trace[211468894] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/grafana-tls; range_end:; response_count:1; response_revision:4333; }","duration":"291.252458ms","start":"2026-05-08T10:26:33.251813Z","end":"2026-05-08T10:26:33.543066Z","steps":["trace[211468894] 'agreement among raft nodes before linearized reading' (duration: 290.956502ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:33.543279Z","caller":"traceutil/trace.go:171","msg":"trace[281145233] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4333; }","duration":"166.556495ms","start":"2026-05-08T10:26:33.376688Z","end":"2026-05-08T10:26:33.543244Z","steps":["trace[281145233] 'agreement among raft nodes before linearized reading' (duration: 165.976794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:33.542843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.957304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T10:26:33.543488Z","caller":"traceutil/trace.go:171","msg":"trace[1211281356] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4333; }","duration":"394.677908ms","start":"2026-05-08T10:26:33.148799Z","end":"2026-05-08T10:26:33.543477Z","steps":["trace[1211281356] 'agreement among raft nodes before linearized reading' (duration: 393.971704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:33.543539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:33.14877Z","time spent":"394.757728ms","remote":"127.0.0.1:46368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-05-08T10:26:33.543681Z","caller":"traceutil/trace.go:171","msg":"trace[2128238967] transaction","detail":"{read_only:false; response_revision:4333; number_of_response:1; }","duration":"340.37367ms","start":"2026-05-08T10:26:33.203254Z","end":"2026-05-08T10:26:33.543627Z","steps":["trace[2128238967] 'process raft request' (duration: 339.048036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:33.543839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:33.203236Z","time spent":"340.536644ms","remote":"127.0.0.1:59446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2919,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:26:33.548249Z","caller":"traceutil/trace.go:171","msg":"trace[457983198] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4334; }","duration":"146.77598ms","start":"2026-05-08T10:26:33.401465Z","end":"2026-05-08T10:26:33.548241Z","steps":["trace[457983198] 'process raft request' (duration: 146.599867ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:34.415903Z","caller":"traceutil/trace.go:171","msg":"trace[2052902731] linearizableReadLoop","detail":"{readStateIndex:4536; appliedIndex:4535; }","duration":"407.414941ms","start":"2026-05-08T10:26:34.008459Z","end":"2026-05-08T10:26:34.415874Z","steps":["trace[2052902731] 'read index received' (duration: 407.154427ms)","trace[2052902731] 'applied index is now lower than readState.Index' (duration: 258.944µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:34.416245Z","caller":"traceutil/trace.go:171","msg":"trace[73739784] transaction","detail":"{read_only:false; response_revision:4342; number_of_response:1; }","duration":"434.516368ms","start":"2026-05-08T10:26:33.981715Z","end":"2026-05-08T10:26:34.416232Z","steps":["trace[73739784] 'process raft request' (duration: 433.962999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:34.416368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:33.981693Z","time spent":"434.583469ms","remote":"127.0.0.1:50242","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:26:34.416498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.023881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-05-08T10:26:34.416586Z","caller":"traceutil/trace.go:171","msg":"trace[1471394662] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4342; }","duration":"408.184824ms","start":"2026-05-08T10:26:34.008373Z","end":"2026-05-08T10:26:34.416558Z","steps":["trace[1471394662] 'agreement among raft nodes before linearized reading' (duration: 407.913219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:34.41662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:34.008344Z","time spent":"408.270056ms","remote":"127.0.0.1:50090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-08T10:26:34.46183Z","caller":"traceutil/trace.go:171","msg":"trace[434773755] transaction","detail":"{read_only:false; response_revision:4343; number_of_response:1; }","duration":"264.460446ms","start":"2026-05-08T10:26:34.197331Z","end":"2026-05-08T10:26:34.461792Z","steps":["trace[434773755] 'process raft request' (duration: 264.14463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:34.462218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.412835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"warn","ts":"2026-05-08T10:26:34.462255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.213751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:26:34.462328Z","caller":"traceutil/trace.go:171","msg":"trace[941160101] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4344; }","duration":"274.604828ms","start":"2026-05-08T10:26:34.187691Z","end":"2026-05-08T10:26:34.462296Z","steps":["trace[941160101] 'agreement among raft nodes before linearized reading' (duration: 274.298162ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:34.462351Z","caller":"traceutil/trace.go:171","msg":"trace[1847406625] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:4344; }","duration":"410.337033ms","start":"2026-05-08T10:26:34.051986Z","end":"2026-05-08T10:26:34.462323Z","steps":["trace[1847406625] 'agreement among raft nodes before linearized reading' (duration: 410.12493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:34.462357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.498317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-08T10:26:34.462407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:34.051914Z","time spent":"410.481556ms","remote":"127.0.0.1:45800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-05-08T10:26:34.462412Z","caller":"traceutil/trace.go:171","msg":"trace[456150599] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:4344; }","duration":"453.564379ms","start":"2026-05-08T10:26:34.008834Z","end":"2026-05-08T10:26:34.462398Z","steps":["trace[456150599] 'agreement among raft nodes before linearized reading' (duration: 453.409616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:34.46245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:34.008804Z","time spent":"453.63677ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":26,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-08T10:26:34.462493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.906008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-05-08T10:26:34.462585Z","caller":"traceutil/trace.go:171","msg":"trace[125821607] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4344; }","duration":"195.07194ms","start":"2026-05-08T10:26:34.267488Z","end":"2026-05-08T10:26:34.46256Z","steps":["trace[125821607] 'agreement among raft nodes before linearized reading' (duration: 194.813586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:34.462553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.517842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:9482"} {"level":"info","ts":"2026-05-08T10:26:34.46268Z","caller":"traceutil/trace.go:171","msg":"trace[1650417319] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:4344; }","duration":"378.729806ms","start":"2026-05-08T10:26:34.08392Z","end":"2026-05-08T10:26:34.46265Z","steps":["trace[1650417319] 'agreement among raft nodes before linearized reading' (duration: 378.291088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:34.462675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.560463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"warn","ts":"2026-05-08T10:26:34.462764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:34.083894Z","time spent":"378.858198ms","remote":"127.0.0.1:50140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":9505,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"info","ts":"2026-05-08T10:26:34.462778Z","caller":"traceutil/trace.go:171","msg":"trace[469276392] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4344; }","duration":"271.698306ms","start":"2026-05-08T10:26:34.191063Z","end":"2026-05-08T10:26:34.462761Z","steps":["trace[469276392] 'agreement among raft nodes before linearized reading' (duration: 271.428221ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:34.462798Z","caller":"traceutil/trace.go:171","msg":"trace[2020224866] transaction","detail":"{read_only:false; response_revision:4344; number_of_response:1; }","duration":"263.121832ms","start":"2026-05-08T10:26:34.199658Z","end":"2026-05-08T10:26:34.46278Z","steps":["trace[2020224866] 'process raft request' (duration: 262.049774ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:35.446903Z","caller":"traceutil/trace.go:171","msg":"trace[1749591991] transaction","detail":"{read_only:false; response_revision:4401; number_of_response:1; }","duration":"120.59172ms","start":"2026-05-08T10:26:35.326287Z","end":"2026-05-08T10:26:35.446879Z","steps":["trace[1749591991] 'process raft request' (duration: 83.631558ms)","trace[1749591991] 'compare' (duration: 36.83643ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:35.496131Z","caller":"traceutil/trace.go:171","msg":"trace[2010741052] linearizableReadLoop","detail":"{readStateIndex:4597; appliedIndex:4595; }","duration":"165.30392ms","start":"2026-05-08T10:26:35.330791Z","end":"2026-05-08T10:26:35.496095Z","steps":["trace[2010741052] 'read index received' (duration: 79.142268ms)","trace[2010741052] 'applied index is now lower than readState.Index' (duration: 86.160012ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:26:35.49632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.504634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:26:35.49636Z","caller":"traceutil/trace.go:171","msg":"trace[1541686948] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4402; }","duration":"165.636236ms","start":"2026-05-08T10:26:35.330712Z","end":"2026-05-08T10:26:35.496348Z","steps":["trace[1541686948] 'agreement among raft nodes before linearized reading' (duration: 165.505713ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:35.496321Z","caller":"traceutil/trace.go:171","msg":"trace[1180684315] transaction","detail":"{read_only:false; response_revision:4402; number_of_response:1; }","duration":"166.850798ms","start":"2026-05-08T10:26:35.3294Z","end":"2026-05-08T10:26:35.496251Z","steps":["trace[1180684315] 'process raft request' (duration: 166.526122ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:35.996987Z","caller":"traceutil/trace.go:171","msg":"trace[1556810802] transaction","detail":"{read_only:false; response_revision:4418; number_of_response:1; }","duration":"148.726753ms","start":"2026-05-08T10:26:35.848228Z","end":"2026-05-08T10:26:35.996955Z","steps":["trace[1556810802] 'process raft request' (duration: 148.467329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:35.997252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.828288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-05-08T10:26:35.997326Z","caller":"traceutil/trace.go:171","msg":"trace[1360162632] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4418; }","duration":"118.923139ms","start":"2026-05-08T10:26:35.878383Z","end":"2026-05-08T10:26:35.997306Z","steps":["trace[1360162632] 'agreement among raft nodes before linearized reading' (duration: 118.666805ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:35.997271Z","caller":"traceutil/trace.go:171","msg":"trace[682861890] linearizableReadLoop","detail":"{readStateIndex:4613; appliedIndex:4612; }","duration":"118.513822ms","start":"2026-05-08T10:26:35.87839Z","end":"2026-05-08T10:26:35.996904Z","steps":["trace[682861890] 'read index received' (duration: 118.283978ms)","trace[682861890] 'applied index is now lower than readState.Index' (duration: 228.514µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:36.13841Z","caller":"traceutil/trace.go:171","msg":"trace[1505656264] transaction","detail":"{read_only:false; response_revision:4420; number_of_response:1; }","duration":"119.79483ms","start":"2026-05-08T10:26:36.018571Z","end":"2026-05-08T10:26:36.138366Z","steps":["trace[1505656264] 'process raft request' (duration: 43.712441ms)","trace[1505656264] 'compare' (duration: 75.775813ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:36.180186Z","caller":"traceutil/trace.go:171","msg":"trace[1899895751] linearizableReadLoop","detail":"{readStateIndex:4616; appliedIndex:4614; }","duration":"151.079079ms","start":"2026-05-08T10:26:36.029071Z","end":"2026-05-08T10:26:36.18015Z","steps":["trace[1899895751] 'read index received' (duration: 33.238424ms)","trace[1899895751] 'applied index is now lower than readState.Index' (duration: 117.839295ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:36.18028Z","caller":"traceutil/trace.go:171","msg":"trace[1001223381] transaction","detail":"{read_only:false; response_revision:4421; number_of_response:1; }","duration":"153.841039ms","start":"2026-05-08T10:26:36.026416Z","end":"2026-05-08T10:26:36.180257Z","steps":["trace[1001223381] 'process raft request' (duration: 153.390231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:36.180359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.251842ms","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:36.180396Z","caller":"traceutil/trace.go:171","msg":"trace[285639206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4421; }","duration":"151.355114ms","start":"2026-05-08T10:26:36.029029Z","end":"2026-05-08T10:26:36.180384Z","steps":["trace[285639206] 'agreement among raft nodes before linearized reading' (duration: 151.254163ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:36.352809Z","caller":"traceutil/trace.go:171","msg":"trace[825526343] linearizableReadLoop","detail":"{readStateIndex:4617; appliedIndex:4616; }","duration":"170.896793ms","start":"2026-05-08T10:26:36.181878Z","end":"2026-05-08T10:26:36.352775Z","steps":["trace[825526343] 'read index received' (duration: 125.427611ms)","trace[825526343] 'applied index is now lower than readState.Index' (duration: 45.467592ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:36.35301Z","caller":"traceutil/trace.go:171","msg":"trace[1604515359] transaction","detail":"{read_only:false; response_revision:4422; number_of_response:1; }","duration":"208.323571ms","start":"2026-05-08T10:26:36.144665Z","end":"2026-05-08T10:26:36.352989Z","steps":["trace[1604515359] 'process raft request' (duration: 162.622105ms)","trace[1604515359] 'compare' (duration: 45.298469ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:26:36.353253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.346222ms","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:36.35334Z","caller":"traceutil/trace.go:171","msg":"trace[2021911646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4422; }","duration":"171.494865ms","start":"2026-05-08T10:26:36.181824Z","end":"2026-05-08T10:26:36.353319Z","steps":["trace[2021911646] 'agreement among raft nodes before linearized reading' (duration: 171.338672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:36.353651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.644246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0.18ad90376ab5292d\" ","response":"range_response_count:1 size:1179"} {"level":"info","ts":"2026-05-08T10:26:36.35371Z","caller":"traceutil/trace.go:171","msg":"trace[1976796717] range","detail":"{range_begin:/registry/events/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0.18ad90376ab5292d; range_end:; response_count:1; response_revision:4422; }","duration":"106.773747ms","start":"2026-05-08T10:26:36.246916Z","end":"2026-05-08T10:26:36.35369Z","steps":["trace[1976796717] 'agreement among raft nodes before linearized reading' (duration: 106.552023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:36.353965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.376702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2348463"} {"level":"info","ts":"2026-05-08T10:26:36.354033Z","caller":"traceutil/trace.go:171","msg":"trace[153907638] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4422; }","duration":"110.502515ms","start":"2026-05-08T10:26:36.243514Z","end":"2026-05-08T10:26:36.354017Z","steps":["trace[153907638] 'agreement among raft nodes before linearized reading' (duration: 109.521967ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:37.193482Z","caller":"traceutil/trace.go:171","msg":"trace[588261309] transaction","detail":"{read_only:false; response_revision:4448; number_of_response:1; }","duration":"154.461125ms","start":"2026-05-08T10:26:37.038979Z","end":"2026-05-08T10:26:37.193441Z","steps":["trace[588261309] 'process raft request' (duration: 147.787875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:37.497363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.448035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-05-08T10:26:37.49748Z","caller":"traceutil/trace.go:171","msg":"trace[535419869] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4448; }","duration":"176.649419ms","start":"2026-05-08T10:26:37.320805Z","end":"2026-05-08T10:26:37.497454Z","steps":["trace[535419869] 'range keys from in-memory index tree' (duration: 176.16242ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:38.066345Z","caller":"traceutil/trace.go:171","msg":"trace[1247202703] transaction","detail":"{read_only:false; response_revision:4456; number_of_response:1; }","duration":"135.601446ms","start":"2026-05-08T10:26:37.930703Z","end":"2026-05-08T10:26:38.066305Z","steps":["trace[1247202703] 'process raft request' (duration: 135.2995ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:39.604939Z","caller":"traceutil/trace.go:171","msg":"trace[947863723] linearizableReadLoop","detail":"{readStateIndex:4662; appliedIndex:4661; }","duration":"165.386756ms","start":"2026-05-08T10:26:39.439527Z","end":"2026-05-08T10:26:39.604914Z","steps":["trace[947863723] 'read index received' (duration: 165.149482ms)","trace[947863723] 'applied index is now lower than readState.Index' (duration: 235.734µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:26:39.605119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.565309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T10:26:39.605045Z","caller":"traceutil/trace.go:171","msg":"trace[1434557411] transaction","detail":"{read_only:false; response_revision:4466; number_of_response:1; }","duration":"189.336142ms","start":"2026-05-08T10:26:39.415633Z","end":"2026-05-08T10:26:39.604969Z","steps":["trace[1434557411] 'process raft request' (duration: 189.116438ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:39.605156Z","caller":"traceutil/trace.go:171","msg":"trace[1093121295] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4466; }","duration":"165.633241ms","start":"2026-05-08T10:26:39.43951Z","end":"2026-05-08T10:26:39.605143Z","steps":["trace[1093121295] 'agreement among raft nodes before linearized reading' (duration: 165.509158ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:42.003465Z","caller":"traceutil/trace.go:171","msg":"trace[1438493001] linearizableReadLoop","detail":"{readStateIndex:4709; appliedIndex:4708; }","duration":"188.168176ms","start":"2026-05-08T10:26:41.815258Z","end":"2026-05-08T10:26:42.003426Z","steps":["trace[1438493001] 'read index received' (duration: 95.340386ms)","trace[1438493001] 'applied index is now lower than readState.Index' (duration: 92.82656ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:42.003612Z","caller":"traceutil/trace.go:171","msg":"trace[1516501756] transaction","detail":"{read_only:false; response_revision:4510; number_of_response:1; }","duration":"218.076896ms","start":"2026-05-08T10:26:41.785491Z","end":"2026-05-08T10:26:42.003568Z","steps":["trace[1516501756] 'process raft request' (duration: 125.172984ms)","trace[1516501756] 'compare' (duration: 92.611527ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:26:42.003775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.494582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bc494b19-0e82-45fa-90b6-8a15a1f759e7\" ","response":"range_response_count:1 size:4065"} {"level":"info","ts":"2026-05-08T10:26:42.00382Z","caller":"traceutil/trace.go:171","msg":"trace[1072196347] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bc494b19-0e82-45fa-90b6-8a15a1f759e7; range_end:; response_count:1; response_revision:4510; }","duration":"188.618264ms","start":"2026-05-08T10:26:41.815183Z","end":"2026-05-08T10:26:42.003801Z","steps":["trace[1072196347] 'agreement among raft nodes before linearized reading' (duration: 188.41748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:42.003841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.816501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-05-08T10:26:42.003936Z","caller":"traceutil/trace.go:171","msg":"trace[965082172] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4510; }","duration":"174.925293ms","start":"2026-05-08T10:26:41.828988Z","end":"2026-05-08T10:26:42.003913Z","steps":["trace[965082172] 'agreement among raft nodes before linearized reading' (duration: 174.704769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:42.004137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.68186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:26:42.004169Z","caller":"traceutil/trace.go:171","msg":"trace[699450629] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:4510; }","duration":"109.762482ms","start":"2026-05-08T10:26:41.8944Z","end":"2026-05-08T10:26:42.004163Z","steps":["trace[699450629] 'agreement among raft nodes before linearized reading' (duration: 109.710531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:42.004444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.374403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-08T10:26:42.004513Z","caller":"traceutil/trace.go:171","msg":"trace[373961389] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4510; }","duration":"165.485045ms","start":"2026-05-08T10:26:41.839009Z","end":"2026-05-08T10:26:42.004494Z","steps":["trace[373961389] 'agreement among raft nodes before linearized reading' (duration: 165.336063ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:44.600172Z","caller":"traceutil/trace.go:171","msg":"trace[61217513] transaction","detail":"{read_only:false; response_revision:4578; number_of_response:1; }","duration":"263.204315ms","start":"2026-05-08T10:26:44.336933Z","end":"2026-05-08T10:26:44.600138Z","steps":["trace[61217513] 'process raft request' (duration: 262.985722ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:44.667823Z","caller":"traceutil/trace.go:171","msg":"trace[1517445845] linearizableReadLoop","detail":"{readStateIndex:4781; appliedIndex:4779; }","duration":"114.426141ms","start":"2026-05-08T10:26:44.553364Z","end":"2026-05-08T10:26:44.66779Z","steps":["trace[1517445845] 'read index received' (duration: 46.64946ms)","trace[1517445845] 'applied index is now lower than readState.Index' (duration: 67.775371ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:44.667891Z","caller":"traceutil/trace.go:171","msg":"trace[1646487198] transaction","detail":"{read_only:false; response_revision:4579; number_of_response:1; }","duration":"213.776497ms","start":"2026-05-08T10:26:44.45407Z","end":"2026-05-08T10:26:44.667847Z","steps":["trace[1646487198] 'process raft request' (duration: 200.428873ms)","trace[1646487198] 'compare' (duration: 13.14441ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:26:44.668208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.805437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-08T10:26:44.668337Z","caller":"traceutil/trace.go:171","msg":"trace[1377189557] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4579; }","duration":"114.96534ms","start":"2026-05-08T10:26:44.553343Z","end":"2026-05-08T10:26:44.668309Z","steps":["trace[1377189557] 'agreement among raft nodes before linearized reading' (duration: 114.559283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:44.669068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.049622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2348463"} {"level":"info","ts":"2026-05-08T10:26:44.669143Z","caller":"traceutil/trace.go:171","msg":"trace[1721968157] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4579; }","duration":"115.202544ms","start":"2026-05-08T10:26:44.553923Z","end":"2026-05-08T10:26:44.669126Z","steps":["trace[1721968157] 'agreement among raft nodes before linearized reading' (duration: 114.059634ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:44.831646Z","caller":"traceutil/trace.go:171","msg":"trace[1797007802] linearizableReadLoop","detail":"{readStateIndex:4787; appliedIndex:4784; }","duration":"105.856431ms","start":"2026-05-08T10:26:44.725762Z","end":"2026-05-08T10:26:44.831618Z","steps":["trace[1797007802] 'read index received' (duration: 49.284557ms)","trace[1797007802] 'applied index is now lower than readState.Index' (duration: 56.570714ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:26:44.832107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.243757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-05-08T10:26:44.83223Z","caller":"traceutil/trace.go:171","msg":"trace[323366215] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4585; }","duration":"106.469431ms","start":"2026-05-08T10:26:44.725743Z","end":"2026-05-08T10:26:44.832212Z","steps":["trace[323366215] 'agreement among raft nodes before linearized reading' (duration: 106.087864ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:44.832545Z","caller":"traceutil/trace.go:171","msg":"trace[1735609058] transaction","detail":"{read_only:false; response_revision:4583; number_of_response:1; }","duration":"126.023624ms","start":"2026-05-08T10:26:44.706507Z","end":"2026-05-08T10:26:44.832531Z","steps":["trace[1735609058] 'process raft request' (duration: 68.606975ms)","trace[1735609058] 'compare' (duration: 56.022055ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:44.832906Z","caller":"traceutil/trace.go:171","msg":"trace[2125497971] transaction","detail":"{read_only:false; response_revision:4584; number_of_response:1; }","duration":"126.202397ms","start":"2026-05-08T10:26:44.706693Z","end":"2026-05-08T10:26:44.832895Z","steps":["trace[2125497971] 'process raft request' (duration: 124.679261ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:44.833104Z","caller":"traceutil/trace.go:171","msg":"trace[930423142] transaction","detail":"{read_only:false; response_revision:4585; number_of_response:1; }","duration":"124.368545ms","start":"2026-05-08T10:26:44.708724Z","end":"2026-05-08T10:26:44.833093Z","steps":["trace[930423142] 'process raft request' (duration: 122.815888ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:45.154143Z","caller":"traceutil/trace.go:171","msg":"trace[1304534697] linearizableReadLoop","detail":"{readStateIndex:4790; appliedIndex:4788; }","duration":"108.562313ms","start":"2026-05-08T10:26:45.045555Z","end":"2026-05-08T10:26:45.154117Z","steps":["trace[1304534697] 'read index received' (duration: 73.316695ms)","trace[1304534697] 'applied index is now lower than readState.Index' (duration: 35.243958ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:45.154273Z","caller":"traceutil/trace.go:171","msg":"trace[829245642] transaction","detail":"{read_only:false; response_revision:4588; number_of_response:1; }","duration":"118.69822ms","start":"2026-05-08T10:26:45.035562Z","end":"2026-05-08T10:26:45.154261Z","steps":["trace[829245642] 'process raft request' (duration: 118.422286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:45.154648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.064792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.133\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-08T10:26:45.154763Z","caller":"traceutil/trace.go:171","msg":"trace[194579868] range","detail":"{range_begin:/registry/masterleases/199.19.213.133; range_end:; response_count:1; response_revision:4588; }","duration":"109.193815ms","start":"2026-05-08T10:26:45.04552Z","end":"2026-05-08T10:26:45.154714Z","steps":["trace[194579868] 'agreement among raft nodes before linearized reading' (duration: 108.92834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:45.429803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.053997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:26:45.429931Z","caller":"traceutil/trace.go:171","msg":"trace[162077410] linearizableReadLoop","detail":"{readStateIndex:4792; appliedIndex:4791; }","duration":"157.601043ms","start":"2026-05-08T10:26:45.272312Z","end":"2026-05-08T10:26:45.429913Z","steps":["trace[162077410] 'read index received' (duration: 45.481µs)","trace[162077410] 'applied index is now lower than readState.Index' (duration: 157.554482ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:45.430273Z","caller":"traceutil/trace.go:171","msg":"trace[158385024] transaction","detail":"{read_only:false; response_revision:4589; number_of_response:1; }","duration":"253.951284ms","start":"2026-05-08T10:26:45.176232Z","end":"2026-05-08T10:26:45.430183Z","steps":["trace[158385024] 'process raft request' (duration: 89.423388ms)","trace[158385024] 'compare' (duration: 163.886514ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:26:45.430381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.048892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T10:26:45.430464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.516072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:26:45.430507Z","caller":"traceutil/trace.go:171","msg":"trace[741586794] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:4589; }","duration":"158.229675ms","start":"2026-05-08T10:26:45.272262Z","end":"2026-05-08T10:26:45.430491Z","steps":["trace[741586794] 'agreement among raft nodes before linearized reading' (duration: 157.95531ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:45.430547Z","caller":"traceutil/trace.go:171","msg":"trace[679629653] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:4589; }","duration":"137.646065ms","start":"2026-05-08T10:26:45.292882Z","end":"2026-05-08T10:26:45.430528Z","steps":["trace[679629653] 'agreement among raft nodes before linearized reading' (duration: 137.487602ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:46.396135Z","caller":"traceutil/trace.go:171","msg":"trace[279973043] transaction","detail":"{read_only:false; response_revision:4592; number_of_response:1; }","duration":"350.203578ms","start":"2026-05-08T10:26:46.045905Z","end":"2026-05-08T10:26:46.396108Z","steps":["trace[279973043] 'process raft request' (duration: 350.042536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:46.39637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:46.045809Z","time spent":"350.477024ms","remote":"127.0.0.1:50052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T10:26:46.403915Z","caller":"traceutil/trace.go:171","msg":"trace[1218363112] linearizableReadLoop","detail":"{readStateIndex:4796; appliedIndex:4794; }","duration":"318.209719ms","start":"2026-05-08T10:26:46.085687Z","end":"2026-05-08T10:26:46.403896Z","steps":["trace[1218363112] 'read index received' (duration: 310.245769ms)","trace[1218363112] 'applied index is now lower than readState.Index' (duration: 7.96236ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:46.403946Z","caller":"traceutil/trace.go:171","msg":"trace[1133526574] transaction","detail":"{read_only:false; response_revision:4593; number_of_response:1; }","duration":"350.164908ms","start":"2026-05-08T10:26:46.053751Z","end":"2026-05-08T10:26:46.403916Z","steps":["trace[1133526574] 'process raft request' (duration: 349.981065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:46.404119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.450465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:26:46.404208Z","caller":"traceutil/trace.go:171","msg":"trace[716358067] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:4593; }","duration":"305.584808ms","start":"2026-05-08T10:26:46.098605Z","end":"2026-05-08T10:26:46.404189Z","steps":["trace[716358067] 'agreement among raft nodes before linearized reading' (duration: 305.459196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:46.404446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:46.053713Z","time spent":"350.29111ms","remote":"127.0.0.1:50154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9815,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:26:46.40447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:46.09859Z","time spent":"305.862482ms","remote":"127.0.0.1:46328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-05-08T10:26:46.404869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.149595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-05-08T10:26:46.404964Z","caller":"traceutil/trace.go:171","msg":"trace[1741867328] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4593; }","duration":"319.276097ms","start":"2026-05-08T10:26:46.085667Z","end":"2026-05-08T10:26:46.404943Z","steps":["trace[1741867328] 'agreement among raft nodes before linearized reading' (duration: 318.352761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:46.405021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:46.085648Z","time spent":"319.360389ms","remote":"127.0.0.1:50090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-05-08T10:26:48.329939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.298693ms","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:48.330023Z","caller":"traceutil/trace.go:171","msg":"trace[337165720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4659; }","duration":"301.435175ms","start":"2026-05-08T10:26:48.028569Z","end":"2026-05-08T10:26:48.330004Z","steps":["trace[337165720] 'range keys from in-memory index tree' (duration: 301.211531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:48.330065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:48.028547Z","time spent":"301.508326ms","remote":"127.0.0.1:49956","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:48.330272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.535251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-05-08T10:26:48.330352Z","caller":"traceutil/trace.go:171","msg":"trace[2139213008] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4659; }","duration":"429.676454ms","start":"2026-05-08T10:26:47.900656Z","end":"2026-05-08T10:26:48.330332Z","steps":["trace[2139213008] 'range keys from in-memory index tree' (duration: 429.331698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:48.33045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:47.900631Z","time spent":"429.807326ms","remote":"127.0.0.1:50154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11523,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-08T10:26:48.330566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.851509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-72818d24-8dbd-48be-a8ee-09722f59b4b6\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-08T10:26:48.330626Z","caller":"traceutil/trace.go:171","msg":"trace[1502426125] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-72818d24-8dbd-48be-a8ee-09722f59b4b6; range_end:; response_count:1; response_revision:4659; }","duration":"263.937781ms","start":"2026-05-08T10:26:48.066675Z","end":"2026-05-08T10:26:48.330613Z","steps":["trace[1502426125] 'range keys from in-memory index tree' (duration: 263.573184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:48.330897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.683188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-72818d24-8dbd-48be-a8ee-09722f59b4b6\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-08T10:26:48.330951Z","caller":"traceutil/trace.go:171","msg":"trace[2133064301] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-72818d24-8dbd-48be-a8ee-09722f59b4b6; range_end:; response_count:1; response_revision:4659; }","duration":"589.79269ms","start":"2026-05-08T10:26:47.741143Z","end":"2026-05-08T10:26:48.330935Z","steps":["trace[2133064301] 'range keys from in-memory index tree' (duration: 589.515235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:48.330981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:47.741115Z","time spent":"589.859391ms","remote":"127.0.0.1:50154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-72818d24-8dbd-48be-a8ee-09722f59b4b6\" "} {"level":"warn","ts":"2026-05-08T10:26:48.331109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.591379ms","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:48.33122Z","caller":"traceutil/trace.go:171","msg":"trace[896967383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4659; }","duration":"580.776732ms","start":"2026-05-08T10:26:47.750416Z","end":"2026-05-08T10:26:48.331192Z","steps":["trace[896967383] 'range keys from in-memory index tree' (duration: 580.291104ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:48.331289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:47.750388Z","time spent":"580.889415ms","remote":"127.0.0.1:50242","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":"info","ts":"2026-05-08T10:26:50.935192Z","caller":"traceutil/trace.go:171","msg":"trace[893515915] transaction","detail":"{read_only:false; response_revision:4682; number_of_response:1; }","duration":"359.42228ms","start":"2026-05-08T10:26:50.575741Z","end":"2026-05-08T10:26:50.935163Z","steps":["trace[893515915] 'process raft request' (duration: 359.244837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:50.935384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:50.575727Z","time spent":"359.542982ms","remote":"127.0.0.1:50154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4926,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:26:51.005862Z","caller":"traceutil/trace.go:171","msg":"trace[729313283] linearizableReadLoop","detail":"{readStateIndex:4888; appliedIndex:4886; }","duration":"288.154703ms","start":"2026-05-08T10:26:50.717668Z","end":"2026-05-08T10:26:51.005823Z","steps":["trace[729313283] 'read index received' (duration: 217.290903ms)","trace[729313283] 'applied index is now lower than readState.Index' (duration: 70.86221ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:51.005975Z","caller":"traceutil/trace.go:171","msg":"trace[1646921162] transaction","detail":"{read_only:false; response_revision:4683; number_of_response:1; }","duration":"308.918994ms","start":"2026-05-08T10:26:50.697023Z","end":"2026-05-08T10:26:51.005942Z","steps":["trace[1646921162] 'process raft request' (duration: 308.570898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:51.006168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:50.696995Z","time spent":"309.058416ms","remote":"127.0.0.1:50242","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:26:51.006229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.529389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-05-08T10:26:51.00632Z","caller":"traceutil/trace.go:171","msg":"trace[1884167519] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4683; }","duration":"288.672502ms","start":"2026-05-08T10:26:50.717627Z","end":"2026-05-08T10:26:51.0063Z","steps":["trace[1884167519] 'agreement among raft nodes before linearized reading' (duration: 288.356797ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:51.096895Z","caller":"traceutil/trace.go:171","msg":"trace[1362025106] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"155.462676ms","start":"2026-05-08T10:26:50.941415Z","end":"2026-05-08T10:26:51.096878Z","steps":["trace[1362025106] 'process raft request' (duration: 151.321074ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:51.097104Z","caller":"traceutil/trace.go:171","msg":"trace[775706499] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"155.147701ms","start":"2026-05-08T10:26:50.941939Z","end":"2026-05-08T10:26:51.097086Z","steps":["trace[775706499] 'process raft request' (duration: 154.804275ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:51.09733Z","caller":"traceutil/trace.go:171","msg":"trace[1583669567] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"155.251983ms","start":"2026-05-08T10:26:50.942068Z","end":"2026-05-08T10:26:51.09732Z","steps":["trace[1583669567] 'process raft request' (duration: 154.952548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:51.097452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.168735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-08T10:26:51.097492Z","caller":"traceutil/trace.go:171","msg":"trace[596837754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4686; }","duration":"237.237685ms","start":"2026-05-08T10:26:50.860245Z","end":"2026-05-08T10:26:51.097482Z","steps":["trace[596837754] 'agreement among raft nodes before linearized reading' (duration: 237.122193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:51.097526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.394685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-08T10:26:51.097538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.878812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-05-08T10:26:51.097554Z","caller":"traceutil/trace.go:171","msg":"trace[96282882] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4686; }","duration":"375.480886ms","start":"2026-05-08T10:26:50.722066Z","end":"2026-05-08T10:26:51.097547Z","steps":["trace[96282882] 'agreement among raft nodes before linearized reading' (duration: 375.371775ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:51.09757Z","caller":"traceutil/trace.go:171","msg":"trace[486807233] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4686; }","duration":"197.927173ms","start":"2026-05-08T10:26:50.899632Z","end":"2026-05-08T10:26:51.097559Z","steps":["trace[486807233] 'agreement among raft nodes before linearized reading' (duration: 197.849071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:51.09763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.163707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-08T10:26:51.097576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:50.722018Z","time spent":"375.553497ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-08T10:26:51.097655Z","caller":"traceutil/trace.go:171","msg":"trace[2043815377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4686; }","duration":"236.217217ms","start":"2026-05-08T10:26:50.861432Z","end":"2026-05-08T10:26:51.09765Z","steps":["trace[2043815377] 'agreement among raft nodes before linearized reading' (duration: 236.152446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:51.097452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.738061ms","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:26:51.097715Z","caller":"traceutil/trace.go:171","msg":"trace[1628961404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4686; }","duration":"338.033305ms","start":"2026-05-08T10:26:50.759677Z","end":"2026-05-08T10:26:51.09771Z","steps":["trace[1628961404] 'agreement among raft nodes before linearized reading' (duration: 337.69977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:51.097735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:50.75966Z","time spent":"338.069626ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T10:26:51.120084Z","caller":"traceutil/trace.go:171","msg":"trace[954869792] transaction","detail":"{read_only:false; response_revision:4687; number_of_response:1; }","duration":"108.051722ms","start":"2026-05-08T10:26:51.012013Z","end":"2026-05-08T10:26:51.120065Z","steps":["trace[954869792] 'process raft request' (duration: 107.933521ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:52.856386Z","caller":"traceutil/trace.go:171","msg":"trace[1438526305] transaction","detail":"{read_only:false; response_revision:4719; number_of_response:1; }","duration":"198.486752ms","start":"2026-05-08T10:26:52.65787Z","end":"2026-05-08T10:26:52.856356Z","steps":["trace[1438526305] 'process raft request' (duration: 198.291659ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:52.914835Z","caller":"traceutil/trace.go:171","msg":"trace[1793269857] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"206.708865ms","start":"2026-05-08T10:26:52.708091Z","end":"2026-05-08T10:26:52.9148Z","steps":["trace[1793269857] 'process raft request' (duration: 204.69365ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:59.021933Z","caller":"traceutil/trace.go:171","msg":"trace[688701121] linearizableReadLoop","detail":"{readStateIndex:4997; appliedIndex:4996; }","duration":"282.893216ms","start":"2026-05-08T10:26:58.739014Z","end":"2026-05-08T10:26:59.021907Z","steps":["trace[688701121] 'read index received' (duration: 282.630342ms)","trace[688701121] 'applied index is now lower than readState.Index' (duration: 261.794µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:59.021972Z","caller":"traceutil/trace.go:171","msg":"trace[1729551724] transaction","detail":"{read_only:false; response_revision:4789; number_of_response:1; }","duration":"284.710547ms","start":"2026-05-08T10:26:58.737236Z","end":"2026-05-08T10:26:59.021946Z","steps":["trace[1729551724] 'process raft request' (duration: 284.526194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:59.022438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.515952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/monitoring/\" range_end:\"/registry/resourcequotas/monitoring0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:26:59.022659Z","caller":"traceutil/trace.go:171","msg":"trace[434591684] range","detail":"{range_begin:/registry/resourcequotas/monitoring/; range_end:/registry/resourcequotas/monitoring0; response_count:0; response_revision:4789; }","duration":"281.804788ms","start":"2026-05-08T10:26:58.740791Z","end":"2026-05-08T10:26:59.022596Z","steps":["trace[434591684] 'agreement among raft nodes before linearized reading' (duration: 281.408261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:59.022695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.64814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-05-08T10:26:59.023088Z","caller":"traceutil/trace.go:171","msg":"trace[157897835] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4789; }","duration":"284.055876ms","start":"2026-05-08T10:26:58.738976Z","end":"2026-05-08T10:26:59.023031Z","steps":["trace[157897835] 'agreement among raft nodes before linearized reading' (duration: 283.079819ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:59.152737Z","caller":"traceutil/trace.go:171","msg":"trace[1075763448] transaction","detail":"{read_only:false; response_revision:4791; number_of_response:1; }","duration":"115.370639ms","start":"2026-05-08T10:26:59.037342Z","end":"2026-05-08T10:26:59.152712Z","steps":["trace[1075763448] 'process raft request' (duration: 115.290517ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:59.152742Z","caller":"traceutil/trace.go:171","msg":"trace[19799103] transaction","detail":"{read_only:false; response_revision:4790; number_of_response:1; }","duration":"127.629518ms","start":"2026-05-08T10:26:59.025077Z","end":"2026-05-08T10:26:59.152707Z","steps":["trace[19799103] 'process raft request' (duration: 88.4894ms)","trace[19799103] 'compare' (duration: 38.911065ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:59.152769Z","caller":"traceutil/trace.go:171","msg":"trace[900189234] linearizableReadLoop","detail":"{readStateIndex:4998; appliedIndex:4997; }","duration":"124.202759ms","start":"2026-05-08T10:26:59.02854Z","end":"2026-05-08T10:26:59.152743Z","steps":["trace[900189234] 'read index received' (duration: 85.04095ms)","trace[900189234] 'applied index is now lower than readState.Index' (duration: 39.159229ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:26:59.15309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.489845ms","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:59.153215Z","caller":"traceutil/trace.go:171","msg":"trace[863944740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4791; }","duration":"124.684688ms","start":"2026-05-08T10:26:59.028505Z","end":"2026-05-08T10:26:59.15319Z","steps":["trace[863944740] 'agreement among raft nodes before linearized reading' (duration: 124.417674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:59.153442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.850721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11500"} {"level":"info","ts":"2026-05-08T10:26:59.153613Z","caller":"traceutil/trace.go:171","msg":"trace[1848853352] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4791; }","duration":"124.999034ms","start":"2026-05-08T10:26:59.028516Z","end":"2026-05-08T10:26:59.153515Z","steps":["trace[1848853352] 'agreement among raft nodes before linearized reading' (duration: 124.612367ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:59.500728Z","caller":"traceutil/trace.go:171","msg":"trace[1405907540] transaction","detail":"{read_only:false; response_revision:4795; number_of_response:1; }","duration":"278.579314ms","start":"2026-05-08T10:26:59.222096Z","end":"2026-05-08T10:26:59.500675Z","steps":["trace[1405907540] 'process raft request' (duration: 200.583283ms)","trace[1405907540] 'compare' (duration: 77.873829ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:59.544216Z","caller":"traceutil/trace.go:171","msg":"trace[478231327] transaction","detail":"{read_only:false; response_revision:4796; number_of_response:1; }","duration":"321.19672ms","start":"2026-05-08T10:26:59.22298Z","end":"2026-05-08T10:26:59.544177Z","steps":["trace[478231327] 'process raft request' (duration: 320.956406ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:59.544268Z","caller":"traceutil/trace.go:171","msg":"trace[1561744260] transaction","detail":"{read_only:false; response_revision:4799; number_of_response:1; }","duration":"317.773592ms","start":"2026-05-08T10:26:59.226474Z","end":"2026-05-08T10:26:59.544247Z","steps":["trace[1561744260] 'process raft request' (duration: 317.679571ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:59.544263Z","caller":"traceutil/trace.go:171","msg":"trace[1649916135] transaction","detail":"{read_only:false; response_revision:4797; number_of_response:1; }","duration":"321.13818ms","start":"2026-05-08T10:26:59.223071Z","end":"2026-05-08T10:26:59.544209Z","steps":["trace[1649916135] 'process raft request' (duration: 320.979407ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:59.544293Z","caller":"traceutil/trace.go:171","msg":"trace[20093643] transaction","detail":"{read_only:false; response_revision:4798; number_of_response:1; }","duration":"320.56333ms","start":"2026-05-08T10:26:59.22368Z","end":"2026-05-08T10:26:59.544244Z","steps":["trace[20093643] 'process raft request' (duration: 320.424958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:59.544423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:59.222959Z","time spent":"321.358804ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:26:59.544445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:59.226462Z","time spent":"317.862185ms","remote":"127.0.0.1:50242","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:59.544458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:59.223659Z","time spent":"320.711793ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":551,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:26:59.544459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:59.223066Z","time spent":"321.281922ms","remote":"127.0.0.1:50242","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:26:59.544556Z","caller":"traceutil/trace.go:171","msg":"trace[256070316] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4799; }","duration":"317.525529ms","start":"2026-05-08T10:26:59.227008Z","end":"2026-05-08T10:26:59.544534Z","steps":["trace[256070316] 'process raft request' (duration: 317.190234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:59.54467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:59.226995Z","time spent":"317.635111ms","remote":"127.0.0.1:50468","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T10:26:59.545564Z","caller":"traceutil/trace.go:171","msg":"trace[2067941201] linearizableReadLoop","detail":"{readStateIndex:5010; appliedIndex:5003; }","duration":"291.062007ms","start":"2026-05-08T10:26:59.254486Z","end":"2026-05-08T10:26:59.545548Z","steps":["trace[2067941201] 'read index received' (duration: 168.21101ms)","trace[2067941201] 'applied index is now lower than readState.Index' (duration: 122.849677ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:59.545793Z","caller":"traceutil/trace.go:171","msg":"trace[302756032] transaction","detail":"{read_only:false; response_revision:4800; number_of_response:1; }","duration":"309.977929ms","start":"2026-05-08T10:26:59.235795Z","end":"2026-05-08T10:26:59.545773Z","steps":["trace[302756032] 'process raft request' (duration: 309.649044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:26:59.546335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:26:59.235774Z","time spent":"310.51979ms","remote":"127.0.0.1:59446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2250,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:26:59.545964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.466154ms","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:26:59.54622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.675894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-05-08T10:26:59.546691Z","caller":"traceutil/trace.go:171","msg":"trace[1568527634] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4800; }","duration":"174.200443ms","start":"2026-05-08T10:26:59.372471Z","end":"2026-05-08T10:26:59.546671Z","steps":["trace[1568527634] 'agreement among raft nodes before linearized reading' (duration: 173.563332ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:59.546817Z","caller":"traceutil/trace.go:171","msg":"trace[685434832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4800; }","duration":"292.122375ms","start":"2026-05-08T10:26:59.254441Z","end":"2026-05-08T10:26:59.546563Z","steps":["trace[685434832] 'agreement among raft nodes before linearized reading' (duration: 291.363122ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:59.715955Z","caller":"traceutil/trace.go:171","msg":"trace[1400530448] transaction","detail":"{read_only:false; response_revision:4804; number_of_response:1; }","duration":"159.308149ms","start":"2026-05-08T10:26:59.556619Z","end":"2026-05-08T10:26:59.715927Z","steps":["trace[1400530448] 'process raft request' (duration: 159.220027ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:26:59.716224Z","caller":"traceutil/trace.go:171","msg":"trace[149060090] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"163.134374ms","start":"2026-05-08T10:26:59.553075Z","end":"2026-05-08T10:26:59.716209Z","steps":["trace[149060090] 'process raft request' (duration: 126.914826ms)","trace[149060090] 'compare' (duration: 35.18486ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:26:59.71654Z","caller":"traceutil/trace.go:171","msg":"trace[745743248] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"161.234171ms","start":"2026-05-08T10:26:59.555293Z","end":"2026-05-08T10:26:59.716527Z","steps":["trace[745743248] 'process raft request' (duration: 160.462319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:06.090479Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15961493782686485472,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T10:27:06.323317Z","caller":"traceutil/trace.go:171","msg":"trace[2060355511] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"740.655862ms","start":"2026-05-08T10:27:05.582642Z","end":"2026-05-08T10:27:06.323298Z","steps":["trace[2060355511] 'process raft request' (duration: 736.426331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:06.324008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:05.582626Z","time spent":"740.920557ms","remote":"127.0.0.1:50242","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:27:06.324261Z","caller":"traceutil/trace.go:171","msg":"trace[1411975562] linearizableReadLoop","detail":"{readStateIndex:5095; appliedIndex:5093; }","duration":"734.166733ms","start":"2026-05-08T10:27:05.590084Z","end":"2026-05-08T10:27:06.324251Z","steps":["trace[1411975562] 'read index received' (duration: 728.911025ms)","trace[1411975562] 'applied index is now lower than readState.Index' (duration: 5.255078ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:27:06.324387Z","caller":"traceutil/trace.go:171","msg":"trace[1318249133] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"738.363974ms","start":"2026-05-08T10:27:05.586Z","end":"2026-05-08T10:27:06.324364Z","steps":["trace[1318249133] 'process raft request' (duration: 737.236835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:06.324482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:05.585966Z","time spent":"738.466496ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:27:06.324626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.994453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:27:06.324666Z","caller":"traceutil/trace.go:171","msg":"trace[201017935] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:4881; }","duration":"145.098725ms","start":"2026-05-08T10:27:06.179556Z","end":"2026-05-08T10:27:06.324655Z","steps":["trace[201017935] 'agreement among raft nodes before linearized reading' (duration: 145.027524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:06.324911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.843919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-08T10:27:06.325063Z","caller":"traceutil/trace.go:171","msg":"trace[1719872603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4881; }","duration":"727.039502ms","start":"2026-05-08T10:27:05.597977Z","end":"2026-05-08T10:27:06.325016Z","steps":["trace[1719872603] 'agreement among raft nodes before linearized reading' (duration: 726.566864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:06.325156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:05.597969Z","time spent":"727.175834ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T10:27:06.325175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.664535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:27:06.325257Z","caller":"traceutil/trace.go:171","msg":"trace[1713369192] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:4881; }","duration":"489.764738ms","start":"2026-05-08T10:27:05.835478Z","end":"2026-05-08T10:27:06.325243Z","steps":["trace[1713369192] 'agreement among raft nodes before linearized reading' (duration: 489.655396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:06.325315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:05.835472Z","time spent":"489.833199ms","remote":"127.0.0.1:54610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"warn","ts":"2026-05-08T10:27:06.325355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.688212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-05-08T10:27:06.325403Z","caller":"traceutil/trace.go:171","msg":"trace[1998373040] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4881; }","duration":"535.783554ms","start":"2026-05-08T10:27:05.789603Z","end":"2026-05-08T10:27:06.325387Z","steps":["trace[1998373040] 'agreement among raft nodes before linearized reading' (duration: 535.688363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:06.325438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:05.789578Z","time spent":"535.852535ms","remote":"127.0.0.1:50090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":668,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-08T10:27:06.325256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.728437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:27:06.325544Z","caller":"traceutil/trace.go:171","msg":"trace[1441966524] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:4881; }","duration":"490.189294ms","start":"2026-05-08T10:27:05.835344Z","end":"2026-05-08T10:27:06.325534Z","steps":["trace[1441966524] 'agreement among raft nodes before linearized reading' (duration: 489.873379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:06.325593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:05.835326Z","time spent":"490.257025ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-05-08T10:27:06.325878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.804971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-05-08T10:27:06.325946Z","caller":"traceutil/trace.go:171","msg":"trace[1961314749] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4881; }","duration":"735.906643ms","start":"2026-05-08T10:27:05.590022Z","end":"2026-05-08T10:27:06.325929Z","steps":["trace[1961314749] 'agreement among raft nodes before linearized reading' (duration: 734.336696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:06.326005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:05.59Z","time spent":"735.997234ms","remote":"127.0.0.1:50090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":683,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-05-08T10:27:07.262968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:06.875221Z","time spent":"387.737815ms","remote":"127.0.0.1:47068","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-08T10:27:07.263224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.477426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:27:07.263391Z","caller":"traceutil/trace.go:171","msg":"trace[647515472] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"933.131624ms","start":"2026-05-08T10:27:06.330233Z","end":"2026-05-08T10:27:07.263365Z","steps":["trace[647515472] 'process raft request' (duration: 137.428446ms)","trace[647515472] 'compare' (duration: 794.926576ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:27:07.263496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:06.330216Z","time spent":"933.243526ms","remote":"127.0.0.1:50242","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:27:07.289606Z","caller":"traceutil/trace.go:171","msg":"trace[87163968] transaction","detail":"{read_only:false; response_revision:4883; number_of_response:1; }","duration":"954.932952ms","start":"2026-05-08T10:27:06.334646Z","end":"2026-05-08T10:27:07.289579Z","steps":["trace[87163968] 'process raft request' (duration: 954.708988ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:07.289787Z","caller":"traceutil/trace.go:171","msg":"trace[1804175770] linearizableReadLoop","detail":"{readStateIndex:5097; appliedIndex:5095; }","duration":"954.001906ms","start":"2026-05-08T10:27:06.335751Z","end":"2026-05-08T10:27:07.289753Z","steps":["trace[1804175770] 'read index received' (duration: 132.086025ms)","trace[1804175770] 'applied index is now lower than readState.Index' (duration: 821.913711ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:27:07.29003Z","caller":"traceutil/trace.go:171","msg":"trace[2018053755] transaction","detail":"{read_only:false; response_revision:4884; number_of_response:1; }","duration":"953.194113ms","start":"2026-05-08T10:27:06.336804Z","end":"2026-05-08T10:27:07.289998Z","steps":["trace[2018053755] 'process raft request' (duration: 952.704585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:07.290162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:06.336797Z","time spent":"953.301114ms","remote":"127.0.0.1:50090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:27:07.290188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:06.33463Z","time spent":"955.056514ms","remote":"127.0.0.1:50154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10791,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:27:07.290555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.776039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-05-08T10:27:07.29061Z","caller":"traceutil/trace.go:171","msg":"trace[1036627379] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4884; }","duration":"954.86735ms","start":"2026-05-08T10:27:06.335727Z","end":"2026-05-08T10:27:07.290595Z","steps":["trace[1036627379] 'agreement among raft nodes before linearized reading' (duration: 954.631845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:07.292766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:06.33572Z","time spent":"957.015266ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-08T10:27:07.302047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.591823ms","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:27:07.302047Z","caller":"traceutil/trace.go:171","msg":"trace[710972917] transaction","detail":"{read_only:false; response_revision:4885; number_of_response:1; }","duration":"762.950186ms","start":"2026-05-08T10:27:06.539072Z","end":"2026-05-08T10:27:07.302022Z","steps":["trace[710972917] 'process raft request' (duration: 762.635061ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:07.302079Z","caller":"traceutil/trace.go:171","msg":"trace[1711725231] transaction","detail":"{read_only:false; response_revision:4886; number_of_response:1; }","duration":"118.772298ms","start":"2026-05-08T10:27:07.183287Z","end":"2026-05-08T10:27:07.30206Z","steps":["trace[1711725231] 'process raft request' (duration: 118.579775ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:07.302107Z","caller":"traceutil/trace.go:171","msg":"trace[1718502640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4886; }","duration":"834.662174ms","start":"2026-05-08T10:27:06.467432Z","end":"2026-05-08T10:27:07.302094Z","steps":["trace[1718502640] 'agreement among raft nodes before linearized reading' (duration: 834.499992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:07.302133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:06.467398Z","time spent":"834.730796ms","remote":"127.0.0.1:50242","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:27:07.302199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:06.539051Z","time spent":"763.060838ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T10:27:07.302036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.652819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T10:27:07.302179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.216232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3198"} {"level":"info","ts":"2026-05-08T10:27:07.302284Z","caller":"traceutil/trace.go:171","msg":"trace[1974959224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4886; }","duration":"271.970424ms","start":"2026-05-08T10:27:07.030298Z","end":"2026-05-08T10:27:07.302269Z","steps":["trace[1974959224] 'agreement among raft nodes before linearized reading' (duration: 271.668129ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:07.302321Z","caller":"traceutil/trace.go:171","msg":"trace[419900650] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4886; }","duration":"845.390606ms","start":"2026-05-08T10:27:06.456913Z","end":"2026-05-08T10:27:07.302304Z","steps":["trace[419900650] 'agreement among raft nodes before linearized reading' (duration: 845.150552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:07.302362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:06.456745Z","time spent":"845.605989ms","remote":"127.0.0.1:46328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3221,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-05-08T10:27:07.445163Z","caller":"traceutil/trace.go:171","msg":"trace[392224628] transaction","detail":"{read_only:false; response_revision:4887; number_of_response:1; }","duration":"147.957839ms","start":"2026-05-08T10:27:07.297176Z","end":"2026-05-08T10:27:07.445134Z","steps":["trace[392224628] 'process raft request' (duration: 89.322872ms)","trace[392224628] 'compare' (duration: 58.394593ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:27:07.446233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.951952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10806"} {"level":"info","ts":"2026-05-08T10:27:07.446271Z","caller":"traceutil/trace.go:171","msg":"trace[2142256233] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4892; }","duration":"147.050513ms","start":"2026-05-08T10:27:07.299215Z","end":"2026-05-08T10:27:07.446265Z","steps":["trace[2142256233] 'agreement among raft nodes before linearized reading' (duration: 146.84181ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:07.445594Z","caller":"traceutil/trace.go:171","msg":"trace[1371520881] transaction","detail":"{read_only:false; response_revision:4888; number_of_response:1; }","duration":"148.248114ms","start":"2026-05-08T10:27:07.297316Z","end":"2026-05-08T10:27:07.445564Z","steps":["trace[1371520881] 'process raft request' (duration: 147.733566ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:07.445777Z","caller":"traceutil/trace.go:171","msg":"trace[1787507388] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"147.120125ms","start":"2026-05-08T10:27:07.298626Z","end":"2026-05-08T10:27:07.445746Z","steps":["trace[1787507388] 'process raft request' (duration: 146.859381ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:07.445854Z","caller":"traceutil/trace.go:171","msg":"trace[473864830] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"145.608878ms","start":"2026-05-08T10:27:07.300205Z","end":"2026-05-08T10:27:07.445814Z","steps":["trace[473864830] 'process raft request' (duration: 145.454555ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:07.445912Z","caller":"traceutil/trace.go:171","msg":"trace[388892960] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"144.292988ms","start":"2026-05-08T10:27:07.301597Z","end":"2026-05-08T10:27:07.44589Z","steps":["trace[388892960] 'process raft request' (duration: 144.144505ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:07.446012Z","caller":"traceutil/trace.go:171","msg":"trace[1922263207] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"140.346431ms","start":"2026-05-08T10:27:07.305656Z","end":"2026-05-08T10:27:07.446002Z","steps":["trace[1922263207] 'process raft request' (duration: 140.240949ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:07.446044Z","caller":"traceutil/trace.go:171","msg":"trace[672472562] linearizableReadLoop","detail":"{readStateIndex:5105; appliedIndex:5100; }","duration":"144.136095ms","start":"2026-05-08T10:27:07.301899Z","end":"2026-05-08T10:27:07.446036Z","steps":["trace[672472562] 'read index received' (duration: 84.595942ms)","trace[672472562] 'applied index is now lower than readState.Index' (duration: 59.539393ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:27:07.448191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.359138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T10:27:07.448259Z","caller":"traceutil/trace.go:171","msg":"trace[801382226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4892; }","duration":"144.4489ms","start":"2026-05-08T10:27:07.303789Z","end":"2026-05-08T10:27:07.448238Z","steps":["trace[801382226] 'agreement among raft nodes before linearized reading' (duration: 144.323418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:07.448399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.121407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"warn","ts":"2026-05-08T10:27:07.448529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.017416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-72818d24-8dbd-48be-a8ee-09722f59b4b6\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-08T10:27:07.448566Z","caller":"traceutil/trace.go:171","msg":"trace[2021643273] range","detail":"{range_begin:/registry/persistentvolumes/pvc-72818d24-8dbd-48be-a8ee-09722f59b4b6; range_end:; response_count:1; response_revision:4892; }","duration":"143.062636ms","start":"2026-05-08T10:27:07.305491Z","end":"2026-05-08T10:27:07.448554Z","steps":["trace[2021643273] 'agreement among raft nodes before linearized reading' (duration: 142.918464ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:07.448685Z","caller":"traceutil/trace.go:171","msg":"trace[201139179] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4892; }","duration":"148.172172ms","start":"2026-05-08T10:27:07.300363Z","end":"2026-05-08T10:27:07.448535Z","steps":["trace[201139179] 'agreement among raft nodes before linearized reading' (duration: 145.991926ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:08.00662Z","caller":"traceutil/trace.go:171","msg":"trace[848000971] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"370.919109ms","start":"2026-05-08T10:27:07.635668Z","end":"2026-05-08T10:27:08.006587Z","steps":["trace[848000971] 'process raft request' (duration: 297.337781ms)","trace[848000971] 'compare' (duration: 73.424815ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:27:08.006832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:07.635637Z","time spent":"371.068231ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:27:08.546912Z","caller":"traceutil/trace.go:171","msg":"trace[1179996642] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"211.385749ms","start":"2026-05-08T10:27:08.335488Z","end":"2026-05-08T10:27:08.546874Z","steps":["trace[1179996642] 'process raft request' (duration: 211.129625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:08.773285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.459969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T10:27:08.773443Z","caller":"traceutil/trace.go:171","msg":"trace[1213690716] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"435.198837ms","start":"2026-05-08T10:27:08.338209Z","end":"2026-05-08T10:27:08.773408Z","steps":["trace[1213690716] 'process raft request' (duration: 288.492844ms)","trace[1213690716] 'compare' (duration: 146.199324ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:27:08.773517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:08.338191Z","time spent":"435.292008ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:27:21.885815Z","caller":"traceutil/trace.go:171","msg":"trace[2096573320] linearizableReadLoop","detail":"{readStateIndex:5241; appliedIndex:5240; }","duration":"122.226597ms","start":"2026-05-08T10:27:21.763557Z","end":"2026-05-08T10:27:21.885784Z","steps":["trace[2096573320] 'read index received' (duration: 88.765887ms)","trace[2096573320] 'applied index is now lower than readState.Index' (duration: 33.45943ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:27:21.886303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.721185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-05-08T10:27:21.886324Z","caller":"traceutil/trace.go:171","msg":"trace[1152425911] transaction","detail":"{read_only:false; response_revision:5021; number_of_response:1; }","duration":"141.457533ms","start":"2026-05-08T10:27:21.744806Z","end":"2026-05-08T10:27:21.886264Z","steps":["trace[1152425911] 'process raft request' (duration: 107.557407ms)","trace[1152425911] 'compare' (duration: 33.229106ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:27:21.886425Z","caller":"traceutil/trace.go:171","msg":"trace[983738928] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5021; }","duration":"122.849858ms","start":"2026-05-08T10:27:21.763504Z","end":"2026-05-08T10:27:21.886354Z","steps":["trace[983738928] 'agreement among raft nodes before linearized reading' (duration: 122.425921ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:47.658611Z","caller":"traceutil/trace.go:171","msg":"trace[2036670636] linearizableReadLoop","detail":"{readStateIndex:5534; appliedIndex:5533; }","duration":"110.555836ms","start":"2026-05-08T10:27:47.54803Z","end":"2026-05-08T10:27:47.658585Z","steps":["trace[2036670636] 'read index received' (duration: 110.410044ms)","trace[2036670636] 'applied index is now lower than readState.Index' (duration: 144.512µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:27:47.658702Z","caller":"traceutil/trace.go:171","msg":"trace[1199151718] transaction","detail":"{read_only:false; response_revision:5302; number_of_response:1; }","duration":"110.995753ms","start":"2026-05-08T10:27:47.547658Z","end":"2026-05-08T10:27:47.658654Z","steps":["trace[1199151718] 'process raft request' (duration: 110.750889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:47.659068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.005114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-08T10:27:47.659376Z","caller":"traceutil/trace.go:171","msg":"trace[2063223533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5302; }","duration":"111.367639ms","start":"2026-05-08T10:27:47.547978Z","end":"2026-05-08T10:27:47.659345Z","steps":["trace[2063223533] 'agreement among raft nodes before linearized reading' (duration: 110.729109ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:47.663463Z","caller":"traceutil/trace.go:171","msg":"trace[2000237610] transaction","detail":"{read_only:false; response_revision:5303; number_of_response:1; }","duration":"101.984911ms","start":"2026-05-08T10:27:47.561457Z","end":"2026-05-08T10:27:47.663442Z","steps":["trace[2000237610] 'process raft request' (duration: 101.853489ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:27:48.645764Z","caller":"traceutil/trace.go:171","msg":"trace[1430237557] linearizableReadLoop","detail":"{readStateIndex:5543; appliedIndex:5542; }","duration":"369.933316ms","start":"2026-05-08T10:27:48.275783Z","end":"2026-05-08T10:27:48.645717Z","steps":["trace[1430237557] 'read index received' (duration: 344.591636ms)","trace[1430237557] 'applied index is now lower than readState.Index' (duration: 25.34033ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:27:48.645921Z","caller":"traceutil/trace.go:171","msg":"trace[2059304202] transaction","detail":"{read_only:false; response_revision:5311; number_of_response:1; }","duration":"380.229789ms","start":"2026-05-08T10:27:48.265654Z","end":"2026-05-08T10:27:48.645884Z","steps":["trace[2059304202] 'process raft request' (duration: 354.797818ms)","trace[2059304202] 'compare' (duration: 25.128196ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:27:48.645995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.19706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"warn","ts":"2026-05-08T10:27:48.646094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:48.265642Z","time spent":"380.36111ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":536,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T10:27:48.646107Z","caller":"traceutil/trace.go:171","msg":"trace[372574898] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5311; }","duration":"370.374974ms","start":"2026-05-08T10:27:48.275716Z","end":"2026-05-08T10:27:48.646091Z","steps":["trace[372574898] 'agreement among raft nodes before linearized reading' (duration: 370.140159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:48.646192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T10:27:48.275703Z","time spent":"370.476215ms","remote":"127.0.0.1:50242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":547,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-05-08T10:27:55.808632Z","caller":"traceutil/trace.go:171","msg":"trace[269970985] linearizableReadLoop","detail":"{readStateIndex:5610; appliedIndex:5609; }","duration":"105.901974ms","start":"2026-05-08T10:27:55.702704Z","end":"2026-05-08T10:27:55.808606Z","steps":["trace[269970985] 'read index received' (duration: 105.60836ms)","trace[269970985] 'applied index is now lower than readState.Index' (duration: 290.424µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T10:27:55.808742Z","caller":"traceutil/trace.go:171","msg":"trace[1979892585] transaction","detail":"{read_only:false; response_revision:5375; number_of_response:1; }","duration":"117.78238ms","start":"2026-05-08T10:27:55.690937Z","end":"2026-05-08T10:27:55.808719Z","steps":["trace[1979892585] 'process raft request' (duration: 117.432475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T10:27:55.808865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.143268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-08T10:27:55.808909Z","caller":"traceutil/trace.go:171","msg":"trace[1606021807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5375; }","duration":"106.246811ms","start":"2026-05-08T10:27:55.702653Z","end":"2026-05-08T10:27:55.8089Z","steps":["trace[1606021807] 'agreement among raft nodes before linearized reading' (duration: 106.051398ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:28:02.041551Z","caller":"traceutil/trace.go:171","msg":"trace[529590451] transaction","detail":"{read_only:false; response_revision:5424; number_of_response:1; }","duration":"144.52307ms","start":"2026-05-08T10:28:01.896999Z","end":"2026-05-08T10:28:02.041523Z","steps":["trace[529590451] 'process raft request' (duration: 144.060473ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:28:02.80461Z","caller":"traceutil/trace.go:171","msg":"trace[824647597] transaction","detail":"{read_only:false; response_revision:5428; number_of_response:1; }","duration":"153.810962ms","start":"2026-05-08T10:28:02.65075Z","end":"2026-05-08T10:28:02.804561Z","steps":["trace[824647597] 'process raft request' (duration: 153.294853ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:29:41.492986Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3054} {"level":"info","ts":"2026-05-08T10:29:41.57824Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3054,"took":"83.998268ms","hash":550088453,"current-db-size-bytes":56926208,"current-db-size":"57 MB","current-db-size-in-use-bytes":30732288,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-08T10:29:41.578347Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":550088453,"revision":3054,"compact-revision":-1} {"level":"info","ts":"2026-05-08T10:31:08.757337Z","caller":"traceutil/trace.go:171","msg":"trace[1226059041] transaction","detail":"{read_only:false; response_revision:6991; number_of_response:1; }","duration":"143.306187ms","start":"2026-05-08T10:31:08.614007Z","end":"2026-05-08T10:31:08.757313Z","steps":["trace[1226059041] 'process raft request' (duration: 143.181025ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:31:08.872704Z","caller":"traceutil/trace.go:171","msg":"trace[1002441425] linearizableReadLoop","detail":"{readStateIndex:7312; appliedIndex:7311; }","duration":"133.587106ms","start":"2026-05-08T10:31:08.739088Z","end":"2026-05-08T10:31:08.872675Z","steps":["trace[1002441425] 'read index received' (duration: 18.576522ms)","trace[1002441425] 'applied index is now lower than readState.Index' (duration: 115.009274ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T10:31:08.872975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.852239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107299"} {"level":"info","ts":"2026-05-08T10:31:08.873031Z","caller":"traceutil/trace.go:171","msg":"trace[1330130465] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:6992; }","duration":"133.95934ms","start":"2026-05-08T10:31:08.73906Z","end":"2026-05-08T10:31:08.873019Z","steps":["trace[1330130465] 'agreement among raft nodes before linearized reading' (duration: 133.717357ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T10:31:08.872927Z","caller":"traceutil/trace.go:171","msg":"trace[1431197889] transaction","detail":"{read_only:false; response_revision:6992; number_of_response:1; }","duration":"222.422262ms","start":"2026-05-08T10:31:08.650456Z","end":"2026-05-08T10:31:08.872879Z","steps":["trace[1431197889] 'process raft request' (duration: 191.148547ms)","trace[1431197889] 'compare' (duration: 30.9216ms)"],"step_count":2}