{"level":"warn","ts":"2026-04-07T12:59:35.613194Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T12:59:35.613295Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.39: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.39:2380","--initial-cluster=instance=https://199.19.213.39:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.39:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.39:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T12:59:35.613398Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T12:59:35.613418Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.39:2380"]} {"level":"info","ts":"2026-04-07T12:59:35.613442Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T12:59:35.614181Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.39:2379"]} {"level":"info","ts":"2026-04-07T12:59:35.614263Z","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.39:2380"],"listen-peer-urls":["https://199.19.213.39:2380"],"advertise-client-urls":["https://199.19.213.39:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.39:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.39:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T12:59:35.619399Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.887125ms"} {"level":"info","ts":"2026-04-07T12:59:35.627122Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"95026ee9c61f87d3","cluster-id":"98e25849a4b355e0"} {"level":"info","ts":"2026-04-07T12:59:35.627323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T12:59:35.627364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 became follower at term 0"} {"level":"info","ts":"2026-04-07T12:59:35.627403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 95026ee9c61f87d3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T12:59:35.627417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 became follower at term 1"} {"level":"info","ts":"2026-04-07T12:59:35.627483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 switched to configuration voters=(10737266411935074259)"} {"level":"warn","ts":"2026-04-07T12:59:35.633774Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T12:59:35.635978Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T12:59:35.637118Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-07T12:59:35.639764Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"95026ee9c61f87d3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T12:59:35.640607Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T12:59:35.640666Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"95026ee9c61f87d3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T12:59:35.640722Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T12:59:35.64076Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T12:59:35.641844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 switched to configuration voters=(10737266411935074259)"} {"level":"info","ts":"2026-04-07T12:59:35.642062Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98e25849a4b355e0","local-member-id":"95026ee9c61f87d3","added-peer-id":"95026ee9c61f87d3","added-peer-peer-urls":["https://199.19.213.39:2380"]} {"level":"info","ts":"2026-04-07T12:59:35.645628Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T12:59:35.645983Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.39:2380"} {"level":"info","ts":"2026-04-07T12:59:35.646012Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.39:2380"} {"level":"info","ts":"2026-04-07T12:59:35.646009Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"95026ee9c61f87d3","initial-advertise-peer-urls":["https://199.19.213.39:2380"],"listen-peer-urls":["https://199.19.213.39:2380"],"advertise-client-urls":["https://199.19.213.39:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.39:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T12:59:35.646054Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T12:59:36.328123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T12:59:36.32819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T12:59:36.328225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 received MsgPreVoteResp from 95026ee9c61f87d3 at term 1"} {"level":"info","ts":"2026-04-07T12:59:36.328244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 became candidate at term 2"} {"level":"info","ts":"2026-04-07T12:59:36.328253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 received MsgVoteResp from 95026ee9c61f87d3 at term 2"} {"level":"info","ts":"2026-04-07T12:59:36.328267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95026ee9c61f87d3 became leader at term 2"} {"level":"info","ts":"2026-04-07T12:59:36.328284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 95026ee9c61f87d3 elected leader 95026ee9c61f87d3 at term 2"} {"level":"info","ts":"2026-04-07T12:59:36.329332Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"95026ee9c61f87d3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.39:2379]}","request-path":"/0/members/95026ee9c61f87d3/attributes","cluster-id":"98e25849a4b355e0","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T12:59:36.329494Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:59:36.32956Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:59:36.329819Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:59:36.330991Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98e25849a4b355e0","local-member-id":"95026ee9c61f87d3","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:59:36.331132Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:59:36.331165Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:59:36.332552Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.39:2379"} {"level":"info","ts":"2026-04-07T12:59:36.332651Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T12:59:36.332756Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T12:59:36.332799Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T12:59:50.684802Z","caller":"traceutil/trace.go:171","msg":"trace[625275304] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"438.754188ms","start":"2026-04-07T12:59:50.24601Z","end":"2026-04-07T12:59:50.684764Z","steps":["trace[625275304] 'process raft request' (duration: 438.549144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:50.686196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:50.245995Z","time spent":"438.934812ms","remote":"127.0.0.1:59594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:59:51.086225Z","caller":"traceutil/trace.go:171","msg":"trace[1267935334] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"385.972484ms","start":"2026-04-07T12:59:50.700199Z","end":"2026-04-07T12:59:51.086172Z","steps":["trace[1267935334] 'process raft request' (duration: 377.280326ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:59:51.086096Z","caller":"traceutil/trace.go:171","msg":"trace[1715278482] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"304.681202ms","start":"2026-04-07T12:59:50.781372Z","end":"2026-04-07T12:59:51.086053Z","steps":["trace[1715278482] 'read index received' (duration: 295.966783ms)","trace[1715278482] 'applied index is now lower than readState.Index' (duration: 8.712569ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:59:51.086455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:50.700124Z","time spent":"386.217089ms","remote":"127.0.0.1:59594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7087,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:59:51.086643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.987679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:59:51.086992Z","caller":"traceutil/trace.go:171","msg":"trace[1212485327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"305.623053ms","start":"2026-04-07T12:59:50.781349Z","end":"2026-04-07T12:59:51.086972Z","steps":["trace[1212485327] 'agreement among raft nodes before linearized reading' (duration: 304.911437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:59:51.087083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:59:50.781298Z","time spent":"305.771216ms","remote":"127.0.0.1:59424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T13:00:13.942652Z","caller":"traceutil/trace.go:171","msg":"trace[187531444] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"160.003692ms","start":"2026-04-07T13:00:13.782612Z","end":"2026-04-07T13:00:13.942615Z","steps":["trace[187531444] 'read index received' (duration: 158.859718ms)","trace[187531444] 'applied index is now lower than readState.Index' (duration: 1.142634ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:00:13.942759Z","caller":"traceutil/trace.go:171","msg":"trace[1297403754] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"210.178269ms","start":"2026-04-07T13:00:13.732526Z","end":"2026-04-07T13:00:13.942704Z","steps":["trace[1297403754] 'process raft request' (duration: 209.25347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:13.943012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.354969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:13.94314Z","caller":"traceutil/trace.go:171","msg":"trace[1311926089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"160.558354ms","start":"2026-04-07T13:00:13.78255Z","end":"2026-04-07T13:00:13.943109Z","steps":["trace[1311926089] 'agreement among raft nodes before linearized reading' (duration: 160.265107ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:14.134854Z","caller":"traceutil/trace.go:171","msg":"trace[1790128724] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"110.346067ms","start":"2026-04-07T13:00:14.024469Z","end":"2026-04-07T13:00:14.134815Z","steps":["trace[1790128724] 'read index received' (duration: 110.104962ms)","trace[1790128724] 'applied index is now lower than readState.Index' (duration: 239.665µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:00:14.134963Z","caller":"traceutil/trace.go:171","msg":"trace[154006406] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"113.731758ms","start":"2026-04-07T13:00:14.021187Z","end":"2026-04-07T13:00:14.134919Z","steps":["trace[154006406] 'process raft request' (duration: 113.423672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:14.135228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.726295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T13:00:14.135322Z","caller":"traceutil/trace.go:171","msg":"trace[1423597253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:584; }","duration":"110.868248ms","start":"2026-04-07T13:00:14.024415Z","end":"2026-04-07T13:00:14.135283Z","steps":["trace[1423597253] 'agreement among raft nodes before linearized reading' (duration: 110.532361ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:14.276983Z","caller":"traceutil/trace.go:171","msg":"trace[662696189] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"129.650978ms","start":"2026-04-07T13:00:14.147295Z","end":"2026-04-07T13:00:14.276946Z","steps":["trace[662696189] 'read index received' (duration: 68.066258ms)","trace[662696189] 'applied index is now lower than readState.Index' (duration: 61.58326ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:00:14.277105Z","caller":"traceutil/trace.go:171","msg":"trace[1928085017] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"137.280889ms","start":"2026-04-07T13:00:14.139794Z","end":"2026-04-07T13:00:14.277075Z","steps":["trace[1928085017] 'process raft request' (duration: 75.626738ms)","trace[1928085017] 'compare' (duration: 61.341025ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:00:14.277218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.885942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:14.277267Z","caller":"traceutil/trace.go:171","msg":"trace[1980872484] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:585; }","duration":"130.003285ms","start":"2026-04-07T13:00:14.147252Z","end":"2026-04-07T13:00:14.277255Z","steps":["trace[1980872484] 'agreement among raft nodes before linearized reading' (duration: 129.884253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:14.502308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.768495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T13:00:14.502334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.541232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:14.502517Z","caller":"traceutil/trace.go:171","msg":"trace[1115628289] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:585; }","duration":"103.039601ms","start":"2026-04-07T13:00:14.399421Z","end":"2026-04-07T13:00:14.502461Z","steps":["trace[1115628289] 'range keys from in-memory index tree' (duration: 102.11234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:14.50253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.024889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:14.502663Z","caller":"traceutil/trace.go:171","msg":"trace[107542878] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:585; }","duration":"102.225773ms","start":"2026-04-07T13:00:14.400407Z","end":"2026-04-07T13:00:14.502632Z","steps":["trace[107542878] 'range keys from in-memory index tree' (duration: 101.740023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:14.502754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.504689ms","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-04-07T13:00:14.502819Z","caller":"traceutil/trace.go:171","msg":"trace[744278974] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"110.625812ms","start":"2026-04-07T13:00:14.39218Z","end":"2026-04-07T13:00:14.502806Z","steps":["trace[744278974] 'range keys from in-memory index tree' (duration: 110.358926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:14.502911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.424759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:14.502954Z","caller":"traceutil/trace.go:171","msg":"trace[1318155181] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:585; }","duration":"103.49295ms","start":"2026-04-07T13:00:14.399449Z","end":"2026-04-07T13:00:14.502942Z","steps":["trace[1318155181] 'count revisions from in-memory index tree' (duration: 102.815745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:14.502991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.37892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:14.503019Z","caller":"traceutil/trace.go:171","msg":"trace[869441550] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:585; }","duration":"120.423461ms","start":"2026-04-07T13:00:14.382589Z","end":"2026-04-07T13:00:14.503013Z","steps":["trace[869441550] 'count revisions from in-memory index tree' (duration: 120.349289ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:14.502533Z","caller":"traceutil/trace.go:171","msg":"trace[160635054] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:585; }","duration":"119.786807ms","start":"2026-04-07T13:00:14.382722Z","end":"2026-04-07T13:00:14.502509Z","steps":["trace[160635054] 'range keys from in-memory index tree' (duration: 119.494951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:14.503172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.266397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:14.503548Z","caller":"traceutil/trace.go:171","msg":"trace[658207801] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:585; }","duration":"121.015102ms","start":"2026-04-07T13:00:14.382425Z","end":"2026-04-07T13:00:14.50344Z","steps":["trace[658207801] 'range keys from in-memory index tree' (duration: 120.083633ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:14.779572Z","caller":"traceutil/trace.go:171","msg":"trace[1811700626] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"269.562641ms","start":"2026-04-07T13:00:14.509388Z","end":"2026-04-07T13:00:14.77895Z","steps":["trace[1811700626] 'process raft request' (duration: 269.080851ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:15.057435Z","caller":"traceutil/trace.go:171","msg":"trace[1976555384] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"536.270782ms","start":"2026-04-07T13:00:14.521114Z","end":"2026-04-07T13:00:15.057385Z","steps":["trace[1976555384] 'process raft request' (duration: 535.923044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:15.057668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:14.521088Z","time spent":"536.463076ms","remote":"127.0.0.1:59594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:00:15.057595Z","caller":"traceutil/trace.go:171","msg":"trace[106678498] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:611; }","duration":"531.523091ms","start":"2026-04-07T13:00:14.525969Z","end":"2026-04-07T13:00:15.057493Z","steps":["trace[106678498] 'read index received' (duration: 248.146536ms)","trace[106678498] 'applied index is now lower than readState.Index' (duration: 283.373245ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:00:15.058057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.021931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:15.058283Z","caller":"traceutil/trace.go:171","msg":"trace[1952495763] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:587; }","duration":"532.36187ms","start":"2026-04-07T13:00:14.525892Z","end":"2026-04-07T13:00:15.058254Z","steps":["trace[1952495763] 'agreement among raft nodes before linearized reading' (duration: 531.908859ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:15.058292Z","caller":"traceutil/trace.go:171","msg":"trace[1750180519] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"370.673352ms","start":"2026-04-07T13:00:14.687539Z","end":"2026-04-07T13:00:15.058212Z","steps":["trace[1750180519] 'process raft request' (duration: 370.009997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:15.058373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:14.525879Z","time spent":"532.477922ms","remote":"127.0.0.1:50676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T13:00:15.058605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:14.68751Z","time spent":"370.881006ms","remote":"127.0.0.1:59538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:00:15.094996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.077163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T13:00:15.095071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.059607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T13:00:15.095079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.170773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:15.095145Z","caller":"traceutil/trace.go:171","msg":"trace[1796630849] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:588; }","duration":"234.165709ms","start":"2026-04-07T13:00:14.860962Z","end":"2026-04-07T13:00:15.095127Z","steps":["trace[1796630849] 'agreement among raft nodes before linearized reading' (duration: 234.038536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:15.095191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.514967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T13:00:15.095172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.50084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:15.095201Z","caller":"traceutil/trace.go:171","msg":"trace[296540988] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:588; }","duration":"134.344867ms","start":"2026-04-07T13:00:14.960829Z","end":"2026-04-07T13:00:15.095174Z","steps":["trace[296540988] 'agreement among raft nodes before linearized reading' (duration: 134.112502ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:15.095228Z","caller":"traceutil/trace.go:171","msg":"trace[864625129] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:588; }","duration":"179.593529ms","start":"2026-04-07T13:00:14.915623Z","end":"2026-04-07T13:00:15.095217Z","steps":["trace[864625129] 'agreement among raft nodes before linearized reading' (duration: 179.540568ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:15.095249Z","caller":"traceutil/trace.go:171","msg":"trace[921414433] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:588; }","duration":"134.662253ms","start":"2026-04-07T13:00:14.960569Z","end":"2026-04-07T13:00:15.095232Z","steps":["trace[921414433] 'agreement among raft nodes before linearized reading' (duration: 134.50142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:15.095206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.357674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T13:00:15.095333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.812273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:15.095361Z","caller":"traceutil/trace.go:171","msg":"trace[1389299533] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:588; }","duration":"234.881705ms","start":"2026-04-07T13:00:14.860472Z","end":"2026-04-07T13:00:15.095354Z","steps":["trace[1389299533] 'agreement among raft nodes before linearized reading' (duration: 234.838374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:15.095412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.37843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:15.095406Z","caller":"traceutil/trace.go:171","msg":"trace[974924251] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:588; }","duration":"179.65761ms","start":"2026-04-07T13:00:14.915695Z","end":"2026-04-07T13:00:15.095353Z","steps":["trace[974924251] 'agreement among raft nodes before linearized reading' (duration: 179.288512ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:15.095441Z","caller":"traceutil/trace.go:171","msg":"trace[2098665655] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:588; }","duration":"207.458161ms","start":"2026-04-07T13:00:14.887975Z","end":"2026-04-07T13:00:15.095434Z","steps":["trace[2098665655] 'agreement among raft nodes before linearized reading' (duration: 207.41305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:15.095465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.973186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:15.095486Z","caller":"traceutil/trace.go:171","msg":"trace[682455488] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:588; }","duration":"235.050318ms","start":"2026-04-07T13:00:14.86043Z","end":"2026-04-07T13:00:15.09548Z","steps":["trace[682455488] 'agreement among raft nodes before linearized reading' (duration: 235.014778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:15.095575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.090947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:15.095617Z","caller":"traceutil/trace.go:171","msg":"trace[1180027204] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:588; }","duration":"178.170459ms","start":"2026-04-07T13:00:14.917433Z","end":"2026-04-07T13:00:15.095604Z","steps":["trace[1180027204] 'agreement among raft nodes before linearized reading' (duration: 178.111538ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:15.095133Z","caller":"traceutil/trace.go:171","msg":"trace[636033907] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:588; }","duration":"207.206716ms","start":"2026-04-07T13:00:14.887857Z","end":"2026-04-07T13:00:15.095063Z","steps":["trace[636033907] 'agreement among raft nodes before linearized reading' (duration: 207.053332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:15.095632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.091813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:15.095662Z","caller":"traceutil/trace.go:171","msg":"trace[1659594028] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:588; }","duration":"207.137614ms","start":"2026-04-07T13:00:14.888518Z","end":"2026-04-07T13:00:15.095655Z","steps":["trace[1659594028] 'agreement among raft nodes before linearized reading' (duration: 207.093773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:15.095614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.48905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:15.0957Z","caller":"traceutil/trace.go:171","msg":"trace[2126589829] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:588; }","duration":"134.629693ms","start":"2026-04-07T13:00:14.96106Z","end":"2026-04-07T13:00:15.095689Z","steps":["trace[2126589829] 'agreement among raft nodes before linearized reading' (duration: 134.527681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:15.095588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.549659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:15.09574Z","caller":"traceutil/trace.go:171","msg":"trace[124238670] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:588; }","duration":"569.700933ms","start":"2026-04-07T13:00:14.526032Z","end":"2026-04-07T13:00:15.095732Z","steps":["trace[124238670] 'agreement among raft nodes before linearized reading' (duration: 569.536759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:15.09577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:14.526006Z","time spent":"569.754524ms","remote":"127.0.0.1:50676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T13:00:15.095898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.484828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T13:00:15.095989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.54978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T13:00:15.096001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.014303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:15.096029Z","caller":"traceutil/trace.go:171","msg":"trace[1083376991] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:588; }","duration":"545.603342ms","start":"2026-04-07T13:00:14.550416Z","end":"2026-04-07T13:00:15.09602Z","steps":["trace[1083376991] 'agreement among raft nodes before linearized reading' (duration: 545.54901ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:00:15.096039Z","caller":"traceutil/trace.go:171","msg":"trace[1553066891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"311.060254ms","start":"2026-04-07T13:00:14.784969Z","end":"2026-04-07T13:00:15.09603Z","steps":["trace[1553066891] 'agreement among raft nodes before linearized reading' (duration: 311.008873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:15.096039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.364772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T13:00:15.096059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:14.550408Z","time spent":"545.643502ms","remote":"127.0.0.1:50680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T13:00:15.096063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.662362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T13:00:15.096064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:14.784954Z","time spent":"311.103954ms","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T13:00:15.096103Z","caller":"traceutil/trace.go:171","msg":"trace[1297596432] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:588; }","duration":"545.698743ms","start":"2026-04-07T13:00:14.550389Z","end":"2026-04-07T13:00:15.096088Z","steps":["trace[1297596432] 'agreement among raft nodes before linearized reading' (duration: 545.645422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:15.096109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.412363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:15.096139Z","caller":"traceutil/trace.go:171","msg":"trace[983719732] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:588; }","duration":"376.449924ms","start":"2026-04-07T13:00:14.719682Z","end":"2026-04-07T13:00:15.096132Z","steps":["trace[983719732] 'agreement among raft nodes before linearized reading' (duration: 376.404783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:15.09614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:14.550354Z","time spent":"545.777435ms","remote":"127.0.0.1:50680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-07T13:00:15.096164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:14.719679Z","time spent":"376.477294ms","remote":"127.0.0.1:50690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-07T13:00:15.09607Z","caller":"traceutil/trace.go:171","msg":"trace[1088055179] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:588; }","duration":"376.427463ms","start":"2026-04-07T13:00:14.719633Z","end":"2026-04-07T13:00:15.096061Z","steps":["trace[1088055179] 'agreement among raft nodes before linearized reading' (duration: 376.329501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:15.096163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.432446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:00:15.096298Z","caller":"traceutil/trace.go:171","msg":"trace[393867743] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:588; }","duration":"544.588199ms","start":"2026-04-07T13:00:14.551688Z","end":"2026-04-07T13:00:15.096276Z","steps":["trace[393867743] 'agreement among raft nodes before linearized reading' (duration: 544.421215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:15.096248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:14.719611Z","time spent":"376.596857ms","remote":"127.0.0.1:50690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-07T13:00:15.096341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:14.551681Z","time spent":"544.64957ms","remote":"127.0.0.1:50680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"info","ts":"2026-04-07T13:00:15.096385Z","caller":"traceutil/trace.go:171","msg":"trace[1030371296] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:588; }","duration":"569.618601ms","start":"2026-04-07T13:00:14.526359Z","end":"2026-04-07T13:00:15.095978Z","steps":["trace[1030371296] 'agreement among raft nodes before linearized reading' (duration: 569.224093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:15.096464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:00:14.526352Z","time spent":"570.093091ms","remote":"127.0.0.1:50676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 "} {"level":"info","ts":"2026-04-07T13:00:59.266917Z","caller":"traceutil/trace.go:171","msg":"trace[1681829547] linearizableReadLoop","detail":"{readStateIndex:1188; appliedIndex:1187; }","duration":"203.674821ms","start":"2026-04-07T13:00:59.063202Z","end":"2026-04-07T13:00:59.266876Z","steps":["trace[1681829547] 'read index received' (duration: 203.356013ms)","trace[1681829547] 'applied index is now lower than readState.Index' (duration: 316.588µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:00:59.267049Z","caller":"traceutil/trace.go:171","msg":"trace[564683674] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"228.969849ms","start":"2026-04-07T13:00:59.038035Z","end":"2026-04-07T13:00:59.267005Z","steps":["trace[564683674] 'process raft request' (duration: 228.542319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:00:59.26724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.012048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-07T13:00:59.267348Z","caller":"traceutil/trace.go:171","msg":"trace[1457850309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1151; }","duration":"204.1427ms","start":"2026-04-07T13:00:59.063128Z","end":"2026-04-07T13:00:59.267271Z","steps":["trace[1457850309] 'agreement among raft nodes before linearized reading' (duration: 203.941586ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:41.927029Z","caller":"traceutil/trace.go:171","msg":"trace[172368887] linearizableReadLoop","detail":"{readStateIndex:1848; appliedIndex:1847; }","duration":"216.324659ms","start":"2026-04-07T13:01:41.710682Z","end":"2026-04-07T13:01:41.927006Z","steps":["trace[172368887] 'read index received' (duration: 216.053964ms)","trace[172368887] 'applied index is now lower than readState.Index' (duration: 269.705µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:01:41.927136Z","caller":"traceutil/trace.go:171","msg":"trace[624754489] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"396.173188ms","start":"2026-04-07T13:01:41.53092Z","end":"2026-04-07T13:01:41.927093Z","steps":["trace[624754489] 'process raft request' (duration: 395.911853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:01:41.927321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:01:41.530899Z","time spent":"396.333681ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:01:41.927332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.635415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"warn","ts":"2026-04-07T13:01:41.927328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.808999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:01:41.92742Z","caller":"traceutil/trace.go:171","msg":"trace[179354281] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1792; }","duration":"216.772108ms","start":"2026-04-07T13:01:41.710622Z","end":"2026-04-07T13:01:41.927394Z","steps":["trace[179354281] 'agreement among raft nodes before linearized reading' (duration: 216.519853ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:41.927448Z","caller":"traceutil/trace.go:171","msg":"trace[99348832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1792; }","duration":"145.985563ms","start":"2026-04-07T13:01:41.781449Z","end":"2026-04-07T13:01:41.927435Z","steps":["trace[99348832] 'agreement among raft nodes before linearized reading' (duration: 145.82647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:01:42.1631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.275323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T13:01:42.16319Z","caller":"traceutil/trace.go:171","msg":"trace[646712406] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"225.732997ms","start":"2026-04-07T13:01:41.937442Z","end":"2026-04-07T13:01:42.163175Z","steps":["trace[646712406] 'process raft request' (duration: 76.211129ms)","trace[646712406] 'compare' (duration: 149.178071ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:01:45.139777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.931463ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:01:45.139936Z","caller":"traceutil/trace.go:171","msg":"trace[741194965] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1823; }","duration":"102.103907ms","start":"2026-04-07T13:01:45.037807Z","end":"2026-04-07T13:01:45.139911Z","steps":["trace[741194965] 'range keys from in-memory index tree' (duration: 101.914573ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:01:45.151903Z","caller":"traceutil/trace.go:171","msg":"trace[360781247] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"141.229283ms","start":"2026-04-07T13:01:45.010624Z","end":"2026-04-07T13:01:45.151853Z","steps":["trace[360781247] 'process raft request' (duration: 141.029309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:02:24.991209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.28149ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-07T13:02:30.868877Z","caller":"traceutil/trace.go:171","msg":"trace[2089890437] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"121.875304ms","start":"2026-04-07T13:02:30.746968Z","end":"2026-04-07T13:02:30.868844Z","steps":["trace[2089890437] 'process raft request' (duration: 121.688301ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:02:30.950747Z","caller":"traceutil/trace.go:171","msg":"trace[742334244] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"203.370756ms","start":"2026-04-07T13:02:30.74735Z","end":"2026-04-07T13:02:30.950721Z","steps":["trace[742334244] 'process raft request' (duration: 203.216822ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:03:02.132008Z","caller":"traceutil/trace.go:171","msg":"trace[658759256] linearizableReadLoop","detail":"{readStateIndex:2530; appliedIndex:2529; }","duration":"176.890458ms","start":"2026-04-07T13:03:01.955088Z","end":"2026-04-07T13:03:02.131978Z","steps":["trace[658759256] 'read index received' (duration: 176.642031ms)","trace[658759256] 'applied index is now lower than readState.Index' (duration: 247.177µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:03:02.132063Z","caller":"traceutil/trace.go:171","msg":"trace[923424453] transaction","detail":"{read_only:false; response_revision:2439; number_of_response:1; }","duration":"210.773423ms","start":"2026-04-07T13:03:01.921255Z","end":"2026-04-07T13:03:02.132029Z","steps":["trace[923424453] 'process raft request' (duration: 210.518326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:03:02.132325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.226754ms","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-04-07T13:03:02.132396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.214192ms","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-04-07T13:03:02.13243Z","caller":"traceutil/trace.go:171","msg":"trace[1095305548] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2439; }","duration":"177.399558ms","start":"2026-04-07T13:03:01.955006Z","end":"2026-04-07T13:03:02.132406Z","steps":["trace[1095305548] 'agreement among raft nodes before linearized reading' (duration: 177.110741ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:03:02.132496Z","caller":"traceutil/trace.go:171","msg":"trace[185068327] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2439; }","duration":"149.363535ms","start":"2026-04-07T13:03:01.983104Z","end":"2026-04-07T13:03:02.132468Z","steps":["trace[185068327] 'agreement among raft nodes before linearized reading' (duration: 149.089169ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:03:32.687553Z","caller":"traceutil/trace.go:171","msg":"trace[1292711708] linearizableReadLoop","detail":"{readStateIndex:2755; appliedIndex:2753; }","duration":"109.671536ms","start":"2026-04-07T13:03:32.57785Z","end":"2026-04-07T13:03:32.687522Z","steps":["trace[1292711708] 'read index received' (duration: 107.931768ms)","trace[1292711708] 'applied index is now lower than readState.Index' (duration: 1.738418ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:03:32.687953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.000512ms","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-04-07T13:03:32.687938Z","caller":"traceutil/trace.go:171","msg":"trace[207294] transaction","detail":"{read_only:false; response_revision:2652; number_of_response:1; }","duration":"120.193234ms","start":"2026-04-07T13:03:32.567534Z","end":"2026-04-07T13:03:32.687727Z","steps":["trace[207294] 'process raft request' (duration: 119.934049ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:03:32.688078Z","caller":"traceutil/trace.go:171","msg":"trace[904939435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2652; }","duration":"110.252118ms","start":"2026-04-07T13:03:32.577805Z","end":"2026-04-07T13:03:32.688057Z","steps":["trace[904939435] 'agreement among raft nodes before linearized reading' (duration: 109.899511ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:03:32.687633Z","caller":"traceutil/trace.go:171","msg":"trace[986681289] transaction","detail":"{read_only:false; response_revision:2651; number_of_response:1; }","duration":"120.357237ms","start":"2026-04-07T13:03:32.567227Z","end":"2026-04-07T13:03:32.687584Z","steps":["trace[986681289] 'process raft request' (duration: 118.459876ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:03:38.046106Z","caller":"traceutil/trace.go:171","msg":"trace[94924567] transaction","detail":"{read_only:false; response_revision:2689; number_of_response:1; }","duration":"118.251361ms","start":"2026-04-07T13:03:37.927828Z","end":"2026-04-07T13:03:38.04608Z","steps":["trace[94924567] 'process raft request' (duration: 94.70003ms)","trace[94924567] 'compare' (duration: 23.410348ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:03:38.216995Z","caller":"traceutil/trace.go:171","msg":"trace[657117795] transaction","detail":"{read_only:false; response_revision:2690; number_of_response:1; }","duration":"118.066558ms","start":"2026-04-07T13:03:38.098891Z","end":"2026-04-07T13:03:38.216957Z","steps":["trace[657117795] 'process raft request' (duration: 117.829953ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:06:20.716801Z","caller":"traceutil/trace.go:171","msg":"trace[404196667] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"108.213931ms","start":"2026-04-07T13:06:20.608563Z","end":"2026-04-07T13:06:20.716777Z","steps":["trace[404196667] 'process raft request' (duration: 107.919515ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:06:20.716695Z","caller":"traceutil/trace.go:171","msg":"trace[505007904] linearizableReadLoop","detail":"{readStateIndex:4946; appliedIndex:4944; }","duration":"101.69616ms","start":"2026-04-07T13:06:20.614957Z","end":"2026-04-07T13:06:20.716653Z","steps":["trace[505007904] 'read index received' (duration: 50.159535ms)","trace[505007904] 'applied index is now lower than readState.Index' (duration: 51.535054ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:06:20.717011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.012286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:06:20.717076Z","caller":"traceutil/trace.go:171","msg":"trace[1331399139] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:4739; }","duration":"102.121279ms","start":"2026-04-07T13:06:20.614935Z","end":"2026-04-07T13:06:20.717057Z","steps":["trace[1331399139] 'agreement among raft nodes before linearized reading' (duration: 101.986966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:06:20.789552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.592029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-04-07T13:06:20.789631Z","caller":"traceutil/trace.go:171","msg":"trace[2139405891] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4740; }","duration":"120.710591ms","start":"2026-04-07T13:06:20.668901Z","end":"2026-04-07T13:06:20.789612Z","steps":["trace[2139405891] 'agreement among raft nodes before linearized reading' (duration: 120.490966ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:06:20.789659Z","caller":"traceutil/trace.go:171","msg":"trace[1959845212] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"107.166608ms","start":"2026-04-07T13:06:20.682469Z","end":"2026-04-07T13:06:20.789636Z","steps":["trace[1959845212] 'process raft request' (duration: 40.069817ms)","trace[1959845212] 'compare' (duration: 66.716883ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:06:20.790533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.292113ms","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-04-07T13:06:20.790619Z","caller":"traceutil/trace.go:171","msg":"trace[913965496] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:4740; }","duration":"170.399896ms","start":"2026-04-07T13:06:20.620202Z","end":"2026-04-07T13:06:20.790601Z","steps":["trace[913965496] 'agreement among raft nodes before linearized reading' (duration: 169.19264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:06:53.028199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.841344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T13:06:53.028447Z","caller":"traceutil/trace.go:171","msg":"trace[545574970] linearizableReadLoop","detail":"{readStateIndex:5397; appliedIndex:5395; }","duration":"247.621115ms","start":"2026-04-07T13:06:52.780804Z","end":"2026-04-07T13:06:53.028425Z","steps":["trace[545574970] 'read index received' (duration: 12.887616ms)","trace[545574970] 'applied index is now lower than readState.Index' (duration: 234.731739ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:06:53.028565Z","caller":"traceutil/trace.go:171","msg":"trace[1626874718] transaction","detail":"{read_only:false; response_revision:5175; number_of_response:1; }","duration":"248.591836ms","start":"2026-04-07T13:06:52.779944Z","end":"2026-04-07T13:06:53.028535Z","steps":["trace[1626874718] 'process raft request' (duration: 32.285292ms)","trace[1626874718] 'compare' (duration: 215.67206ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:06:53.028697Z","caller":"traceutil/trace.go:171","msg":"trace[1876489834] transaction","detail":"{read_only:false; response_revision:5176; number_of_response:1; }","duration":"247.313689ms","start":"2026-04-07T13:06:52.781325Z","end":"2026-04-07T13:06:53.028639Z","steps":["trace[1876489834] 'process raft request' (duration: 247.012532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:06:53.02875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.947362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:06:53.028944Z","caller":"traceutil/trace.go:171","msg":"trace[1874293104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5176; }","duration":"248.154556ms","start":"2026-04-07T13:06:52.780754Z","end":"2026-04-07T13:06:53.028908Z","steps":["trace[1874293104] 'agreement among raft nodes before linearized reading' (duration: 247.902441ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:06:53.27262Z","caller":"traceutil/trace.go:171","msg":"trace[154334271] linearizableReadLoop","detail":"{readStateIndex:5400; appliedIndex:5398; }","duration":"244.09635ms","start":"2026-04-07T13:06:53.028492Z","end":"2026-04-07T13:06:53.272588Z","steps":["trace[154334271] 'read index received' (duration: 186.458943ms)","trace[154334271] 'applied index is now lower than readState.Index' (duration: 57.635847ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:06:53.272678Z","caller":"traceutil/trace.go:171","msg":"trace[1373786226] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"365.992386ms","start":"2026-04-07T13:06:52.906646Z","end":"2026-04-07T13:06:53.272638Z","steps":["trace[1373786226] 'process raft request' (duration: 307.905499ms)","trace[1373786226] 'compare' (duration: 57.818341ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:06:53.272844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.30211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-04-07T13:06:53.272872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:06:52.906621Z","time spent":"366.133929ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:06:53.272896Z","caller":"traceutil/trace.go:171","msg":"trace[634470938] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5178; }","duration":"452.424933ms","start":"2026-04-07T13:06:52.820454Z","end":"2026-04-07T13:06:53.272879Z","steps":["trace[634470938] 'agreement among raft nodes before linearized reading' (duration: 452.235958ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:06:53.272932Z","caller":"traceutil/trace.go:171","msg":"trace[1314463718] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"326.263355ms","start":"2026-04-07T13:06:52.946659Z","end":"2026-04-07T13:06:53.272922Z","steps":["trace[1314463718] 'process raft request' (duration: 325.877706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:06:53.273047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:06:52.820429Z","time spent":"452.558985ms","remote":"127.0.0.1:59574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-07T13:06:53.273158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:06:52.946629Z","time spent":"326.447038ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:06:53.943608Z","caller":"traceutil/trace.go:171","msg":"trace[1975079058] linearizableReadLoop","detail":"{readStateIndex:5406; appliedIndex:5405; }","duration":"161.101759ms","start":"2026-04-07T13:06:53.782487Z","end":"2026-04-07T13:06:53.943589Z","steps":["trace[1975079058] 'read index received' (duration: 160.957356ms)","trace[1975079058] 'applied index is now lower than readState.Index' (duration: 143.663µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:06:53.943718Z","caller":"traceutil/trace.go:171","msg":"trace[1211589617] transaction","detail":"{read_only:false; response_revision:5184; number_of_response:1; }","duration":"372.123589ms","start":"2026-04-07T13:06:53.571583Z","end":"2026-04-07T13:06:53.943706Z","steps":["trace[1211589617] 'process raft request' (duration: 371.841443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:06:53.943822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:06:53.571551Z","time spent":"372.19092ms","remote":"127.0.0.1:59858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:06:53.943953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.473717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:06:53.94399Z","caller":"traceutil/trace.go:171","msg":"trace[806450553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5184; }","duration":"161.695972ms","start":"2026-04-07T13:06:53.782285Z","end":"2026-04-07T13:06:53.943981Z","steps":["trace[806450553] 'agreement among raft nodes before linearized reading' (duration: 161.634441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:06:53.944247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.959413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-07T13:06:53.944337Z","caller":"traceutil/trace.go:171","msg":"trace[573684056] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5184; }","duration":"159.076845ms","start":"2026-04-07T13:06:53.785242Z","end":"2026-04-07T13:06:53.944319Z","steps":["trace[573684056] 'agreement among raft nodes before linearized reading' (duration: 158.865711ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:06:54.438056Z","caller":"traceutil/trace.go:171","msg":"trace[1565364670] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"101.229703ms","start":"2026-04-07T13:06:54.33679Z","end":"2026-04-07T13:06:54.43802Z","steps":["trace[1565364670] 'process raft request' (duration: 100.776954ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:09:36.372925Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3252} {"level":"info","ts":"2026-04-07T13:09:36.467873Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3252,"took":"93.010003ms","hash":2146983377,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":33521664,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-07T13:09:36.467974Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2146983377,"revision":3252,"compact-revision":-1} {"level":"info","ts":"2026-04-07T13:12:32.974942Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000021af.wal"} {"level":"info","ts":"2026-04-07T13:12:37.325586Z","caller":"traceutil/trace.go:171","msg":"trace[1493565740] transaction","detail":"{read_only:false; response_revision:8283; number_of_response:1; }","duration":"151.042515ms","start":"2026-04-07T13:12:37.174521Z","end":"2026-04-07T13:12:37.325564Z","steps":["trace[1493565740] 'process raft request' (duration: 150.866711ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:37.460874Z","caller":"traceutil/trace.go:171","msg":"trace[469353266] transaction","detail":"{read_only:false; response_revision:8285; number_of_response:1; }","duration":"127.712785ms","start":"2026-04-07T13:12:37.333132Z","end":"2026-04-07T13:12:37.460844Z","steps":["trace[469353266] 'process raft request' (duration: 95.125887ms)","trace[469353266] 'compare' (duration: 32.425104ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:12:48.034424Z","caller":"traceutil/trace.go:171","msg":"trace[1290991049] transaction","detail":"{read_only:false; response_revision:8356; number_of_response:1; }","duration":"102.387219ms","start":"2026-04-07T13:12:47.932003Z","end":"2026-04-07T13:12:48.03439Z","steps":["trace[1290991049] 'process raft request' (duration: 102.040552ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:49.066449Z","caller":"traceutil/trace.go:171","msg":"trace[2060273110] linearizableReadLoop","detail":"{readStateIndex:8740; appliedIndex:8739; }","duration":"285.797478ms","start":"2026-04-07T13:12:48.78063Z","end":"2026-04-07T13:12:49.066427Z","steps":["trace[2060273110] 'read index received' (duration: 285.594623ms)","trace[2060273110] 'applied index is now lower than readState.Index' (duration: 202.185µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:12:49.066504Z","caller":"traceutil/trace.go:171","msg":"trace[231022927] transaction","detail":"{read_only:false; response_revision:8363; number_of_response:1; }","duration":"334.022617ms","start":"2026-04-07T13:12:48.732449Z","end":"2026-04-07T13:12:49.066471Z","steps":["trace[231022927] 'process raft request' (duration: 333.827272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:49.06658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.929001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T13:12:49.0666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.009672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:12:49.066612Z","caller":"traceutil/trace.go:171","msg":"trace[682708180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8363; }","duration":"286.009483ms","start":"2026-04-07T13:12:48.780592Z","end":"2026-04-07T13:12:49.066602Z","steps":["trace[682708180] 'agreement among raft nodes before linearized reading' (duration: 285.939702ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:49.066635Z","caller":"traceutil/trace.go:171","msg":"trace[1211927034] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8363; }","duration":"129.087183ms","start":"2026-04-07T13:12:48.937537Z","end":"2026-04-07T13:12:49.066625Z","steps":["trace[1211927034] 'agreement among raft nodes before linearized reading' (duration: 129.025862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:49.066644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:12:48.732409Z","time spent":"334.158199ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:12:49.899932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.892404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T13:12:49.900045Z","caller":"traceutil/trace.go:171","msg":"trace[891041167] linearizableReadLoop","detail":"{readStateIndex:8742; appliedIndex:8741; }","duration":"374.631994ms","start":"2026-04-07T13:12:49.525395Z","end":"2026-04-07T13:12:49.900027Z","steps":["trace[891041167] 'read index received' (duration: 48.431653ms)","trace[891041167] 'applied index is now lower than readState.Index' (duration: 326.199011ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:12:49.900148Z","caller":"traceutil/trace.go:171","msg":"trace[1494457645] transaction","detail":"{read_only:false; response_revision:8364; number_of_response:1; }","duration":"385.516212ms","start":"2026-04-07T13:12:49.514619Z","end":"2026-04-07T13:12:49.900135Z","steps":["trace[1494457645] 'process raft request' (duration: 59.324711ms)","trace[1494457645] 'compare' (duration: 325.723931ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:12:49.900221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:12:49.514596Z","time spent":"385.586314ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:12:49.900564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.799026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:12:49.900691Z","caller":"traceutil/trace.go:171","msg":"trace[966230429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8364; }","duration":"118.98241ms","start":"2026-04-07T13:12:49.781687Z","end":"2026-04-07T13:12:49.90067Z","steps":["trace[966230429] 'agreement among raft nodes before linearized reading' (duration: 118.713884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:49.90072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.684335ms","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-04-07T13:12:49.900742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.373767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-79rlw\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-04-07T13:12:49.900769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.50242ms","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-04-07T13:12:49.900816Z","caller":"traceutil/trace.go:171","msg":"trace[768696709] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8364; }","duration":"321.832458ms","start":"2026-04-07T13:12:49.578961Z","end":"2026-04-07T13:12:49.900794Z","steps":["trace[768696709] 'agreement among raft nodes before linearized reading' (duration: 321.468341ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:49.900853Z","caller":"traceutil/trace.go:171","msg":"trace[483985217] range","detail":"{range_begin:/registry/pods/openstack/keepalived-79rlw; range_end:; response_count:1; response_revision:8364; }","duration":"328.506669ms","start":"2026-04-07T13:12:49.572316Z","end":"2026-04-07T13:12:49.900823Z","steps":["trace[483985217] 'agreement among raft nodes before linearized reading' (duration: 328.148642ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:49.90087Z","caller":"traceutil/trace.go:171","msg":"trace[491028618] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8364; }","duration":"329.593322ms","start":"2026-04-07T13:12:49.571238Z","end":"2026-04-07T13:12:49.900832Z","steps":["trace[491028618] 'agreement among raft nodes before linearized reading' (duration: 329.362437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:49.900882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:12:49.578939Z","time spent":"321.932351ms","remote":"127.0.0.1:59546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":669,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T13:12:49.900835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.40794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-04-07T13:12:49.900909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:12:49.571183Z","time spent":"329.717995ms","remote":"127.0.0.1:59546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T13:12:49.900911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:12:49.572309Z","time spent":"328.591022ms","remote":"127.0.0.1:59594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6551,"request content":"key:\"/registry/pods/openstack/keepalived-79rlw\" "} {"level":"info","ts":"2026-04-07T13:12:49.900969Z","caller":"traceutil/trace.go:171","msg":"trace[1731335695] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8364; }","duration":"375.582323ms","start":"2026-04-07T13:12:49.525359Z","end":"2026-04-07T13:12:49.900941Z","steps":["trace[1731335695] 'agreement among raft nodes before linearized reading' (duration: 375.066212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:12:49.901024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:12:49.525346Z","time spent":"375.666615ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":550,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-04-07T13:12:50.664766Z","caller":"traceutil/trace.go:171","msg":"trace[1465390537] transaction","detail":"{read_only:false; response_revision:8374; number_of_response:1; }","duration":"131.32471ms","start":"2026-04-07T13:12:50.533422Z","end":"2026-04-07T13:12:50.664746Z","steps":["trace[1465390537] 'process raft request' (duration: 131.181987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:13:16.607525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.491999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-07T13:13:16.607652Z","caller":"traceutil/trace.go:171","msg":"trace[311625061] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8598; }","duration":"304.692823ms","start":"2026-04-07T13:13:16.302933Z","end":"2026-04-07T13:13:16.607626Z","steps":["trace[311625061] 'range keys from in-memory index tree' (duration: 304.215714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:13:16.607707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:13:16.302908Z","time spent":"304.786826ms","remote":"127.0.0.1:59574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1877,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-07T13:13:16.60773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.957442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T13:13:16.607838Z","caller":"traceutil/trace.go:171","msg":"trace[1230081533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8598; }","duration":"162.098435ms","start":"2026-04-07T13:13:16.44571Z","end":"2026-04-07T13:13:16.607809Z","steps":["trace[1230081533] 'range keys from in-memory index tree' (duration: 161.711357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:13:16.607855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.141334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-07T13:13:16.607531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.400361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:13:16.607979Z","caller":"traceutil/trace.go:171","msg":"trace[1396563403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8598; }","duration":"132.338509ms","start":"2026-04-07T13:13:16.475614Z","end":"2026-04-07T13:13:16.607953Z","steps":["trace[1396563403] 'range keys from in-memory index tree' (duration: 131.885829ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:13:16.608061Z","caller":"traceutil/trace.go:171","msg":"trace[1474367824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8598; }","duration":"241.962612ms","start":"2026-04-07T13:13:16.366045Z","end":"2026-04-07T13:13:16.608008Z","steps":["trace[1474367824] 'range keys from in-memory index tree' (duration: 241.186106ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:13:16.726947Z","caller":"traceutil/trace.go:171","msg":"trace[1382335994] linearizableReadLoop","detail":"{readStateIndex:8990; appliedIndex:8988; }","duration":"111.190976ms","start":"2026-04-07T13:13:16.615726Z","end":"2026-04-07T13:13:16.726917Z","steps":["trace[1382335994] 'read index received' (duration: 65.870977ms)","trace[1382335994] 'applied index is now lower than readState.Index' (duration: 45.318699ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:13:16.727073Z","caller":"traceutil/trace.go:171","msg":"trace[1517302082] transaction","detail":"{read_only:false; response_revision:8600; number_of_response:1; }","duration":"113.733672ms","start":"2026-04-07T13:13:16.613299Z","end":"2026-04-07T13:13:16.727032Z","steps":["trace[1517302082] 'process raft request' (duration: 111.569225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:13:16.727237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.478723ms","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":"info","ts":"2026-04-07T13:13:16.727319Z","caller":"traceutil/trace.go:171","msg":"trace[1766549938] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:8600; }","duration":"111.602766ms","start":"2026-04-07T13:13:16.615698Z","end":"2026-04-07T13:13:16.727301Z","steps":["trace[1766549938] 'agreement among raft nodes before linearized reading' (duration: 111.3311ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:13:29.734081Z","caller":"traceutil/trace.go:171","msg":"trace[1790237368] transaction","detail":"{read_only:false; number_of_response:0; response_revision:8728; }","duration":"114.159451ms","start":"2026-04-07T13:13:29.619887Z","end":"2026-04-07T13:13:29.734046Z","steps":["trace[1790237368] 'compare' (duration: 91.981757ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:13:30.416705Z","caller":"traceutil/trace.go:171","msg":"trace[392525903] linearizableReadLoop","detail":"{readStateIndex:9129; appliedIndex:9128; }","duration":"246.785335ms","start":"2026-04-07T13:13:30.169899Z","end":"2026-04-07T13:13:30.416684Z","steps":["trace[392525903] 'read index received' (duration: 246.55005ms)","trace[392525903] 'applied index is now lower than readState.Index' (duration: 233.845µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:13:30.416832Z","caller":"traceutil/trace.go:171","msg":"trace[733807344] transaction","detail":"{read_only:false; response_revision:8730; number_of_response:1; }","duration":"269.771846ms","start":"2026-04-07T13:13:30.147037Z","end":"2026-04-07T13:13:30.416809Z","steps":["trace[733807344] 'process raft request' (duration: 269.47161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:13:30.417042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.126792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-57mm8\" ","response":"range_response_count:1 size:4609"} {"level":"info","ts":"2026-04-07T13:13:30.417084Z","caller":"traceutil/trace.go:171","msg":"trace[1231390486] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-57mm8; range_end:; response_count:1; response_revision:8730; }","duration":"247.209834ms","start":"2026-04-07T13:13:30.169864Z","end":"2026-04-07T13:13:30.417074Z","steps":["trace[1231390486] 'agreement among raft nodes before linearized reading' (duration: 247.072282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:13:30.449881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.664253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-79rlw\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-07T13:13:30.449992Z","caller":"traceutil/trace.go:171","msg":"trace[539663545] range","detail":"{range_begin:/registry/pods/openstack/keepalived-79rlw; range_end:; response_count:1; response_revision:8731; }","duration":"261.817616ms","start":"2026-04-07T13:13:30.188153Z","end":"2026-04-07T13:13:30.449971Z","steps":["trace[539663545] 'agreement among raft nodes before linearized reading' (duration: 261.561491ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:13:30.450104Z","caller":"traceutil/trace.go:171","msg":"trace[1206871005] transaction","detail":"{read_only:false; response_revision:8731; number_of_response:1; }","duration":"280.033156ms","start":"2026-04-07T13:13:30.170034Z","end":"2026-04-07T13:13:30.450067Z","steps":["trace[1206871005] 'process raft request' (duration: 279.429183ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:13:31.041723Z","caller":"traceutil/trace.go:171","msg":"trace[1986482047] transaction","detail":"{read_only:false; response_revision:8742; number_of_response:1; }","duration":"164.165768ms","start":"2026-04-07T13:13:30.877509Z","end":"2026-04-07T13:13:31.041675Z","steps":["trace[1986482047] 'process raft request' (duration: 152.895937ms)","trace[1986482047] 'compare' (duration: 11.084377ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:13:31.342076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.231746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T13:13:31.342314Z","caller":"traceutil/trace.go:171","msg":"trace[524054968] transaction","detail":"{read_only:false; response_revision:8743; number_of_response:1; }","duration":"174.416449ms","start":"2026-04-07T13:13:31.167864Z","end":"2026-04-07T13:13:31.34228Z","steps":["trace[524054968] 'process raft request' (duration: 70.280023ms)","trace[524054968] 'compare' (duration: 103.066082ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:14:36.388109Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6553} {"level":"info","ts":"2026-04-07T13:14:36.483997Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6553,"took":"93.257653ms","hash":2503940812,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":26488832,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-07T13:14:36.484095Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2503940812,"revision":6553,"compact-revision":3252} {"level":"info","ts":"2026-04-07T13:14:49.233195Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"95026ee9c61f87d3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T13:14:49.238546Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T13:14:49.238642Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-04-07T13:18:16.253341Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339485324098664,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T13:18:16.668913Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.650579023s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T13:18:16.669445Z","caller":"traceutil/trace.go:171","msg":"trace[978353341] linearizableReadLoop","detail":"{readStateIndex:11869; appliedIndex:11868; }","duration":"916.998984ms","start":"2026-04-07T13:18:15.752388Z","end":"2026-04-07T13:18:16.669387Z","steps":["trace[978353341] 'read index received' (duration: 916.829021ms)","trace[978353341] 'applied index is now lower than readState.Index' (duration: 168.693µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:18:16.669544Z","caller":"traceutil/trace.go:171","msg":"trace[764640081] transaction","detail":"{read_only:false; response_revision:11343; number_of_response:1; }","duration":"1.651317579s","start":"2026-04-07T13:18:15.018193Z","end":"2026-04-07T13:18:16.669511Z","steps":["trace[764640081] 'process raft request' (duration: 1.650952761s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:16.669727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:15.018164Z","time spent":"1.651463322s","remote":"127.0.0.1:40664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3175,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:18:16.669751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.331432ms","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-04-07T13:18:16.669829Z","caller":"traceutil/trace.go:171","msg":"trace[454029384] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11343; }","duration":"917.430454ms","start":"2026-04-07T13:18:15.752381Z","end":"2026-04-07T13:18:16.669811Z","steps":["trace[454029384] 'agreement among raft nodes before linearized reading' (duration: 917.174468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:16.669861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:15.752314Z","time spent":"917.541006ms","remote":"127.0.0.1:59546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":669,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-07T13:18:16.675352Z","caller":"traceutil/trace.go:171","msg":"trace[638313023] transaction","detail":"{read_only:false; response_revision:11344; number_of_response:1; }","duration":"212.207012ms","start":"2026-04-07T13:18:16.463117Z","end":"2026-04-07T13:18:16.675324Z","steps":["trace[638313023] 'process raft request' (duration: 212.012077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:16.675781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.229408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:18:16.675863Z","caller":"traceutil/trace.go:171","msg":"trace[1432028908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11345; }","duration":"895.325871ms","start":"2026-04-07T13:18:15.780516Z","end":"2026-04-07T13:18:16.675842Z","steps":["trace[1432028908] 'agreement among raft nodes before linearized reading' (duration: 895.225569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:16.675831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.331949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-07T13:18:16.675899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:15.780506Z","time spent":"895.384432ms","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T13:18:16.675941Z","caller":"traceutil/trace.go:171","msg":"trace[1873284117] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11345; }","duration":"344.451581ms","start":"2026-04-07T13:18:16.331463Z","end":"2026-04-07T13:18:16.675915Z","steps":["trace[1873284117] 'agreement among raft nodes before linearized reading' (duration: 344.115874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:16.676021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.985083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-07T13:18:16.676067Z","caller":"traceutil/trace.go:171","msg":"trace[1567629614] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:11345; }","duration":"688.068525ms","start":"2026-04-07T13:18:15.987985Z","end":"2026-04-07T13:18:16.676054Z","steps":["trace[1567629614] 'agreement among raft nodes before linearized reading' (duration: 687.900452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:16.676078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:16.331433Z","time spent":"344.631456ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T13:18:16.676104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:15.987972Z","time spent":"688.123446ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":550,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-07T13:18:16.676098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.879197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T13:18:16.676067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.723927ms","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-04-07T13:18:16.676158Z","caller":"traceutil/trace.go:171","msg":"trace[2121844013] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:11345; }","duration":"107.072111ms","start":"2026-04-07T13:18:16.569072Z","end":"2026-04-07T13:18:16.676144Z","steps":["trace[2121844013] 'agreement among raft nodes before linearized reading' (duration: 106.891618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:16.676181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.486243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:18:16.676183Z","caller":"traceutil/trace.go:171","msg":"trace[1038561820] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:11345; }","duration":"922.869101ms","start":"2026-04-07T13:18:15.75329Z","end":"2026-04-07T13:18:16.676159Z","steps":["trace[1038561820] 'agreement among raft nodes before linearized reading' (duration: 922.677117ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:18:16.67622Z","caller":"traceutil/trace.go:171","msg":"trace[1946615628] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:11345; }","duration":"858.566725ms","start":"2026-04-07T13:18:15.817643Z","end":"2026-04-07T13:18:16.67621Z","steps":["trace[1946615628] 'agreement among raft nodes before linearized reading' (duration: 858.467603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:16.676229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:15.75328Z","time spent":"922.939802ms","remote":"127.0.0.1:59546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T13:18:16.676241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.479711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-07T13:18:16.676256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:15.817623Z","time spent":"858.625116ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-04-07T13:18:16.676268Z","caller":"traceutil/trace.go:171","msg":"trace[225969376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11345; }","duration":"593.544352ms","start":"2026-04-07T13:18:16.082715Z","end":"2026-04-07T13:18:16.67626Z","steps":["trace[225969376] 'agreement among raft nodes before linearized reading' (duration: 593.46618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:16.676298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:16.082695Z","time spent":"593.595473ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T13:18:16.676298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.287418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-07T13:18:16.67635Z","caller":"traceutil/trace.go:171","msg":"trace[1224438987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11345; }","duration":"344.370099ms","start":"2026-04-07T13:18:16.331965Z","end":"2026-04-07T13:18:16.676336Z","steps":["trace[1224438987] 'agreement among raft nodes before linearized reading' (duration: 344.250327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:16.676402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:16.331951Z","time spent":"344.442962ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T13:18:16.676703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.640269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T13:18:16.676765Z","caller":"traceutil/trace.go:171","msg":"trace[1541015461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11345; }","duration":"346.73803ms","start":"2026-04-07T13:18:16.330013Z","end":"2026-04-07T13:18:16.676751Z","steps":["trace[1541015461] 'agreement among raft nodes before linearized reading' (duration: 345.637567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:16.676809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:16.329996Z","time spent":"346.804282ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T13:18:20.634947Z","caller":"traceutil/trace.go:171","msg":"trace[276646637] transaction","detail":"{read_only:false; response_revision:11370; number_of_response:1; }","duration":"311.427774ms","start":"2026-04-07T13:18:20.323493Z","end":"2026-04-07T13:18:20.63492Z","steps":["trace[276646637] 'process raft request' (duration: 311.24879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:20.635118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:20.323467Z","time spent":"311.561037ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:18:21.194849Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339485324098741,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T13:18:21.695599Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339485324098741,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T13:18:22.196552Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339485324098741,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T13:18:22.697881Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339485324098741,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T13:18:23.198513Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339485324098741,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T13:18:23.2584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999898888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T13:18:23.258472Z","caller":"traceutil/trace.go:171","msg":"trace[1031383310] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000020921s","start":"2026-04-07T13:18:21.258426Z","end":"2026-04-07T13:18:23.258447Z","steps":["trace[1031383310] 'agreement among raft nodes before linearized reading' (duration: 1.999924788s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:23.258534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:21.258403Z","time spent":"2.000119393s","remote":"127.0.0.1:59432","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-04-07T13:18:23.699501Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339485324098741,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T13:18:23.781635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999696554s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-07T13:18:23.781775Z","caller":"traceutil/trace.go:171","msg":"trace[263885407] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999921399s","start":"2026-04-07T13:18:21.781823Z","end":"2026-04-07T13:18:23.781744Z","steps":["trace[263885407] 'agreement among raft nodes before linearized reading' (duration: 1.999733285s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:23.781846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:21.78179Z","time spent":"2.000041672s","remote":"127.0.0.1:59424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/07 13:18:23 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-07T13:18:24.200395Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339485324098741,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T13:18:24.358846Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.667672789s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-07T13:18:24.389599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:22.09025Z","time spent":"2.299340827s","remote":"127.0.0.1:48352","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-07T13:18:24.391237Z","caller":"traceutil/trace.go:171","msg":"trace[180638425] transaction","detail":"{read_only:false; response_revision:11371; number_of_response:1; }","duration":"3.700151535s","start":"2026-04-07T13:18:20.691037Z","end":"2026-04-07T13:18:24.391188Z","steps":["trace[180638425] 'process raft request' (duration: 3.668043357s)","trace[180638425] 'compare' (duration: 29.585604ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:18:24.391452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:20.691007Z","time spent":"3.700329408s","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:18:24.391457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.701224578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:1 size:5129"} {"level":"info","ts":"2026-04-07T13:18:24.391551Z","caller":"traceutil/trace.go:171","msg":"trace[1601657508] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:1; response_revision:11370; }","duration":"3.70136774s","start":"2026-04-07T13:18:20.690158Z","end":"2026-04-07T13:18:24.391526Z","steps":["trace[1601657508] 'range keys from in-memory index tree' (duration: 3.700962712s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:24.3916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:20.690134Z","time spent":"3.701457722s","remote":"127.0.0.1:59640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":5153,"request content":"key:\"/registry/jobs/openstack/staffeln-db-sync\" "} {"level":"info","ts":"2026-04-07T13:18:24.47856Z","caller":"traceutil/trace.go:171","msg":"trace[76517579] transaction","detail":"{read_only:false; response_revision:11372; number_of_response:1; }","duration":"693.272526ms","start":"2026-04-07T13:18:23.785248Z","end":"2026-04-07T13:18:24.478521Z","steps":["trace[76517579] 'process raft request' (duration: 693.065361ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:18:24.478585Z","caller":"traceutil/trace.go:171","msg":"trace[792324631] linearizableReadLoop","detail":"{readStateIndex:11901; appliedIndex:11898; }","duration":"3.784006309s","start":"2026-04-07T13:18:20.694552Z","end":"2026-04-07T13:18:24.478559Z","steps":["trace[792324631] 'read index received' (duration: 3.664519402s)","trace[792324631] 'applied index is now lower than readState.Index' (duration: 119.485397ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:18:24.478733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:23.785224Z","time spent":"693.422829ms","remote":"127.0.0.1:59526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T13:18:24.478835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.781817243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T13:18:24.478933Z","caller":"traceutil/trace.go:171","msg":"trace[1185351615] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11373; }","duration":"3.781942035s","start":"2026-04-07T13:18:20.696976Z","end":"2026-04-07T13:18:24.478918Z","steps":["trace[1185351615] 'agreement among raft nodes before linearized reading' (duration: 3.78171921s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:24.478975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:20.696968Z","time spent":"3.781995986s","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T13:18:24.479039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.982453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T13:18:24.479112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069053398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:18:24.479207Z","caller":"traceutil/trace.go:171","msg":"trace[132512935] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:11373; }","duration":"1.069195881s","start":"2026-04-07T13:18:23.409975Z","end":"2026-04-07T13:18:24.479171Z","steps":["trace[132512935] 'agreement among raft nodes before linearized reading' (duration: 1.069104419s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:24.479271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:23.409876Z","time spent":"1.069383645s","remote":"127.0.0.1:40632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":31,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-07T13:18:24.4793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.383998859s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-04-07T13:18:24.479336Z","caller":"traceutil/trace.go:171","msg":"trace[671841900] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:11373; }","duration":"3.384068111s","start":"2026-04-07T13:18:21.095256Z","end":"2026-04-07T13:18:24.479325Z","steps":["trace[671841900] 'agreement among raft nodes before linearized reading' (duration: 3.383982099s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:24.479267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.784575271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-07T13:18:24.479368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:21.095236Z","time spent":"3.384126421s","remote":"127.0.0.1:59546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-04-07T13:18:24.479416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.7761247s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-07T13:18:24.479404Z","caller":"traceutil/trace.go:171","msg":"trace[903549379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11373; }","duration":"3.784874208s","start":"2026-04-07T13:18:20.69451Z","end":"2026-04-07T13:18:24.479384Z","steps":["trace[903549379] 'agreement among raft nodes before linearized reading' (duration: 3.784163173s)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:18:24.47935Z","caller":"traceutil/trace.go:171","msg":"trace[129349385] transaction","detail":"{read_only:false; response_revision:11373; number_of_response:1; }","duration":"295.993604ms","start":"2026-04-07T13:18:24.183304Z","end":"2026-04-07T13:18:24.479297Z","steps":["trace[129349385] 'process raft request' (duration: 295.168157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:24.479445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.761842645s","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-04-07T13:18:24.478988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.065575105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T13:18:24.479477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.584758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-07T13:18:24.479473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:20.69449Z","time spent":"3.784970349s","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T13:18:24.479501Z","caller":"traceutil/trace.go:171","msg":"trace[1254358230] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11373; }","duration":"3.761896736s","start":"2026-04-07T13:18:20.717585Z","end":"2026-04-07T13:18:24.479482Z","steps":["trace[1254358230] 'agreement among raft nodes before linearized reading' (duration: 3.761720252s)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:18:24.479507Z","caller":"traceutil/trace.go:171","msg":"trace[32959088] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:11373; }","duration":"3.066188367s","start":"2026-04-07T13:18:21.413303Z","end":"2026-04-07T13:18:24.479492Z","steps":["trace[32959088] 'agreement among raft nodes before linearized reading' (duration: 3.065429451s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:24.479538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:20.717556Z","time spent":"3.761974088s","remote":"127.0.0.1:59546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":669,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T13:18:24.479547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:21.413281Z","time spent":"3.066253719s","remote":"127.0.0.1:40528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-04-07T13:18:24.479547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.779899311s","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-04-07T13:18:24.479429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.287041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T13:18:24.479581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.798208443s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-04-07T13:18:24.479608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.764173394s","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-04-07T13:18:24.479116Z","caller":"traceutil/trace.go:171","msg":"trace[934178865] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11373; }","duration":"852.115016ms","start":"2026-04-07T13:18:23.626983Z","end":"2026-04-07T13:18:24.479098Z","steps":["trace[934178865] 'agreement among raft nodes before linearized reading' (duration: 851.900211ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:18:24.479647Z","caller":"traceutil/trace.go:171","msg":"trace[402238673] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:11373; }","duration":"182.540867ms","start":"2026-04-07T13:18:24.29707Z","end":"2026-04-07T13:18:24.479611Z","steps":["trace[402238673] 'agreement among raft nodes before linearized reading' (duration: 182.325242ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:18:24.479666Z","caller":"traceutil/trace.go:171","msg":"trace[1110811479] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:11373; }","duration":"2.798303304s","start":"2026-04-07T13:18:21.681325Z","end":"2026-04-07T13:18:24.479628Z","steps":["trace[1110811479] 'agreement among raft nodes before linearized reading' (duration: 2.79809468s)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:18:24.479508Z","caller":"traceutil/trace.go:171","msg":"trace[1056575554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11373; }","duration":"689.895794ms","start":"2026-04-07T13:18:23.789603Z","end":"2026-04-07T13:18:24.479499Z","steps":["trace[1056575554] 'agreement among raft nodes before linearized reading' (duration: 689.852963ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:18:24.479684Z","caller":"traceutil/trace.go:171","msg":"trace[1378269674] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:11373; }","duration":"3.764266386s","start":"2026-04-07T13:18:20.715397Z","end":"2026-04-07T13:18:24.479663Z","steps":["trace[1378269674] 'agreement among raft nodes before linearized reading' (duration: 3.764046451s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:24.479695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:23.626947Z","time spent":"852.735588ms","remote":"127.0.0.1:59730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":104,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-07T13:18:24.479708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:21.681303Z","time spent":"2.798396276s","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-04-07T13:18:24.479713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.77704064s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-07T13:18:24.479711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.777879147s","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-04-07T13:18:24.479735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:20.71539Z","time spent":"3.764331367s","remote":"127.0.0.1:59546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-07T13:18:24.479744Z","caller":"traceutil/trace.go:171","msg":"trace[2139342354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11373; }","duration":"3.777097281s","start":"2026-04-07T13:18:20.702638Z","end":"2026-04-07T13:18:24.479735Z","steps":["trace[2139342354] 'agreement among raft nodes before linearized reading' (duration: 3.776986099s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:24.479746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.853178538s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11496"} {"level":"info","ts":"2026-04-07T13:18:24.479758Z","caller":"traceutil/trace.go:171","msg":"trace[1945699595] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11373; }","duration":"3.777938968s","start":"2026-04-07T13:18:20.701809Z","end":"2026-04-07T13:18:24.479748Z","steps":["trace[1945699595] 'agreement among raft nodes before linearized reading' (duration: 3.777826196s)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:18:24.4796Z","caller":"traceutil/trace.go:171","msg":"trace[1601607763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11373; }","duration":"3.779982513s","start":"2026-04-07T13:18:20.699604Z","end":"2026-04-07T13:18:24.479587Z","steps":["trace[1601607763] 'agreement among raft nodes before linearized reading' (duration: 3.779770159s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:24.47978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:20.702623Z","time spent":"3.777149192s","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T13:18:24.479784Z","caller":"traceutil/trace.go:171","msg":"trace[1644561171] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11373; }","duration":"1.8532437s","start":"2026-04-07T13:18:22.626531Z","end":"2026-04-07T13:18:24.479775Z","steps":["trace[1644561171] 'agreement among raft nodes before linearized reading' (duration: 1.853112707s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:24.479793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:20.701802Z","time spent":"3.7779847s","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T13:18:24.479811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:20.699596Z","time spent":"3.780207698s","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T13:18:24.479207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.772349199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-79rlw\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-07T13:18:24.479457Z","caller":"traceutil/trace.go:171","msg":"trace[1297413001] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:11373; }","duration":"3.776166611s","start":"2026-04-07T13:18:20.70328Z","end":"2026-04-07T13:18:24.479446Z","steps":["trace[1297413001] 'agreement among raft nodes before linearized reading' (duration: 3.776057789s)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:18:24.479859Z","caller":"traceutil/trace.go:171","msg":"trace[534216048] range","detail":"{range_begin:/registry/pods/openstack/keepalived-79rlw; range_end:; response_count:1; response_revision:11373; }","duration":"3.773018345s","start":"2026-04-07T13:18:20.706829Z","end":"2026-04-07T13:18:24.479847Z","steps":["trace[534216048] 'agreement among raft nodes before linearized reading' (duration: 3.772239077s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:24.479718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:23.789572Z","time spent":"690.137539ms","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T13:18:24.479896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:20.706822Z","time spent":"3.773064665s","remote":"127.0.0.1:59594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6551,"request content":"key:\"/registry/pods/openstack/keepalived-79rlw\" "} {"level":"warn","ts":"2026-04-07T13:18:24.479818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:22.626512Z","time spent":"1.853299491s","remote":"127.0.0.1:59594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11520,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-07T13:18:24.479906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.01818739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"warn","ts":"2026-04-07T13:18:24.479862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:20.703236Z","time spent":"3.776611212s","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":550,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"info","ts":"2026-04-07T13:18:24.479959Z","caller":"traceutil/trace.go:171","msg":"trace[1816467558] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:11373; }","duration":"2.018279813s","start":"2026-04-07T13:18:22.461665Z","end":"2026-04-07T13:18:24.479944Z","steps":["trace[1816467558] 'agreement among raft nodes before linearized reading' (duration: 2.018130958s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:24.479994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:22.461642Z","time spent":"2.018344854s","remote":"127.0.0.1:59600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2012,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-04-07T13:18:24.480299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.51205625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:18:24.482558Z","caller":"traceutil/trace.go:171","msg":"trace[1508895533] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:11373; }","duration":"3.514495791s","start":"2026-04-07T13:18:20.968036Z","end":"2026-04-07T13:18:24.482532Z","steps":["trace[1508895533] 'agreement among raft nodes before linearized reading' (duration: 3.51206207s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:24.482647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:20.968013Z","time spent":"3.514599433s","remote":"127.0.0.1:34940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"warn","ts":"2026-04-07T13:18:55.995712Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339485324099364,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T13:18:56.496529Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339485324099364,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T13:18:56.997226Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339485324099364,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T13:18:57.498159Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339485324099364,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T13:18:57.779873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000331999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-07T13:18:57.77999Z","caller":"traceutil/trace.go:171","msg":"trace[995141746] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000520853s","start":"2026-04-07T13:18:55.779442Z","end":"2026-04-07T13:18:57.779963Z","steps":["trace[995141746] 'agreement among raft nodes before linearized reading' (duration: 2.00036918s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:57.780041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:55.779423Z","time spent":"2.000607854s","remote":"127.0.0.1:59432","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-04-07T13:18:57.999226Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9787339485324099364,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T13:18:58.20227Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.800278786s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T13:18:58.202735Z","caller":"traceutil/trace.go:171","msg":"trace[1289505303] transaction","detail":"{read_only:false; response_revision:11584; number_of_response:1; }","duration":"2.800904349s","start":"2026-04-07T13:18:55.401783Z","end":"2026-04-07T13:18:58.202687Z","steps":["trace[1289505303] 'process raft request' (duration: 2.800747056s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:58.202878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:55.401746Z","time spent":"2.801057454s","remote":"127.0.0.1:59858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:18:58.260298Z","caller":"traceutil/trace.go:171","msg":"trace[2021150930] transaction","detail":"{read_only:false; response_revision:11585; number_of_response:1; }","duration":"2.545598428s","start":"2026-04-07T13:18:55.714652Z","end":"2026-04-07T13:18:58.260251Z","steps":["trace[2021150930] 'process raft request' (duration: 2.506808648s)","trace[2021150930] 'compare' (duration: 38.414912ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:18:58.260515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:55.714622Z","time spent":"2.545777991s","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:18:58.260723Z","caller":"traceutil/trace.go:171","msg":"trace[1680192496] linearizableReadLoop","detail":"{readStateIndex:12127; appliedIndex:12124; }","duration":"2.765976433s","start":"2026-04-07T13:18:55.4947Z","end":"2026-04-07T13:18:58.260676Z","steps":["trace[1680192496] 'read index received' (duration: 2.707791707s)","trace[1680192496] 'applied index is now lower than readState.Index' (duration: 58.183296ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:18:58.260819Z","caller":"traceutil/trace.go:171","msg":"trace[1240987859] transaction","detail":"{read_only:false; response_revision:11586; number_of_response:1; }","duration":"1.75231921s","start":"2026-04-07T13:18:56.508463Z","end":"2026-04-07T13:18:58.260782Z","steps":["trace[1240987859] 'process raft request' (duration: 1.751728097s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:58.260888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.766180817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:18:58.260944Z","caller":"traceutil/trace.go:171","msg":"trace[640572651] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:11586; }","duration":"2.766284759s","start":"2026-04-07T13:18:55.494647Z","end":"2026-04-07T13:18:58.260932Z","steps":["trace[640572651] 'agreement among raft nodes before linearized reading' (duration: 2.766176967s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:58.26099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:55.494624Z","time spent":"2.766348911s","remote":"127.0.0.1:59644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":2,"response size":31,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-04-07T13:18:58.260984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:56.50843Z","time spent":"1.752454963s","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:18:58.268302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.548966959s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-04-07T13:18:58.268313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.547471387s","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-04-07T13:18:58.268348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.559683148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-07T13:18:58.268383Z","caller":"traceutil/trace.go:171","msg":"trace[462155535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11586; }","duration":"1.547571468s","start":"2026-04-07T13:18:56.720798Z","end":"2026-04-07T13:18:58.268369Z","steps":["trace[462155535] 'agreement among raft nodes before linearized reading' (duration: 1.547340524s)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:18:58.268403Z","caller":"traceutil/trace.go:171","msg":"trace[433244140] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11586; }","duration":"1.559791721s","start":"2026-04-07T13:18:56.708597Z","end":"2026-04-07T13:18:58.268388Z","steps":["trace[433244140] 'agreement among raft nodes before linearized reading' (duration: 1.559572776s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:58.268439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:56.720788Z","time spent":"1.54762763s","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-07T13:18:58.268448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.548205743s","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-04-07T13:18:58.268455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:56.708567Z","time spent":"1.559879262s","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T13:18:58.268488Z","caller":"traceutil/trace.go:171","msg":"trace[1824238415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11586; }","duration":"1.548265544s","start":"2026-04-07T13:18:56.720212Z","end":"2026-04-07T13:18:58.268477Z","steps":["trace[1824238415] 'agreement among raft nodes before linearized reading' (duration: 1.548174212s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:58.26853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:56.720201Z","time spent":"1.548321975s","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T13:18:58.268361Z","caller":"traceutil/trace.go:171","msg":"trace[200916918] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:11586; }","duration":"1.54905305s","start":"2026-04-07T13:18:56.719295Z","end":"2026-04-07T13:18:58.268348Z","steps":["trace[200916918] 'agreement among raft nodes before linearized reading' (duration: 1.548893457s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:58.268623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.552407092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-07T13:18:58.268648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:56.719288Z","time spent":"1.549353377s","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":550,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-07T13:18:58.26876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.968167591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/staffeln-db-sync-w7lq4.18a4158ab2a8030e\" ","response":"range_response_count:1 size:882"} {"level":"info","ts":"2026-04-07T13:18:58.268652Z","caller":"traceutil/trace.go:171","msg":"trace[190159217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11586; }","duration":"1.552454823s","start":"2026-04-07T13:18:56.716188Z","end":"2026-04-07T13:18:58.268643Z","steps":["trace[190159217] 'agreement among raft nodes before linearized reading' (duration: 1.552350051s)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:18:58.268803Z","caller":"traceutil/trace.go:171","msg":"trace[630849964] range","detail":"{range_begin:/registry/events/openstack/staffeln-db-sync-w7lq4.18a4158ab2a8030e; range_end:; response_count:1; response_revision:11586; }","duration":"1.968205901s","start":"2026-04-07T13:18:56.300584Z","end":"2026-04-07T13:18:58.26879Z","steps":["trace[630849964] 'agreement among raft nodes before linearized reading' (duration: 1.968100259s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:58.268801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.562189492s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-07T13:18:58.268889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.402741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:18:58.268898Z","caller":"traceutil/trace.go:171","msg":"trace[506778448] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11586; }","duration":"1.562338725s","start":"2026-04-07T13:18:56.706538Z","end":"2026-04-07T13:18:58.268877Z","steps":["trace[506778448] 'agreement among raft nodes before linearized reading' (duration: 1.56208131s)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:18:58.268927Z","caller":"traceutil/trace.go:171","msg":"trace[73824737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11586; }","duration":"488.474403ms","start":"2026-04-07T13:18:57.780444Z","end":"2026-04-07T13:18:58.268918Z","steps":["trace[73824737] 'agreement among raft nodes before linearized reading' (duration: 488.394801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:58.268921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.786650915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:1 size:5129"} {"level":"warn","ts":"2026-04-07T13:18:58.268969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:57.780425Z","time spent":"488.537264ms","remote":"127.0.0.1:59424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T13:18:58.268844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.716070695s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:18:58.269006Z","caller":"traceutil/trace.go:171","msg":"trace[1212310816] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:1; response_revision:11586; }","duration":"1.786830209s","start":"2026-04-07T13:18:56.482154Z","end":"2026-04-07T13:18:58.268985Z","steps":["trace[1212310816] 'agreement among raft nodes before linearized reading' (duration: 1.786517203s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:58.269043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.00558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"warn","ts":"2026-04-07T13:18:58.26905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.496803922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-79rlw\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-07T13:18:58.26907Z","caller":"traceutil/trace.go:171","msg":"trace[1722145273] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:11586; }","duration":"1.716388662s","start":"2026-04-07T13:18:56.552631Z","end":"2026-04-07T13:18:58.269019Z","steps":["trace[1722145273] 'agreement among raft nodes before linearized reading' (duration: 1.715987294s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:58.269142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05090108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:18:58.269182Z","caller":"traceutil/trace.go:171","msg":"trace[449869230] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:11586; }","duration":"1.050985871s","start":"2026-04-07T13:18:57.218185Z","end":"2026-04-07T13:18:58.26917Z","steps":["trace[449869230] 'agreement among raft nodes before linearized reading' (duration: 1.05092372s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:58.269177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:56.55261Z","time spent":"1.716548895s","remote":"127.0.0.1:60182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-07T13:18:58.269214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:57.218158Z","time spent":"1.051047483s","remote":"127.0.0.1:34920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"warn","ts":"2026-04-07T13:18:58.269248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262338274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-07T13:18:58.268943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:56.706506Z","time spent":"1.562430297s","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T13:18:58.269303Z","caller":"traceutil/trace.go:171","msg":"trace[240223389] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:11586; }","duration":"1.262445136s","start":"2026-04-07T13:18:57.006849Z","end":"2026-04-07T13:18:58.269294Z","steps":["trace[240223389] 'agreement among raft nodes before linearized reading' (duration: 1.262349504s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:58.269332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:57.006823Z","time spent":"1.262498728s","remote":"127.0.0.1:59550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":13,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-07T13:18:58.269354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328873748s","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-04-07T13:18:58.269084Z","caller":"traceutil/trace.go:171","msg":"trace[1610984060] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:11586; }","duration":"664.110162ms","start":"2026-04-07T13:18:57.604961Z","end":"2026-04-07T13:18:58.269071Z","steps":["trace[1610984060] 'agreement among raft nodes before linearized reading' (duration: 664.01047ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:18:58.26939Z","caller":"traceutil/trace.go:171","msg":"trace[2027940723] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11586; }","duration":"1.328933169s","start":"2026-04-07T13:18:56.940447Z","end":"2026-04-07T13:18:58.269381Z","steps":["trace[2027940723] 'agreement among raft nodes before linearized reading' (duration: 1.328826447s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:58.269405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:57.604934Z","time spent":"664.46017ms","remote":"127.0.0.1:59546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-04-07T13:18:58.269456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:56.940436Z","time spent":"1.328979421s","remote":"127.0.0.1:59546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":669,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T13:18:58.26905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:56.482128Z","time spent":"1.786912401s","remote":"127.0.0.1:59640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":5153,"request content":"key:\"/registry/jobs/openstack/staffeln-db-sync\" "} {"level":"warn","ts":"2026-04-07T13:18:58.268834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:56.300511Z","time spent":"1.968315833s","remote":"127.0.0.1:59526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":906,"request content":"key:\"/registry/events/openstack/staffeln-db-sync-w7lq4.18a4158ab2a8030e\" "} {"level":"warn","ts":"2026-04-07T13:18:58.268836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:56.716176Z","time spent":"1.552647937s","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-07T13:18:58.268937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.332930275s","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-04-07T13:18:58.269771Z","caller":"traceutil/trace.go:171","msg":"trace[1954741591] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:11586; }","duration":"1.333758823s","start":"2026-04-07T13:18:56.935999Z","end":"2026-04-07T13:18:58.269758Z","steps":["trace[1954741591] 'agreement among raft nodes before linearized reading' (duration: 1.332870054s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:58.269833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:56.935901Z","time spent":"1.333920306s","remote":"127.0.0.1:59546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-07T13:18:58.269087Z","caller":"traceutil/trace.go:171","msg":"trace[1694219536] range","detail":"{range_begin:/registry/pods/openstack/keepalived-79rlw; range_end:; response_count:1; response_revision:11586; }","duration":"1.496865443s","start":"2026-04-07T13:18:56.772212Z","end":"2026-04-07T13:18:58.269077Z","steps":["trace[1694219536] 'agreement among raft nodes before linearized reading' (duration: 1.496762061s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:58.269993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:56.7722Z","time spent":"1.497764043s","remote":"127.0.0.1:59594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6551,"request content":"key:\"/registry/pods/openstack/keepalived-79rlw\" "} {"level":"warn","ts":"2026-04-07T13:18:58.269341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.004405325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:18:58.270547Z","caller":"traceutil/trace.go:171","msg":"trace[253921847] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:11586; }","duration":"2.005907787s","start":"2026-04-07T13:18:56.264595Z","end":"2026-04-07T13:18:58.270503Z","steps":["trace[253921847] 'agreement among raft nodes before linearized reading' (duration: 2.003529076s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:18:58.270691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:18:56.26449Z","time spent":"2.006150662s","remote":"127.0.0.1:36848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":1,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true "} {"level":"warn","ts":"2026-04-07T13:18:58.381443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.90106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:18:58.381545Z","caller":"traceutil/trace.go:171","msg":"trace[1364335333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11595; }","duration":"102.047534ms","start":"2026-04-07T13:18:58.279477Z","end":"2026-04-07T13:18:58.381524Z","steps":["trace[1364335333] 'agreement among raft nodes before linearized reading' (duration: 101.8663ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:19:36.395259Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9451} {"level":"info","ts":"2026-04-07T13:19:36.4807Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9451,"took":"83.048094ms","hash":3626639310,"current-db-size-bytes":61120512,"current-db-size":"61 MB","current-db-size-in-use-bytes":25444352,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-07T13:19:36.480792Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3626639310,"revision":9451,"compact-revision":6553}