{"level":"warn","ts":"2026-03-31T20:24:48.29404Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T20:24:48.294137Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.59: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.59:2380","--initial-cluster=instance=https://199.19.213.59:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.59:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.59: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-03-31T20:24:48.294209Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T20:24:48.294224Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.59:2380"]} {"level":"info","ts":"2026-03-31T20:24:48.294245Z","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-03-31T20:24:48.295169Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.59:2379"]} {"level":"info","ts":"2026-03-31T20:24:48.295256Z","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":16,"max-cpu-available":16,"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.59:2380"],"listen-peer-urls":["https://199.19.213.59:2380"],"advertise-client-urls":["https://199.19.213.59:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.59:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.59: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-03-31T20:24:48.299093Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.537603ms"} {"level":"info","ts":"2026-03-31T20:24:48.308767Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"41c06612bf92f90","cluster-id":"7f792254ff9520b"} {"level":"info","ts":"2026-03-31T20:24:48.308859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T20:24:48.308892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became follower at term 0"} {"level":"info","ts":"2026-03-31T20:24:48.308911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 41c06612bf92f90 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T20:24:48.308924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became follower at term 1"} {"level":"info","ts":"2026-03-31T20:24:48.308987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 switched to configuration voters=(296118689918955408)"} {"level":"warn","ts":"2026-03-31T20:24:48.313778Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T20:24:48.316845Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T20:24:48.318016Z","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-03-31T20:24:48.320169Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"41c06612bf92f90","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T20:24:48.32233Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"41c06612bf92f90","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T20:24:48.322495Z","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-03-31T20:24:48.322574Z","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-03-31T20:24:48.322594Z","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-03-31T20:24:48.322792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 switched to configuration voters=(296118689918955408)"} {"level":"info","ts":"2026-03-31T20:24:48.322921Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7f792254ff9520b","local-member-id":"41c06612bf92f90","added-peer-id":"41c06612bf92f90","added-peer-peer-urls":["https://199.19.213.59:2380"]} {"level":"info","ts":"2026-03-31T20:24:48.322959Z","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-03-31T20:24:48.322998Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.59:2380"} {"level":"info","ts":"2026-03-31T20:24:48.324182Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.59:2380"} {"level":"info","ts":"2026-03-31T20:24:48.324538Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"41c06612bf92f90","initial-advertise-peer-urls":["https://199.19.213.59:2380"],"listen-peer-urls":["https://199.19.213.59:2380"],"advertise-client-urls":["https://199.19.213.59:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.59:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T20:24:48.324614Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T20:24:49.209997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T20:24:49.210053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T20:24:49.210078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 received MsgPreVoteResp from 41c06612bf92f90 at term 1"} {"level":"info","ts":"2026-03-31T20:24:49.210097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became candidate at term 2"} {"level":"info","ts":"2026-03-31T20:24:49.210108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 received MsgVoteResp from 41c06612bf92f90 at term 2"} {"level":"info","ts":"2026-03-31T20:24:49.210124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became leader at term 2"} {"level":"info","ts":"2026-03-31T20:24:49.210137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 41c06612bf92f90 elected leader 41c06612bf92f90 at term 2"} {"level":"info","ts":"2026-03-31T20:24:49.211059Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T20:24:49.211211Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"41c06612bf92f90","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.59:2379]}","request-path":"/0/members/41c06612bf92f90/attributes","cluster-id":"7f792254ff9520b","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T20:24:49.211219Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T20:24:49.211292Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T20:24:49.211514Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T20:24:49.211561Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T20:24:49.211873Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7f792254ff9520b","local-member-id":"41c06612bf92f90","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T20:24:49.212026Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T20:24:49.212103Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T20:24:49.213865Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.59:2379"} {"level":"info","ts":"2026-03-31T20:24:49.21532Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-31T20:25:07.698404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.802454ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-31T20:25:07.698586Z","caller":"traceutil/trace.go:171","msg":"trace[1759518168] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"280.202158ms","start":"2026-03-31T20:25:07.418364Z","end":"2026-03-31T20:25:07.698566Z","steps":["trace[1759518168] 'read index received' (duration: 53.045765ms)","trace[1759518168] 'applied index is now lower than readState.Index' (duration: 227.154523ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:25:07.698686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.306131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:25:07.698717Z","caller":"traceutil/trace.go:171","msg":"trace[149832219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"280.372163ms","start":"2026-03-31T20:25:07.418331Z","end":"2026-03-31T20:25:07.698703Z","steps":["trace[149832219] 'agreement among raft nodes before linearized reading' (duration: 280.297011ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:25:20.82792Z","caller":"traceutil/trace.go:171","msg":"trace[1840590439] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"584.506385ms","start":"2026-03-31T20:25:20.243389Z","end":"2026-03-31T20:25:20.827895Z","steps":["trace[1840590439] 'process raft request' (duration: 584.354581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:25:20.828569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:25:20.243368Z","time spent":"584.63561ms","remote":"127.0.0.1:55806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:25:20.836586Z","caller":"traceutil/trace.go:171","msg":"trace[315180174] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"568.703194ms","start":"2026-03-31T20:25:20.267841Z","end":"2026-03-31T20:25:20.836544Z","steps":["trace[315180174] 'process raft request' (duration: 568.413736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:25:20.836777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:25:20.267816Z","time spent":"568.877167ms","remote":"127.0.0.1:55806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:25:20.837846Z","caller":"traceutil/trace.go:171","msg":"trace[1906810585] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:582; }","duration":"377.988272ms","start":"2026-03-31T20:25:20.459837Z","end":"2026-03-31T20:25:20.837826Z","steps":["trace[1906810585] 'read index received' (duration: 367.96356ms)","trace[1906810585] 'applied index is now lower than readState.Index' (duration: 10.023972ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:25:20.837873Z","caller":"traceutil/trace.go:171","msg":"trace[345616177] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"370.865176ms","start":"2026-03-31T20:25:20.466979Z","end":"2026-03-31T20:25:20.837844Z","steps":["trace[345616177] 'process raft request' (duration: 370.797223ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:25:20.837911Z","caller":"traceutil/trace.go:171","msg":"trace[1228424442] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"511.217153ms","start":"2026-03-31T20:25:20.326667Z","end":"2026-03-31T20:25:20.837884Z","steps":["trace[1228424442] 'process raft request' (duration: 510.030841ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:25:20.838047Z","caller":"traceutil/trace.go:171","msg":"trace[1627431640] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"169.758104ms","start":"2026-03-31T20:25:20.66819Z","end":"2026-03-31T20:25:20.837948Z","steps":["trace[1627431640] 'process raft request' (duration: 169.613181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:25:20.838051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:25:20.466961Z","time spent":"370.983779ms","remote":"127.0.0.1:55806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T20:25:20.838258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:25:20.326644Z","time spent":"511.312695ms","remote":"127.0.0.1:55806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:25:20.838348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.391095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:25:20.839902Z","caller":"traceutil/trace.go:171","msg":"trace[1754229454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"296.960196ms","start":"2026-03-31T20:25:20.542909Z","end":"2026-03-31T20:25:20.839869Z","steps":["trace[1754229454] 'agreement among raft nodes before linearized reading' (duration: 295.370155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:25:20.83992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.071905ms","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-03-31T20:25:20.840074Z","caller":"traceutil/trace.go:171","msg":"trace[1164547849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:563; }","duration":"380.247761ms","start":"2026-03-31T20:25:20.459793Z","end":"2026-03-31T20:25:20.840041Z","steps":["trace[1164547849] 'agreement among raft nodes before linearized reading' (duration: 380.024444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:25:20.840102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:25:20.459777Z","time spent":"380.318922ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-31T20:25:27.055379Z","caller":"traceutil/trace.go:171","msg":"trace[491544030] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"183.830701ms","start":"2026-03-31T20:25:26.871513Z","end":"2026-03-31T20:25:27.055344Z","steps":["trace[491544030] 'process raft request' (duration: 145.650124ms)","trace[491544030] 'compare' (duration: 37.94025ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:25:27.05553Z","caller":"traceutil/trace.go:171","msg":"trace[1978096335] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"183.859503ms","start":"2026-03-31T20:25:26.871658Z","end":"2026-03-31T20:25:27.055517Z","steps":["trace[1978096335] 'process raft request' (duration: 183.566444ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:25:27.202295Z","caller":"traceutil/trace.go:171","msg":"trace[230888153] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"134.347549ms","start":"2026-03-31T20:25:27.067918Z","end":"2026-03-31T20:25:27.202266Z","steps":["trace[230888153] 'process raft request' (duration: 125.036885ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:25:27.873432Z","caller":"traceutil/trace.go:171","msg":"trace[1255740958] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"155.794969ms","start":"2026-03-31T20:25:27.7176Z","end":"2026-03-31T20:25:27.873395Z","steps":["trace[1255740958] 'process raft request' (duration: 155.552923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:25:46.005841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.813903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:25:46.005932Z","caller":"traceutil/trace.go:171","msg":"trace[940399338] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"945.800321ms","start":"2026-03-31T20:25:45.060118Z","end":"2026-03-31T20:25:46.005919Z","steps":["trace[940399338] 'read index received' (duration: 39.531µs)","trace[940399338] 'applied index is now lower than readState.Index' (duration: 945.75942ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:25:46.005999Z","caller":"traceutil/trace.go:171","msg":"trace[163951711] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"996.67092ms","start":"2026-03-31T20:25:45.009321Z","end":"2026-03-31T20:25:46.005992Z","steps":["trace[163951711] 'compare' (duration: 986.298349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:25:46.006051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:25:45.009305Z","time spent":"996.712931ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:25:46.006303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.192322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wghcv\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-03-31T20:25:46.006333Z","caller":"traceutil/trace.go:171","msg":"trace[2137285686] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wghcv; range_end:; response_count:1; response_revision:758; }","duration":"946.257284ms","start":"2026-03-31T20:25:45.060065Z","end":"2026-03-31T20:25:46.006322Z","steps":["trace[2137285686] 'agreement among raft nodes before linearized reading' (duration: 946.133391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:25:46.006356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:25:45.060045Z","time spent":"946.305865ms","remote":"127.0.0.1:55934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wghcv\" "} {"level":"warn","ts":"2026-03-31T20:25:46.006745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.490122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-31T20:25:46.006813Z","caller":"traceutil/trace.go:171","msg":"trace[1448248282] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:758; }","duration":"783.612935ms","start":"2026-03-31T20:25:45.223181Z","end":"2026-03-31T20:25:46.006794Z","steps":["trace[1448248282] 'agreement among raft nodes before linearized reading' (duration: 783.451441ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:25:46.006847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:25:45.223154Z","time spent":"783.685997ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T20:25:46.00634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.149567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-31T20:25:46.006995Z","caller":"traceutil/trace.go:171","msg":"trace[1854135000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:758; }","duration":"867.851116ms","start":"2026-03-31T20:25:45.139107Z","end":"2026-03-31T20:25:46.006958Z","steps":["trace[1854135000] 'agreement among raft nodes before linearized reading' (duration: 867.140687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:25:46.00702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.899589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-31T20:25:46.007098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:25:45.139072Z","time spent":"867.986079ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T20:25:46.007151Z","caller":"traceutil/trace.go:171","msg":"trace[53870592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:758; }","duration":"865.131035ms","start":"2026-03-31T20:25:45.141985Z","end":"2026-03-31T20:25:46.007116Z","steps":["trace[53870592] 'agreement among raft nodes before linearized reading' (duration: 864.667822ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:25:46.007255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:25:45.141961Z","time spent":"865.275659ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T20:25:46.355194Z","caller":"traceutil/trace.go:171","msg":"trace[1027687552] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"343.278085ms","start":"2026-03-31T20:25:46.011888Z","end":"2026-03-31T20:25:46.355166Z","steps":["trace[1027687552] 'process raft request' (duration: 343.087341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:25:46.355493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:25:46.011871Z","time spent":"343.506851ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:25:46.360548Z","caller":"traceutil/trace.go:171","msg":"trace[1168953353] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"345.76425ms","start":"2026-03-31T20:25:46.014772Z","end":"2026-03-31T20:25:46.360536Z","steps":["trace[1168953353] 'process raft request' (duration: 345.719419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:25:46.360677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:25:46.014749Z","time spent":"345.858683ms","remote":"127.0.0.1:55934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:25:46.36075Z","caller":"traceutil/trace.go:171","msg":"trace[2104223549] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"348.40685ms","start":"2026-03-31T20:25:46.012324Z","end":"2026-03-31T20:25:46.360731Z","steps":["trace[2104223549] 'process raft request' (duration: 348.121433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:25:46.360903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:25:46.012296Z","time spent":"348.554354ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:25:46.36074Z","caller":"traceutil/trace.go:171","msg":"trace[521910066] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"347.135996ms","start":"2026-03-31T20:25:46.013544Z","end":"2026-03-31T20:25:46.36068Z","steps":["trace[521910066] 'process raft request' (duration: 346.923671ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:25:46.360552Z","caller":"traceutil/trace.go:171","msg":"trace[23042082] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"348.528473ms","start":"2026-03-31T20:25:46.011999Z","end":"2026-03-31T20:25:46.360527Z","steps":["trace[23042082] 'process raft request' (duration: 348.350117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:25:46.361307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:25:46.013537Z","time spent":"347.613469ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:25:46.361573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:25:46.011993Z","time spent":"349.396365ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:26:15.339557Z","caller":"traceutil/trace.go:171","msg":"trace[1072939769] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"104.360396ms","start":"2026-03-31T20:26:15.235154Z","end":"2026-03-31T20:26:15.339515Z","steps":["trace[1072939769] 'process raft request' (duration: 104.104179ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:26:35.541658Z","caller":"traceutil/trace.go:171","msg":"trace[1909757389] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"129.254718ms","start":"2026-03-31T20:26:35.41236Z","end":"2026-03-31T20:26:35.541614Z","steps":["trace[1909757389] 'process raft request' (duration: 128.95632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:26:57.775036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.842709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:26:57.775156Z","caller":"traceutil/trace.go:171","msg":"trace[2074007365] linearizableReadLoop","detail":"{readStateIndex:1850; appliedIndex:1849; }","duration":"671.510684ms","start":"2026-03-31T20:26:57.103629Z","end":"2026-03-31T20:26:57.77514Z","steps":["trace[2074007365] 'read index received' (duration: 192.432538ms)","trace[2074007365] 'applied index is now lower than readState.Index' (duration: 479.076806ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:26:57.775324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.703609ms","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-03-31T20:26:57.775303Z","caller":"traceutil/trace.go:171","msg":"trace[737658136] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"689.406119ms","start":"2026-03-31T20:26:57.085836Z","end":"2026-03-31T20:26:57.775242Z","steps":["trace[737658136] 'process raft request' (duration: 210.243431ms)","trace[737658136] 'compare' (duration: 478.733277ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:26:57.775366Z","caller":"traceutil/trace.go:171","msg":"trace[1967056655] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1792; }","duration":"671.783712ms","start":"2026-03-31T20:26:57.103573Z","end":"2026-03-31T20:26:57.775357Z","steps":["trace[1967056655] 'agreement among raft nodes before linearized reading' (duration: 671.646249ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:26:57.775404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:26:57.103552Z","time spent":"671.844273ms","remote":"127.0.0.1:55854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":681,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-03-31T20:26:57.775488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:26:57.085805Z","time spent":"689.587603ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:26:57.823018Z","caller":"traceutil/trace.go:171","msg":"trace[1375932835] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"198.114176ms","start":"2026-03-31T20:26:57.624881Z","end":"2026-03-31T20:26:57.822995Z","steps":["trace[1375932835] 'process raft request' (duration: 197.952332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:26:57.823115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.77567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:26:57.823186Z","caller":"traceutil/trace.go:171","msg":"trace[192204759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1793; }","duration":"405.887553ms","start":"2026-03-31T20:26:57.417283Z","end":"2026-03-31T20:26:57.823171Z","steps":["trace[192204759] 'agreement among raft nodes before linearized reading' (duration: 405.749809ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:26:57.823226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:26:57.417266Z","time spent":"405.951915ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T20:26:57.823269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.849124ms","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-03-31T20:26:57.823352Z","caller":"traceutil/trace.go:171","msg":"trace[1509049941] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1793; }","duration":"300.964478ms","start":"2026-03-31T20:26:57.522364Z","end":"2026-03-31T20:26:57.823328Z","steps":["trace[1509049941] 'agreement among raft nodes before linearized reading' (duration: 300.766063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:26:57.823393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.488119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a0af3abe-724b-4145-934c-2e9416e3fc04\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-03-31T20:26:57.823406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:26:57.522346Z","time spent":"301.04652ms","remote":"127.0.0.1:48532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4613,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-03-31T20:26:57.823429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.344476ms","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-03-31T20:26:57.823463Z","caller":"traceutil/trace.go:171","msg":"trace[1197243030] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a0af3abe-724b-4145-934c-2e9416e3fc04; range_end:; response_count:1; response_revision:1793; }","duration":"349.594632ms","start":"2026-03-31T20:26:57.473848Z","end":"2026-03-31T20:26:57.823443Z","steps":["trace[1197243030] 'agreement among raft nodes before linearized reading' (duration: 349.392826ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:26:57.823518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:26:57.47383Z","time spent":"349.675924ms","remote":"127.0.0.1:55934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a0af3abe-724b-4145-934c-2e9416e3fc04\" "} {"level":"info","ts":"2026-03-31T20:26:57.823519Z","caller":"traceutil/trace.go:171","msg":"trace[1156625645] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1793; }","duration":"458.48103ms","start":"2026-03-31T20:26:57.365016Z","end":"2026-03-31T20:26:57.823497Z","steps":["trace[1156625645] 'agreement among raft nodes before linearized reading' (duration: 458.290395ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:26:57.823588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:26:57.364994Z","time spent":"458.573652ms","remote":"127.0.0.1:55854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":666,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-03-31T20:26:58.14193Z","caller":"traceutil/trace.go:171","msg":"trace[314164696] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"211.633198ms","start":"2026-03-31T20:26:57.930264Z","end":"2026-03-31T20:26:58.141897Z","steps":["trace[314164696] 'process raft request' (duration: 161.037724ms)","trace[314164696] 'compare' (duration: 50.363318ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:26:58.142094Z","caller":"traceutil/trace.go:171","msg":"trace[707529245] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"209.314787ms","start":"2026-03-31T20:26:57.932763Z","end":"2026-03-31T20:26:58.142078Z","steps":["trace[707529245] 'process raft request' (duration: 209.077521ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:27:06.42423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.415181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:27:06.424603Z","caller":"traceutil/trace.go:171","msg":"trace[373183159] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"566.092224ms","start":"2026-03-31T20:27:05.858489Z","end":"2026-03-31T20:27:06.424581Z","steps":["trace[373183159] 'process raft request' (duration: 130.231322ms)","trace[373183159] 'compare' (duration: 435.323249ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:27:06.424654Z","caller":"traceutil/trace.go:171","msg":"trace[2030044803] linearizableReadLoop","detail":"{readStateIndex:1949; appliedIndex:1948; }","duration":"544.127386ms","start":"2026-03-31T20:27:05.880512Z","end":"2026-03-31T20:27:06.424639Z","steps":["trace[2030044803] 'read index received' (duration: 108.200601ms)","trace[2030044803] 'applied index is now lower than readState.Index' (duration: 435.925045ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:27:06.42471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:27:05.85847Z","time spent":"566.205699ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:27:06.424911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.398522ms","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-03-31T20:27:06.424961Z","caller":"traceutil/trace.go:171","msg":"trace[1996185777] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1887; }","duration":"544.452453ms","start":"2026-03-31T20:27:05.880493Z","end":"2026-03-31T20:27:06.424946Z","steps":["trace[1996185777] 'agreement among raft nodes before linearized reading' (duration: 544.261019ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:27:06.424999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:27:05.880433Z","time spent":"544.557756ms","remote":"127.0.0.1:48532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4613,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-03-31T20:27:06.425424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.639721ms","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-03-31T20:27:06.425553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.363391ms","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-03-31T20:27:06.425587Z","caller":"traceutil/trace.go:171","msg":"trace[20544373] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1887; }","duration":"226.432393ms","start":"2026-03-31T20:27:06.199145Z","end":"2026-03-31T20:27:06.425577Z","steps":["trace[20544373] 'agreement among raft nodes before linearized reading' (duration: 226.344911ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:27:06.425681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.138909ms","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":"warn","ts":"2026-03-31T20:27:06.425778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.005504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11272"} {"level":"info","ts":"2026-03-31T20:27:06.425786Z","caller":"traceutil/trace.go:171","msg":"trace[1702255772] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1887; }","duration":"225.278772ms","start":"2026-03-31T20:27:06.200481Z","end":"2026-03-31T20:27:06.42576Z","steps":["trace[1702255772] 'agreement among raft nodes before linearized reading' (duration: 225.071287ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:27:06.425853Z","caller":"traceutil/trace.go:171","msg":"trace[892814758] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1887; }","duration":"404.129098ms","start":"2026-03-31T20:27:06.021707Z","end":"2026-03-31T20:27:06.425837Z","steps":["trace[892814758] 'agreement among raft nodes before linearized reading' (duration: 403.942894ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:27:06.425855Z","caller":"traceutil/trace.go:171","msg":"trace[227189516] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1887; }","duration":"479.718653ms","start":"2026-03-31T20:27:05.945739Z","end":"2026-03-31T20:27:06.425458Z","steps":["trace[227189516] 'agreement among raft nodes before linearized reading' (duration: 479.650561ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:27:06.4259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:27:06.021684Z","time spent":"404.205019ms","remote":"127.0.0.1:55934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11294,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-03-31T20:27:06.425903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:27:05.945725Z","time spent":"480.164543ms","remote":"127.0.0.1:46942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-31T20:27:06.425682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.966909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-03-31T20:27:06.42658Z","caller":"traceutil/trace.go:171","msg":"trace[526017742] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1887; }","duration":"184.908993ms","start":"2026-03-31T20:27:06.241652Z","end":"2026-03-31T20:27:06.426561Z","steps":["trace[526017742] 'agreement among raft nodes before linearized reading' (duration: 183.816985ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:27:40.636235Z","caller":"traceutil/trace.go:171","msg":"trace[1147306616] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"102.519361ms","start":"2026-03-31T20:27:40.533675Z","end":"2026-03-31T20:27:40.636194Z","steps":["trace[1147306616] 'process raft request' (duration: 102.089759ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:27:40.794705Z","caller":"traceutil/trace.go:171","msg":"trace[1298518237] linearizableReadLoop","detail":"{readStateIndex:2210; appliedIndex:2209; }","duration":"127.495988ms","start":"2026-03-31T20:27:40.667185Z","end":"2026-03-31T20:27:40.794681Z","steps":["trace[1298518237] 'read index received' (duration: 126.107812ms)","trace[1298518237] 'applied index is now lower than readState.Index' (duration: 1.387116ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:27:40.794828Z","caller":"traceutil/trace.go:171","msg":"trace[400015149] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"155.077374ms","start":"2026-03-31T20:27:40.639727Z","end":"2026-03-31T20:27:40.794804Z","steps":["trace[400015149] 'process raft request' (duration: 153.614496ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:27:40.79492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.714924ms","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-03-31T20:27:40.794964Z","caller":"traceutil/trace.go:171","msg":"trace[1405143504] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2134; }","duration":"127.800596ms","start":"2026-03-31T20:27:40.667152Z","end":"2026-03-31T20:27:40.794952Z","steps":["trace[1405143504] 'agreement among raft nodes before linearized reading' (duration: 127.658763ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:28:52.634774Z","caller":"traceutil/trace.go:171","msg":"trace[647753522] linearizableReadLoop","detail":"{readStateIndex:2789; appliedIndex:2788; }","duration":"104.865837ms","start":"2026-03-31T20:28:52.529885Z","end":"2026-03-31T20:28:52.634751Z","steps":["trace[647753522] 'read index received' (duration: 104.63554ms)","trace[647753522] 'applied index is now lower than readState.Index' (duration: 229.147µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:28:52.63492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.013241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T20:28:52.634887Z","caller":"traceutil/trace.go:171","msg":"trace[1146231680] transaction","detail":"{read_only:false; response_revision:2681; number_of_response:1; }","duration":"117.802953ms","start":"2026-03-31T20:28:52.517033Z","end":"2026-03-31T20:28:52.634836Z","steps":["trace[1146231680] 'process raft request' (duration: 117.535216ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:28:52.634959Z","caller":"traceutil/trace.go:171","msg":"trace[1910267672] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2681; }","duration":"105.094773ms","start":"2026-03-31T20:28:52.529853Z","end":"2026-03-31T20:28:52.634948Z","steps":["trace[1910267672] 'agreement among raft nodes before linearized reading' (duration: 105.00035ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:28:53.551669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.474052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:28:53.551812Z","caller":"traceutil/trace.go:171","msg":"trace[618361767] linearizableReadLoop","detail":"{readStateIndex:2792; appliedIndex:2791; }","duration":"266.168332ms","start":"2026-03-31T20:28:53.285621Z","end":"2026-03-31T20:28:53.551789Z","steps":["trace[618361767] 'read index received' (duration: 75.461343ms)","trace[618361767] 'applied index is now lower than readState.Index' (duration: 190.704799ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:28:53.551907Z","caller":"traceutil/trace.go:171","msg":"trace[981367802] transaction","detail":"{read_only:false; response_revision:2684; number_of_response:1; }","duration":"417.794804ms","start":"2026-03-31T20:28:53.134088Z","end":"2026-03-31T20:28:53.551882Z","steps":["trace[981367802] 'process raft request' (duration: 226.998283ms)","trace[981367802] 'compare' (duration: 190.296448ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:28:53.552005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:28:53.134073Z","time spent":"417.878376ms","remote":"127.0.0.1:55822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:28:53.55204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.400927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T20:28:53.552125Z","caller":"traceutil/trace.go:171","msg":"trace[82477882] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2684; }","duration":"266.50385ms","start":"2026-03-31T20:28:53.285604Z","end":"2026-03-31T20:28:53.552108Z","steps":["trace[82477882] 'agreement among raft nodes before linearized reading' (duration: 266.295225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:28:53.555048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.628442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-03-31T20:28:53.555109Z","caller":"traceutil/trace.go:171","msg":"trace[1923372194] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2685; }","duration":"191.730354ms","start":"2026-03-31T20:28:53.363365Z","end":"2026-03-31T20:28:53.555095Z","steps":["trace[1923372194] 'agreement among raft nodes before linearized reading' (duration: 191.54858ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:28:53.555183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.764706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-31T20:28:53.555257Z","caller":"traceutil/trace.go:171","msg":"trace[246299077] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2685; }","duration":"166.861089ms","start":"2026-03-31T20:28:53.388379Z","end":"2026-03-31T20:28:53.555241Z","steps":["trace[246299077] 'agreement among raft nodes before linearized reading' (duration: 166.713504ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:28:53.555286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.456335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-31T20:28:53.555401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.996338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T20:28:53.55545Z","caller":"traceutil/trace.go:171","msg":"trace[619238013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2685; }","duration":"168.090981ms","start":"2026-03-31T20:28:53.387345Z","end":"2026-03-31T20:28:53.555436Z","steps":["trace[619238013] 'agreement among raft nodes before linearized reading' (duration: 167.956987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:28:53.555456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.225928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:28:53.555446Z","caller":"traceutil/trace.go:171","msg":"trace[1079307595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2685; }","duration":"164.770294ms","start":"2026-03-31T20:28:53.390656Z","end":"2026-03-31T20:28:53.555426Z","steps":["trace[1079307595] 'agreement among raft nodes before linearized reading' (duration: 164.286912ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:28:53.55552Z","caller":"traceutil/trace.go:171","msg":"trace[1276088961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2685; }","duration":"137.339051ms","start":"2026-03-31T20:28:53.41816Z","end":"2026-03-31T20:28:53.555499Z","steps":["trace[1276088961] 'agreement among raft nodes before linearized reading' (duration: 137.214028ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:28:53.555681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.041457ms","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-03-31T20:28:53.555842Z","caller":"traceutil/trace.go:171","msg":"trace[289815520] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2685; }","duration":"166.225142ms","start":"2026-03-31T20:28:53.38956Z","end":"2026-03-31T20:28:53.555785Z","steps":["trace[289815520] 'agreement among raft nodes before linearized reading' (duration: 165.73373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:28:54.19484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.816526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:28:54.194956Z","caller":"traceutil/trace.go:171","msg":"trace[1136538071] transaction","detail":"{read_only:false; response_revision:2686; number_of_response:1; }","duration":"635.107574ms","start":"2026-03-31T20:28:53.559828Z","end":"2026-03-31T20:28:54.194936Z","steps":["trace[1136538071] 'process raft request' (duration: 395.105014ms)","trace[1136538071] 'compare' (duration: 239.365034ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:28:54.195023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:28:53.559811Z","time spent":"635.180187ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:28:54.261493Z","caller":"traceutil/trace.go:171","msg":"trace[236389533] transaction","detail":"{read_only:false; response_revision:2687; number_of_response:1; }","duration":"700.884775ms","start":"2026-03-31T20:28:53.560583Z","end":"2026-03-31T20:28:54.261468Z","steps":["trace[236389533] 'process raft request' (duration: 700.706041ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:28:54.26173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:28:53.560567Z","time spent":"701.006079ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:28:54.261843Z","caller":"traceutil/trace.go:171","msg":"trace[582432465] transaction","detail":"{read_only:false; response_revision:2688; number_of_response:1; }","duration":"701.182494ms","start":"2026-03-31T20:28:53.56064Z","end":"2026-03-31T20:28:54.261822Z","steps":["trace[582432465] 'process raft request' (duration: 700.785163ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:28:54.261904Z","caller":"traceutil/trace.go:171","msg":"trace[1875941874] transaction","detail":"{read_only:false; response_revision:2689; number_of_response:1; }","duration":"701.169413ms","start":"2026-03-31T20:28:53.56069Z","end":"2026-03-31T20:28:54.261859Z","steps":["trace[1875941874] 'process raft request' (duration: 700.968878ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:28:54.261958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:28:53.560629Z","time spent":"701.268016ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:28:54.262024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:28:53.560663Z","time spent":"701.289256ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:28:54.262065Z","caller":"traceutil/trace.go:171","msg":"trace[370020180] linearizableReadLoop","detail":"{readStateIndex:2798; appliedIndex:2793; }","duration":"669.869899ms","start":"2026-03-31T20:28:53.592167Z","end":"2026-03-31T20:28:54.262037Z","steps":["trace[370020180] 'read index received' (duration: 362.704012ms)","trace[370020180] 'applied index is now lower than readState.Index' (duration: 307.164427ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:28:54.262108Z","caller":"traceutil/trace.go:171","msg":"trace[307428111] transaction","detail":"{read_only:false; response_revision:2690; number_of_response:1; }","duration":"700.922546ms","start":"2026-03-31T20:28:53.561177Z","end":"2026-03-31T20:28:54.2621Z","steps":["trace[307428111] 'process raft request' (duration: 700.635949ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:28:54.262341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.167997ms","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":"warn","ts":"2026-03-31T20:28:54.262367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.244444ms","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":"warn","ts":"2026-03-31T20:28:54.262357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:28:53.561162Z","time spent":"701.09319ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:28:54.262404Z","caller":"traceutil/trace.go:171","msg":"trace[996710051] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2690; }","duration":"670.25955ms","start":"2026-03-31T20:28:53.592131Z","end":"2026-03-31T20:28:54.26239Z","steps":["trace[996710051] 'agreement among raft nodes before linearized reading' (duration: 670.023163ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:28:54.262412Z","caller":"traceutil/trace.go:171","msg":"trace[51480200] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2690; }","duration":"666.323647ms","start":"2026-03-31T20:28:53.596076Z","end":"2026-03-31T20:28:54.2624Z","steps":["trace[51480200] 'agreement among raft nodes before linearized reading' (duration: 666.154672ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:28:54.262468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:28:53.592119Z","time spent":"670.318521ms","remote":"127.0.0.1:55854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":666,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-03-31T20:28:54.262481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:28:53.596067Z","time spent":"666.403909ms","remote":"127.0.0.1:55854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":681,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-03-31T20:28:54.262555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.618035ms","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-03-31T20:28:54.262643Z","caller":"traceutil/trace.go:171","msg":"trace[696586450] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2690; }","duration":"428.870663ms","start":"2026-03-31T20:28:53.833759Z","end":"2026-03-31T20:28:54.262629Z","steps":["trace[696586450] 'agreement among raft nodes before linearized reading' (duration: 428.534904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:28:54.262756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:28:53.833738Z","time spent":"428.982355ms","remote":"127.0.0.1:55940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1318,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-03-31T20:29:01.421381Z","caller":"traceutil/trace.go:171","msg":"trace[1930423207] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"114.121457ms","start":"2026-03-31T20:29:01.307245Z","end":"2026-03-31T20:29:01.421367Z","steps":["trace[1930423207] 'process raft request' (duration: 113.927743ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:29:55.708308Z","caller":"traceutil/trace.go:171","msg":"trace[250180453] linearizableReadLoop","detail":"{readStateIndex:3414; appliedIndex:3413; }","duration":"101.860559ms","start":"2026-03-31T20:29:55.606422Z","end":"2026-03-31T20:29:55.708283Z","steps":["trace[250180453] 'read index received' (duration: 101.584731ms)","trace[250180453] 'applied index is now lower than readState.Index' (duration: 274.788µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:29:55.708412Z","caller":"traceutil/trace.go:171","msg":"trace[890000643] transaction","detail":"{read_only:false; response_revision:3279; number_of_response:1; }","duration":"152.157978ms","start":"2026-03-31T20:29:55.556226Z","end":"2026-03-31T20:29:55.708384Z","steps":["trace[890000643] 'process raft request' (duration: 151.840959ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:29:55.708482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.029903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:29:55.708531Z","caller":"traceutil/trace.go:171","msg":"trace[533069298] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:3279; }","duration":"102.110856ms","start":"2026-03-31T20:29:55.606405Z","end":"2026-03-31T20:29:55.708516Z","steps":["trace[533069298] 'agreement among raft nodes before linearized reading' (duration: 102.016834ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:08.865494Z","caller":"traceutil/trace.go:171","msg":"trace[594501911] transaction","detail":"{read_only:false; response_revision:4299; number_of_response:1; }","duration":"175.561826ms","start":"2026-03-31T20:31:08.689896Z","end":"2026-03-31T20:31:08.865458Z","steps":["trace[594501911] 'process raft request' (duration: 131.194191ms)","trace[594501911] 'compare' (duration: 43.964314ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:31:08.865623Z","caller":"traceutil/trace.go:171","msg":"trace[1947306708] transaction","detail":"{read_only:false; response_revision:4300; number_of_response:1; }","duration":"142.953248ms","start":"2026-03-31T20:31:08.722633Z","end":"2026-03-31T20:31:08.865586Z","steps":["trace[1947306708] 'process raft request' (duration: 142.782154ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:11.567321Z","caller":"traceutil/trace.go:171","msg":"trace[1402987345] transaction","detail":"{read_only:false; response_revision:4439; number_of_response:1; }","duration":"124.636382ms","start":"2026-03-31T20:31:11.442663Z","end":"2026-03-31T20:31:11.567299Z","steps":["trace[1402987345] 'process raft request' (duration: 124.514629ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:12.856713Z","caller":"traceutil/trace.go:171","msg":"trace[349736952] linearizableReadLoop","detail":"{readStateIndex:4648; appliedIndex:4647; }","duration":"107.014784ms","start":"2026-03-31T20:31:12.749682Z","end":"2026-03-31T20:31:12.856697Z","steps":["trace[349736952] 'read index received' (duration: 95.564475ms)","trace[349736952] 'applied index is now lower than readState.Index' (duration: 11.449798ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:31:12.856956Z","caller":"traceutil/trace.go:171","msg":"trace[777913944] transaction","detail":"{read_only:false; response_revision:4456; number_of_response:1; }","duration":"166.190962ms","start":"2026-03-31T20:31:12.690685Z","end":"2026-03-31T20:31:12.856876Z","steps":["trace[777913944] 'process raft request' (duration: 154.650012ms)","trace[777913944] 'compare' (duration: 11.168701ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:31:12.857126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.437234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:777357"} {"level":"info","ts":"2026-03-31T20:31:12.857161Z","caller":"traceutil/trace.go:171","msg":"trace[1438962824] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4456; }","duration":"107.528576ms","start":"2026-03-31T20:31:12.749623Z","end":"2026-03-31T20:31:12.857151Z","steps":["trace[1438962824] 'agreement among raft nodes before linearized reading' (duration: 107.150256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:31:15.106762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.054693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"warn","ts":"2026-03-31T20:31:15.106839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.892967ms","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-03-31T20:31:15.106915Z","caller":"traceutil/trace.go:171","msg":"trace[1792773507] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4524; }","duration":"180.231677ms","start":"2026-03-31T20:31:14.926651Z","end":"2026-03-31T20:31:15.106883Z","steps":["trace[1792773507] 'range keys from in-memory index tree' (duration: 179.888629ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:15.106921Z","caller":"traceutil/trace.go:171","msg":"trace[458797194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4524; }","duration":"183.008269ms","start":"2026-03-31T20:31:14.923893Z","end":"2026-03-31T20:31:15.106901Z","steps":["trace[458797194] 'range keys from in-memory index tree' (duration: 182.736223ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:15.106996Z","caller":"traceutil/trace.go:171","msg":"trace[645454457] transaction","detail":"{read_only:false; response_revision:4525; number_of_response:1; }","duration":"179.851947ms","start":"2026-03-31T20:31:14.927126Z","end":"2026-03-31T20:31:15.106978Z","steps":["trace[645454457] 'process raft request' (duration: 172.380433ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:15.141069Z","caller":"traceutil/trace.go:171","msg":"trace[2128424704] transaction","detail":"{read_only:false; response_revision:4526; number_of_response:1; }","duration":"213.279708ms","start":"2026-03-31T20:31:14.92776Z","end":"2026-03-31T20:31:15.141039Z","steps":["trace[2128424704] 'process raft request' (duration: 213.03043ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:15.141106Z","caller":"traceutil/trace.go:171","msg":"trace[1371337871] transaction","detail":"{read_only:false; response_revision:4527; number_of_response:1; }","duration":"213.328439ms","start":"2026-03-31T20:31:14.927758Z","end":"2026-03-31T20:31:15.141086Z","steps":["trace[1371337871] 'process raft request' (duration: 213.179115ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:15.141239Z","caller":"traceutil/trace.go:171","msg":"trace[1050834139] transaction","detail":"{read_only:false; response_revision:4529; number_of_response:1; }","duration":"213.366299ms","start":"2026-03-31T20:31:14.927859Z","end":"2026-03-31T20:31:15.141225Z","steps":["trace[1050834139] 'process raft request' (duration: 213.164464ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:15.141343Z","caller":"traceutil/trace.go:171","msg":"trace[628614500] transaction","detail":"{read_only:false; response_revision:4530; number_of_response:1; }","duration":"213.439042ms","start":"2026-03-31T20:31:14.927891Z","end":"2026-03-31T20:31:15.14133Z","steps":["trace[628614500] 'process raft request' (duration: 213.170655ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:15.141333Z","caller":"traceutil/trace.go:171","msg":"trace[981149351] transaction","detail":"{read_only:false; response_revision:4531; number_of_response:1; }","duration":"202.427845ms","start":"2026-03-31T20:31:14.938882Z","end":"2026-03-31T20:31:15.14131Z","steps":["trace[981149351] 'process raft request' (duration: 202.22472ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:15.141334Z","caller":"traceutil/trace.go:171","msg":"trace[851070862] linearizableReadLoop","detail":"{readStateIndex:4725; appliedIndex:4719; }","duration":"206.031938ms","start":"2026-03-31T20:31:14.935202Z","end":"2026-03-31T20:31:15.141234Z","steps":["trace[851070862] 'read index received' (duration: 164.285892ms)","trace[851070862] 'applied index is now lower than readState.Index' (duration: 41.744786ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:31:15.141235Z","caller":"traceutil/trace.go:171","msg":"trace[1302074466] transaction","detail":"{read_only:false; response_revision:4528; number_of_response:1; }","duration":"213.39778ms","start":"2026-03-31T20:31:14.927818Z","end":"2026-03-31T20:31:15.141216Z","steps":["trace[1302074466] 'process raft request' (duration: 213.167894ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:31:15.141686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.446119ms","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-03-31T20:31:15.141775Z","caller":"traceutil/trace.go:171","msg":"trace[337454434] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4531; }","duration":"206.604173ms","start":"2026-03-31T20:31:14.935149Z","end":"2026-03-31T20:31:15.141753Z","steps":["trace[337454434] 'agreement among raft nodes before linearized reading' (duration: 206.306025ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:31:15.150931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.412819ms","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-03-31T20:31:15.151041Z","caller":"traceutil/trace.go:171","msg":"trace[1477727948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4532; }","duration":"196.562343ms","start":"2026-03-31T20:31:14.954464Z","end":"2026-03-31T20:31:15.151027Z","steps":["trace[1477727948] 'agreement among raft nodes before linearized reading' (duration: 196.368518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:31:15.151153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.189422ms","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-03-31T20:31:15.151251Z","caller":"traceutil/trace.go:171","msg":"trace[783353354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4532; }","duration":"196.321836ms","start":"2026-03-31T20:31:14.954898Z","end":"2026-03-31T20:31:15.15122Z","steps":["trace[783353354] 'agreement among raft nodes before linearized reading' (duration: 196.07797ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:15.282925Z","caller":"traceutil/trace.go:171","msg":"trace[734085815] transaction","detail":"{read_only:false; response_revision:4533; number_of_response:1; }","duration":"136.804797ms","start":"2026-03-31T20:31:15.146086Z","end":"2026-03-31T20:31:15.282891Z","steps":["trace[734085815] 'process raft request' (duration: 113.4334ms)","trace[734085815] 'compare' (duration: 22.878705ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:31:15.283078Z","caller":"traceutil/trace.go:171","msg":"trace[1332909849] linearizableReadLoop","detail":"{readStateIndex:4730; appliedIndex:4727; }","duration":"127.24346ms","start":"2026-03-31T20:31:15.15581Z","end":"2026-03-31T20:31:15.283054Z","steps":["trace[1332909849] 'read index received' (duration: 103.78161ms)","trace[1332909849] 'applied index is now lower than readState.Index' (duration: 23.46072ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:31:15.283183Z","caller":"traceutil/trace.go:171","msg":"trace[1912801762] transaction","detail":"{read_only:false; response_revision:4535; number_of_response:1; }","duration":"134.796846ms","start":"2026-03-31T20:31:15.148375Z","end":"2026-03-31T20:31:15.283172Z","steps":["trace[1912801762] 'process raft request' (duration: 134.642723ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:15.283271Z","caller":"traceutil/trace.go:171","msg":"trace[161610266] transaction","detail":"{read_only:false; response_revision:4534; number_of_response:1; }","duration":"136.298086ms","start":"2026-03-31T20:31:15.146887Z","end":"2026-03-31T20:31:15.283185Z","steps":["trace[161610266] 'process raft request' (duration: 135.776802ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:31:15.283643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.809874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-03-31T20:31:15.283726Z","caller":"traceutil/trace.go:171","msg":"trace[1356197313] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4535; }","duration":"127.928807ms","start":"2026-03-31T20:31:15.155775Z","end":"2026-03-31T20:31:15.283704Z","steps":["trace[1356197313] 'agreement among raft nodes before linearized reading' (duration: 127.514126ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:15.292112Z","caller":"traceutil/trace.go:171","msg":"trace[1978542819] transaction","detail":"{read_only:false; response_revision:4536; number_of_response:1; }","duration":"136.174052ms","start":"2026-03-31T20:31:15.155914Z","end":"2026-03-31T20:31:15.292088Z","steps":["trace[1978542819] 'process raft request' (duration: 135.994927ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:15.292354Z","caller":"traceutil/trace.go:171","msg":"trace[903455354] transaction","detail":"{read_only:false; response_revision:4538; number_of_response:1; }","duration":"133.623055ms","start":"2026-03-31T20:31:15.158706Z","end":"2026-03-31T20:31:15.292329Z","steps":["trace[903455354] 'process raft request' (duration: 133.498812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:31:15.292483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.596848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-03-31T20:31:15.292539Z","caller":"traceutil/trace.go:171","msg":"trace[1131621301] transaction","detail":"{read_only:false; response_revision:4537; number_of_response:1; }","duration":"134.859167ms","start":"2026-03-31T20:31:15.157669Z","end":"2026-03-31T20:31:15.292528Z","steps":["trace[1131621301] 'process raft request' (duration: 134.375354ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:15.292552Z","caller":"traceutil/trace.go:171","msg":"trace[644569450] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4538; }","duration":"112.704391ms","start":"2026-03-31T20:31:15.179832Z","end":"2026-03-31T20:31:15.292536Z","steps":["trace[644569450] 'agreement among raft nodes before linearized reading' (duration: 112.508216ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:18.444286Z","caller":"traceutil/trace.go:171","msg":"trace[1752944455] transaction","detail":"{read_only:false; response_revision:4610; number_of_response:1; }","duration":"131.819318ms","start":"2026-03-31T20:31:18.312443Z","end":"2026-03-31T20:31:18.444262Z","steps":["trace[1752944455] 'process raft request' (duration: 125.568396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:31:23.898715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.068771ms","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":"warn","ts":"2026-03-31T20:31:23.898769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.440747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-c47d949f5-qlkgd\" ","response":"range_response_count:1 size:7812"} {"level":"info","ts":"2026-03-31T20:31:23.898859Z","caller":"traceutil/trace.go:171","msg":"trace[757673880] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-c47d949f5-qlkgd; range_end:; response_count:1; response_revision:4700; }","duration":"119.54288ms","start":"2026-03-31T20:31:23.779279Z","end":"2026-03-31T20:31:23.898822Z","steps":["trace[757673880] 'range keys from in-memory index tree' (duration: 119.308942ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:23.898874Z","caller":"traceutil/trace.go:171","msg":"trace[858089951] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4700; }","duration":"158.183914ms","start":"2026-03-31T20:31:23.740582Z","end":"2026-03-31T20:31:23.898766Z","steps":["trace[858089951] 'range keys from in-memory index tree' (duration: 157.931168ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:23.911582Z","caller":"traceutil/trace.go:171","msg":"trace[805119056] transaction","detail":"{read_only:false; response_revision:4701; number_of_response:1; }","duration":"115.602367ms","start":"2026-03-31T20:31:23.795952Z","end":"2026-03-31T20:31:23.911554Z","steps":["trace[805119056] 'process raft request' (duration: 115.450353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:31:24.369484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.220181ms","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-03-31T20:31:24.369579Z","caller":"traceutil/trace.go:171","msg":"trace[310802101] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4711; }","duration":"219.332686ms","start":"2026-03-31T20:31:24.150227Z","end":"2026-03-31T20:31:24.36956Z","steps":["trace[310802101] 'range keys from in-memory index tree' (duration: 219.043697ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:31:30.389668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.29466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:31:30.389865Z","caller":"traceutil/trace.go:171","msg":"trace[1390481688] linearizableReadLoop","detail":"{readStateIndex:5033; appliedIndex:5032; }","duration":"226.619084ms","start":"2026-03-31T20:31:30.163226Z","end":"2026-03-31T20:31:30.389845Z","steps":["trace[1390481688] 'read index received' (duration: 68.938563ms)","trace[1390481688] 'applied index is now lower than readState.Index' (duration: 157.676481ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:31:30.389988Z","caller":"traceutil/trace.go:171","msg":"trace[767684146] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"245.301299ms","start":"2026-03-31T20:31:30.14465Z","end":"2026-03-31T20:31:30.389951Z","steps":["trace[767684146] 'process raft request' (duration: 87.615438ms)","trace[767684146] 'compare' (duration: 156.85362ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:31:30.390124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.87068ms","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-03-31T20:31:30.390219Z","caller":"traceutil/trace.go:171","msg":"trace[1612042701] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4827; }","duration":"227.016834ms","start":"2026-03-31T20:31:30.163179Z","end":"2026-03-31T20:31:30.390196Z","steps":["trace[1612042701] 'agreement among raft nodes before linearized reading' (duration: 226.768448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:31:30.390218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.032314ms","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-03-31T20:31:30.39028Z","caller":"traceutil/trace.go:171","msg":"trace[1359156296] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4827; }","duration":"162.132737ms","start":"2026-03-31T20:31:30.228129Z","end":"2026-03-31T20:31:30.390262Z","steps":["trace[1359156296] 'agreement among raft nodes before linearized reading' (duration: 161.950612ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:31:30.391235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.497589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2575982"} {"level":"info","ts":"2026-03-31T20:31:30.391387Z","caller":"traceutil/trace.go:171","msg":"trace[1673387738] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:4827; }","duration":"204.668343ms","start":"2026-03-31T20:31:30.186693Z","end":"2026-03-31T20:31:30.391361Z","steps":["trace[1673387738] 'agreement among raft nodes before linearized reading' (duration: 203.296237ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:31.256873Z","caller":"traceutil/trace.go:171","msg":"trace[1851011930] transaction","detail":"{read_only:false; response_revision:4837; number_of_response:1; }","duration":"145.426303ms","start":"2026-03-31T20:31:31.111413Z","end":"2026-03-31T20:31:31.256839Z","steps":["trace[1851011930] 'process raft request' (duration: 145.152126ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:31.416203Z","caller":"traceutil/trace.go:171","msg":"trace[954212789] transaction","detail":"{read_only:false; response_revision:4838; number_of_response:1; }","duration":"155.697691ms","start":"2026-03-31T20:31:31.260472Z","end":"2026-03-31T20:31:31.416169Z","steps":["trace[954212789] 'process raft request' (duration: 129.750465ms)","trace[954212789] 'compare' (duration: 25.73365ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:31:31.826978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.377587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:31:31.827139Z","caller":"traceutil/trace.go:171","msg":"trace[542647865] linearizableReadLoop","detail":"{readStateIndex:5050; appliedIndex:5049; }","duration":"199.025696ms","start":"2026-03-31T20:31:31.628092Z","end":"2026-03-31T20:31:31.827118Z","steps":["trace[542647865] 'read index received' (duration: 34.731µs)","trace[542647865] 'applied index is now lower than readState.Index' (duration: 198.989695ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:31:31.827271Z","caller":"traceutil/trace.go:171","msg":"trace[2094394879] transaction","detail":"{read_only:false; response_revision:4844; number_of_response:1; }","duration":"319.88143ms","start":"2026-03-31T20:31:31.507372Z","end":"2026-03-31T20:31:31.827253Z","steps":["trace[2094394879] 'process raft request' (duration: 35.161055ms)","trace[2094394879] 'compare' (duration: 284.294634ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:31:31.827375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:31:31.507349Z","time spent":"319.967172ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:31:31.827593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.50466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16484"} {"level":"info","ts":"2026-03-31T20:31:31.827661Z","caller":"traceutil/trace.go:171","msg":"trace[1596420124] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4845; }","duration":"199.613123ms","start":"2026-03-31T20:31:31.628039Z","end":"2026-03-31T20:31:31.827653Z","steps":["trace[1596420124] 'agreement among raft nodes before linearized reading' (duration: 199.451978ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:34.313337Z","caller":"traceutil/trace.go:171","msg":"trace[1264987432] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"164.469728ms","start":"2026-03-31T20:31:34.148664Z","end":"2026-03-31T20:31:34.313134Z","steps":["trace[1264987432] 'process raft request' (duration: 164.244062ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:40.250968Z","caller":"traceutil/trace.go:171","msg":"trace[806398107] transaction","detail":"{read_only:false; response_revision:5000; number_of_response:1; }","duration":"100.75114ms","start":"2026-03-31T20:31:40.150195Z","end":"2026-03-31T20:31:40.250946Z","steps":["trace[806398107] 'process raft request' (duration: 100.593347ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:44.153803Z","caller":"traceutil/trace.go:171","msg":"trace[1609025854] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"137.899265ms","start":"2026-03-31T20:31:44.015876Z","end":"2026-03-31T20:31:44.153775Z","steps":["trace[1609025854] 'process raft request' (duration: 88.20134ms)","trace[1609025854] 'compare' (duration: 49.558531ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:31:44.172633Z","caller":"traceutil/trace.go:171","msg":"trace[151554418] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"156.357073ms","start":"2026-03-31T20:31:44.01625Z","end":"2026-03-31T20:31:44.172607Z","steps":["trace[151554418] 'process raft request' (duration: 156.169348ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:44.172707Z","caller":"traceutil/trace.go:171","msg":"trace[1407667069] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"156.237819ms","start":"2026-03-31T20:31:44.016459Z","end":"2026-03-31T20:31:44.172697Z","steps":["trace[1407667069] 'process raft request' (duration: 156.070895ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:31:44.17264Z","caller":"traceutil/trace.go:171","msg":"trace[132891887] transaction","detail":"{read_only:false; response_revision:5041; number_of_response:1; }","duration":"155.478869ms","start":"2026-03-31T20:31:44.017144Z","end":"2026-03-31T20:31:44.172623Z","steps":["trace[132891887] 'process raft request' (duration: 155.437218ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:32:10.967875Z","caller":"traceutil/trace.go:171","msg":"trace[1502257975] transaction","detail":"{read_only:false; response_revision:5348; number_of_response:1; }","duration":"133.614094ms","start":"2026-03-31T20:32:10.834241Z","end":"2026-03-31T20:32:10.967855Z","steps":["trace[1502257975] 'process raft request' (duration: 133.4821ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:32:10.968851Z","caller":"traceutil/trace.go:171","msg":"trace[352639555] transaction","detail":"{read_only:false; response_revision:5349; number_of_response:1; }","duration":"132.440363ms","start":"2026-03-31T20:32:10.836394Z","end":"2026-03-31T20:32:10.968834Z","steps":["trace[352639555] 'process raft request' (duration: 132.176277ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:32:11.205049Z","caller":"traceutil/trace.go:171","msg":"trace[1440842978] transaction","detail":"{read_only:false; response_revision:5352; number_of_response:1; }","duration":"185.67054ms","start":"2026-03-31T20:32:11.019345Z","end":"2026-03-31T20:32:11.205015Z","steps":["trace[1440842978] 'process raft request' (duration: 184.943851ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:32:19.890904Z","caller":"traceutil/trace.go:171","msg":"trace[2040432787] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"106.128374ms","start":"2026-03-31T20:32:19.784741Z","end":"2026-03-31T20:32:19.89087Z","steps":["trace[2040432787] 'process raft request' (duration: 100.830527ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:34:49.236746Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3235} {"level":"info","ts":"2026-03-31T20:34:49.31835Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3235,"took":"80.050556ms","hash":1079246176,"current-db-size-bytes":60223488,"current-db-size":"60 MB","current-db-size-in-use-bytes":33800192,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-03-31T20:34:49.31842Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1079246176,"revision":3235,"compact-revision":-1} {"level":"info","ts":"2026-03-31T20:37:16.582196Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000020cf.wal"} {"level":"info","ts":"2026-03-31T20:37:21.485588Z","caller":"traceutil/trace.go:171","msg":"trace[1556272610] linearizableReadLoop","detail":"{readStateIndex:8439; appliedIndex:8438; }","duration":"170.3371ms","start":"2026-03-31T20:37:21.315225Z","end":"2026-03-31T20:37:21.485562Z","steps":["trace[1556272610] 'read index received' (duration: 120.235572ms)","trace[1556272610] 'applied index is now lower than readState.Index' (duration: 50.100448ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:37:21.485673Z","caller":"traceutil/trace.go:171","msg":"trace[1435968885] transaction","detail":"{read_only:false; response_revision:8080; number_of_response:1; }","duration":"221.802041ms","start":"2026-03-31T20:37:21.26384Z","end":"2026-03-31T20:37:21.485642Z","steps":["trace[1435968885] 'process raft request' (duration: 171.704154ms)","trace[1435968885] 'compare' (duration: 49.901132ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:37:21.48583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.600356ms","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-03-31T20:37:21.485876Z","caller":"traceutil/trace.go:171","msg":"trace[744607862] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8080; }","duration":"170.705109ms","start":"2026-03-31T20:37:21.315158Z","end":"2026-03-31T20:37:21.485863Z","steps":["trace[744607862] 'agreement among raft nodes before linearized reading' (duration: 170.545745ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:37:21.485879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.712886ms","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-03-31T20:37:21.485945Z","caller":"traceutil/trace.go:171","msg":"trace[1332066552] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8080; }","duration":"161.792898ms","start":"2026-03-31T20:37:21.324137Z","end":"2026-03-31T20:37:21.48593Z","steps":["trace[1332066552] 'agreement among raft nodes before linearized reading' (duration: 161.601563ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:37:32.663403Z","caller":"traceutil/trace.go:171","msg":"trace[456795771] linearizableReadLoop","detail":"{readStateIndex:8518; appliedIndex:8517; }","duration":"300.388205ms","start":"2026-03-31T20:37:32.362992Z","end":"2026-03-31T20:37:32.66338Z","steps":["trace[456795771] 'read index received' (duration: 300.247452ms)","trace[456795771] 'applied index is now lower than readState.Index' (duration: 139.643µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:37:32.663517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:37:32.340889Z","time spent":"322.624961ms","remote":"127.0.0.1:55786","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-31T20:37:32.66384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.848887ms","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-03-31T20:37:32.663866Z","caller":"traceutil/trace.go:171","msg":"trace[460575076] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8153; }","duration":"300.886338ms","start":"2026-03-31T20:37:32.362971Z","end":"2026-03-31T20:37:32.663857Z","steps":["trace[460575076] 'agreement among raft nodes before linearized reading' (duration: 300.778065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:37:32.663885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:37:32.362938Z","time spent":"300.943359ms","remote":"127.0.0.1:55854","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":"warn","ts":"2026-03-31T20:37:32.664025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.536923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T20:37:32.664063Z","caller":"traceutil/trace.go:171","msg":"trace[585875054] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8153; }","duration":"275.606294ms","start":"2026-03-31T20:37:32.388449Z","end":"2026-03-31T20:37:32.664056Z","steps":["trace[585875054] 'agreement among raft nodes before linearized reading' (duration: 275.545682ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:37:32.664221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.409773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:37:32.66424Z","caller":"traceutil/trace.go:171","msg":"trace[1155800220] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8153; }","duration":"240.454284ms","start":"2026-03-31T20:37:32.42378Z","end":"2026-03-31T20:37:32.664234Z","steps":["trace[1155800220] 'agreement among raft nodes before linearized reading' (duration: 240.418713ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:37:32.664333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.911311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:37:32.66435Z","caller":"traceutil/trace.go:171","msg":"trace[1689193149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8153; }","duration":"246.956653ms","start":"2026-03-31T20:37:32.417388Z","end":"2026-03-31T20:37:32.664345Z","steps":["trace[1689193149] 'agreement among raft nodes before linearized reading' (duration: 246.931692ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:37:32.664482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.422804ms","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-03-31T20:37:32.664506Z","caller":"traceutil/trace.go:171","msg":"trace[1188250595] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8153; }","duration":"264.485985ms","start":"2026-03-31T20:37:32.400013Z","end":"2026-03-31T20:37:32.664499Z","steps":["trace[1188250595] 'agreement among raft nodes before linearized reading' (duration: 264.416193ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:37:33.199961Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427412238556464090,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T20:37:33.272954Z","caller":"traceutil/trace.go:171","msg":"trace[1719592273] transaction","detail":"{read_only:false; response_revision:8155; number_of_response:1; }","duration":"574.034939ms","start":"2026-03-31T20:37:32.698896Z","end":"2026-03-31T20:37:33.272931Z","steps":["trace[1719592273] 'process raft request' (duration: 553.847977ms)","trace[1719592273] 'compare' (duration: 19.661279ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:37:33.273071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:37:32.698871Z","time spent":"574.137661ms","remote":"127.0.0.1:55854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":644,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:37:33.286595Z","caller":"traceutil/trace.go:171","msg":"trace[1888584754] linearizableReadLoop","detail":"{readStateIndex:8521; appliedIndex:8519; }","duration":"587.610069ms","start":"2026-03-31T20:37:32.698958Z","end":"2026-03-31T20:37:33.286568Z","steps":["trace[1888584754] 'read index received' (duration: 553.772893ms)","trace[1888584754] 'applied index is now lower than readState.Index' (duration: 33.836326ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:37:33.286756Z","caller":"traceutil/trace.go:171","msg":"trace[1724235602] transaction","detail":"{read_only:false; response_revision:8156; number_of_response:1; }","duration":"579.800207ms","start":"2026-03-31T20:37:32.706915Z","end":"2026-03-31T20:37:33.286715Z","steps":["trace[1724235602] 'process raft request' (duration: 579.49932ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:37:33.286912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.933358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-31T20:37:33.286955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:37:32.706888Z","time spent":"579.957422ms","remote":"127.0.0.1:55854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:37:33.286979Z","caller":"traceutil/trace.go:171","msg":"trace[1119680962] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:8156; }","duration":"588.048001ms","start":"2026-03-31T20:37:32.698917Z","end":"2026-03-31T20:37:33.286965Z","steps":["trace[1119680962] 'agreement among raft nodes before linearized reading' (duration: 587.783554ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:37:33.286946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.903473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t2cpm\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-31T20:37:33.287095Z","caller":"traceutil/trace.go:171","msg":"trace[1711938373] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t2cpm; range_end:; response_count:1; response_revision:8156; }","duration":"538.093158ms","start":"2026-03-31T20:37:32.748973Z","end":"2026-03-31T20:37:33.287066Z","steps":["trace[1711938373] 'agreement among raft nodes before linearized reading' (duration: 537.833151ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:37:33.287156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:37:32.748957Z","time spent":"538.185251ms","remote":"127.0.0.1:55934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6542,"request content":"key:\"/registry/pods/openstack/keepalived-t2cpm\" "} {"level":"warn","ts":"2026-03-31T20:37:33.287028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:37:32.698886Z","time spent":"588.132204ms","remote":"127.0.0.1:55786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-03-31T20:37:33.408203Z","caller":"traceutil/trace.go:171","msg":"trace[1719214591] transaction","detail":"{read_only:false; response_revision:8158; number_of_response:1; }","duration":"113.95142ms","start":"2026-03-31T20:37:33.294219Z","end":"2026-03-31T20:37:33.40817Z","steps":["trace[1719214591] 'process raft request' (duration: 89.167188ms)","trace[1719214591] 'compare' (duration: 24.538215ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:37:34.21842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.837819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:37:34.218543Z","caller":"traceutil/trace.go:171","msg":"trace[609691694] linearizableReadLoop","detail":"{readStateIndex:8527; appliedIndex:8526; }","duration":"567.794667ms","start":"2026-03-31T20:37:33.650732Z","end":"2026-03-31T20:37:34.218527Z","steps":["trace[609691694] 'read index received' (duration: 378.810515ms)","trace[609691694] 'applied index is now lower than readState.Index' (duration: 188.982342ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:37:34.218574Z","caller":"traceutil/trace.go:171","msg":"trace[614849370] transaction","detail":"{read_only:false; response_revision:8162; number_of_response:1; }","duration":"620.712677ms","start":"2026-03-31T20:37:33.597827Z","end":"2026-03-31T20:37:34.21854Z","steps":["trace[614849370] 'process raft request' (duration: 431.655794ms)","trace[614849370] 'compare' (duration: 188.674164ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:37:34.218696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:37:33.59781Z","time spent":"620.825221ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:37:34.21877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.020412ms","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-03-31T20:37:34.218862Z","caller":"traceutil/trace.go:171","msg":"trace[180912642] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8162; }","duration":"568.143036ms","start":"2026-03-31T20:37:33.650686Z","end":"2026-03-31T20:37:34.218829Z","steps":["trace[180912642] 'agreement among raft nodes before linearized reading' (duration: 567.92852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:37:34.218771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.642158ms","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-03-31T20:37:34.218838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.773283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"} {"level":"warn","ts":"2026-03-31T20:37:34.218901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:37:33.650663Z","time spent":"568.229058ms","remote":"127.0.0.1:56002","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-03-31T20:37:34.218924Z","caller":"traceutil/trace.go:171","msg":"trace[987343518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8162; }","duration":"561.847253ms","start":"2026-03-31T20:37:33.657064Z","end":"2026-03-31T20:37:34.218911Z","steps":["trace[987343518] 'agreement among raft nodes before linearized reading' (duration: 561.570106ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:37:34.218939Z","caller":"traceutil/trace.go:171","msg":"trace[208292882] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8162; }","duration":"269.920508ms","start":"2026-03-31T20:37:33.949Z","end":"2026-03-31T20:37:34.218921Z","steps":["trace[208292882] 'agreement among raft nodes before linearized reading' (duration: 269.65903ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:37:34.21898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:37:33.657054Z","time spent":"561.914175ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T20:38:01.707767Z","caller":"traceutil/trace.go:171","msg":"trace[1149004377] transaction","detail":"{read_only:false; response_revision:8399; number_of_response:1; }","duration":"189.948627ms","start":"2026-03-31T20:38:01.51778Z","end":"2026-03-31T20:38:01.707729Z","steps":["trace[1149004377] 'process raft request' (duration: 189.707691ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:38:01.708016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.216184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t2cpm\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-31T20:38:01.708132Z","caller":"traceutil/trace.go:171","msg":"trace[413825789] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t2cpm; range_end:; response_count:1; response_revision:8399; }","duration":"156.376798ms","start":"2026-03-31T20:38:01.551732Z","end":"2026-03-31T20:38:01.708108Z","steps":["trace[413825789] 'agreement among raft nodes before linearized reading' (duration: 156.10935ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:38:01.708373Z","caller":"traceutil/trace.go:171","msg":"trace[758274281] linearizableReadLoop","detail":"{readStateIndex:8776; appliedIndex:8775; }","duration":"155.892256ms","start":"2026-03-31T20:38:01.551768Z","end":"2026-03-31T20:38:01.70766Z","steps":["trace[758274281] 'read index received' (duration: 154.433048ms)","trace[758274281] 'applied index is now lower than readState.Index' (duration: 1.458258ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:38:14.10062Z","caller":"traceutil/trace.go:171","msg":"trace[314261867] transaction","detail":"{read_only:false; response_revision:8527; number_of_response:1; }","duration":"103.54239ms","start":"2026-03-31T20:38:13.99705Z","end":"2026-03-31T20:38:14.100593Z","steps":["trace[314261867] 'process raft request' (duration: 103.387347ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:38:14.581705Z","caller":"traceutil/trace.go:171","msg":"trace[909261649] transaction","detail":"{read_only:false; response_revision:8531; number_of_response:1; }","duration":"100.390878ms","start":"2026-03-31T20:38:14.481292Z","end":"2026-03-31T20:38:14.581682Z","steps":["trace[909261649] 'process raft request' (duration: 100.339346ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:38:14.58184Z","caller":"traceutil/trace.go:171","msg":"trace[181797719] transaction","detail":"{read_only:false; response_revision:8530; number_of_response:1; }","duration":"100.590494ms","start":"2026-03-31T20:38:14.48122Z","end":"2026-03-31T20:38:14.581811Z","steps":["trace[181797719] 'process raft request' (duration: 100.276856ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:38:15.09961Z","caller":"traceutil/trace.go:171","msg":"trace[1649388074] linearizableReadLoop","detail":"{readStateIndex:8918; appliedIndex:8917; }","duration":"131.601287ms","start":"2026-03-31T20:38:14.96799Z","end":"2026-03-31T20:38:15.099591Z","steps":["trace[1649388074] 'read index received' (duration: 131.525005ms)","trace[1649388074] 'applied index is now lower than readState.Index' (duration: 75.412µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:38:15.09968Z","caller":"traceutil/trace.go:171","msg":"trace[2109868141] transaction","detail":"{read_only:false; response_revision:8532; number_of_response:1; }","duration":"136.595365ms","start":"2026-03-31T20:38:14.963057Z","end":"2026-03-31T20:38:15.099652Z","steps":["trace[2109868141] 'process raft request' (duration: 136.417791ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:38:15.100091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.077358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-rvxnv\" ","response":"range_response_count:1 size:4614"} {"level":"info","ts":"2026-03-31T20:38:15.100165Z","caller":"traceutil/trace.go:171","msg":"trace[1613369519] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-rvxnv; range_end:; response_count:1; response_revision:8532; }","duration":"132.186382ms","start":"2026-03-31T20:38:14.967957Z","end":"2026-03-31T20:38:15.100144Z","steps":["trace[1613369519] 'agreement among raft nodes before linearized reading' (duration: 131.71226ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:38:15.209992Z","caller":"traceutil/trace.go:171","msg":"trace[1130046944] transaction","detail":"{read_only:false; response_revision:8533; number_of_response:1; }","duration":"241.916351ms","start":"2026-03-31T20:38:14.968044Z","end":"2026-03-31T20:38:15.20996Z","steps":["trace[1130046944] 'process raft request' (duration: 184.145376ms)","trace[1130046944] 'compare' (duration: 57.595801ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:38:16.295153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427412238556465219,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:38:16.589167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.69424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:38:16.589374Z","caller":"traceutil/trace.go:171","msg":"trace[606948749] transaction","detail":"{read_only:false; response_revision:8537; number_of_response:1; }","duration":"811.876875ms","start":"2026-03-31T20:38:15.777471Z","end":"2026-03-31T20:38:16.589348Z","steps":["trace[606948749] 'process raft request' (duration: 553.883316ms)","trace[606948749] 'compare' (duration: 256.914441ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:38:16.589521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:38:15.777444Z","time spent":"812.025729ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:38:16.63782Z","caller":"traceutil/trace.go:171","msg":"trace[359671029] linearizableReadLoop","detail":"{readStateIndex:8925; appliedIndex:8923; }","duration":"842.9572ms","start":"2026-03-31T20:38:15.794834Z","end":"2026-03-31T20:38:16.637791Z","steps":["trace[359671029] 'read index received' (duration: 536.498697ms)","trace[359671029] 'applied index is now lower than readState.Index' (duration: 306.457363ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:38:16.637966Z","caller":"traceutil/trace.go:171","msg":"trace[1652538621] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"853.935914ms","start":"2026-03-31T20:38:15.784019Z","end":"2026-03-31T20:38:16.637955Z","steps":["trace[1652538621] 'process raft request' (duration: 853.569095ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:38:16.63809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:38:15.784001Z","time spent":"854.003565ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:38:16.638144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.644332ms","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-03-31T20:38:16.638277Z","caller":"traceutil/trace.go:171","msg":"trace[1135097034] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8538; }","duration":"776.816268ms","start":"2026-03-31T20:38:15.861431Z","end":"2026-03-31T20:38:16.638247Z","steps":["trace[1135097034] 'agreement among raft nodes before linearized reading' (duration: 776.579961ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:38:16.638339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:38:15.861414Z","time spent":"776.91042ms","remote":"127.0.0.1:55854","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":"warn","ts":"2026-03-31T20:38:16.638489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.055131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-03-31T20:38:16.638485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.579436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t2cpm\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-31T20:38:16.638581Z","caller":"traceutil/trace.go:171","msg":"trace[918397904] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:8538; }","duration":"724.215895ms","start":"2026-03-31T20:38:15.914343Z","end":"2026-03-31T20:38:16.638559Z","steps":["trace[918397904] 'agreement among raft nodes before linearized reading' (duration: 724.026141ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:38:16.638611Z","caller":"traceutil/trace.go:171","msg":"trace[1884011658] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t2cpm; range_end:; response_count:1; response_revision:8538; }","duration":"843.801901ms","start":"2026-03-31T20:38:15.794777Z","end":"2026-03-31T20:38:16.638579Z","steps":["trace[1884011658] 'agreement among raft nodes before linearized reading' (duration: 843.480313ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:38:16.63864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:38:15.914328Z","time spent":"724.297768ms","remote":"127.0.0.1:55894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"warn","ts":"2026-03-31T20:38:16.638542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.294537ms","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-03-31T20:38:16.638769Z","caller":"traceutil/trace.go:171","msg":"trace[1361732688] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8538; }","duration":"773.563283ms","start":"2026-03-31T20:38:15.865186Z","end":"2026-03-31T20:38:16.638749Z","steps":["trace[1361732688] 'agreement among raft nodes before linearized reading' (duration: 773.230315ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:38:16.638865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:38:15.865167Z","time spent":"773.681507ms","remote":"127.0.0.1:55854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-03-31T20:38:16.638596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.919662ms","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-03-31T20:38:16.639041Z","caller":"traceutil/trace.go:171","msg":"trace[83928039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8538; }","duration":"157.419105ms","start":"2026-03-31T20:38:16.481604Z","end":"2026-03-31T20:38:16.639023Z","steps":["trace[83928039] 'agreement among raft nodes before linearized reading' (duration: 156.621943ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:38:16.638688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:38:15.794757Z","time spent":"843.914594ms","remote":"127.0.0.1:55934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6542,"request content":"key:\"/registry/pods/openstack/keepalived-t2cpm\" "} {"level":"info","ts":"2026-03-31T20:38:16.762506Z","caller":"traceutil/trace.go:171","msg":"trace[654894627] transaction","detail":"{read_only:false; response_revision:8540; number_of_response:1; }","duration":"119.236036ms","start":"2026-03-31T20:38:16.643196Z","end":"2026-03-31T20:38:16.762432Z","steps":["trace[654894627] 'process raft request' (duration: 69.937609ms)","trace[654894627] 'compare' (duration: 49.106872ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:38:16.76282Z","caller":"traceutil/trace.go:171","msg":"trace[348166161] transaction","detail":"{read_only:false; response_revision:8541; number_of_response:1; }","duration":"119.594055ms","start":"2026-03-31T20:38:16.643194Z","end":"2026-03-31T20:38:16.762789Z","steps":["trace[348166161] 'process raft request' (duration: 119.192215ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:38:16.762941Z","caller":"traceutil/trace.go:171","msg":"trace[876392045] transaction","detail":"{read_only:false; response_revision:8542; number_of_response:1; }","duration":"119.721319ms","start":"2026-03-31T20:38:16.643197Z","end":"2026-03-31T20:38:16.762918Z","steps":["trace[876392045] 'process raft request' (duration: 119.507113ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:38:16.763059Z","caller":"traceutil/trace.go:171","msg":"trace[797467013] transaction","detail":"{read_only:false; response_revision:8543; number_of_response:1; }","duration":"115.681864ms","start":"2026-03-31T20:38:16.64735Z","end":"2026-03-31T20:38:16.763032Z","steps":["trace[797467013] 'process raft request' (duration: 115.501938ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:38:16.803448Z","caller":"traceutil/trace.go:171","msg":"trace[469492600] transaction","detail":"{read_only:false; response_revision:8544; number_of_response:1; }","duration":"116.038613ms","start":"2026-03-31T20:38:16.687388Z","end":"2026-03-31T20:38:16.803427Z","steps":["trace[469492600] 'process raft request' (duration: 115.873699ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:38:38.372063Z","caller":"traceutil/trace.go:171","msg":"trace[995165427] transaction","detail":"{read_only:false; response_revision:8814; number_of_response:1; }","duration":"206.906596ms","start":"2026-03-31T20:38:38.165133Z","end":"2026-03-31T20:38:38.37204Z","steps":["trace[995165427] 'process raft request' (duration: 206.71228ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:38:38.375671Z","caller":"traceutil/trace.go:171","msg":"trace[1843974873] transaction","detail":"{read_only:false; response_revision:8815; number_of_response:1; }","duration":"189.129005ms","start":"2026-03-31T20:38:38.186518Z","end":"2026-03-31T20:38:38.375647Z","steps":["trace[1843974873] 'process raft request' (duration: 189.005352ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:22.564425Z","caller":"traceutil/trace.go:171","msg":"trace[1963933099] transaction","detail":"{read_only:false; response_revision:9261; number_of_response:1; }","duration":"212.417097ms","start":"2026-03-31T20:39:22.351986Z","end":"2026-03-31T20:39:22.564403Z","steps":["trace[1963933099] 'process raft request' (duration: 212.269994ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:22.776258Z","caller":"traceutil/trace.go:171","msg":"trace[1720700017] linearizableReadLoop","detail":"{readStateIndex:9680; appliedIndex:9678; }","duration":"356.653982ms","start":"2026-03-31T20:39:22.419575Z","end":"2026-03-31T20:39:22.776229Z","steps":["trace[1720700017] 'read index received' (duration: 144.670475ms)","trace[1720700017] 'applied index is now lower than readState.Index' (duration: 211.980777ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:39:22.77628Z","caller":"traceutil/trace.go:171","msg":"trace[1963158771] transaction","detail":"{read_only:false; response_revision:9262; number_of_response:1; }","duration":"399.08644ms","start":"2026-03-31T20:39:22.377155Z","end":"2026-03-31T20:39:22.776242Z","steps":["trace[1963158771] 'process raft request' (duration: 359.405132ms)","trace[1963158771] 'compare' (duration: 39.344518ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:39:22.776405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.819696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:39:22.776447Z","caller":"traceutil/trace.go:171","msg":"trace[118054817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9262; }","duration":"356.917368ms","start":"2026-03-31T20:39:22.419518Z","end":"2026-03-31T20:39:22.776436Z","steps":["trace[118054817] 'agreement among raft nodes before linearized reading' (duration: 356.802986ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:39:22.776476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:39:22.377131Z","time spent":"399.262964ms","remote":"127.0.0.1:55786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:39:22.776485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:39:22.4195Z","time spent":"356.97688ms","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T20:39:22.80199Z","caller":"traceutil/trace.go:171","msg":"trace[1088692093] transaction","detail":"{read_only:false; number_of_response:0; response_revision:9262; }","duration":"230.883636ms","start":"2026-03-31T20:39:22.57109Z","end":"2026-03-31T20:39:22.801974Z","steps":["trace[1088692093] 'process raft request' (duration: 230.755233ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:22.982302Z","caller":"traceutil/trace.go:171","msg":"trace[2037758063] transaction","detail":"{read_only:false; response_revision:9263; number_of_response:1; }","duration":"170.351099ms","start":"2026-03-31T20:39:22.811906Z","end":"2026-03-31T20:39:22.982257Z","steps":["trace[2037758063] 'process raft request' (duration: 169.919997ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:39:49.245704Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6600} {"level":"info","ts":"2026-03-31T20:39:49.345392Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6600,"took":"95.876482ms","hash":3835031422,"current-db-size-bytes":60223488,"current-db-size":"60 MB","current-db-size-in-use-bytes":26963968,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-03-31T20:39:49.345482Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3835031422,"revision":6600,"compact-revision":3235} {"level":"info","ts":"2026-03-31T20:39:56.982755Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"41c06612bf92f90","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-31T20:39:56.987848Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-31T20:39:56.987919Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-03-31T20:40:51.881345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.401555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t2cpm\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-03-31T20:40:51.881439Z","caller":"traceutil/trace.go:171","msg":"trace[805873540] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t2cpm; range_end:; response_count:1; response_revision:10086; }","duration":"285.509148ms","start":"2026-03-31T20:40:51.595908Z","end":"2026-03-31T20:40:51.881417Z","steps":["trace[805873540] 'range keys from in-memory index tree' (duration: 285.303863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:51.881511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.047315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-31T20:40:51.881525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.719022ms","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-03-31T20:40:51.881593Z","caller":"traceutil/trace.go:171","msg":"trace[653911027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10086; }","duration":"359.830384ms","start":"2026-03-31T20:40:51.521743Z","end":"2026-03-31T20:40:51.881573Z","steps":["trace[653911027] 'range keys from in-memory index tree' (duration: 359.531467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:51.881651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.616897ms","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-03-31T20:40:51.881886Z","caller":"traceutil/trace.go:171","msg":"trace[283516619] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:10086; }","duration":"362.866533ms","start":"2026-03-31T20:40:51.519006Z","end":"2026-03-31T20:40:51.881873Z","steps":["trace[283516619] 'range keys from in-memory index tree' (duration: 362.513054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:51.881649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:51.52172Z","time spent":"359.917087ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T20:40:51.881492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.93305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-03-31T20:40:51.882049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:51.518997Z","time spent":"362.928944ms","remote":"127.0.0.1:56002","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-03-31T20:40:51.882124Z","caller":"traceutil/trace.go:171","msg":"trace[498934409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10086; }","duration":"364.655389ms","start":"2026-03-31T20:40:51.51745Z","end":"2026-03-31T20:40:51.882106Z","steps":["trace[498934409] 'range keys from in-memory index tree' (duration: 363.763075ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:40:51.881749Z","caller":"traceutil/trace.go:171","msg":"trace[488374303] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:10086; }","duration":"261.169538ms","start":"2026-03-31T20:40:51.620379Z","end":"2026-03-31T20:40:51.881549Z","steps":["trace[488374303] 'count revisions from in-memory index tree' (duration: 260.968992ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:51.882173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:51.517432Z","time spent":"364.727052ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T20:40:53.920023Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427412238556469452,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:40:54.42106Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427412238556469452,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:40:55.937664Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427412238556469452,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:40:55.938115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.518674417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-31T20:40:55.938187Z","caller":"traceutil/trace.go:171","msg":"trace[375588925] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.51879655s","start":"2026-03-31T20:40:53.419373Z","end":"2026-03-31T20:40:55.93817Z","steps":["trace[375588925] 'agreement among raft nodes before linearized reading' (duration: 2.518713048s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:55.93822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:53.419343Z","time spent":"2.518869382s","remote":"127.0.0.1:46964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T20:40:56.43874Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427412238556469452,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:40:56.44525Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.069190022s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T20:40:56.445682Z","caller":"traceutil/trace.go:171","msg":"trace[932334288] transaction","detail":"{read_only:false; response_revision:10097; number_of_response:1; }","duration":"3.069718686s","start":"2026-03-31T20:40:53.375939Z","end":"2026-03-31T20:40:56.445657Z","steps":["trace[932334288] 'process raft request' (duration: 3.069656685s)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:40:56.44569Z","caller":"traceutil/trace.go:171","msg":"trace[1911202405] transaction","detail":"{read_only:false; response_revision:10096; number_of_response:1; }","duration":"3.069752647s","start":"2026-03-31T20:40:53.375914Z","end":"2026-03-31T20:40:56.445667Z","steps":["trace[1911202405] 'process raft request' (duration: 3.069533792s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:56.445818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:53.375896Z","time spent":"3.069853309s","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:40:56.44584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:53.375919Z","time spent":"3.069822259s","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:40:56.45837Z","caller":"traceutil/trace.go:171","msg":"trace[284698424] transaction","detail":"{read_only:false; response_revision:10098; number_of_response:1; }","duration":"2.935621861s","start":"2026-03-31T20:40:53.52272Z","end":"2026-03-31T20:40:56.458342Z","steps":["trace[284698424] 'process raft request' (duration: 2.935284262s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:56.458583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:53.522701Z","time spent":"2.935781305s","remote":"127.0.0.1:42758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:40:56.458655Z","caller":"traceutil/trace.go:171","msg":"trace[1130944290] linearizableReadLoop","detail":"{readStateIndex:10557; appliedIndex:10552; }","duration":"3.039196747s","start":"2026-03-31T20:40:53.419432Z","end":"2026-03-31T20:40:56.458629Z","steps":["trace[1130944290] 'read index received' (duration: 3.026077346s)","trace[1130944290] 'applied index is now lower than readState.Index' (duration: 13.118151ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:40:56.458707Z","caller":"traceutil/trace.go:171","msg":"trace[1336454831] transaction","detail":"{read_only:false; response_revision:10099; number_of_response:1; }","duration":"1.863642413s","start":"2026-03-31T20:40:54.595042Z","end":"2026-03-31T20:40:56.458684Z","steps":["trace[1336454831] 'process raft request' (duration: 1.863220522s)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:40:56.458806Z","caller":"traceutil/trace.go:171","msg":"trace[1428373046] transaction","detail":"{read_only:false; response_revision:10100; number_of_response:1; }","duration":"424.340396ms","start":"2026-03-31T20:40:56.034379Z","end":"2026-03-31T20:40:56.458719Z","steps":["trace[1428373046] 'process raft request' (duration: 424.13226ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:56.458833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:54.595015Z","time spent":"1.863739226s","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:40:56.458877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.14099311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:56.458944Z","caller":"traceutil/trace.go:171","msg":"trace[256998662] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:10100; }","duration":"2.141086932s","start":"2026-03-31T20:40:54.317838Z","end":"2026-03-31T20:40:56.458925Z","steps":["trace[256998662] 'agreement among raft nodes before linearized reading' (duration: 2.140976459s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:56.459002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:54.317824Z","time spent":"2.141161213s","remote":"127.0.0.1:42702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true "} {"level":"warn","ts":"2026-03-31T20:40:56.459008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.845503836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:56.459082Z","caller":"traceutil/trace.go:171","msg":"trace[1703706966] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:10100; }","duration":"2.845608748s","start":"2026-03-31T20:40:53.613454Z","end":"2026-03-31T20:40:56.459063Z","steps":["trace[1703706966] 'agreement among raft nodes before linearized reading' (duration: 2.845462775s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:56.459132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.012813061s","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-03-31T20:40:56.458942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:56.034356Z","time spent":"424.51933ms","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:40:56.459142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:53.613429Z","time spent":"2.84569905s","remote":"127.0.0.1:42672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true "} {"level":"warn","ts":"2026-03-31T20:40:56.459179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.556856407s","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-03-31T20:40:56.4592Z","caller":"traceutil/trace.go:171","msg":"trace[1992733286] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10100; }","duration":"3.012944754s","start":"2026-03-31T20:40:53.446239Z","end":"2026-03-31T20:40:56.459184Z","steps":["trace[1992733286] 'agreement among raft nodes before linearized reading' (duration: 3.012793361s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:56.459196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.017367959s","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-03-31T20:40:56.459104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.557454612s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T20:40:56.459239Z","caller":"traceutil/trace.go:171","msg":"trace[944883630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10100; }","duration":"3.017430711s","start":"2026-03-31T20:40:53.441798Z","end":"2026-03-31T20:40:56.459229Z","steps":["trace[944883630] 'agreement among raft nodes before linearized reading' (duration: 3.017274937s)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:40:56.459239Z","caller":"traceutil/trace.go:171","msg":"trace[468717125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10100; }","duration":"2.556967679s","start":"2026-03-31T20:40:53.902253Z","end":"2026-03-31T20:40:56.459221Z","steps":["trace[468717125] 'agreement among raft nodes before linearized reading' (duration: 2.556766824s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:56.459251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.557039951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-03-31T20:40:56.459269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:53.441789Z","time spent":"3.017475043s","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T20:40:56.459292Z","caller":"traceutil/trace.go:171","msg":"trace[2065074010] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:10100; }","duration":"2.557093624s","start":"2026-03-31T20:40:53.902187Z","end":"2026-03-31T20:40:56.459281Z","steps":["trace[2065074010] 'agreement among raft nodes before linearized reading' (duration: 2.556946889s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:56.459283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:53.902234Z","time spent":"2.557036561s","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T20:40:56.459075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251041428s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"} {"level":"warn","ts":"2026-03-31T20:40:56.459326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:53.902179Z","time spent":"2.557139434s","remote":"127.0.0.1:56002","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-03-31T20:40:56.459337Z","caller":"traceutil/trace.go:171","msg":"trace[1357843125] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:10100; }","duration":"1.251383558s","start":"2026-03-31T20:40:55.207946Z","end":"2026-03-31T20:40:56.45933Z","steps":["trace[1357843125] 'agreement among raft nodes before linearized reading' (duration: 1.250872334s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:56.458993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.022526883s","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-03-31T20:40:56.459359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:55.207922Z","time spent":"1.251430499s","remote":"127.0.0.1:55934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11521,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-03-31T20:40:56.459344Z","caller":"traceutil/trace.go:171","msg":"trace[802213613] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:10100; }","duration":"2.557759239s","start":"2026-03-31T20:40:53.901503Z","end":"2026-03-31T20:40:56.459262Z","steps":["trace[802213613] 'agreement among raft nodes before linearized reading' (duration: 2.557355899s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:56.459236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:53.446223Z","time spent":"3.013005186s","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-31T20:40:56.459202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.551812295s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-t2cpm\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-03-31T20:40:56.459361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.685189438s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-03-31T20:40:56.459554Z","caller":"traceutil/trace.go:171","msg":"trace[1938174450] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:10100; }","duration":"1.685422446s","start":"2026-03-31T20:40:54.774112Z","end":"2026-03-31T20:40:56.459535Z","steps":["trace[1938174450] 'agreement among raft nodes before linearized reading' (duration: 1.685105367s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:56.459353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.559926106s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-03-31T20:40:56.459607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:54.774089Z","time spent":"1.685503627s","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-03-31T20:40:56.459447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:53.901475Z","time spent":"2.557951155s","remote":"127.0.0.1:33980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":31,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "} {"level":"info","ts":"2026-03-31T20:40:56.459602Z","caller":"traceutil/trace.go:171","msg":"trace[1835010656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:10100; }","duration":"2.560251194s","start":"2026-03-31T20:40:53.899342Z","end":"2026-03-31T20:40:56.459593Z","steps":["trace[1835010656] 'agreement among raft nodes before linearized reading' (duration: 2.559915475s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:56.459699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:53.899311Z","time spent":"2.560377618s","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T20:40:56.459427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.162351093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:9 size:77882"} {"level":"info","ts":"2026-03-31T20:40:56.45975Z","caller":"traceutil/trace.go:171","msg":"trace[1740450194] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:9; response_revision:10100; }","duration":"2.162684732s","start":"2026-03-31T20:40:54.297045Z","end":"2026-03-31T20:40:56.459729Z","steps":["trace[1740450194] 'agreement among raft nodes before linearized reading' (duration: 2.162146667s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:56.459807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:54.296969Z","time spent":"2.162821715s","remote":"127.0.0.1:56194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":9,"response size":77904,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"warn","ts":"2026-03-31T20:40:56.459457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.966257553s","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-03-31T20:40:56.459864Z","caller":"traceutil/trace.go:171","msg":"trace[534022962] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:10100; }","duration":"1.966713453s","start":"2026-03-31T20:40:54.49313Z","end":"2026-03-31T20:40:56.459843Z","steps":["trace[534022962] 'agreement among raft nodes before linearized reading' (duration: 1.966264992s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:56.459935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:54.493095Z","time spent":"1.966823006s","remote":"127.0.0.1:55854","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":"warn","ts":"2026-03-31T20:40:56.459462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.40126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:40:56.459575Z","caller":"traceutil/trace.go:171","msg":"trace[2123189092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10100; }","duration":"3.022989375s","start":"2026-03-31T20:40:53.436409Z","end":"2026-03-31T20:40:56.459398Z","steps":["trace[2123189092] 'agreement among raft nodes before linearized reading' (duration: 3.02241093s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:56.460272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:53.436392Z","time spent":"3.023854847s","remote":"127.0.0.1:56002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-31T20:40:56.460218Z","caller":"traceutil/trace.go:171","msg":"trace[1844854620] range","detail":"{range_begin:/registry/pods/openstack/keepalived-t2cpm; range_end:; response_count:1; response_revision:10100; }","duration":"2.552909974s","start":"2026-03-31T20:40:53.907284Z","end":"2026-03-31T20:40:56.460194Z","steps":["trace[1844854620] 'agreement among raft nodes before linearized reading' (duration: 2.55161209s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:56.460436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:53.907254Z","time spent":"2.55316374s","remote":"127.0.0.1:55934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6542,"request content":"key:\"/registry/pods/openstack/keepalived-t2cpm\" "} {"level":"info","ts":"2026-03-31T20:40:56.46025Z","caller":"traceutil/trace.go:171","msg":"trace[776951460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10100; }","duration":"520.044396ms","start":"2026-03-31T20:40:55.94005Z","end":"2026-03-31T20:40:56.460095Z","steps":["trace[776951460] 'agreement among raft nodes before linearized reading' (duration: 519.131392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:40:56.460606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:40:55.939972Z","time spent":"520.623171ms","remote":"127.0.0.1:46976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T20:44:49.253181Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9494} {"level":"info","ts":"2026-03-31T20:44:49.338004Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9494,"took":"81.698296ms","hash":964683180,"current-db-size-bytes":60223488,"current-db-size":"60 MB","current-db-size-in-use-bytes":25096192,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-31T20:44:49.338103Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":964683180,"revision":9494,"compact-revision":6600}