{"level":"warn","ts":"2026-07-24T04:34:29.128675Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T04:34:29.128773Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-07-24T04:34:29.128853Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T04:34:29.129348Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-07-24T04:34:29.129545Z","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-07-24T04:34:29.130259Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-07-24T04:34:29.130359Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-07-24T04:34:29.135509Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.91581ms"} {"level":"info","ts":"2026-07-24T04:34:29.147814Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-07-24T04:34:29.14788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T04:34:29.1479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-07-24T04:34:29.147911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T04:34:29.147923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-07-24T04:34:29.147958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-07-24T04:34:29.155464Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T04:34:29.156548Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T04:34:29.157547Z","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-07-24T04:34:29.161624Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T04:34:29.161784Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T04:34:29.162092Z","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-07-24T04:34:29.162218Z","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-07-24T04:34:29.162257Z","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-07-24T04:34:29.16245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-07-24T04:34:29.162527Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-07-24T04:34:29.165623Z","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-07-24T04:34:29.165764Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-07-24T04:34:29.165847Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-07-24T04:34:29.165904Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T04:34:29.165973Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T04:34:29.448334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T04:34:29.44839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T04:34:29.44843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-07-24T04:34:29.448456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-07-24T04:34:29.448472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-07-24T04:34:29.448493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-07-24T04:34:29.448511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-07-24T04:34:29.449593Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:34:29.450259Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T04:34:29.450263Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T04:34:29.450337Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T04:34:29.450504Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T04:34:29.45057Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T04:34:29.452991Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-24T04:34:29.461033Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:34:29.46217Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-07-24T04:34:29.462713Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:34:29.4628Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-07-24T04:34:35.730681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.171975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-07-24T04:34:35.730771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.606993ms","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-07-24T04:34:35.730819Z","caller":"traceutil/trace.go:171","msg":"trace[847153880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:325; }","duration":"222.891635ms","start":"2026-07-24T04:34:35.507913Z","end":"2026-07-24T04:34:35.730805Z","steps":["trace[847153880] 'range keys from in-memory index tree' (duration: 222.470591ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:34:35.730827Z","caller":"traceutil/trace.go:171","msg":"trace[1919473826] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:325; }","duration":"185.354997ms","start":"2026-07-24T04:34:35.545447Z","end":"2026-07-24T04:34:35.730802Z","steps":["trace[1919473826] 'range keys from in-memory index tree' (duration: 185.077694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:34:36.062919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.525805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:34:36.063167Z","caller":"traceutil/trace.go:171","msg":"trace[1169712995] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:337; }","duration":"262.137776ms","start":"2026-07-24T04:34:35.801014Z","end":"2026-07-24T04:34:36.063152Z","steps":["trace[1169712995] 'read index received' (duration: 79.965674ms)","trace[1169712995] 'applied index is now lower than readState.Index' (duration: 182.171032ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:34:36.063351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.329148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-24T04:34:36.063564Z","caller":"traceutil/trace.go:171","msg":"trace[1745428445] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"329.128032ms","start":"2026-07-24T04:34:35.734396Z","end":"2026-07-24T04:34:36.063524Z","steps":["trace[1745428445] 'process raft request' (duration: 146.649558ms)","trace[1745428445] 'compare' (duration: 180.401494ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:34:36.063543Z","caller":"traceutil/trace.go:171","msg":"trace[1842509455] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"328.451146ms","start":"2026-07-24T04:34:35.735075Z","end":"2026-07-24T04:34:36.063526Z","steps":["trace[1842509455] 'process raft request' (duration: 328.014572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:34:36.063765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:34:35.734388Z","time spent":"329.277514ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:34:36.063903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:34:35.735068Z","time spent":"328.751739ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:34:36.063628Z","caller":"traceutil/trace.go:171","msg":"trace[1267900739] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:328; }","duration":"262.63322ms","start":"2026-07-24T04:34:35.800984Z","end":"2026-07-24T04:34:36.063617Z","steps":["trace[1267900739] 'agreement among raft nodes before linearized reading' (duration: 262.250297ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:34:36.333393Z","caller":"traceutil/trace.go:171","msg":"trace[137091413] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"251.138015ms","start":"2026-07-24T04:34:36.082225Z","end":"2026-07-24T04:34:36.333363Z","steps":["trace[137091413] 'process raft request' (duration: 152.115731ms)","trace[137091413] 'compare' (duration: 98.860172ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:35:05.153775Z","caller":"traceutil/trace.go:171","msg":"trace[1574435266] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"110.960383ms","start":"2026-07-24T04:35:05.0428Z","end":"2026-07-24T04:35:05.153761Z","steps":["trace[1574435266] 'process raft request' (duration: 110.88596ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:07.288811Z","caller":"traceutil/trace.go:171","msg":"trace[884154607] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"100.738586ms","start":"2026-07-24T04:35:07.188044Z","end":"2026-07-24T04:35:07.288783Z","steps":["trace[884154607] 'read index received' (duration: 100.490616ms)","trace[884154607] 'applied index is now lower than readState.Index' (duration: 246.77µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:35:07.28906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.993527ms","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-07-24T04:35:07.289096Z","caller":"traceutil/trace.go:171","msg":"trace[954653642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"101.083141ms","start":"2026-07-24T04:35:07.188001Z","end":"2026-07-24T04:35:07.289084Z","steps":["trace[954653642] 'agreement among raft nodes before linearized reading' (duration: 100.889333ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:07.289398Z","caller":"traceutil/trace.go:171","msg":"trace[788676709] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"126.063219ms","start":"2026-07-24T04:35:07.163325Z","end":"2026-07-24T04:35:07.289388Z","steps":["trace[788676709] 'process raft request' (duration: 125.311277ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:07.424494Z","caller":"traceutil/trace.go:171","msg":"trace[71970498] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"130.841243ms","start":"2026-07-24T04:35:07.293637Z","end":"2026-07-24T04:35:07.424478Z","steps":["trace[71970498] 'process raft request' (duration: 130.744409ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:08.416336Z","caller":"traceutil/trace.go:171","msg":"trace[839595373] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"350.536825ms","start":"2026-07-24T04:35:08.065781Z","end":"2026-07-24T04:35:08.416317Z","steps":["trace[839595373] 'process raft request' (duration: 341.44229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:08.416546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:35:08.065757Z","time spent":"350.708072ms","remote":"127.0.0.1:37422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036015,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:35:08.416595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.777098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:35:08.416754Z","caller":"traceutil/trace.go:171","msg":"trace[1088701589] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:610; }","duration":"311.931784ms","start":"2026-07-24T04:35:08.104752Z","end":"2026-07-24T04:35:08.416683Z","steps":["trace[1088701589] 'agreement among raft nodes before linearized reading' (duration: 311.660433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:08.416842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:35:08.104736Z","time spent":"312.09004ms","remote":"127.0.0.1:54304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-24T04:35:08.417254Z","caller":"traceutil/trace.go:171","msg":"trace[633776100] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"311.471604ms","start":"2026-07-24T04:35:08.104782Z","end":"2026-07-24T04:35:08.416254Z","steps":["trace[633776100] 'read index received' (duration: 301.998973ms)","trace[633776100] 'applied index is now lower than readState.Index' (duration: 9.471711ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:35:08.417396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.467975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:35:08.417433Z","caller":"traceutil/trace.go:171","msg":"trace[8498393] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:610; }","duration":"312.526738ms","start":"2026-07-24T04:35:08.104895Z","end":"2026-07-24T04:35:08.417422Z","steps":["trace[8498393] 'agreement among raft nodes before linearized reading' (duration: 312.462525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:08.417471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:35:08.104888Z","time spent":"312.573ms","remote":"127.0.0.1:54304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-24T04:35:08.417474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.047941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:35:08.417449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.260583ms","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":"warn","ts":"2026-07-24T04:35:08.41758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.739973ms","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-07-24T04:35:08.417603Z","caller":"traceutil/trace.go:171","msg":"trace[1188784668] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:610; }","duration":"169.778825ms","start":"2026-07-24T04:35:08.247818Z","end":"2026-07-24T04:35:08.417597Z","steps":["trace[1188784668] 'agreement among raft nodes before linearized reading' (duration: 169.745624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:08.417618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.17629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:35:08.417658Z","caller":"traceutil/trace.go:171","msg":"trace[689999380] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:610; }","duration":"288.216282ms","start":"2026-07-24T04:35:08.12943Z","end":"2026-07-24T04:35:08.417647Z","steps":["trace[689999380] 'agreement among raft nodes before linearized reading' (duration: 288.163249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:08.417643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.414553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:35:08.417638Z","caller":"traceutil/trace.go:171","msg":"trace[1708266521] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:610; }","duration":"169.487793ms","start":"2026-07-24T04:35:08.248103Z","end":"2026-07-24T04:35:08.417591Z","steps":["trace[1708266521] 'agreement among raft nodes before linearized reading' (duration: 169.256533ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:08.417728Z","caller":"traceutil/trace.go:171","msg":"trace[1617138216] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:610; }","duration":"312.515068ms","start":"2026-07-24T04:35:08.10518Z","end":"2026-07-24T04:35:08.417695Z","steps":["trace[1617138216] 'agreement among raft nodes before linearized reading' (duration: 312.418293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:08.417823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.106615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-24T04:35:08.417846Z","caller":"traceutil/trace.go:171","msg":"trace[299646728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"199.144417ms","start":"2026-07-24T04:35:08.218695Z","end":"2026-07-24T04:35:08.417839Z","steps":["trace[299646728] 'agreement among raft nodes before linearized reading' (duration: 199.037752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:08.41787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:35:08.10516Z","time spent":"312.664134ms","remote":"127.0.0.1:54304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"info","ts":"2026-07-24T04:35:08.417544Z","caller":"traceutil/trace.go:171","msg":"trace[1515989858] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:610; }","duration":"287.159486ms","start":"2026-07-24T04:35:08.130369Z","end":"2026-07-24T04:35:08.417528Z","steps":["trace[1515989858] 'agreement among raft nodes before linearized reading' (duration: 287.042421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:08.418011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.920129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-24T04:35:08.418052Z","caller":"traceutil/trace.go:171","msg":"trace[524746451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"199.997982ms","start":"2026-07-24T04:35:08.21804Z","end":"2026-07-24T04:35:08.418038Z","steps":["trace[524746451] 'agreement among raft nodes before linearized reading' (duration: 199.853266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:08.418084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.773245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:35:08.418132Z","caller":"traceutil/trace.go:171","msg":"trace[1618956194] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:610; }","duration":"288.863848ms","start":"2026-07-24T04:35:08.129255Z","end":"2026-07-24T04:35:08.418118Z","steps":["trace[1618956194] 'agreement among raft nodes before linearized reading' (duration: 288.764484ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:11.39674Z","caller":"traceutil/trace.go:171","msg":"trace[177993659] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"184.442577ms","start":"2026-07-24T04:35:11.212269Z","end":"2026-07-24T04:35:11.396712Z","steps":["trace[177993659] 'process raft request' (duration: 183.7963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:11.918205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.529848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:35:11.918896Z","caller":"traceutil/trace.go:171","msg":"trace[1037676654] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"164.211211ms","start":"2026-07-24T04:35:11.754666Z","end":"2026-07-24T04:35:11.918878Z","steps":["trace[1037676654] 'process raft request' (duration: 163.904498ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:11.918918Z","caller":"traceutil/trace.go:171","msg":"trace[263039519] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"469.706614ms","start":"2026-07-24T04:35:11.449182Z","end":"2026-07-24T04:35:11.918888Z","steps":["trace[263039519] 'process raft request' (duration: 322.419955ms)","trace[263039519] 'compare' (duration: 146.209615ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:35:11.918861Z","caller":"traceutil/trace.go:171","msg":"trace[2028918525] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"435.206383ms","start":"2026-07-24T04:35:11.483612Z","end":"2026-07-24T04:35:11.918819Z","steps":["trace[2028918525] 'process raft request' (duration: 434.716823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:11.91905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:35:11.449165Z","time spent":"469.818198ms","remote":"127.0.0.1:37800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:35:11.919161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:35:11.483581Z","time spent":"435.466023ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:35:27.047342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.052937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:35:27.047468Z","caller":"traceutil/trace.go:171","msg":"trace[1468275087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"151.197372ms","start":"2026-07-24T04:35:26.89622Z","end":"2026-07-24T04:35:27.047417Z","steps":["trace[1468275087] 'range keys from in-memory index tree' (duration: 150.946493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:49.397096Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021965303719184,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T04:35:49.793175Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.155284306s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T04:35:49.793742Z","caller":"traceutil/trace.go:171","msg":"trace[1887217925] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"1.155905303s","start":"2026-07-24T04:35:48.637814Z","end":"2026-07-24T04:35:49.79372Z","steps":["trace[1887217925] 'process raft request' (duration: 1.155770529s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:49.793867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:35:48.637805Z","time spent":"1.156002055s","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:35:49.794904Z","caller":"traceutil/trace.go:171","msg":"trace[1897583492] linearizableReadLoop","detail":"{readStateIndex:1171; appliedIndex:1170; }","duration":"898.776584ms","start":"2026-07-24T04:35:48.896075Z","end":"2026-07-24T04:35:49.794852Z","steps":["trace[1897583492] 'read index received' (duration: 897.578291ms)","trace[1897583492] 'applied index is now lower than readState.Index' (duration: 1.197323ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:35:49.794946Z","caller":"traceutil/trace.go:171","msg":"trace[390193464] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"235.217124ms","start":"2026-07-24T04:35:49.559701Z","end":"2026-07-24T04:35:49.794918Z","steps":["trace[390193464] 'process raft request' (duration: 235.047369ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:49.794949Z","caller":"traceutil/trace.go:171","msg":"trace[1309377362] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"1.156989193s","start":"2026-07-24T04:35:48.637922Z","end":"2026-07-24T04:35:49.794911Z","steps":["trace[1309377362] 'process raft request' (duration: 1.156627883s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:49.795021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.935058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:35:49.795164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:35:48.637906Z","time spent":"1.157137757s","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:35:49.795269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.219197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-24T04:35:49.795361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.077958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-24T04:35:49.795355Z","caller":"traceutil/trace.go:171","msg":"trace[1430721564] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1139; }","duration":"422.336359ms","start":"2026-07-24T04:35:49.372997Z","end":"2026-07-24T04:35:49.795333Z","steps":["trace[1430721564] 'agreement among raft nodes before linearized reading' (duration: 422.094343ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:49.795395Z","caller":"traceutil/trace.go:171","msg":"trace[1198160314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1139; }","duration":"213.164112ms","start":"2026-07-24T04:35:49.582221Z","end":"2026-07-24T04:35:49.795385Z","steps":["trace[1198160314] 'agreement among raft nodes before linearized reading' (duration: 212.879043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:49.795419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:35:49.372982Z","time spent":"422.427343ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-24T04:35:49.795561Z","caller":"traceutil/trace.go:171","msg":"trace[315509945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1139; }","duration":"899.039671ms","start":"2026-07-24T04:35:48.896024Z","end":"2026-07-24T04:35:49.795064Z","steps":["trace[315509945] 'agreement among raft nodes before linearized reading' (duration: 898.955969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:49.795592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:35:48.896006Z","time spent":"899.578627ms","remote":"127.0.0.1:37344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T04:35:50.199799Z","caller":"traceutil/trace.go:171","msg":"trace[1983059836] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"107.34855ms","start":"2026-07-24T04:35:50.092429Z","end":"2026-07-24T04:35:50.199778Z","steps":["trace[1983059836] 'process raft request' (duration: 107.192106ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:35:50.907395Z","caller":"traceutil/trace.go:171","msg":"trace[2138299056] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"308.570578ms","start":"2026-07-24T04:35:50.598793Z","end":"2026-07-24T04:35:50.907363Z","steps":["trace[2138299056] 'process raft request' (duration: 307.785957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:35:50.907613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:35:50.598783Z","time spent":"308.727073ms","remote":"127.0.0.1:37498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:36:48.144423Z","caller":"traceutil/trace.go:171","msg":"trace[1306958478] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1935; }","duration":"280.990515ms","start":"2026-07-24T04:36:47.863384Z","end":"2026-07-24T04:36:48.144375Z","steps":["trace[1306958478] 'read index received' (duration: 235.452077ms)","trace[1306958478] 'applied index is now lower than readState.Index' (duration: 45.535298ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:36:48.144522Z","caller":"traceutil/trace.go:171","msg":"trace[980492886] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"273.252434ms","start":"2026-07-24T04:36:47.871261Z","end":"2026-07-24T04:36:48.144514Z","steps":["trace[980492886] 'process raft request' (duration: 273.004929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:48.144667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.639445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:36:48.144716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.303721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-43fb45eb-c9a2-4bce-8960-a8fab923faa0\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-07-24T04:36:48.144728Z","caller":"traceutil/trace.go:171","msg":"trace[113875545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1866; }","duration":"247.752637ms","start":"2026-07-24T04:36:47.896961Z","end":"2026-07-24T04:36:48.144714Z","steps":["trace[113875545] 'agreement among raft nodes before linearized reading' (duration: 247.653315ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:48.144753Z","caller":"traceutil/trace.go:171","msg":"trace[1498953277] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-43fb45eb-c9a2-4bce-8960-a8fab923faa0; range_end:; response_count:1; response_revision:1866; }","duration":"281.384493ms","start":"2026-07-24T04:36:47.863358Z","end":"2026-07-24T04:36:48.144743Z","steps":["trace[1498953277] 'agreement among raft nodes before linearized reading' (duration: 281.194229ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:48.144465Z","caller":"traceutil/trace.go:171","msg":"trace[1950424811] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"134.602898ms","start":"2026-07-24T04:36:48.009841Z","end":"2026-07-24T04:36:48.144444Z","steps":["trace[1950424811] 'process raft request' (duration: 134.544687ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:48.264505Z","caller":"traceutil/trace.go:171","msg":"trace[2058202244] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"115.654984ms","start":"2026-07-24T04:36:48.148826Z","end":"2026-07-24T04:36:48.264481Z","steps":["trace[2058202244] 'process raft request' (duration: 82.675104ms)","trace[2058202244] 'compare' (duration: 32.44523ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:36:48.264636Z","caller":"traceutil/trace.go:171","msg":"trace[1054034763] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"115.095333ms","start":"2026-07-24T04:36:48.149521Z","end":"2026-07-24T04:36:48.264617Z","steps":["trace[1054034763] 'process raft request' (duration: 114.549514ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:48.264691Z","caller":"traceutil/trace.go:171","msg":"trace[1818918064] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"114.110477ms","start":"2026-07-24T04:36:48.15057Z","end":"2026-07-24T04:36:48.26468Z","steps":["trace[1818918064] 'process raft request' (duration: 113.584037ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:36:48.264775Z","caller":"traceutil/trace.go:171","msg":"trace[2020137243] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"115.195855ms","start":"2026-07-24T04:36:48.149568Z","end":"2026-07-24T04:36:48.264764Z","steps":["trace[2020137243] 'process raft request' (duration: 114.545233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:36:48.573685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.876438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T04:36:48.57376Z","caller":"traceutil/trace.go:171","msg":"trace[1018173397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1871; }","duration":"115.997211ms","start":"2026-07-24T04:36:48.457748Z","end":"2026-07-24T04:36:48.573745Z","steps":["trace[1018173397] 'range keys from in-memory index tree' (duration: 115.438839ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:01.407933Z","caller":"traceutil/trace.go:171","msg":"trace[1108737611] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"143.605366ms","start":"2026-07-24T04:37:01.264306Z","end":"2026-07-24T04:37:01.407911Z","steps":["trace[1108737611] 'process raft request' (duration: 143.471863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:22.334986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.340219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-24T04:37:22.335101Z","caller":"traceutil/trace.go:171","msg":"trace[1544942664] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2106; }","duration":"371.515862ms","start":"2026-07-24T04:37:21.963556Z","end":"2026-07-24T04:37:22.335072Z","steps":["trace[1544942664] 'range keys from in-memory index tree' (duration: 371.122416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:22.335098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.529898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c51fd74fd6eb11\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-07-24T04:37:22.335296Z","caller":"traceutil/trace.go:171","msg":"trace[27552673] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18c51fd74fd6eb11; range_end:; response_count:1; response_revision:2106; }","duration":"372.800613ms","start":"2026-07-24T04:37:21.962452Z","end":"2026-07-24T04:37:22.335252Z","steps":["trace[27552673] 'range keys from in-memory index tree' (duration: 372.300345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:22.335371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:21.96243Z","time spent":"372.926444ms","remote":"127.0.0.1:37404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":868,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18c51fd74fd6eb11\" "} {"level":"warn","ts":"2026-07-24T04:37:22.335153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:37:21.963534Z","time spent":"371.607203ms","remote":"127.0.0.1:58808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-24T04:37:22.41082Z","caller":"traceutil/trace.go:171","msg":"trace[755246172] linearizableReadLoop","detail":"{readStateIndex:2186; appliedIndex:2185; }","duration":"132.389584ms","start":"2026-07-24T04:37:22.278396Z","end":"2026-07-24T04:37:22.410785Z","steps":["trace[755246172] 'read index received' (duration: 132.228341ms)","trace[755246172] 'applied index is now lower than readState.Index' (duration: 159.473µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:37:22.410989Z","caller":"traceutil/trace.go:171","msg":"trace[100240132] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"142.160205ms","start":"2026-07-24T04:37:22.268815Z","end":"2026-07-24T04:37:22.410976Z","steps":["trace[100240132] 'process raft request' (duration: 141.734858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:22.411157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.744009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12384"} {"level":"info","ts":"2026-07-24T04:37:22.411232Z","caller":"traceutil/trace.go:171","msg":"trace[1505019108] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2107; }","duration":"132.83994ms","start":"2026-07-24T04:37:22.278375Z","end":"2026-07-24T04:37:22.411214Z","steps":["trace[1505019108] 'agreement among raft nodes before linearized reading' (duration: 132.649187ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:22.526374Z","caller":"traceutil/trace.go:171","msg":"trace[724950541] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"188.266845ms","start":"2026-07-24T04:37:22.338074Z","end":"2026-07-24T04:37:22.526341Z","steps":["trace[724950541] 'process raft request' (duration: 171.537424ms)","trace[724950541] 'compare' (duration: 16.486416ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:37:22.526535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.717392ms","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-07-24T04:37:22.526604Z","caller":"traceutil/trace.go:171","msg":"trace[567585857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2108; }","duration":"163.840204ms","start":"2026-07-24T04:37:22.362753Z","end":"2026-07-24T04:37:22.526593Z","steps":["trace[567585857] 'agreement among raft nodes before linearized reading' (duration: 163.618611ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:22.52631Z","caller":"traceutil/trace.go:171","msg":"trace[276451936] linearizableReadLoop","detail":"{readStateIndex:2187; appliedIndex:2186; }","duration":"115.361848ms","start":"2026-07-24T04:37:22.410915Z","end":"2026-07-24T04:37:22.526277Z","steps":["trace[276451936] 'read index received' (duration: 98.630617ms)","trace[276451936] 'applied index is now lower than readState.Index' (duration: 16.730101ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:37:22.52726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.66997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:37:22.527294Z","caller":"traceutil/trace.go:171","msg":"trace[626604091] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2108; }","duration":"102.753992ms","start":"2026-07-24T04:37:22.424531Z","end":"2026-07-24T04:37:22.527285Z","steps":["trace[626604091] 'agreement among raft nodes before linearized reading' (duration: 102.66407ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:22.801487Z","caller":"traceutil/trace.go:171","msg":"trace[1229757644] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"269.525411ms","start":"2026-07-24T04:37:22.53193Z","end":"2026-07-24T04:37:22.801455Z","steps":["trace[1229757644] 'process raft request' (duration: 269.47816ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:22.801803Z","caller":"traceutil/trace.go:171","msg":"trace[1351708236] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"269.875266ms","start":"2026-07-24T04:37:22.531916Z","end":"2026-07-24T04:37:22.801791Z","steps":["trace[1351708236] 'process raft request' (duration: 269.455619ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:22.801848Z","caller":"traceutil/trace.go:171","msg":"trace[813955256] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"270.002928ms","start":"2026-07-24T04:37:22.5318Z","end":"2026-07-24T04:37:22.801803Z","steps":["trace[813955256] 'process raft request' (duration: 269.48335ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:22.801905Z","caller":"traceutil/trace.go:171","msg":"trace[1958116211] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"254.616758ms","start":"2026-07-24T04:37:22.547277Z","end":"2026-07-24T04:37:22.801894Z","steps":["trace[1958116211] 'process raft request' (duration: 254.312394ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:22.802094Z","caller":"traceutil/trace.go:171","msg":"trace[1786777019] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"270.299803ms","start":"2026-07-24T04:37:22.531783Z","end":"2026-07-24T04:37:22.802083Z","steps":["trace[1786777019] 'process raft request' (duration: 268.17564ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:37:22.802241Z","caller":"traceutil/trace.go:171","msg":"trace[1151135999] linearizableReadLoop","detail":"{readStateIndex:2193; appliedIndex:2188; }","duration":"249.418797ms","start":"2026-07-24T04:37:22.552793Z","end":"2026-07-24T04:37:22.802212Z","steps":["trace[1151135999] 'read index received' (duration: 247.113611ms)","trace[1151135999] 'applied index is now lower than readState.Index' (duration: 2.303666ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:37:22.802379Z","caller":"traceutil/trace.go:171","msg":"trace[1630833979] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"241.023006ms","start":"2026-07-24T04:37:22.561309Z","end":"2026-07-24T04:37:22.802332Z","steps":["trace[1630833979] 'process raft request' (duration: 240.479598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:37:22.802563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.737732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-24T04:37:22.802622Z","caller":"traceutil/trace.go:171","msg":"trace[327677737] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2114; }","duration":"249.836443ms","start":"2026-07-24T04:37:22.552766Z","end":"2026-07-24T04:37:22.802603Z","steps":["trace[327677737] 'agreement among raft nodes before linearized reading' (duration: 249.60902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:38:59.391072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021965303722754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T04:38:59.89214Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021965303722754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T04:39:00.393161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021965303722754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T04:39:00.396815Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.647568809s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T04:39:00.397294Z","caller":"traceutil/trace.go:171","msg":"trace[753607359] transaction","detail":"{read_only:false; response_revision:2737; number_of_response:1; }","duration":"1.648165558s","start":"2026-07-24T04:38:58.749107Z","end":"2026-07-24T04:39:00.397273Z","steps":["trace[753607359] 'process raft request' (duration: 1.648000516s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:00.397472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:38:58.749081Z","time spent":"1.648269669s","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:39:00.40808Z","caller":"traceutil/trace.go:171","msg":"trace[258223765] linearizableReadLoop","detail":"{readStateIndex:2843; appliedIndex:2839; }","duration":"1.517292272s","start":"2026-07-24T04:38:58.890766Z","end":"2026-07-24T04:39:00.408058Z","steps":["trace[258223765] 'read index received' (duration: 1.506296553s)","trace[258223765] 'applied index is now lower than readState.Index' (duration: 10.994459ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:39:00.408236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.514500707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:39:00.408219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.517432684s","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-07-24T04:39:00.408283Z","caller":"traceutil/trace.go:171","msg":"trace[1943290688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2740; }","duration":"1.514600668s","start":"2026-07-24T04:38:58.893669Z","end":"2026-07-24T04:39:00.40827Z","steps":["trace[1943290688] 'agreement among raft nodes before linearized reading' (duration: 1.514501667s)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:00.408296Z","caller":"traceutil/trace.go:171","msg":"trace[478903264] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2740; }","duration":"1.517576756s","start":"2026-07-24T04:38:58.890709Z","end":"2026-07-24T04:39:00.408286Z","steps":["trace[478903264] 'agreement among raft nodes before linearized reading' (duration: 1.517425124s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:00.40832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:38:58.893648Z","time spent":"1.514663999s","remote":"127.0.0.1:37356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:39:00.408339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:38:58.890682Z","time spent":"1.517646307s","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:39:00.408485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.008379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-07-24T04:39:00.408557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.11906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c51fea99874b27\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-24T04:39:00.408593Z","caller":"traceutil/trace.go:171","msg":"trace[1232171951] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c51fea99874b27; range_end:; response_count:1; response_revision:2740; }","duration":"626.19279ms","start":"2026-07-24T04:38:59.782392Z","end":"2026-07-24T04:39:00.408585Z","steps":["trace[1232171951] 'agreement among raft nodes before linearized reading' (duration: 626.069099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:00.408606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.700195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:39:00.408619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:38:59.78237Z","time spent":"626.243141ms","remote":"127.0.0.1:37404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":803,"request content":"key:\"/registry/events/auth-system/keycloak-0.18c51fea99874b27\" "} {"level":"warn","ts":"2026-07-24T04:39:00.408666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.161442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T04:39:00.408701Z","caller":"traceutil/trace.go:171","msg":"trace[1105246141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2740; }","duration":"945.211124ms","start":"2026-07-24T04:38:59.463482Z","end":"2026-07-24T04:39:00.408693Z","steps":["trace[1105246141] 'agreement among raft nodes before linearized reading' (duration: 945.094652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:00.408693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.988531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-24T04:39:00.408733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:38:59.463474Z","time spent":"945.253564ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-24T04:39:00.408557Z","caller":"traceutil/trace.go:171","msg":"trace[1957104130] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2740; }","duration":"371.134681ms","start":"2026-07-24T04:39:00.037404Z","end":"2026-07-24T04:39:00.408539Z","steps":["trace[1957104130] 'agreement among raft nodes before linearized reading' (duration: 370.847237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:00.408807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:00.037376Z","time spent":"371.424414ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-24T04:39:00.408866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.388096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:39:00.4089Z","caller":"traceutil/trace.go:171","msg":"trace[1264847309] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2740; }","duration":"945.501567ms","start":"2026-07-24T04:38:59.463386Z","end":"2026-07-24T04:39:00.408888Z","steps":["trace[1264847309] 'agreement among raft nodes before linearized reading' (duration: 945.411216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:00.408928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:38:59.463157Z","time spent":"945.7647ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":19,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:39:00.409017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.969813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-24T04:39:00.409056Z","caller":"traceutil/trace.go:171","msg":"trace[65429272] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2740; }","duration":"946.034733ms","start":"2026-07-24T04:38:59.463014Z","end":"2026-07-24T04:39:00.409049Z","steps":["trace[65429272] 'agreement among raft nodes before linearized reading' (duration: 945.959222ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:00.409046Z","caller":"traceutil/trace.go:171","msg":"trace[38357641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2740; }","duration":"945.050041ms","start":"2026-07-24T04:38:59.463678Z","end":"2026-07-24T04:39:00.408728Z","steps":["trace[38357641] 'agreement among raft nodes before linearized reading' (duration: 944.538735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:00.409078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:38:59.462996Z","time spent":"946.072794ms","remote":"127.0.0.1:37586","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-07-24T04:39:00.409115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.066424ms","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-07-24T04:39:00.409147Z","caller":"traceutil/trace.go:171","msg":"trace[1625997573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2740; }","duration":"946.125604ms","start":"2026-07-24T04:38:59.463014Z","end":"2026-07-24T04:39:00.409139Z","steps":["trace[1625997573] 'agreement among raft nodes before linearized reading' (duration: 946.044063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:00.409177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:38:59.462996Z","time spent":"946.175486ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-24T04:39:00.409194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.087574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-24T04:39:00.40921Z","caller":"traceutil/trace.go:171","msg":"trace[909666957] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2740; }","duration":"946.150235ms","start":"2026-07-24T04:38:59.463054Z","end":"2026-07-24T04:39:00.409205Z","steps":["trace[909666957] 'agreement among raft nodes before linearized reading' (duration: 946.090214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:00.409233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:38:59.463034Z","time spent":"946.194765ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-24T04:39:00.409097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:38:59.463668Z","time spent":"945.417205ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-24T04:39:00.409407Z","caller":"traceutil/trace.go:171","msg":"trace[207263393] transaction","detail":"{read_only:false; response_revision:2739; number_of_response:1; }","duration":"186.910132ms","start":"2026-07-24T04:39:00.222485Z","end":"2026-07-24T04:39:00.409395Z","steps":["trace[207263393] 'process raft request' (duration: 185.466044ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:00.408629Z","caller":"traceutil/trace.go:171","msg":"trace[852098823] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:2740; }","duration":"929.749257ms","start":"2026-07-24T04:38:59.478873Z","end":"2026-07-24T04:39:00.408622Z","steps":["trace[852098823] 'agreement among raft nodes before linearized reading' (duration: 929.711256ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:00.408098Z","caller":"traceutil/trace.go:171","msg":"trace[1517859474] transaction","detail":"{read_only:false; response_revision:2738; number_of_response:1; }","duration":"943.053925ms","start":"2026-07-24T04:38:59.465015Z","end":"2026-07-24T04:39:00.408069Z","steps":["trace[1517859474] 'process raft request' (duration: 942.764231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:00.409508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:38:59.478857Z","time spent":"930.586027ms","remote":"127.0.0.1:54320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:39:00.409555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:38:59.464991Z","time spent":"944.519564ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:39:00.724441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.380789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:39:00.724636Z","caller":"traceutil/trace.go:171","msg":"trace[440992920] transaction","detail":"{read_only:false; response_revision:2742; number_of_response:1; }","duration":"307.915287ms","start":"2026-07-24T04:39:00.416697Z","end":"2026-07-24T04:39:00.724612Z","steps":["trace[440992920] 'process raft request' (duration: 38.166934ms)","trace[440992920] 'compare' (duration: 269.255957ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:39:00.725072Z","caller":"traceutil/trace.go:171","msg":"trace[1108671382] linearizableReadLoop","detail":"{readStateIndex:2847; appliedIndex:2843; }","duration":"305.71716ms","start":"2026-07-24T04:39:00.41933Z","end":"2026-07-24T04:39:00.725047Z","steps":["trace[1108671382] 'read index received' (duration: 8.600899ms)","trace[1108671382] 'applied index is now lower than readState.Index' (duration: 297.115261ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:39:00.725214Z","caller":"traceutil/trace.go:171","msg":"trace[1739942102] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"307.22419ms","start":"2026-07-24T04:39:00.4179Z","end":"2026-07-24T04:39:00.725124Z","steps":["trace[1739942102] 'process raft request' (duration: 306.660392ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:00.725264Z","caller":"traceutil/trace.go:171","msg":"trace[2073479908] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"305.869172ms","start":"2026-07-24T04:39:00.419386Z","end":"2026-07-24T04:39:00.725256Z","steps":["trace[2073479908] 'process raft request' (duration: 305.529998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:00.725307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:00.417879Z","time spent":"307.383061ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:39:00.725409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:00.41938Z","time spent":"305.924113ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:39:00.725229Z","caller":"traceutil/trace.go:171","msg":"trace[1389451534] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"306.862445ms","start":"2026-07-24T04:39:00.418351Z","end":"2026-07-24T04:39:00.725214Z","steps":["trace[1389451534] 'process raft request' (duration: 306.47403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:00.725779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.439589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:39:00.725831Z","caller":"traceutil/trace.go:171","msg":"trace[569767570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2747; }","duration":"306.50848ms","start":"2026-07-24T04:39:00.41931Z","end":"2026-07-24T04:39:00.725818Z","steps":["trace[569767570] 'agreement among raft nodes before linearized reading' (duration: 306.416229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:00.725872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:00.419302Z","time spent":"306.562501ms","remote":"127.0.0.1:37344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:39:00.726165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.679471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-24T04:39:00.726217Z","caller":"traceutil/trace.go:171","msg":"trace[2064786315] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"306.654432ms","start":"2026-07-24T04:39:00.419554Z","end":"2026-07-24T04:39:00.726208Z","steps":["trace[2064786315] 'process raft request' (duration: 305.443717ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:00.726218Z","caller":"traceutil/trace.go:171","msg":"trace[604759427] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2747; }","duration":"235.836483ms","start":"2026-07-24T04:39:00.49037Z","end":"2026-07-24T04:39:00.726207Z","steps":["trace[604759427] 'agreement among raft nodes before linearized reading' (duration: 235.645271ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:00.72618Z","caller":"traceutil/trace.go:171","msg":"trace[350302511] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"306.741703ms","start":"2026-07-24T04:39:00.419422Z","end":"2026-07-24T04:39:00.726164Z","steps":["trace[350302511] 'process raft request' (duration: 305.536408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:39:00.726527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:00.419547Z","time spent":"306.700763ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:39:00.726597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:00.419411Z","time spent":"307.141747ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:39:00.726369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:00.418331Z","time spent":"307.484993ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:39:00.729193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:39:00.416681Z","time spent":"308.000769ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:39:58.7769Z","caller":"traceutil/trace.go:171","msg":"trace[592150809] transaction","detail":"{read_only:false; response_revision:3045; number_of_response:1; }","duration":"140.349018ms","start":"2026-07-24T04:39:58.636515Z","end":"2026-07-24T04:39:58.776864Z","steps":["trace[592150809] 'process raft request' (duration: 139.314394ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:39:58.780602Z","caller":"traceutil/trace.go:171","msg":"trace[515682773] transaction","detail":"{read_only:false; response_revision:3046; number_of_response:1; }","duration":"100.286359ms","start":"2026-07-24T04:39:58.680297Z","end":"2026-07-24T04:39:58.780583Z","steps":["trace[515682773] 'process raft request' (duration: 99.818694ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:33.478869Z","caller":"traceutil/trace.go:171","msg":"trace[1202609939] transaction","detail":"{read_only:false; response_revision:4051; number_of_response:1; }","duration":"148.634928ms","start":"2026-07-24T04:42:33.330106Z","end":"2026-07-24T04:42:33.478741Z","steps":["trace[1202609939] 'process raft request' (duration: 148.161547ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:33.48227Z","caller":"traceutil/trace.go:171","msg":"trace[1010459797] transaction","detail":"{read_only:false; response_revision:4052; number_of_response:1; }","duration":"149.393983ms","start":"2026-07-24T04:42:33.332831Z","end":"2026-07-24T04:42:33.482225Z","steps":["trace[1010459797] 'process raft request' (duration: 148.962815ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:33.482161Z","caller":"traceutil/trace.go:171","msg":"trace[1749243471] transaction","detail":"{read_only:false; response_revision:4053; number_of_response:1; }","duration":"145.28173ms","start":"2026-07-24T04:42:33.336818Z","end":"2026-07-24T04:42:33.482099Z","steps":["trace[1749243471] 'process raft request' (duration: 145.193278ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:39.295538Z","caller":"traceutil/trace.go:171","msg":"trace[65042257] transaction","detail":"{read_only:false; response_revision:4398; number_of_response:1; }","duration":"119.966796ms","start":"2026-07-24T04:42:39.175542Z","end":"2026-07-24T04:42:39.295509Z","steps":["trace[65042257] 'process raft request' (duration: 119.763002ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:39.301616Z","caller":"traceutil/trace.go:171","msg":"trace[902691135] transaction","detail":"{read_only:false; response_revision:4399; number_of_response:1; }","duration":"109.133519ms","start":"2026-07-24T04:42:39.192458Z","end":"2026-07-24T04:42:39.301591Z","steps":["trace[902691135] 'process raft request' (duration: 108.542048ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:44.358874Z","caller":"traceutil/trace.go:171","msg":"trace[405813531] linearizableReadLoop","detail":"{readStateIndex:4716; appliedIndex:4715; }","duration":"514.749761ms","start":"2026-07-24T04:42:43.844089Z","end":"2026-07-24T04:42:44.358839Z","steps":["trace[405813531] 'read index received' (duration: 467.180506ms)","trace[405813531] 'applied index is now lower than readState.Index' (duration: 47.567765ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:42:44.35911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.980006ms","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-07-24T04:42:44.35922Z","caller":"traceutil/trace.go:171","msg":"trace[1537167905] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4543; }","duration":"515.10879ms","start":"2026-07-24T04:42:43.844077Z","end":"2026-07-24T04:42:44.359186Z","steps":["trace[1537167905] 'agreement among raft nodes before linearized reading' (duration: 514.852884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:44.359271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:43.844006Z","time spent":"515.254612ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-24T04:42:44.359475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.413701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T04:42:44.359509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.786948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-24T04:42:44.35954Z","caller":"traceutil/trace.go:171","msg":"trace[742332698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4543; }","duration":"463.532093ms","start":"2026-07-24T04:42:43.895992Z","end":"2026-07-24T04:42:44.359524Z","steps":["trace[742332698] 'agreement among raft nodes before linearized reading' (duration: 463.378ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:44.359581Z","caller":"traceutil/trace.go:171","msg":"trace[777786742] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4543; }","duration":"463.89166ms","start":"2026-07-24T04:42:43.895674Z","end":"2026-07-24T04:42:44.359566Z","steps":["trace[777786742] 'agreement among raft nodes before linearized reading' (duration: 463.669576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:44.359589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:43.895981Z","time spent":"463.599114ms","remote":"127.0.0.1:37344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:42:44.359629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:43.895663Z","time spent":"463.957211ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-24T04:42:44.359967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.442685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-96fef519-6544-4bf2-b57c-1225b9df0344\" ","response":"range_response_count:1 size:4052"} {"level":"info","ts":"2026-07-24T04:42:44.36Z","caller":"traceutil/trace.go:171","msg":"trace[1541481445] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-96fef519-6544-4bf2-b57c-1225b9df0344; range_end:; response_count:1; response_revision:4543; }","duration":"322.531517ms","start":"2026-07-24T04:42:44.03746Z","end":"2026-07-24T04:42:44.359992Z","steps":["trace[1541481445] 'agreement among raft nodes before linearized reading' (duration: 322.448506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:44.360022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:44.037434Z","time spent":"322.583168ms","remote":"127.0.0.1:37498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4074,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-96fef519-6544-4bf2-b57c-1225b9df0344\" "} {"level":"warn","ts":"2026-07-24T04:42:44.360018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.246158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-24T04:42:44.360066Z","caller":"traceutil/trace.go:171","msg":"trace[956271621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4543; }","duration":"466.314668ms","start":"2026-07-24T04:42:43.893735Z","end":"2026-07-24T04:42:44.36005Z","steps":["trace[956271621] 'agreement among raft nodes before linearized reading' (duration: 466.153875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:44.36012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:43.893721Z","time spent":"466.38795ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T04:42:44.360132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.548723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-24T04:42:44.360177Z","caller":"traceutil/trace.go:171","msg":"trace[126351664] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4543; }","duration":"470.637253ms","start":"2026-07-24T04:42:43.889516Z","end":"2026-07-24T04:42:44.360153Z","steps":["trace[126351664] 'agreement among raft nodes before linearized reading' (duration: 470.540522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:44.360206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:43.889495Z","time spent":"470.704925ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-24T04:42:44.358907Z","caller":"traceutil/trace.go:171","msg":"trace[27781565] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4543; }","duration":"575.018992ms","start":"2026-07-24T04:42:43.78386Z","end":"2026-07-24T04:42:44.358879Z","steps":["trace[27781565] 'process raft request' (duration: 527.483217ms)","trace[27781565] 'compare' (duration: 47.28699ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:42:44.360505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.882566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-24T04:42:44.360554Z","caller":"traceutil/trace.go:171","msg":"trace[695080110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4543; }","duration":"494.952978ms","start":"2026-07-24T04:42:43.865589Z","end":"2026-07-24T04:42:44.360542Z","steps":["trace[695080110] 'agreement among raft nodes before linearized reading' (duration: 493.50198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:44.36059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:43.86554Z","time spent":"495.04269ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T04:42:44.360634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:43.783847Z","time spent":"576.643274ms","remote":"127.0.0.1:37548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":67,"response count":0,"response size":40,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-07-24T04:42:44.360658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.170785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T04:42:44.360715Z","caller":"traceutil/trace.go:171","msg":"trace[1594826821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4543; }","duration":"460.301608ms","start":"2026-07-24T04:42:43.900404Z","end":"2026-07-24T04:42:44.360705Z","steps":["trace[1594826821] 'agreement among raft nodes before linearized reading' (duration: 458.749528ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:44.36075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:43.900357Z","time spent":"460.38531ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-24T04:42:45.093819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.787262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:21 size:499072"} {"level":"info","ts":"2026-07-24T04:42:45.093893Z","caller":"traceutil/trace.go:171","msg":"trace[817898664] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:21; response_revision:4560; }","duration":"298.914815ms","start":"2026-07-24T04:42:44.794962Z","end":"2026-07-24T04:42:45.093877Z","steps":["trace[817898664] 'range keys from in-memory index tree' (duration: 298.380625ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:48.919147Z","caller":"traceutil/trace.go:171","msg":"trace[710381172] transaction","detail":"{read_only:false; response_revision:4635; number_of_response:1; }","duration":"120.484335ms","start":"2026-07-24T04:42:48.798617Z","end":"2026-07-24T04:42:48.919101Z","steps":["trace[710381172] 'process raft request' (duration: 119.74145ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:49.286004Z","caller":"traceutil/trace.go:171","msg":"trace[827077235] linearizableReadLoop","detail":"{readStateIndex:4818; appliedIndex:4817; }","duration":"135.67558ms","start":"2026-07-24T04:42:49.150312Z","end":"2026-07-24T04:42:49.285987Z","steps":["trace[827077235] 'read index received' (duration: 135.375695ms)","trace[827077235] 'applied index is now lower than readState.Index' (duration: 299.075µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:42:49.286094Z","caller":"traceutil/trace.go:171","msg":"trace[936730811] transaction","detail":"{read_only:false; response_revision:4641; number_of_response:1; }","duration":"141.299471ms","start":"2026-07-24T04:42:49.144755Z","end":"2026-07-24T04:42:49.286054Z","steps":["trace[936730811] 'process raft request' (duration: 141.054966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:49.286191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.865494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4848"} {"level":"info","ts":"2026-07-24T04:42:49.286218Z","caller":"traceutil/trace.go:171","msg":"trace[822520566] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4641; }","duration":"135.953196ms","start":"2026-07-24T04:42:49.150259Z","end":"2026-07-24T04:42:49.286212Z","steps":["trace[822520566] 'agreement among raft nodes before linearized reading' (duration: 135.802063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:56.107951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.880272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:42:56.108039Z","caller":"traceutil/trace.go:171","msg":"trace[1774141527] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4740; }","duration":"561.043736ms","start":"2026-07-24T04:42:55.546977Z","end":"2026-07-24T04:42:56.108021Z","steps":["trace[1774141527] 'count revisions from in-memory index tree' (duration: 560.701089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:56.108082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:55.546938Z","time spent":"561.132688ms","remote":"127.0.0.1:37716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:42:56.108757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.722247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-07-24T04:42:56.108866Z","caller":"traceutil/trace.go:171","msg":"trace[795172115] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4740; }","duration":"695.893531ms","start":"2026-07-24T04:42:55.412952Z","end":"2026-07-24T04:42:56.108845Z","steps":["trace[795172115] 'range keys from in-memory index tree' (duration: 694.634006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:56.108921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:55.412931Z","time spent":"695.978402ms","remote":"127.0.0.1:37434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2207433,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-07-24T04:42:56.108796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.827228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:42:56.109627Z","caller":"traceutil/trace.go:171","msg":"trace[1146994683] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"548.627043ms","start":"2026-07-24T04:42:55.560985Z","end":"2026-07-24T04:42:56.109612Z","steps":["trace[1146994683] 'process raft request' (duration: 54.897229ms)","trace[1146994683] 'compare' (duration: 491.771866ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:42:56.110527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:55.560956Z","time spent":"548.702905ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:42:56.114437Z","caller":"traceutil/trace.go:171","msg":"trace[190212172] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"490.198836ms","start":"2026-07-24T04:42:55.624225Z","end":"2026-07-24T04:42:56.114424Z","steps":["trace[190212172] 'process raft request' (duration: 490.113685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:56.11451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:55.6242Z","time spent":"490.278338ms","remote":"127.0.0.1:37404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T04:42:56.31723Z","caller":"traceutil/trace.go:171","msg":"trace[1454162926] linearizableReadLoop","detail":"{readStateIndex:4924; appliedIndex:4920; }","duration":"621.375573ms","start":"2026-07-24T04:42:55.695836Z","end":"2026-07-24T04:42:56.317212Z","steps":["trace[1454162926] 'read index received' (duration: 418.494821ms)","trace[1454162926] 'applied index is now lower than readState.Index' (duration: 202.880052ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:42:56.317287Z","caller":"traceutil/trace.go:171","msg":"trace[1670064522] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"668.123292ms","start":"2026-07-24T04:42:55.649158Z","end":"2026-07-24T04:42:56.317282Z","steps":["trace[1670064522] 'process raft request' (duration: 668.01129ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:56.317313Z","caller":"traceutil/trace.go:171","msg":"trace[1423004188] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"661.989742ms","start":"2026-07-24T04:42:55.655314Z","end":"2026-07-24T04:42:56.317304Z","steps":["trace[1423004188] 'process raft request' (duration: 661.8785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:56.317366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:55.655305Z","time spent":"662.018973ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:42:56.317365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:55.649152Z","time spent":"668.154812ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:42:56.317522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.695218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16231"} {"level":"warn","ts":"2026-07-24T04:42:56.317527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.308351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-24T04:42:56.317539Z","caller":"traceutil/trace.go:171","msg":"trace[512845160] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4745; }","duration":"621.75191ms","start":"2026-07-24T04:42:55.695782Z","end":"2026-07-24T04:42:56.317534Z","steps":["trace[512845160] 'agreement among raft nodes before linearized reading' (duration: 621.661268ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:56.317544Z","caller":"traceutil/trace.go:171","msg":"trace[1117350360] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4745; }","duration":"546.325512ms","start":"2026-07-24T04:42:55.771211Z","end":"2026-07-24T04:42:56.317537Z","steps":["trace[1117350360] 'agreement among raft nodes before linearized reading' (duration: 546.269091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:56.317553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:55.69576Z","time spent":"621.79034ms","remote":"127.0.0.1:37498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16253,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-07-24T04:42:56.317563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:55.77118Z","time spent":"546.378803ms","remote":"127.0.0.1:37476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1957,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-07-24T04:42:56.317698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.825299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:42:56.31775Z","caller":"traceutil/trace.go:171","msg":"trace[1487919551] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:4745; }","duration":"362.911492ms","start":"2026-07-24T04:42:55.954827Z","end":"2026-07-24T04:42:56.317738Z","steps":["trace[1487919551] 'agreement among raft nodes before linearized reading' (duration: 362.82203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:56.317776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:55.954816Z","time spent":"362.953962ms","remote":"127.0.0.1:46056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-07-24T04:42:56.317781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.874987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7b9153cd-dec7-4faa-aad1-e491ccc05d2c\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-07-24T04:42:56.317809Z","caller":"traceutil/trace.go:171","msg":"trace[84487119] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7b9153cd-dec7-4faa-aad1-e491ccc05d2c; range_end:; response_count:1; response_revision:4745; }","duration":"441.936739ms","start":"2026-07-24T04:42:55.875864Z","end":"2026-07-24T04:42:56.317801Z","steps":["trace[84487119] 'agreement among raft nodes before linearized reading' (duration: 441.868468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:56.31783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:55.875843Z","time spent":"441.9814ms","remote":"127.0.0.1:37498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7b9153cd-dec7-4faa-aad1-e491ccc05d2c\" "} {"level":"warn","ts":"2026-07-24T04:42:56.317875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.913197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-07-24T04:42:56.317891Z","caller":"traceutil/trace.go:171","msg":"trace[1214935276] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4745; }","duration":"176.946017ms","start":"2026-07-24T04:42:56.14094Z","end":"2026-07-24T04:42:56.317886Z","steps":["trace[1214935276] 'agreement among raft nodes before linearized reading' (duration: 176.896757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:56.317944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.181947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-7459d8dd7b-wnz87.18c52025f3076689\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-07-24T04:42:56.317248Z","caller":"traceutil/trace.go:171","msg":"trace[1308537193] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"673.287262ms","start":"2026-07-24T04:42:55.643946Z","end":"2026-07-24T04:42:56.317233Z","steps":["trace[1308537193] 'process raft request' (duration: 673.098468ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:56.317962Z","caller":"traceutil/trace.go:171","msg":"trace[1457665359] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-7459d8dd7b-wnz87.18c52025f3076689; range_end:; response_count:1; response_revision:4745; }","duration":"201.217359ms","start":"2026-07-24T04:42:56.116738Z","end":"2026-07-24T04:42:56.317956Z","steps":["trace[1457665359] 'agreement among raft nodes before linearized reading' (duration: 201.137567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:56.317988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:55.643924Z","time spent":"674.042377ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:42:56.317717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.255276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:42:56.318056Z","caller":"traceutil/trace.go:171","msg":"trace[673347869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4745; }","duration":"422.644025ms","start":"2026-07-24T04:42:55.895406Z","end":"2026-07-24T04:42:56.31805Z","steps":["trace[673347869] 'agreement among raft nodes before linearized reading' (duration: 422.289447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:56.318074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:42:55.895395Z","time spent":"422.673605ms","remote":"127.0.0.1:37344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T04:42:56.749593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.326892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:42:56.749914Z","caller":"traceutil/trace.go:171","msg":"trace[1272629255] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"263.822556ms","start":"2026-07-24T04:42:56.486075Z","end":"2026-07-24T04:42:56.749898Z","steps":["trace[1272629255] 'process raft request' (duration: 78.095017ms)","trace[1272629255] 'compare' (duration: 185.070806ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:42:56.75016Z","caller":"traceutil/trace.go:171","msg":"trace[370869658] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"261.220065ms","start":"2026-07-24T04:42:56.488928Z","end":"2026-07-24T04:42:56.750148Z","steps":["trace[370869658] 'process raft request' (duration: 260.773667ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:56.750133Z","caller":"traceutil/trace.go:171","msg":"trace[1786430442] linearizableReadLoop","detail":"{readStateIndex:4938; appliedIndex:4936; }","duration":"237.571555ms","start":"2026-07-24T04:42:56.512537Z","end":"2026-07-24T04:42:56.750109Z","steps":["trace[1786430442] 'read index received' (duration: 51.647253ms)","trace[1786430442] 'applied index is now lower than readState.Index' (duration: 185.921932ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:42:56.750552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.975833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-24T04:42:56.750568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.573419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-24T04:42:56.750638Z","caller":"traceutil/trace.go:171","msg":"trace[332910056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4759; }","duration":"232.66545ms","start":"2026-07-24T04:42:56.517956Z","end":"2026-07-24T04:42:56.750621Z","steps":["trace[332910056] 'agreement among raft nodes before linearized reading' (duration: 232.465526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:56.750567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.63869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T04:42:56.750635Z","caller":"traceutil/trace.go:171","msg":"trace[635966769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4759; }","duration":"238.106236ms","start":"2026-07-24T04:42:56.512508Z","end":"2026-07-24T04:42:56.750615Z","steps":["trace[635966769] 'agreement among raft nodes before linearized reading' (duration: 237.707548ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:56.75076Z","caller":"traceutil/trace.go:171","msg":"trace[278078137] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4759; }","duration":"232.870704ms","start":"2026-07-24T04:42:56.517858Z","end":"2026-07-24T04:42:56.750729Z","steps":["trace[278078137] 'agreement among raft nodes before linearized reading' (duration: 232.550808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:56.751084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.21608ms","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-07-24T04:42:56.751124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.552584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-24T04:42:56.751134Z","caller":"traceutil/trace.go:171","msg":"trace[1307348189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4759; }","duration":"234.365934ms","start":"2026-07-24T04:42:56.516753Z","end":"2026-07-24T04:42:56.751119Z","steps":["trace[1307348189] 'agreement among raft nodes before linearized reading' (duration: 234.19303ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:56.75116Z","caller":"traceutil/trace.go:171","msg":"trace[1715474906] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4759; }","duration":"238.611585ms","start":"2026-07-24T04:42:56.512535Z","end":"2026-07-24T04:42:56.751146Z","steps":["trace[1715474906] 'agreement among raft nodes before linearized reading' (duration: 237.83895ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:56.930812Z","caller":"traceutil/trace.go:171","msg":"trace[966525485] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"174.52276ms","start":"2026-07-24T04:42:56.756266Z","end":"2026-07-24T04:42:56.930788Z","steps":["trace[966525485] 'process raft request' (duration: 144.689571ms)","trace[966525485] 'compare' (duration: 29.733407ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:42:56.943369Z","caller":"traceutil/trace.go:171","msg":"trace[274309081] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"186.82367ms","start":"2026-07-24T04:42:56.75653Z","end":"2026-07-24T04:42:56.943354Z","steps":["trace[274309081] 'process raft request' (duration: 186.688347ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:56.9437Z","caller":"traceutil/trace.go:171","msg":"trace[1707147713] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"187.034384ms","start":"2026-07-24T04:42:56.756637Z","end":"2026-07-24T04:42:56.943671Z","steps":["trace[1707147713] 'process raft request' (duration: 186.678447ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:56.943758Z","caller":"traceutil/trace.go:171","msg":"trace[529268303] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"186.774889ms","start":"2026-07-24T04:42:56.75696Z","end":"2026-07-24T04:42:56.943735Z","steps":["trace[529268303] 'process raft request' (duration: 186.574535ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:56.943817Z","caller":"traceutil/trace.go:171","msg":"trace[19396082] linearizableReadLoop","detail":"{readStateIndex:4943; appliedIndex:4938; }","duration":"185.512404ms","start":"2026-07-24T04:42:56.758261Z","end":"2026-07-24T04:42:56.943774Z","steps":["trace[19396082] 'read index received' (duration: 142.646641ms)","trace[19396082] 'applied index is now lower than readState.Index' (duration: 42.864853ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:42:56.944056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.755758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-24T04:42:56.944115Z","caller":"traceutil/trace.go:171","msg":"trace[855648650] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"186.600956ms","start":"2026-07-24T04:42:56.7575Z","end":"2026-07-24T04:42:56.944101Z","steps":["trace[855648650] 'process raft request' (duration: 186.197268ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:56.944136Z","caller":"traceutil/trace.go:171","msg":"trace[1310573968] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4764; }","duration":"185.895771ms","start":"2026-07-24T04:42:56.758225Z","end":"2026-07-24T04:42:56.94412Z","steps":["trace[1310573968] 'agreement among raft nodes before linearized reading' (duration: 185.628256ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:57.100172Z","caller":"traceutil/trace.go:171","msg":"trace[1443965026] linearizableReadLoop","detail":"{readStateIndex:4944; appliedIndex:4943; }","duration":"110.870331ms","start":"2026-07-24T04:42:56.989277Z","end":"2026-07-24T04:42:57.100147Z","steps":["trace[1443965026] 'read index received' (duration: 109.549315ms)","trace[1443965026] 'applied index is now lower than readState.Index' (duration: 1.319936ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:42:57.100497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.191968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-07-24T04:42:57.100541Z","caller":"traceutil/trace.go:171","msg":"trace[208886530] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4765; }","duration":"111.268619ms","start":"2026-07-24T04:42:56.989262Z","end":"2026-07-24T04:42:57.100531Z","steps":["trace[208886530] 'agreement among raft nodes before linearized reading' (duration: 110.987134ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:57.100582Z","caller":"traceutil/trace.go:171","msg":"trace[166609283] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"113.560964ms","start":"2026-07-24T04:42:56.986999Z","end":"2026-07-24T04:42:57.10056Z","steps":["trace[166609283] 'process raft request' (duration: 111.879381ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:58.261627Z","caller":"traceutil/trace.go:171","msg":"trace[333381304] linearizableReadLoop","detail":"{readStateIndex:4956; appliedIndex:4955; }","duration":"110.869875ms","start":"2026-07-24T04:42:58.150733Z","end":"2026-07-24T04:42:58.261603Z","steps":["trace[333381304] 'read index received' (duration: 110.630521ms)","trace[333381304] 'applied index is now lower than readState.Index' (duration: 238.064µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:42:58.261696Z","caller":"traceutil/trace.go:171","msg":"trace[1989197110] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"145.710541ms","start":"2026-07-24T04:42:58.115964Z","end":"2026-07-24T04:42:58.261674Z","steps":["trace[1989197110] 'process raft request' (duration: 145.461296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:42:58.26207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.314194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-xtdcx\" ","response":"range_response_count:1 size:5150"} {"level":"info","ts":"2026-07-24T04:42:58.262133Z","caller":"traceutil/trace.go:171","msg":"trace[724796410] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-xtdcx; range_end:; response_count:1; response_revision:4776; }","duration":"111.419956ms","start":"2026-07-24T04:42:58.1507Z","end":"2026-07-24T04:42:58.26212Z","steps":["trace[724796410] 'agreement among raft nodes before linearized reading' (duration: 111.019209ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:58.410045Z","caller":"traceutil/trace.go:171","msg":"trace[2122545771] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"119.983903ms","start":"2026-07-24T04:42:58.290034Z","end":"2026-07-24T04:42:58.410018Z","steps":["trace[2122545771] 'process raft request' (duration: 118.319711ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:58.412Z","caller":"traceutil/trace.go:171","msg":"trace[1692320573] transaction","detail":"{read_only:false; response_revision:4781; number_of_response:1; }","duration":"121.191196ms","start":"2026-07-24T04:42:58.290779Z","end":"2026-07-24T04:42:58.411971Z","steps":["trace[1692320573] 'process raft request' (duration: 120.923061ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:58.412542Z","caller":"traceutil/trace.go:171","msg":"trace[1942291984] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"121.919299ms","start":"2026-07-24T04:42:58.290608Z","end":"2026-07-24T04:42:58.412527Z","steps":["trace[1942291984] 'process raft request' (duration: 120.979001ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:42:58.879983Z","caller":"traceutil/trace.go:171","msg":"trace[739907481] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"121.766146ms","start":"2026-07-24T04:42:58.758193Z","end":"2026-07-24T04:42:58.879959Z","steps":["trace[739907481] 'process raft request' (duration: 121.611533ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:05.308357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.453595ms","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-07-24T04:43:05.308456Z","caller":"traceutil/trace.go:171","msg":"trace[1805401782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4875; }","duration":"279.579937ms","start":"2026-07-24T04:43:05.028855Z","end":"2026-07-24T04:43:05.308435Z","steps":["trace[1805401782] 'range keys from in-memory index tree' (duration: 279.261711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:05.308481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.27145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-24T04:43:05.308532Z","caller":"traceutil/trace.go:171","msg":"trace[1770615613] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4875; }","duration":"281.358252ms","start":"2026-07-24T04:43:05.027157Z","end":"2026-07-24T04:43:05.308516Z","steps":["trace[1770615613] 'range keys from in-memory index tree' (duration: 281.165058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:05.308577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.059672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-24T04:43:05.308355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.674688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T04:43:05.308668Z","caller":"traceutil/trace.go:171","msg":"trace[48102005] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:4875; }","duration":"217.185214ms","start":"2026-07-24T04:43:05.091462Z","end":"2026-07-24T04:43:05.308647Z","steps":["trace[48102005] 'count revisions from in-memory index tree' (duration: 217.014281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:05.308652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.035419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-24T04:43:05.308697Z","caller":"traceutil/trace.go:171","msg":"trace[592477729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4875; }","duration":"282.064195ms","start":"2026-07-24T04:43:05.026623Z","end":"2026-07-24T04:43:05.308687Z","steps":["trace[592477729] 'range keys from in-memory index tree' (duration: 281.498954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:05.308704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.371203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-24T04:43:05.308735Z","caller":"traceutil/trace.go:171","msg":"trace[896116936] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4875; }","duration":"186.14276ms","start":"2026-07-24T04:43:05.122574Z","end":"2026-07-24T04:43:05.308716Z","steps":["trace[896116936] 'range keys from in-memory index tree' (duration: 185.912757ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:05.308776Z","caller":"traceutil/trace.go:171","msg":"trace[1166913207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4875; }","duration":"279.448975ms","start":"2026-07-24T04:43:05.029307Z","end":"2026-07-24T04:43:05.308756Z","steps":["trace[1166913207] 'range keys from in-memory index tree' (duration: 279.277732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:09.454466Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021965303728064,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T04:43:09.550764Z","caller":"traceutil/trace.go:171","msg":"trace[1331634552] linearizableReadLoop","detail":"{readStateIndex:5090; appliedIndex:5089; }","duration":"596.660597ms","start":"2026-07-24T04:43:08.954083Z","end":"2026-07-24T04:43:09.550743Z","steps":["trace[1331634552] 'read index received' (duration: 596.404252ms)","trace[1331634552] 'applied index is now lower than readState.Index' (duration: 254.815µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:43:09.550994Z","caller":"traceutil/trace.go:171","msg":"trace[3561493] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"598.597983ms","start":"2026-07-24T04:43:08.952308Z","end":"2026-07-24T04:43:09.550906Z","steps":["trace[3561493] 'process raft request' (duration: 598.201026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:09.551053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.186621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:43:09.551101Z","caller":"traceutil/trace.go:171","msg":"trace[197829452] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:4904; }","duration":"251.263553ms","start":"2026-07-24T04:43:09.299826Z","end":"2026-07-24T04:43:09.551089Z","steps":["trace[197829452] 'agreement among raft nodes before linearized reading' (duration: 251.150951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:09.551112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.012034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10394"} {"level":"warn","ts":"2026-07-24T04:43:09.551151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:08.952288Z","time spent":"598.789606ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:43:09.551167Z","caller":"traceutil/trace.go:171","msg":"trace[1231576071] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4904; }","duration":"597.131536ms","start":"2026-07-24T04:43:08.954022Z","end":"2026-07-24T04:43:09.551154Z","steps":["trace[1231576071] 'agreement among raft nodes before linearized reading' (duration: 596.849711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:09.551205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:08.953992Z","time spent":"597.204498ms","remote":"127.0.0.1:37498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10416,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-24T04:43:09.551667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.793007ms","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-07-24T04:43:09.551713Z","caller":"traceutil/trace.go:171","msg":"trace[1858236866] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4904; }","duration":"507.8861ms","start":"2026-07-24T04:43:09.043816Z","end":"2026-07-24T04:43:09.551702Z","steps":["trace[1858236866] 'agreement among raft nodes before linearized reading' (duration: 507.131265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:09.55183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:09.043791Z","time spent":"508.025932ms","remote":"127.0.0.1:37476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-07-24T04:43:10.089003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757021965303728068,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T04:43:10.183124Z","caller":"traceutil/trace.go:171","msg":"trace[657724064] linearizableReadLoop","detail":"{readStateIndex:5091; appliedIndex:5090; }","duration":"594.617661ms","start":"2026-07-24T04:43:09.588488Z","end":"2026-07-24T04:43:10.183106Z","steps":["trace[657724064] 'read index received' (duration: 594.58799ms)","trace[657724064] 'applied index is now lower than readState.Index' (duration: 27.451µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:43:10.183203Z","caller":"traceutil/trace.go:171","msg":"trace[1343079640] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"620.172006ms","start":"2026-07-24T04:43:09.563Z","end":"2026-07-24T04:43:10.183172Z","steps":["trace[1343079640] 'process raft request' (duration: 620.001413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:10.183302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.802834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-07-24T04:43:10.183331Z","caller":"traceutil/trace.go:171","msg":"trace[2120108305] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4905; }","duration":"594.857986ms","start":"2026-07-24T04:43:09.588464Z","end":"2026-07-24T04:43:10.183322Z","steps":["trace[2120108305] 'agreement among raft nodes before linearized reading' (duration: 594.731163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:10.183351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:09.588453Z","time spent":"594.892776ms","remote":"127.0.0.1:58614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3266,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-07-24T04:43:10.183355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:09.562973Z","time spent":"620.287929ms","remote":"127.0.0.1:37498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10669,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:43:10.183579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.524091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-24T04:43:10.183603Z","caller":"traceutil/trace.go:171","msg":"trace[1485900189] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4905; }","duration":"582.569492ms","start":"2026-07-24T04:43:09.601028Z","end":"2026-07-24T04:43:10.183597Z","steps":["trace[1485900189] 'agreement among raft nodes before linearized reading' (duration: 582.507311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:10.18362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:09.601017Z","time spent":"582.599103ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-24T04:43:10.183801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.468191ms","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-07-24T04:43:10.183824Z","caller":"traceutil/trace.go:171","msg":"trace[754452507] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4905; }","duration":"222.494321ms","start":"2026-07-24T04:43:09.961323Z","end":"2026-07-24T04:43:10.183818Z","steps":["trace[754452507] 'agreement among raft nodes before linearized reading' (duration: 222.43149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:10.18394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.639255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-24T04:43:10.183928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.576534ms","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-07-24T04:43:10.183964Z","caller":"traceutil/trace.go:171","msg":"trace[2090469309] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4905; }","duration":"531.683356ms","start":"2026-07-24T04:43:09.652273Z","end":"2026-07-24T04:43:10.183956Z","steps":["trace[2090469309] 'agreement among raft nodes before linearized reading' (duration: 531.632315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:10.183998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:09.652261Z","time spent":"531.727897ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-24T04:43:10.183997Z","caller":"traceutil/trace.go:171","msg":"trace[1334226643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4905; }","duration":"531.657445ms","start":"2026-07-24T04:43:09.652322Z","end":"2026-07-24T04:43:10.183979Z","steps":["trace[1334226643] 'agreement among raft nodes before linearized reading' (duration: 531.471202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:10.18405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:09.652315Z","time spent":"531.722407ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-24T04:43:10.184193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.822459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-24T04:43:10.184216Z","caller":"traceutil/trace.go:171","msg":"trace[1836626996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4905; }","duration":"531.89329ms","start":"2026-07-24T04:43:09.652317Z","end":"2026-07-24T04:43:10.18421Z","steps":["trace[1836626996] 'agreement among raft nodes before linearized reading' (duration: 531.835239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:10.184232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:09.652296Z","time spent":"531.933161ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T04:43:10.184513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.144165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T04:43:10.184612Z","caller":"traceutil/trace.go:171","msg":"trace[875552458] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4905; }","duration":"531.336408ms","start":"2026-07-24T04:43:09.653255Z","end":"2026-07-24T04:43:10.184591Z","steps":["trace[875552458] 'agreement among raft nodes before linearized reading' (duration: 530.617975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:10.184665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:09.653243Z","time spent":"531.40927ms","remote":"127.0.0.1:37586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-24T04:43:10.353611Z","caller":"traceutil/trace.go:171","msg":"trace[462178387] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"163.009691ms","start":"2026-07-24T04:43:10.190569Z","end":"2026-07-24T04:43:10.353579Z","steps":["trace[462178387] 'process raft request' (duration: 162.759996ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:10.411164Z","caller":"traceutil/trace.go:171","msg":"trace[1831954338] linearizableReadLoop","detail":"{readStateIndex:5093; appliedIndex:5091; }","duration":"220.507931ms","start":"2026-07-24T04:43:10.190633Z","end":"2026-07-24T04:43:10.41114Z","steps":["trace[1831954338] 'read index received' (duration: 162.683115ms)","trace[1831954338] 'applied index is now lower than readState.Index' (duration: 57.823886ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:43:10.411373Z","caller":"traceutil/trace.go:171","msg":"trace[465748297] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"219.215388ms","start":"2026-07-24T04:43:10.19212Z","end":"2026-07-24T04:43:10.411335Z","steps":["trace[465748297] 'process raft request' (duration: 218.850161ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:10.411626Z","caller":"traceutil/trace.go:171","msg":"trace[1311862565] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"220.919979ms","start":"2026-07-24T04:43:10.190675Z","end":"2026-07-24T04:43:10.411595Z","steps":["trace[1311862565] 'process raft request' (duration: 220.145364ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:10.411743Z","caller":"traceutil/trace.go:171","msg":"trace[424527846] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"221.053922ms","start":"2026-07-24T04:43:10.190676Z","end":"2026-07-24T04:43:10.41173Z","steps":["trace[424527846] 'process raft request' (duration: 220.218656ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:10.411907Z","caller":"traceutil/trace.go:171","msg":"trace[1963966814] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"221.315087ms","start":"2026-07-24T04:43:10.190581Z","end":"2026-07-24T04:43:10.411896Z","steps":["trace[1963966814] 'process raft request' (duration: 220.030902ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:10.411975Z","caller":"traceutil/trace.go:171","msg":"trace[320682791] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"221.276226ms","start":"2026-07-24T04:43:10.190679Z","end":"2026-07-24T04:43:10.411955Z","steps":["trace[320682791] 'process raft request' (duration: 220.075243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:10.412029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.363328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-7b9153cd-dec7-4faa-aad1-e491ccc05d2c\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-24T04:43:10.412127Z","caller":"traceutil/trace.go:171","msg":"trace[269517790] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"219.978532ms","start":"2026-07-24T04:43:10.192141Z","end":"2026-07-24T04:43:10.412119Z","steps":["trace[269517790] 'process raft request' (duration: 218.890301ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:10.412157Z","caller":"traceutil/trace.go:171","msg":"trace[716882448] range","detail":"{range_begin:/registry/persistentvolumes/pvc-7b9153cd-dec7-4faa-aad1-e491ccc05d2c; range_end:; response_count:1; response_revision:4914; }","duration":"221.517261ms","start":"2026-07-24T04:43:10.190597Z","end":"2026-07-24T04:43:10.412114Z","steps":["trace[716882448] 'agreement among raft nodes before linearized reading' (duration: 221.196015ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:10.412283Z","caller":"traceutil/trace.go:171","msg":"trace[478642420] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"220.069154ms","start":"2026-07-24T04:43:10.192146Z","end":"2026-07-24T04:43:10.412216Z","steps":["trace[478642420] 'process raft request' (duration: 218.956773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:10.412013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.744821ms","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-07-24T04:43:10.412379Z","caller":"traceutil/trace.go:171","msg":"trace[64555467] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:4914; }","duration":"189.200139ms","start":"2026-07-24T04:43:10.223168Z","end":"2026-07-24T04:43:10.412368Z","steps":["trace[64555467] 'agreement among raft nodes before linearized reading' (duration: 188.727ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:10.412081Z","caller":"traceutil/trace.go:171","msg":"trace[161055870] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"220.226188ms","start":"2026-07-24T04:43:10.191846Z","end":"2026-07-24T04:43:10.412072Z","steps":["trace[161055870] 'process raft request' (duration: 219.087225ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:11.738489Z","caller":"traceutil/trace.go:171","msg":"trace[2002837250] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"179.237544ms","start":"2026-07-24T04:43:11.559226Z","end":"2026-07-24T04:43:11.738463Z","steps":["trace[2002837250] 'process raft request' (duration: 179.072921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:12.270615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.515793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"warn","ts":"2026-07-24T04:43:12.270632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.960573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:43:12.270693Z","caller":"traceutil/trace.go:171","msg":"trace[929985756] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4918; }","duration":"143.685826ms","start":"2026-07-24T04:43:12.12699Z","end":"2026-07-24T04:43:12.270676Z","steps":["trace[929985756] 'range keys from in-memory index tree' (duration: 143.321019ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:12.270723Z","caller":"traceutil/trace.go:171","msg":"trace[1717390695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4918; }","duration":"374.092715ms","start":"2026-07-24T04:43:11.8966Z","end":"2026-07-24T04:43:12.270692Z","steps":["trace[1717390695] 'range keys from in-memory index tree' (duration: 373.880111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:12.270767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:11.896575Z","time spent":"374.182827ms","remote":"127.0.0.1:37344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T04:43:18.622727Z","caller":"traceutil/trace.go:171","msg":"trace[1111851454] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"168.048157ms","start":"2026-07-24T04:43:18.454645Z","end":"2026-07-24T04:43:18.622693Z","steps":["trace[1111851454] 'process raft request' (duration: 167.844933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:18.622976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.722981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-24T04:43:18.623061Z","caller":"traceutil/trace.go:171","msg":"trace[1548813529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4991; }","duration":"167.840973ms","start":"2026-07-24T04:43:18.455182Z","end":"2026-07-24T04:43:18.623023Z","steps":["trace[1548813529] 'agreement among raft nodes before linearized reading' (duration: 167.586078ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:18.622665Z","caller":"traceutil/trace.go:171","msg":"trace[1795231610] linearizableReadLoop","detail":"{readStateIndex:5179; appliedIndex:5178; }","duration":"167.444216ms","start":"2026-07-24T04:43:18.455201Z","end":"2026-07-24T04:43:18.622645Z","steps":["trace[1795231610] 'read index received' (duration: 167.302233ms)","trace[1795231610] 'applied index is now lower than readState.Index' (duration: 140.983µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:43:18.677835Z","caller":"traceutil/trace.go:171","msg":"trace[1690549028] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"220.239783ms","start":"2026-07-24T04:43:18.457579Z","end":"2026-07-24T04:43:18.677819Z","steps":["trace[1690549028] 'process raft request' (duration: 216.087586ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:18.67851Z","caller":"traceutil/trace.go:171","msg":"trace[1638381629] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"157.212254ms","start":"2026-07-24T04:43:18.521258Z","end":"2026-07-24T04:43:18.67847Z","steps":["trace[1638381629] 'process raft request' (duration: 156.522701ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:18.679014Z","caller":"traceutil/trace.go:171","msg":"trace[352537834] transaction","detail":"{read_only:false; response_revision:4995; number_of_response:1; }","duration":"152.975825ms","start":"2026-07-24T04:43:18.525986Z","end":"2026-07-24T04:43:18.678962Z","steps":["trace[352537834] 'process raft request' (duration: 152.150819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:18.679154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.530555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:43:18.679203Z","caller":"traceutil/trace.go:171","msg":"trace[1353081463] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4996; }","duration":"216.631387ms","start":"2026-07-24T04:43:18.462559Z","end":"2026-07-24T04:43:18.67919Z","steps":["trace[1353081463] 'agreement among raft nodes before linearized reading' (duration: 216.509455ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:18.679405Z","caller":"traceutil/trace.go:171","msg":"trace[2102728792] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"155.528683ms","start":"2026-07-24T04:43:18.523863Z","end":"2026-07-24T04:43:18.679391Z","steps":["trace[2102728792] 'process raft request' (duration: 154.162487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:18.679859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.528664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T04:43:18.679909Z","caller":"traceutil/trace.go:171","msg":"trace[1020073251] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4996; }","duration":"224.598285ms","start":"2026-07-24T04:43:18.455298Z","end":"2026-07-24T04:43:18.679896Z","steps":["trace[1020073251] 'agreement among raft nodes before linearized reading' (duration: 224.485003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:18.680029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.780278ms","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-07-24T04:43:18.68006Z","caller":"traceutil/trace.go:171","msg":"trace[1020721096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4996; }","duration":"224.819619ms","start":"2026-07-24T04:43:18.455233Z","end":"2026-07-24T04:43:18.680052Z","steps":["trace[1020721096] 'agreement among raft nodes before linearized reading' (duration: 224.742798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:36.199025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.1732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T04:43:36.199327Z","caller":"traceutil/trace.go:171","msg":"trace[747015542] transaction","detail":"{read_only:false; response_revision:5100; number_of_response:1; }","duration":"155.073797ms","start":"2026-07-24T04:43:36.04422Z","end":"2026-07-24T04:43:36.199294Z","steps":["trace[747015542] 'compare' (duration: 149.043308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:45.994867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.035112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:43:45.994957Z","caller":"traceutil/trace.go:171","msg":"trace[1944119371] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:5241; }","duration":"100.179835ms","start":"2026-07-24T04:43:45.894755Z","end":"2026-07-24T04:43:45.994935Z","steps":["trace[1944119371] 'count revisions from in-memory index tree' (duration: 99.93525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:46.91093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.378715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-24T04:43:46.911Z","caller":"traceutil/trace.go:171","msg":"trace[2045638892] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5251; }","duration":"101.503557ms","start":"2026-07-24T04:43:46.809481Z","end":"2026-07-24T04:43:46.910985Z","steps":["trace[2045638892] 'range keys from in-memory index tree' (duration: 101.178031ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:56.932705Z","caller":"traceutil/trace.go:171","msg":"trace[78470498] transaction","detail":"{read_only:false; response_revision:5307; number_of_response:1; }","duration":"110.725006ms","start":"2026-07-24T04:43:56.821948Z","end":"2026-07-24T04:43:56.932673Z","steps":["trace[78470498] 'process raft request' (duration: 101.659345ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:56.932758Z","caller":"traceutil/trace.go:171","msg":"trace[1944359857] transaction","detail":"{read_only:false; response_revision:5308; number_of_response:1; }","duration":"109.752819ms","start":"2026-07-24T04:43:56.822984Z","end":"2026-07-24T04:43:56.932737Z","steps":["trace[1944359857] 'process raft request' (duration: 109.609316ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:44:29.549501Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2895} {"level":"info","ts":"2026-07-24T04:44:29.644277Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2895,"took":"93.295648ms","hash":2510741730,"current-db-size-bytes":52461568,"current-db-size":"52 MB","current-db-size-in-use-bytes":28999680,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-24T04:44:29.644368Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2510741730,"revision":2895,"compact-revision":-1} {"level":"info","ts":"2026-07-24T04:45:39.578331Z","caller":"traceutil/trace.go:171","msg":"trace[1807295771] transaction","detail":"{read_only:false; response_revision:5964; number_of_response:1; }","duration":"199.206083ms","start":"2026-07-24T04:45:39.379085Z","end":"2026-07-24T04:45:39.578291Z","steps":["trace[1807295771] 'process raft request' (duration: 198.747356ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:39.616478Z","caller":"traceutil/trace.go:171","msg":"trace[884133355] linearizableReadLoop","detail":"{readStateIndex:6184; appliedIndex:6182; }","duration":"169.930228ms","start":"2026-07-24T04:45:39.446523Z","end":"2026-07-24T04:45:39.616453Z","steps":["trace[884133355] 'read index received' (duration: 131.185239ms)","trace[884133355] 'applied index is now lower than readState.Index' (duration: 38.743719ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:39.616499Z","caller":"traceutil/trace.go:171","msg":"trace[1317890372] transaction","detail":"{read_only:false; response_revision:5965; number_of_response:1; }","duration":"237.387113ms","start":"2026-07-24T04:45:39.37908Z","end":"2026-07-24T04:45:39.616467Z","steps":["trace[1317890372] 'process raft request' (duration: 237.115349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:45:39.616659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.120061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-24T04:45:39.616683Z","caller":"traceutil/trace.go:171","msg":"trace[613490457] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5965; }","duration":"170.207232ms","start":"2026-07-24T04:45:39.446468Z","end":"2026-07-24T04:45:39.616676Z","steps":["trace[613490457] 'agreement among raft nodes before linearized reading' (duration: 170.07813ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:43.795606Z","caller":"traceutil/trace.go:171","msg":"trace[363905263] linearizableReadLoop","detail":"{readStateIndex:6207; appliedIndex:6205; }","duration":"113.398597ms","start":"2026-07-24T04:45:43.682184Z","end":"2026-07-24T04:45:43.795583Z","steps":["trace[363905263] 'read index received' (duration: 9.272811ms)","trace[363905263] 'applied index is now lower than readState.Index' (duration: 104.124756ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:45:43.795678Z","caller":"traceutil/trace.go:171","msg":"trace[1505193250] transaction","detail":"{read_only:false; response_revision:5987; number_of_response:1; }","duration":"127.386913ms","start":"2026-07-24T04:45:43.668259Z","end":"2026-07-24T04:45:43.795646Z","steps":["trace[1505193250] 'process raft request' (duration: 113.340995ms)","trace[1505193250] 'compare' (duration: 13.820124ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:45:43.795846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.647881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-07-24T04:45:43.795884Z","caller":"traceutil/trace.go:171","msg":"trace[2122367688] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5987; }","duration":"113.698821ms","start":"2026-07-24T04:45:43.682174Z","end":"2026-07-24T04:45:43.795873Z","steps":["trace[2122367688] 'agreement among raft nodes before linearized reading' (duration: 113.539179ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:47:15.383763Z","caller":"traceutil/trace.go:171","msg":"trace[259448150] transaction","detail":"{read_only:false; response_revision:6707; number_of_response:1; }","duration":"114.831199ms","start":"2026-07-24T04:47:15.268904Z","end":"2026-07-24T04:47:15.383735Z","steps":["trace[259448150] 'process raft request' (duration: 114.454323ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:49:29.556632Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5564} {"level":"info","ts":"2026-07-24T04:49:29.636608Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5564,"took":"78.522693ms","hash":215961102,"current-db-size-bytes":52461568,"current-db-size":"52 MB","current-db-size-in-use-bytes":18309120,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-24T04:49:29.636663Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":215961102,"revision":5564,"compact-revision":2895} {"level":"info","ts":"2026-07-24T04:50:58.904776Z","caller":"traceutil/trace.go:171","msg":"trace[487998992] transaction","detail":"{read_only:false; response_revision:7861; number_of_response:1; }","duration":"106.142644ms","start":"2026-07-24T04:50:58.798608Z","end":"2026-07-24T04:50:58.904751Z","steps":["trace[487998992] 'process raft request' (duration: 94.159598ms)","trace[487998992] 'compare' (duration: 11.722689ms)"],"step_count":2}