{"level":"warn","ts":"2026-04-06T17:05:57.512234Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-06T17:05:57.512339Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62: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://162.253.55.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-06T17:05:57.512419Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-06T17:05:57.512432Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-04-06T17:05:57.512453Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-06T17:05:57.513104Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-04-06T17:05:57.513184Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-06T17:05:57.516812Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.343972ms"} {"level":"info","ts":"2026-04-06T17:05:57.529648Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-04-06T17:05:57.529737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-04-06T17:05:57.529767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-04-06T17:05:57.529783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-06T17:05:57.529797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-04-06T17:05:57.529841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-04-06T17:05:57.541609Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-06T17:05:57.546431Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-06T17:05:57.548083Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-06T17:05:57.550779Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-06T17:05:57.550954Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-06T17:05:57.551127Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-06T17:05:57.551362Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-06T17:05:57.551447Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-06T17:05:57.552023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-04-06T17:05:57.5522Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-04-06T17:05:57.553953Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-06T17:05:57.554428Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-06T17:05:57.554459Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-06T17:05:57.554599Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-04-06T17:05:57.554708Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-04-06T17:05:58.130424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-04-06T17:05:58.130469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-06T17:05:58.130531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-04-06T17:05:58.130547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-04-06T17:05:58.13056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-04-06T17:05:58.13057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-04-06T17:05:58.130579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-04-06T17:05:58.131603Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-04-06T17:05:58.13176Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T17:05:58.131698Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T17:05:58.131864Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T17:05:58.132177Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-06T17:05:58.132197Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-06T17:05:58.1329Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T17:05:58.133095Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T17:05:58.133138Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T17:05:58.13464Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-06T17:05:58.135675Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-04-06T17:06:07.40085Z","caller":"traceutil/trace.go:171","msg":"trace[349489409] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:363; }","duration":"120.867004ms","start":"2026-04-06T17:06:07.279956Z","end":"2026-04-06T17:06:07.400823Z","steps":["trace[349489409] 'read index received' (duration: 8.542209ms)","trace[349489409] 'applied index is now lower than readState.Index' (duration: 112.321535ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:06:07.400953Z","caller":"traceutil/trace.go:171","msg":"trace[1782352052] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"128.131841ms","start":"2026-04-06T17:06:07.272782Z","end":"2026-04-06T17:06:07.400914Z","steps":["trace[1782352052] 'process raft request' (duration: 85.9378ms)","trace[1782352052] 'compare' (duration: 41.990156ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:06:07.401067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.060718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-06T17:06:07.401137Z","caller":"traceutil/trace.go:171","msg":"trace[1958305302] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:353; }","duration":"121.187031ms","start":"2026-04-06T17:06:07.279934Z","end":"2026-04-06T17:06:07.401121Z","steps":["trace[1958305302] 'agreement among raft nodes before linearized reading' (duration: 121.050038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:06:07.635598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.341164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:06:07.635721Z","caller":"traceutil/trace.go:171","msg":"trace[114455130] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:369; }","duration":"155.49951ms","start":"2026-04-06T17:06:07.4802Z","end":"2026-04-06T17:06:07.6357Z","steps":["trace[114455130] 'read index received' (duration: 22.478959ms)","trace[114455130] 'applied index is now lower than readState.Index' (duration: 133.019241ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:06:07.635745Z","caller":"traceutil/trace.go:171","msg":"trace[2128223920] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"160.656747ms","start":"2026-04-06T17:06:07.475057Z","end":"2026-04-06T17:06:07.635714Z","steps":["trace[2128223920] 'process raft request' (duration: 27.609965ms)","trace[2128223920] 'compare' (duration: 132.182451ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:06:07.635921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.162005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-06T17:06:07.635974Z","caller":"traceutil/trace.go:171","msg":"trace[738143033] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:358; }","duration":"104.249008ms","start":"2026-04-06T17:06:07.531714Z","end":"2026-04-06T17:06:07.635963Z","steps":["trace[738143033] 'agreement among raft nodes before linearized reading' (duration: 104.109354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:06:07.636302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.081114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-06T17:06:07.636447Z","caller":"traceutil/trace.go:171","msg":"trace[1361591673] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:358; }","duration":"156.235068ms","start":"2026-04-06T17:06:07.480146Z","end":"2026-04-06T17:06:07.636381Z","steps":["trace[1361591673] 'agreement among raft nodes before linearized reading' (duration: 155.663354ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:06:07.8011Z","caller":"traceutil/trace.go:171","msg":"trace[1460480354] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"101.378948ms","start":"2026-04-06T17:06:07.699698Z","end":"2026-04-06T17:06:07.801076Z","steps":["trace[1460480354] 'process raft request' (duration: 71.745984ms)","trace[1460480354] 'compare' (duration: 29.214264ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:06:07.801344Z","caller":"traceutil/trace.go:171","msg":"trace[1780718441] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"101.338716ms","start":"2026-04-06T17:06:07.699996Z","end":"2026-04-06T17:06:07.801334Z","steps":["trace[1780718441] 'process raft request' (duration: 100.770262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:06:16.244534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.643539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:06:16.244656Z","caller":"traceutil/trace.go:171","msg":"trace[75227683] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"221.837772ms","start":"2026-04-06T17:06:16.022801Z","end":"2026-04-06T17:06:16.244639Z","steps":["trace[75227683] 'read index received' (duration: 61.442µs)","trace[75227683] 'applied index is now lower than readState.Index' (duration: 221.77266ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:06:16.244761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.964665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-06T17:06:16.244797Z","caller":"traceutil/trace.go:171","msg":"trace[1861931] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"222.041006ms","start":"2026-04-06T17:06:16.022746Z","end":"2026-04-06T17:06:16.244787Z","steps":["trace[1861931] 'agreement among raft nodes before linearized reading' (duration: 221.974055ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:06:16.244887Z","caller":"traceutil/trace.go:171","msg":"trace[889087135] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"505.423362ms","start":"2026-04-06T17:06:15.739433Z","end":"2026-04-06T17:06:16.244856Z","steps":["trace[889087135] 'process raft request' (duration: 242.25237ms)","trace[889087135] 'compare' (duration: 262.509076ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:06:16.245052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:06:15.739382Z","time spent":"505.588577ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:06:32.232241Z","caller":"traceutil/trace.go:171","msg":"trace[1317053405] transaction","detail":"{read_only:false; number_of_response:1; response_revision:574; }","duration":"118.060617ms","start":"2026-04-06T17:06:32.114155Z","end":"2026-04-06T17:06:32.232216Z","steps":["trace[1317053405] 'process raft request' (duration: 117.947175ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:06:32.232293Z","caller":"traceutil/trace.go:171","msg":"trace[1567351012] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"130.999439ms","start":"2026-04-06T17:06:32.101214Z","end":"2026-04-06T17:06:32.232213Z","steps":["trace[1567351012] 'process raft request' (duration: 124.170029ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:06:33.493385Z","caller":"traceutil/trace.go:171","msg":"trace[597047329] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"351.967685ms","start":"2026-04-06T17:06:33.141393Z","end":"2026-04-06T17:06:33.493361Z","steps":["trace[597047329] 'process raft request' (duration: 343.28817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:06:33.4935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:06:33.141372Z","time spent":"352.083348ms","remote":"127.0.0.1:45206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T17:06:33.495002Z","caller":"traceutil/trace.go:171","msg":"trace[851752479] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"353.155295ms","start":"2026-04-06T17:06:33.141815Z","end":"2026-04-06T17:06:33.49497Z","steps":["trace[851752479] 'process raft request' (duration: 352.96297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:06:33.495201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:06:33.141787Z","time spent":"353.324179ms","remote":"127.0.0.1:45206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:06:33.50052Z","caller":"traceutil/trace.go:171","msg":"trace[2049932817] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"243.873726ms","start":"2026-04-06T17:06:33.256621Z","end":"2026-04-06T17:06:33.500495Z","steps":["trace[2049932817] 'process raft request' (duration: 238.587395ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:06:39.24452Z","caller":"traceutil/trace.go:171","msg":"trace[89320648] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"232.464063ms","start":"2026-04-06T17:06:39.012031Z","end":"2026-04-06T17:06:39.244495Z","steps":["trace[89320648] 'process raft request' (duration: 231.94958ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:06:39.603206Z","caller":"traceutil/trace.go:171","msg":"trace[1023455423] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"297.459ms","start":"2026-04-06T17:06:39.305721Z","end":"2026-04-06T17:06:39.60318Z","steps":["trace[1023455423] 'process raft request' (duration: 297.287296ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:06:39.974886Z","caller":"traceutil/trace.go:171","msg":"trace[1729895191] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"356.694324ms","start":"2026-04-06T17:06:39.618169Z","end":"2026-04-06T17:06:39.974864Z","steps":["trace[1729895191] 'process raft request' (duration: 343.451175ms)","trace[1729895191] 'compare' (duration: 12.911741ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:06:39.975006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:06:39.618143Z","time spent":"356.805897ms","remote":"127.0.0.1:45356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:06:39.975131Z","caller":"traceutil/trace.go:171","msg":"trace[285244280] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"356.817637ms","start":"2026-04-06T17:06:39.618305Z","end":"2026-04-06T17:06:39.975123Z","steps":["trace[285244280] 'process raft request' (duration: 356.53128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:06:39.975192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:06:39.618293Z","time spent":"356.858089ms","remote":"127.0.0.1:45356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T17:07:23.225227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.746857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:07:23.225359Z","caller":"traceutil/trace.go:171","msg":"trace[1928136957] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1192; }","duration":"194.965572ms","start":"2026-04-06T17:07:23.030362Z","end":"2026-04-06T17:07:23.225327Z","steps":["trace[1928136957] 'count revisions from in-memory index tree' (duration: 194.640585ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:08:09.128338Z","caller":"traceutil/trace.go:171","msg":"trace[1793935851] transaction","detail":"{read_only:false; response_revision:1776; number_of_response:1; }","duration":"112.969773ms","start":"2026-04-06T17:08:09.015341Z","end":"2026-04-06T17:08:09.128311Z","steps":["trace[1793935851] 'process raft request' (duration: 112.84392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:17.655734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.121123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-06T17:08:17.655847Z","caller":"traceutil/trace.go:171","msg":"trace[801361205] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1891; }","duration":"380.283746ms","start":"2026-04-06T17:08:17.275544Z","end":"2026-04-06T17:08:17.655827Z","steps":["trace[801361205] 'range keys from in-memory index tree' (duration: 379.938788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:17.655887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:08:17.275527Z","time spent":"380.351608ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-06T17:08:17.6558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.103669ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:08:17.655939Z","caller":"traceutil/trace.go:171","msg":"trace[1701683782] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1891; }","duration":"111.277503ms","start":"2026-04-06T17:08:17.544639Z","end":"2026-04-06T17:08:17.655917Z","steps":["trace[1701683782] 'range keys from in-memory index tree' (duration: 111.084468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:17.655927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.219381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e5083d88-6666-4aec-9da0-ef41983a0d16\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-06T17:08:17.656081Z","caller":"traceutil/trace.go:171","msg":"trace[1402097465] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e5083d88-6666-4aec-9da0-ef41983a0d16; range_end:; response_count:1; response_revision:1891; }","duration":"398.433715ms","start":"2026-04-06T17:08:17.257618Z","end":"2026-04-06T17:08:17.656052Z","steps":["trace[1402097465] 'range keys from in-memory index tree' (duration: 397.950034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:17.656174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:08:17.257597Z","time spent":"398.562349ms","remote":"127.0.0.1:45356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e5083d88-6666-4aec-9da0-ef41983a0d16\" "} {"level":"warn","ts":"2026-04-06T17:08:17.656408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.748104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-06T17:08:17.656849Z","caller":"traceutil/trace.go:171","msg":"trace[410386845] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1891; }","duration":"380.208935ms","start":"2026-04-06T17:08:17.276585Z","end":"2026-04-06T17:08:17.656794Z","steps":["trace[410386845] 'range keys from in-memory index tree' (duration: 378.974425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:08:17.656938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:08:17.276559Z","time spent":"380.334607ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-06T17:08:18.049557Z","caller":"traceutil/trace.go:171","msg":"trace[1028302834] linearizableReadLoop","detail":"{readStateIndex:1963; appliedIndex:1962; }","duration":"113.399783ms","start":"2026-04-06T17:08:17.936128Z","end":"2026-04-06T17:08:18.049527Z","steps":["trace[1028302834] 'read index received' (duration: 101.783228ms)","trace[1028302834] 'applied index is now lower than readState.Index' (duration: 11.615295ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:08:18.04957Z","caller":"traceutil/trace.go:171","msg":"trace[720808511] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"147.428508ms","start":"2026-04-06T17:08:17.902104Z","end":"2026-04-06T17:08:18.049532Z","steps":["trace[720808511] 'process raft request' (duration: 135.666909ms)","trace[720808511] 'compare' (duration: 11.504623ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:08:18.049786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.647578ms","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-04-06T17:08:18.049823Z","caller":"traceutil/trace.go:171","msg":"trace[1303367783] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1895; }","duration":"113.73503ms","start":"2026-04-06T17:08:17.936076Z","end":"2026-04-06T17:08:18.049811Z","steps":["trace[1303367783] 'agreement among raft nodes before linearized reading' (duration: 113.592657ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:08:30.277244Z","caller":"traceutil/trace.go:171","msg":"trace[1084019256] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"144.90071ms","start":"2026-04-06T17:08:30.13231Z","end":"2026-04-06T17:08:30.277211Z","steps":["trace[1084019256] 'process raft request' (duration: 144.696645ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:08:30.353673Z","caller":"traceutil/trace.go:171","msg":"trace[417092743] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"220.108344ms","start":"2026-04-06T17:08:30.133534Z","end":"2026-04-06T17:08:30.353642Z","steps":["trace[417092743] 'process raft request' (duration: 172.01322ms)","trace[417092743] 'compare' (duration: 47.969931ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:08:33.932537Z","caller":"traceutil/trace.go:171","msg":"trace[1628660446] transaction","detail":"{read_only:false; response_revision:2026; number_of_response:1; }","duration":"147.969462ms","start":"2026-04-06T17:08:33.784543Z","end":"2026-04-06T17:08:33.932513Z","steps":["trace[1628660446] 'process raft request' (duration: 147.539512ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:06.701785Z","caller":"traceutil/trace.go:171","msg":"trace[1104757767] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"167.192086ms","start":"2026-04-06T17:09:06.534543Z","end":"2026-04-06T17:09:06.701735Z","steps":["trace[1104757767] 'process raft request' (duration: 81.590376ms)","trace[1104757767] 'compare' (duration: 85.285963ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:09:06.701934Z","caller":"traceutil/trace.go:171","msg":"trace[1266369849] linearizableReadLoop","detail":"{readStateIndex:2286; appliedIndex:2285; }","duration":"142.021072ms","start":"2026-04-06T17:09:06.559876Z","end":"2026-04-06T17:09:06.701897Z","steps":["trace[1266369849] 'read index received' (duration: 56.196766ms)","trace[1266369849] 'applied index is now lower than readState.Index' (duration: 85.821336ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:09:06.702122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.219627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:09:06.702203Z","caller":"traceutil/trace.go:171","msg":"trace[1991194446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2208; }","duration":"142.33707ms","start":"2026-04-06T17:09:06.559847Z","end":"2026-04-06T17:09:06.702184Z","steps":["trace[1991194446] 'agreement among raft nodes before linearized reading' (duration: 142.183976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:07.056324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.026912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:09:07.056428Z","caller":"traceutil/trace.go:171","msg":"trace[1528251916] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2210; }","duration":"131.151695ms","start":"2026-04-06T17:09:06.925249Z","end":"2026-04-06T17:09:07.056401Z","steps":["trace[1528251916] 'count revisions from in-memory index tree' (duration: 130.9242ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:12.886221Z","caller":"traceutil/trace.go:171","msg":"trace[387841819] linearizableReadLoop","detail":"{readStateIndex:2318; appliedIndex:2317; }","duration":"326.437405ms","start":"2026-04-06T17:09:12.559759Z","end":"2026-04-06T17:09:12.886197Z","steps":["trace[387841819] 'read index received' (duration: 326.282531ms)","trace[387841819] 'applied index is now lower than readState.Index' (duration: 153.724µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:09:12.886374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.601029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:09:12.886408Z","caller":"traceutil/trace.go:171","msg":"trace[1322433599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2237; }","duration":"326.679071ms","start":"2026-04-06T17:09:12.559718Z","end":"2026-04-06T17:09:12.886397Z","steps":["trace[1322433599] 'agreement among raft nodes before linearized reading' (duration: 326.608179ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:12.886359Z","caller":"traceutil/trace.go:171","msg":"trace[429028061] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"337.24799ms","start":"2026-04-06T17:09:12.549068Z","end":"2026-04-06T17:09:12.886316Z","steps":["trace[429028061] 'process raft request' (duration: 336.979824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:12.886446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:12.55968Z","time spent":"326.758813ms","remote":"127.0.0.1:45154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-06T17:09:12.886575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:12.549044Z","time spent":"337.421094ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:09:13.162975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.002777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:09:13.163213Z","caller":"traceutil/trace.go:171","msg":"trace[162766800] linearizableReadLoop","detail":"{readStateIndex:2320; appliedIndex:2318; }","duration":"276.888885ms","start":"2026-04-06T17:09:12.886301Z","end":"2026-04-06T17:09:13.16319Z","steps":["trace[162766800] 'read index received' (duration: 41.487448ms)","trace[162766800] 'applied index is now lower than readState.Index' (duration: 235.400266ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:09:13.163371Z","caller":"traceutil/trace.go:171","msg":"trace[137565618] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"367.851402ms","start":"2026-04-06T17:09:12.79548Z","end":"2026-04-06T17:09:13.163332Z","steps":["trace[137565618] 'process raft request' (duration: 367.624547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:13.163555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.726152ms","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":"warn","ts":"2026-04-06T17:09:13.163561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.646498ms","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-04-06T17:09:13.16362Z","caller":"traceutil/trace.go:171","msg":"trace[2032847151] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2239; }","duration":"426.823844ms","start":"2026-04-06T17:09:12.736777Z","end":"2026-04-06T17:09:13.163601Z","steps":["trace[2032847151] 'agreement among raft nodes before linearized reading' (duration: 426.550618ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:13.16348Z","caller":"traceutil/trace.go:171","msg":"trace[1654869980] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"437.414664ms","start":"2026-04-06T17:09:12.725914Z","end":"2026-04-06T17:09:13.163329Z","steps":["trace[1654869980] 'process raft request' (duration: 201.953796ms)","trace[1654869980] 'compare' (duration: 234.866394ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:09:13.163642Z","caller":"traceutil/trace.go:171","msg":"trace[1478711688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2239; }","duration":"320.765421ms","start":"2026-04-06T17:09:12.842857Z","end":"2026-04-06T17:09:13.163622Z","steps":["trace[1478711688] 'agreement among raft nodes before linearized reading' (duration: 320.465234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:13.163599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.233842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-06T17:09:13.163678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:12.736764Z","time spent":"426.901046ms","remote":"127.0.0.1:45448","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-04-06T17:09:13.163696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:12.842838Z","time spent":"320.845512ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-06T17:09:13.163734Z","caller":"traceutil/trace.go:171","msg":"trace[2001419657] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2239; }","duration":"432.426206ms","start":"2026-04-06T17:09:12.731271Z","end":"2026-04-06T17:09:13.163697Z","steps":["trace[2001419657] 'agreement among raft nodes before linearized reading' (duration: 432.072648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:13.163763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:12.725888Z","time spent":"437.790313ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:09:13.163802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:12.731248Z","time spent":"432.540539ms","remote":"127.0.0.1:45550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":67,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-06T17:09:13.164138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:12.795454Z","time spent":"368.131139ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:09:13.572432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.759825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:09:13.572673Z","caller":"traceutil/trace.go:171","msg":"trace[1345726689] linearizableReadLoop","detail":"{readStateIndex:2322; appliedIndex:2320; }","duration":"332.587619ms","start":"2026-04-06T17:09:13.240053Z","end":"2026-04-06T17:09:13.57264Z","steps":["trace[1345726689] 'read index received' (duration: 104.594258ms)","trace[1345726689] 'applied index is now lower than readState.Index' (duration: 227.991891ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:09:13.572839Z","caller":"traceutil/trace.go:171","msg":"trace[2146970403] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"404.122378ms","start":"2026-04-06T17:09:13.168701Z","end":"2026-04-06T17:09:13.572823Z","steps":["trace[2146970403] 'process raft request' (duration: 403.837581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:13.572921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:13.168683Z","time spent":"404.19353ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:09:13.573118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.522221ms","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":"info","ts":"2026-04-06T17:09:13.573243Z","caller":"traceutil/trace.go:171","msg":"trace[16357202] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2241; }","duration":"305.733876ms","start":"2026-04-06T17:09:13.267475Z","end":"2026-04-06T17:09:13.573209Z","steps":["trace[16357202] 'agreement among raft nodes before linearized reading' (duration: 305.427418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:13.573284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.242805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-06T17:09:13.573308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:13.267447Z","time spent":"305.848229ms","remote":"127.0.0.1:45356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":22,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-04-06T17:09:13.572935Z","caller":"traceutil/trace.go:171","msg":"trace[1119128273] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"404.939767ms","start":"2026-04-06T17:09:13.167954Z","end":"2026-04-06T17:09:13.572894Z","steps":["trace[1119128273] 'process raft request' (duration: 176.634599ms)","trace[1119128273] 'compare' (duration: 227.508699ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:09:13.573359Z","caller":"traceutil/trace.go:171","msg":"trace[2044225303] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2241; }","duration":"333.373408ms","start":"2026-04-06T17:09:13.239964Z","end":"2026-04-06T17:09:13.573337Z","steps":["trace[2044225303] 'agreement among raft nodes before linearized reading' (duration: 333.101891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:13.573404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:13.239937Z","time spent":"333.4578ms","remote":"127.0.0.1:52248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-06T17:09:13.573573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:09:13.16793Z","time spent":"405.45182ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:09:47.422321Z","caller":"traceutil/trace.go:171","msg":"trace[876062969] transaction","detail":"{read_only:false; response_revision:2601; number_of_response:1; }","duration":"130.429449ms","start":"2026-04-06T17:09:47.291866Z","end":"2026-04-06T17:09:47.422295Z","steps":["trace[876062969] 'process raft request' (duration: 130.260445ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:09:47.42303Z","caller":"traceutil/trace.go:171","msg":"trace[1074123760] transaction","detail":"{read_only:false; response_revision:2602; number_of_response:1; }","duration":"123.327583ms","start":"2026-04-06T17:09:47.299683Z","end":"2026-04-06T17:09:47.42301Z","steps":["trace[1074123760] 'process raft request' (duration: 123.21249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:48.178963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.068967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-04-06T17:09:48.179069Z","caller":"traceutil/trace.go:171","msg":"trace[962652944] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2605; }","duration":"221.232581ms","start":"2026-04-06T17:09:47.957807Z","end":"2026-04-06T17:09:48.17904Z","steps":["trace[962652944] 'range keys from in-memory index tree' (duration: 220.864693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:09:48.179096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.469945ms","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-04-06T17:09:48.179161Z","caller":"traceutil/trace.go:171","msg":"trace[385835987] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2605; }","duration":"256.589237ms","start":"2026-04-06T17:09:47.922556Z","end":"2026-04-06T17:09:48.179146Z","steps":["trace[385835987] 'range keys from in-memory index tree' (duration: 256.226879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:10:21.716062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.980796ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-06T17:10:21.716178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:10:21.326574Z","time spent":"389.586946ms","remote":"127.0.0.1:45188","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-06T17:10:21.755621Z","caller":"traceutil/trace.go:171","msg":"trace[256722400] linearizableReadLoop","detail":"{readStateIndex:2909; appliedIndex:2908; }","duration":"196.819481ms","start":"2026-04-06T17:10:21.558771Z","end":"2026-04-06T17:10:21.755591Z","steps":["trace[256722400] 'read index received' (duration: 196.612396ms)","trace[256722400] 'applied index is now lower than readState.Index' (duration: 205.635µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:10:21.755745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.952854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:10:21.755776Z","caller":"traceutil/trace.go:171","msg":"trace[1079382051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2805; }","duration":"197.041426ms","start":"2026-04-06T17:10:21.558724Z","end":"2026-04-06T17:10:21.755765Z","steps":["trace[1079382051] 'agreement among raft nodes before linearized reading' (duration: 196.970514ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:10:21.755675Z","caller":"traceutil/trace.go:171","msg":"trace[1487706959] transaction","detail":"{read_only:false; response_revision:2805; number_of_response:1; }","duration":"210.91091ms","start":"2026-04-06T17:10:21.544725Z","end":"2026-04-06T17:10:21.755636Z","steps":["trace[1487706959] 'process raft request' (duration: 210.652104ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:10:21.756245Z","caller":"traceutil/trace.go:171","msg":"trace[642544697] transaction","detail":"{read_only:false; response_revision:2806; number_of_response:1; }","duration":"194.466616ms","start":"2026-04-06T17:10:21.561766Z","end":"2026-04-06T17:10:21.756233Z","steps":["trace[642544697] 'process raft request' (duration: 194.321392ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:00.440717Z","caller":"traceutil/trace.go:171","msg":"trace[339257536] transaction","detail":"{read_only:false; response_revision:4592; number_of_response:1; }","duration":"120.748154ms","start":"2026-04-06T17:14:00.319933Z","end":"2026-04-06T17:14:00.440681Z","steps":["trace[339257536] 'process raft request' (duration: 64.279248ms)","trace[339257536] 'compare' (duration: 56.324463ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:14:06.465146Z","caller":"traceutil/trace.go:171","msg":"trace[187765992] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"223.643162ms","start":"2026-04-06T17:14:06.241464Z","end":"2026-04-06T17:14:06.465107Z","steps":["trace[187765992] 'process raft request' (duration: 223.350805ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:08.664294Z","caller":"traceutil/trace.go:171","msg":"trace[28422453] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"103.606135ms","start":"2026-04-06T17:14:08.560655Z","end":"2026-04-06T17:14:08.664261Z","steps":["trace[28422453] 'process raft request' (duration: 103.427411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:08.664847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.233869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:14:08.664893Z","caller":"traceutil/trace.go:171","msg":"trace[824593391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4806; }","duration":"104.318031ms","start":"2026-04-06T17:14:08.560561Z","end":"2026-04-06T17:14:08.664879Z","steps":["trace[824593391] 'agreement among raft nodes before linearized reading' (duration: 104.236249ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:08.872714Z","caller":"traceutil/trace.go:171","msg":"trace[719515335] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"107.628648ms","start":"2026-04-06T17:14:08.765056Z","end":"2026-04-06T17:14:08.872685Z","steps":["trace[719515335] 'process raft request' (duration: 107.485165ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:12.844163Z","caller":"traceutil/trace.go:171","msg":"trace[1453291900] transaction","detail":"{read_only:false; response_revision:4983; number_of_response:1; }","duration":"229.439117ms","start":"2026-04-06T17:14:12.614705Z","end":"2026-04-06T17:14:12.844144Z","steps":["trace[1453291900] 'process raft request' (duration: 229.322904ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:12.929783Z","caller":"traceutil/trace.go:171","msg":"trace[926701783] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"255.130746ms","start":"2026-04-06T17:14:12.674616Z","end":"2026-04-06T17:14:12.929747Z","steps":["trace[926701783] 'process raft request' (duration: 254.797258ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:12.929947Z","caller":"traceutil/trace.go:171","msg":"trace[1061597184] linearizableReadLoop","detail":"{readStateIndex:5170; appliedIndex:5168; }","duration":"128.426203ms","start":"2026-04-06T17:14:12.801484Z","end":"2026-04-06T17:14:12.92991Z","steps":["trace[1061597184] 'read index received' (duration: 42.694275ms)","trace[1061597184] 'applied index is now lower than readState.Index' (duration: 85.729638ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:14:12.930023Z","caller":"traceutil/trace.go:171","msg":"trace[1589356552] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"253.312873ms","start":"2026-04-06T17:14:12.676656Z","end":"2026-04-06T17:14:12.929969Z","steps":["trace[1589356552] 'process raft request' (duration: 253.031597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:12.930228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.728261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-29f07ca7-e496-421c-8edb-07840c577203\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-06T17:14:12.930305Z","caller":"traceutil/trace.go:171","msg":"trace[1623982998] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-29f07ca7-e496-421c-8edb-07840c577203; range_end:; response_count:1; response_revision:4985; }","duration":"128.852783ms","start":"2026-04-06T17:14:12.801434Z","end":"2026-04-06T17:14:12.930287Z","steps":["trace[1623982998] 'agreement among raft nodes before linearized reading' (duration: 128.609697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:12.930798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.98966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:777450"} {"level":"info","ts":"2026-04-06T17:14:12.930874Z","caller":"traceutil/trace.go:171","msg":"trace[1740825472] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4985; }","duration":"112.099233ms","start":"2026-04-06T17:14:12.818757Z","end":"2026-04-06T17:14:12.930856Z","steps":["trace[1740825472] 'agreement among raft nodes before linearized reading' (duration: 111.402067ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:13.433671Z","caller":"traceutil/trace.go:171","msg":"trace[395564584] linearizableReadLoop","detail":"{readStateIndex:5174; appliedIndex:5173; }","duration":"298.395725ms","start":"2026-04-06T17:14:13.135245Z","end":"2026-04-06T17:14:13.433641Z","steps":["trace[395564584] 'read index received' (duration: 236.197285ms)","trace[395564584] 'applied index is now lower than readState.Index' (duration: 62.19723ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:14:13.434019Z","caller":"traceutil/trace.go:171","msg":"trace[1136243471] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"310.318752ms","start":"2026-04-06T17:14:13.123685Z","end":"2026-04-06T17:14:13.434004Z","steps":["trace[1136243471] 'process raft request' (duration: 247.778865ms)","trace[1136243471] 'compare' (duration: 62.040565ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:14:13.434129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:14:13.123671Z","time spent":"310.378794ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:14:13.434388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.087391ms","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-04-06T17:14:13.434505Z","caller":"traceutil/trace.go:171","msg":"trace[1611010147] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4989; }","duration":"299.262054ms","start":"2026-04-06T17:14:13.135217Z","end":"2026-04-06T17:14:13.434479Z","steps":["trace[1611010147] 'agreement among raft nodes before linearized reading' (duration: 298.922406ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:13.442585Z","caller":"traceutil/trace.go:171","msg":"trace[318611716] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"284.329817ms","start":"2026-04-06T17:14:13.158232Z","end":"2026-04-06T17:14:13.442562Z","steps":["trace[318611716] 'process raft request' (duration: 284.187573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:13.445179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.890708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:14:13.445257Z","caller":"traceutil/trace.go:171","msg":"trace[1629444298] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:4992; }","duration":"233.017631ms","start":"2026-04-06T17:14:13.21222Z","end":"2026-04-06T17:14:13.445238Z","steps":["trace[1629444298] 'agreement among raft nodes before linearized reading' (duration: 232.885298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:13.445784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.951598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-29f07ca7-e496-421c-8edb-07840c577203\" ","response":"range_response_count:1 size:3720"} {"level":"info","ts":"2026-04-06T17:14:13.44584Z","caller":"traceutil/trace.go:171","msg":"trace[703947225] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-29f07ca7-e496-421c-8edb-07840c577203; range_end:; response_count:1; response_revision:4992; }","duration":"136.053961ms","start":"2026-04-06T17:14:13.309773Z","end":"2026-04-06T17:14:13.445827Z","steps":["trace[703947225] 'agreement among raft nodes before linearized reading' (duration: 135.897897ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:17.82911Z","caller":"traceutil/trace.go:171","msg":"trace[1598909918] transaction","detail":"{read_only:false; response_revision:5095; number_of_response:1; }","duration":"177.098227ms","start":"2026-04-06T17:14:17.651985Z","end":"2026-04-06T17:14:17.829083Z","steps":["trace[1598909918] 'process raft request' (duration: 149.066304ms)","trace[1598909918] 'compare' (duration: 27.750747ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:14:17.829286Z","caller":"traceutil/trace.go:171","msg":"trace[1725318445] transaction","detail":"{read_only:false; response_revision:5096; number_of_response:1; }","duration":"174.842395ms","start":"2026-04-06T17:14:17.654417Z","end":"2026-04-06T17:14:17.829259Z","steps":["trace[1725318445] 'process raft request' (duration: 174.62965ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:21.311502Z","caller":"traceutil/trace.go:171","msg":"trace[1649838591] transaction","detail":"{read_only:false; response_revision:5146; number_of_response:1; }","duration":"116.856133ms","start":"2026-04-06T17:14:21.194604Z","end":"2026-04-06T17:14:21.31146Z","steps":["trace[1649838591] 'process raft request' (duration: 116.580097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:21.311698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.7337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/memcached-memcached\" ","response":"range_response_count:1 size:7179"} {"level":"warn","ts":"2026-04-06T17:14:21.311528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.815436ms","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-04-06T17:14:21.311773Z","caller":"traceutil/trace.go:171","msg":"trace[1606634900] range","detail":"{range_begin:/registry/deployments/openstack/memcached-memcached; range_end:; response_count:1; response_revision:5146; }","duration":"119.848913ms","start":"2026-04-06T17:14:21.191908Z","end":"2026-04-06T17:14:21.311757Z","steps":["trace[1606634900] 'agreement among raft nodes before linearized reading' (duration: 119.627008ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:21.311832Z","caller":"traceutil/trace.go:171","msg":"trace[1344918437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5146; }","duration":"124.146923ms","start":"2026-04-06T17:14:21.187649Z","end":"2026-04-06T17:14:21.311796Z","steps":["trace[1344918437] 'agreement among raft nodes before linearized reading' (duration: 123.706343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:21.311861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.961585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:10 size:381374"} {"level":"info","ts":"2026-04-06T17:14:21.311922Z","caller":"traceutil/trace.go:171","msg":"trace[999278343] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:10; response_revision:5146; }","duration":"120.044618ms","start":"2026-04-06T17:14:21.191864Z","end":"2026-04-06T17:14:21.311909Z","steps":["trace[999278343] 'agreement among raft nodes before linearized reading' (duration: 119.7201ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:21.352503Z","caller":"traceutil/trace.go:171","msg":"trace[834053124] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"106.87699ms","start":"2026-04-06T17:14:21.245604Z","end":"2026-04-06T17:14:21.352481Z","steps":["trace[834053124] 'process raft request' (duration: 106.830749ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:21.352617Z","caller":"traceutil/trace.go:171","msg":"trace[1827603015] transaction","detail":"{read_only:false; response_revision:5147; number_of_response:1; }","duration":"129.258283ms","start":"2026-04-06T17:14:21.223331Z","end":"2026-04-06T17:14:21.35259Z","steps":["trace[1827603015] 'process raft request' (duration: 128.993377ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:21.443918Z","caller":"traceutil/trace.go:171","msg":"trace[1114281412] transaction","detail":"{read_only:false; response_revision:5149; number_of_response:1; }","duration":"128.342021ms","start":"2026-04-06T17:14:21.315553Z","end":"2026-04-06T17:14:21.443895Z","steps":["trace[1114281412] 'process raft request' (duration: 81.411987ms)","trace[1114281412] 'compare' (duration: 46.74753ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:14:21.44405Z","caller":"traceutil/trace.go:171","msg":"trace[665849356] linearizableReadLoop","detail":"{readStateIndex:5339; appliedIndex:5335; }","duration":"105.604821ms","start":"2026-04-06T17:14:21.338417Z","end":"2026-04-06T17:14:21.444022Z","steps":["trace[665849356] 'read index received' (duration: 13.959135ms)","trace[665849356] 'applied index is now lower than readState.Index' (duration: 91.643616ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:14:21.44409Z","caller":"traceutil/trace.go:171","msg":"trace[1057474449] transaction","detail":"{read_only:false; response_revision:5150; number_of_response:1; }","duration":"122.585417ms","start":"2026-04-06T17:14:21.321493Z","end":"2026-04-06T17:14:21.444079Z","steps":["trace[1057474449] 'process raft request' (duration: 122.343822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:21.444301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.865748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-06T17:14:21.444345Z","caller":"traceutil/trace.go:171","msg":"trace[650620313] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:5150; }","duration":"105.97267ms","start":"2026-04-06T17:14:21.33836Z","end":"2026-04-06T17:14:21.444333Z","steps":["trace[650620313] 'agreement among raft nodes before linearized reading' (duration: 105.802216ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:21.579823Z","caller":"traceutil/trace.go:171","msg":"trace[2084770376] linearizableReadLoop","detail":"{readStateIndex:5342; appliedIndex:5341; }","duration":"105.274203ms","start":"2026-04-06T17:14:21.474523Z","end":"2026-04-06T17:14:21.579797Z","steps":["trace[2084770376] 'read index received' (duration: 77.578387ms)","trace[2084770376] 'applied index is now lower than readState.Index' (duration: 27.694806ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:14:21.579871Z","caller":"traceutil/trace.go:171","msg":"trace[2074617366] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"112.331368ms","start":"2026-04-06T17:14:21.4675Z","end":"2026-04-06T17:14:21.579831Z","steps":["trace[2074617366] 'process raft request' (duration: 84.662023ms)","trace[2074617366] 'compare' (duration: 27.521261ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:14:21.580085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.029881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-06T17:14:21.580124Z","caller":"traceutil/trace.go:171","msg":"trace[1765491287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5152; }","duration":"112.100942ms","start":"2026-04-06T17:14:21.468012Z","end":"2026-04-06T17:14:21.580113Z","steps":["trace[1765491287] 'agreement among raft nodes before linearized reading' (duration: 111.912788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:21.580234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.975936ms","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-04-06T17:14:21.580334Z","caller":"traceutil/trace.go:171","msg":"trace[1323456618] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5152; }","duration":"105.12082ms","start":"2026-04-06T17:14:21.475188Z","end":"2026-04-06T17:14:21.580309Z","steps":["trace[1323456618] 'agreement among raft nodes before linearized reading' (duration: 104.838753ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:21.766382Z","caller":"traceutil/trace.go:171","msg":"trace[152352115] transaction","detail":"{read_only:false; response_revision:5155; number_of_response:1; }","duration":"118.743498ms","start":"2026-04-06T17:14:21.647601Z","end":"2026-04-06T17:14:21.766345Z","steps":["trace[152352115] 'process raft request' (duration: 117.206042ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:29.932189Z","caller":"traceutil/trace.go:171","msg":"trace[523229605] transaction","detail":"{read_only:false; response_revision:5312; number_of_response:1; }","duration":"136.334447ms","start":"2026-04-06T17:14:29.795824Z","end":"2026-04-06T17:14:29.932159Z","steps":["trace[523229605] 'process raft request' (duration: 126.096538ms)","trace[523229605] 'compare' (duration: 10.026584ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:14:30.80764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.670226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:14:30.807907Z","caller":"traceutil/trace.go:171","msg":"trace[1655984094] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"161.217867ms","start":"2026-04-06T17:14:30.646664Z","end":"2026-04-06T17:14:30.807882Z","steps":["trace[1655984094] 'process raft request' (duration: 56.899556ms)","trace[1655984094] 'compare' (duration: 103.41341ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:14:31.121019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.159194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ba0e46f7-2264-49fa-be58-7d44a9660049\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-04-06T17:14:31.121112Z","caller":"traceutil/trace.go:171","msg":"trace[1979543981] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ba0e46f7-2264-49fa-be58-7d44a9660049; range_end:; response_count:1; response_revision:5320; }","duration":"109.318968ms","start":"2026-04-06T17:14:31.011772Z","end":"2026-04-06T17:14:31.121091Z","steps":["trace[1979543981] 'agreement among raft nodes before linearized reading' (duration: 109.064992ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:31.121569Z","caller":"traceutil/trace.go:171","msg":"trace[57371693] transaction","detail":"{read_only:false; response_revision:5320; number_of_response:1; }","duration":"114.452497ms","start":"2026-04-06T17:14:31.00635Z","end":"2026-04-06T17:14:31.120802Z","steps":["trace[57371693] 'process raft request' (duration: 111.13311ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:33.165361Z","caller":"traceutil/trace.go:171","msg":"trace[791758922] transaction","detail":"{read_only:false; response_revision:5357; number_of_response:1; }","duration":"132.121019ms","start":"2026-04-06T17:14:33.033214Z","end":"2026-04-06T17:14:33.165335Z","steps":["trace[791758922] 'process raft request' (duration: 131.814332ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:33.187967Z","caller":"traceutil/trace.go:171","msg":"trace[306547173] transaction","detail":"{read_only:false; response_revision:5358; number_of_response:1; }","duration":"154.629214ms","start":"2026-04-06T17:14:33.033302Z","end":"2026-04-06T17:14:33.187931Z","steps":["trace[306547173] 'process raft request' (duration: 154.393269ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:33.188142Z","caller":"traceutil/trace.go:171","msg":"trace[2033356454] transaction","detail":"{read_only:false; response_revision:5359; number_of_response:1; }","duration":"154.746976ms","start":"2026-04-06T17:14:33.033371Z","end":"2026-04-06T17:14:33.188118Z","steps":["trace[2033356454] 'process raft request' (duration: 154.521731ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:33.1882Z","caller":"traceutil/trace.go:171","msg":"trace[2050928050] transaction","detail":"{read_only:false; response_revision:5360; number_of_response:1; }","duration":"154.784528ms","start":"2026-04-06T17:14:33.033388Z","end":"2026-04-06T17:14:33.188172Z","steps":["trace[2050928050] 'process raft request' (duration: 154.565423ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:33.188257Z","caller":"traceutil/trace.go:171","msg":"trace[1216830291] transaction","detail":"{read_only:false; response_revision:5361; number_of_response:1; }","duration":"154.799438ms","start":"2026-04-06T17:14:33.033416Z","end":"2026-04-06T17:14:33.188215Z","steps":["trace[1216830291] 'process raft request' (duration: 154.575602ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:33.188455Z","caller":"traceutil/trace.go:171","msg":"trace[898814886] transaction","detail":"{read_only:false; response_revision:5362; number_of_response:1; }","duration":"154.9091ms","start":"2026-04-06T17:14:33.033533Z","end":"2026-04-06T17:14:33.188442Z","steps":["trace[898814886] 'process raft request' (duration: 154.580112ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:33.188651Z","caller":"traceutil/trace.go:171","msg":"trace[627964446] linearizableReadLoop","detail":"{readStateIndex:5554; appliedIndex:5548; }","duration":"150.505427ms","start":"2026-04-06T17:14:33.038131Z","end":"2026-04-06T17:14:33.188636Z","steps":["trace[627964446] 'read index received' (duration: 126.912508ms)","trace[627964446] 'applied index is now lower than readState.Index' (duration: 23.591909ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:14:33.188865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.720333ms","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-04-06T17:14:33.188908Z","caller":"traceutil/trace.go:171","msg":"trace[1322509870] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5364; }","duration":"150.794374ms","start":"2026-04-06T17:14:33.0381Z","end":"2026-04-06T17:14:33.188894Z","steps":["trace[1322509870] 'agreement among raft nodes before linearized reading' (duration: 150.654151ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:43.974026Z","caller":"traceutil/trace.go:171","msg":"trace[1158599171] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"199.562801ms","start":"2026-04-06T17:14:43.774426Z","end":"2026-04-06T17:14:43.973988Z","steps":["trace[1158599171] 'process raft request' (duration: 196.743555ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:44.21556Z","caller":"traceutil/trace.go:171","msg":"trace[368862931] linearizableReadLoop","detail":"{readStateIndex:5711; appliedIndex:5710; }","duration":"213.324201ms","start":"2026-04-06T17:14:44.002204Z","end":"2026-04-06T17:14:44.215528Z","steps":["trace[368862931] 'read index received' (duration: 165.499427ms)","trace[368862931] 'applied index is now lower than readState.Index' (duration: 47.823524ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:14:44.215858Z","caller":"traceutil/trace.go:171","msg":"trace[693497455] transaction","detail":"{read_only:false; response_revision:5514; number_of_response:1; }","duration":"214.048159ms","start":"2026-04-06T17:14:44.001761Z","end":"2026-04-06T17:14:44.215809Z","steps":["trace[693497455] 'process raft request' (duration: 165.940428ms)","trace[693497455] 'compare' (duration: 47.325232ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:14:44.216133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.873494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-04-06T17:14:44.216215Z","caller":"traceutil/trace.go:171","msg":"trace[1445374930] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5514; }","duration":"214.024298ms","start":"2026-04-06T17:14:44.002174Z","end":"2026-04-06T17:14:44.216198Z","steps":["trace[1445374930] 'agreement among raft nodes before linearized reading' (duration: 213.595798ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:44.362732Z","caller":"traceutil/trace.go:171","msg":"trace[158444537] transaction","detail":"{read_only:false; response_revision:5515; number_of_response:1; }","duration":"342.356069ms","start":"2026-04-06T17:14:44.020337Z","end":"2026-04-06T17:14:44.362693Z","steps":["trace[158444537] 'process raft request' (duration: 318.045592ms)","trace[158444537] 'compare' (duration: 24.078882ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:14:44.362897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.173484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"warn","ts":"2026-04-06T17:14:44.362896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.566229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-06T17:14:44.362906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:14:44.020293Z","time spent":"342.526673ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:14:44.362896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.825754ms","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-04-06T17:14:44.362938Z","caller":"traceutil/trace.go:171","msg":"trace[1407970773] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5516; }","duration":"236.253616ms","start":"2026-04-06T17:14:44.126673Z","end":"2026-04-06T17:14:44.362926Z","steps":["trace[1407970773] 'agreement among raft nodes before linearized reading' (duration: 236.056482ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:44.362938Z","caller":"traceutil/trace.go:171","msg":"trace[1120463400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5516; }","duration":"283.658341ms","start":"2026-04-06T17:14:44.079271Z","end":"2026-04-06T17:14:44.362929Z","steps":["trace[1120463400] 'agreement among raft nodes before linearized reading' (duration: 283.567108ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:44.362949Z","caller":"traceutil/trace.go:171","msg":"trace[1950906302] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5516; }","duration":"289.922047ms","start":"2026-04-06T17:14:44.073018Z","end":"2026-04-06T17:14:44.36294Z","steps":["trace[1950906302] 'agreement among raft nodes before linearized reading' (duration: 289.780993ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:44.362754Z","caller":"traceutil/trace.go:171","msg":"trace[317272419] linearizableReadLoop","detail":"{readStateIndex:5712; appliedIndex:5711; }","duration":"147.066348ms","start":"2026-04-06T17:14:44.215656Z","end":"2026-04-06T17:14:44.362722Z","steps":["trace[317272419] 'read index received' (duration: 122.69505ms)","trace[317272419] 'applied index is now lower than readState.Index' (duration: 24.367918ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:14:44.363057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.607027ms","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":"warn","ts":"2026-04-06T17:14:44.363163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.354981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18a3d3e76d7e29cc\" ","response":"range_response_count:1 size:740"} {"level":"warn","ts":"2026-04-06T17:14:44.36317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.387869ms","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-04-06T17:14:44.363198Z","caller":"traceutil/trace.go:171","msg":"trace[186608366] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18a3d3e76d7e29cc; range_end:; response_count:1; response_revision:5516; }","duration":"143.407203ms","start":"2026-04-06T17:14:44.219781Z","end":"2026-04-06T17:14:44.363189Z","steps":["trace[186608366] 'agreement among raft nodes before linearized reading' (duration: 143.320001ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:44.363207Z","caller":"traceutil/trace.go:171","msg":"trace[549440482] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5516; }","duration":"142.466521ms","start":"2026-04-06T17:14:44.220734Z","end":"2026-04-06T17:14:44.363201Z","steps":["trace[549440482] 'agreement among raft nodes before linearized reading' (duration: 142.383129ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:44.363175Z","caller":"traceutil/trace.go:171","msg":"trace[390324546] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:5516; }","duration":"255.855333ms","start":"2026-04-06T17:14:44.107299Z","end":"2026-04-06T17:14:44.363154Z","steps":["trace[390324546] 'agreement among raft nodes before linearized reading' (duration: 255.475574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:44.363085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.438746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-06T17:14:44.363677Z","caller":"traceutil/trace.go:171","msg":"trace[1642372327] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5516; }","duration":"160.084111ms","start":"2026-04-06T17:14:44.203566Z","end":"2026-04-06T17:14:44.36365Z","steps":["trace[1642372327] 'agreement among raft nodes before linearized reading' (duration: 159.279753ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:44.533609Z","caller":"traceutil/trace.go:171","msg":"trace[1376344371] transaction","detail":"{read_only:false; response_revision:5518; number_of_response:1; }","duration":"166.963951ms","start":"2026-04-06T17:14:44.366604Z","end":"2026-04-06T17:14:44.533568Z","steps":["trace[1376344371] 'process raft request' (duration: 112.523362ms)","trace[1376344371] 'compare' (duration: 54.076401ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:14:44.533939Z","caller":"traceutil/trace.go:171","msg":"trace[2099328602] linearizableReadLoop","detail":"{readStateIndex:5716; appliedIndex:5714; }","duration":"167.161166ms","start":"2026-04-06T17:14:44.366754Z","end":"2026-04-06T17:14:44.533915Z","steps":["trace[2099328602] 'read index received' (duration: 20.262923ms)","trace[2099328602] 'applied index is now lower than readState.Index' (duration: 146.896803ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:14:44.534009Z","caller":"traceutil/trace.go:171","msg":"trace[410997805] transaction","detail":"{read_only:false; response_revision:5520; number_of_response:1; }","duration":"161.572696ms","start":"2026-04-06T17:14:44.372404Z","end":"2026-04-06T17:14:44.533976Z","steps":["trace[410997805] 'process raft request' (duration: 161.388001ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:44.53394Z","caller":"traceutil/trace.go:171","msg":"trace[36037638] transaction","detail":"{read_only:false; response_revision:5519; number_of_response:1; }","duration":"167.294269ms","start":"2026-04-06T17:14:44.366611Z","end":"2026-04-06T17:14:44.533906Z","steps":["trace[36037638] 'process raft request' (duration: 166.869639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:14:44.534193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.425722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-06T17:14:44.534275Z","caller":"traceutil/trace.go:171","msg":"trace[2039497260] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5520; }","duration":"167.538815ms","start":"2026-04-06T17:14:44.36671Z","end":"2026-04-06T17:14:44.534249Z","steps":["trace[2039497260] 'agreement among raft nodes before linearized reading' (duration: 167.31148ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:14:45.340199Z","caller":"traceutil/trace.go:171","msg":"trace[768483713] transaction","detail":"{read_only:false; response_revision:5528; number_of_response:1; }","duration":"113.493305ms","start":"2026-04-06T17:14:45.22668Z","end":"2026-04-06T17:14:45.340174Z","steps":["trace[768483713] 'process raft request' (duration: 113.29332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:15:30.675935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.654108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:15:30.676208Z","caller":"traceutil/trace.go:171","msg":"trace[170671562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5910; }","duration":"115.973396ms","start":"2026-04-06T17:15:30.560211Z","end":"2026-04-06T17:15:30.676184Z","steps":["trace[170671562] 'range keys from in-memory index tree' (duration: 115.575737ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:15:58.162486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3006} {"level":"info","ts":"2026-04-06T17:15:58.246638Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3006,"took":"82.648704ms","hash":4126240469,"current-db-size-bytes":58806272,"current-db-size":"59 MB","current-db-size-in-use-bytes":31793152,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-06T17:15:58.246715Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4126240469,"revision":3006,"compact-revision":-1} {"level":"info","ts":"2026-04-06T17:20:40.394135Z","caller":"traceutil/trace.go:171","msg":"trace[1561022851] linearizableReadLoop","detail":"{readStateIndex:8493; appliedIndex:8492; }","duration":"169.50859ms","start":"2026-04-06T17:20:40.2246Z","end":"2026-04-06T17:20:40.394108Z","steps":["trace[1561022851] 'read index received' (duration: 169.317085ms)","trace[1561022851] 'applied index is now lower than readState.Index' (duration: 190.595µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:20:40.394203Z","caller":"traceutil/trace.go:171","msg":"trace[2127161042] transaction","detail":"{read_only:false; response_revision:8211; number_of_response:1; }","duration":"241.318672ms","start":"2026-04-06T17:20:40.152819Z","end":"2026-04-06T17:20:40.394138Z","steps":["trace[2127161042] 'process raft request' (duration: 241.131137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:40.394334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.5407ms","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-04-06T17:20:40.394366Z","caller":"traceutil/trace.go:171","msg":"trace[126604138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8211; }","duration":"159.620192ms","start":"2026-04-06T17:20:40.234736Z","end":"2026-04-06T17:20:40.394356Z","steps":["trace[126604138] 'agreement among raft nodes before linearized reading' (duration: 159.492189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:40.394381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.766077ms","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-04-06T17:20:40.394462Z","caller":"traceutil/trace.go:171","msg":"trace[302054545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8211; }","duration":"169.882639ms","start":"2026-04-06T17:20:40.22456Z","end":"2026-04-06T17:20:40.394443Z","steps":["trace[302054545] 'agreement among raft nodes before linearized reading' (duration: 169.652153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:50.651393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.615627ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:20:50.651572Z","caller":"traceutil/trace.go:171","msg":"trace[2002726120] transaction","detail":"{read_only:false; response_revision:8268; number_of_response:1; }","duration":"205.39476ms","start":"2026-04-06T17:20:50.446154Z","end":"2026-04-06T17:20:50.651549Z","steps":["trace[2002726120] 'process raft request' (duration: 34.529929ms)","trace[2002726120] 'compare' (duration: 170.483153ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:20:50.655479Z","caller":"traceutil/trace.go:171","msg":"trace[1571440138] transaction","detail":"{read_only:false; response_revision:8269; number_of_response:1; }","duration":"116.250749ms","start":"2026-04-06T17:20:50.539183Z","end":"2026-04-06T17:20:50.655433Z","steps":["trace[1571440138] 'process raft request' (duration: 116.107446ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:20:50.660558Z","caller":"traceutil/trace.go:171","msg":"trace[1991545348] linearizableReadLoop","detail":"{readStateIndex:8555; appliedIndex:8553; }","duration":"100.063144ms","start":"2026-04-06T17:20:50.560472Z","end":"2026-04-06T17:20:50.660535Z","steps":["trace[1991545348] 'read index received' (duration: 94.834873ms)","trace[1991545348] 'applied index is now lower than readState.Index' (duration: 5.226931ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:20:50.660591Z","caller":"traceutil/trace.go:171","msg":"trace[953705862] transaction","detail":"{read_only:false; response_revision:8270; number_of_response:1; }","duration":"120.331103ms","start":"2026-04-06T17:20:50.540242Z","end":"2026-04-06T17:20:50.660573Z","steps":["trace[953705862] 'process raft request' (duration: 120.153399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:50.6607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.203417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:20:50.66079Z","caller":"traceutil/trace.go:171","msg":"trace[1220328723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8270; }","duration":"100.327531ms","start":"2026-04-06T17:20:50.560438Z","end":"2026-04-06T17:20:50.660766Z","steps":["trace[1220328723] 'agreement among raft nodes before linearized reading' (duration: 100.190977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:51.427609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.274955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-06T17:20:51.427718Z","caller":"traceutil/trace.go:171","msg":"trace[2023392805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8272; }","duration":"105.435199ms","start":"2026-04-06T17:20:51.322261Z","end":"2026-04-06T17:20:51.427697Z","steps":["trace[2023392805] 'range keys from in-memory index tree' (duration: 105.08961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:51.930802Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675878673868535,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-06T17:20:51.995161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:20:51.42869Z","time spent":"566.467061ms","remote":"127.0.0.1:45188","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-06T17:20:52.322382Z","caller":"traceutil/trace.go:171","msg":"trace[922394773] transaction","detail":"{read_only:false; response_revision:8273; number_of_response:1; }","duration":"890.114205ms","start":"2026-04-06T17:20:51.432242Z","end":"2026-04-06T17:20:52.322357Z","steps":["trace[922394773] 'process raft request' (duration: 889.91096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:52.322537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:20:51.43223Z","time spent":"890.226927ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T17:20:52.322558Z","caller":"traceutil/trace.go:171","msg":"trace[403034007] linearizableReadLoop","detail":"{readStateIndex:8560; appliedIndex:8557; }","duration":"891.772044ms","start":"2026-04-06T17:20:51.430756Z","end":"2026-04-06T17:20:52.322528Z","steps":["trace[403034007] 'read index received' (duration: 564.364903ms)","trace[403034007] 'applied index is now lower than readState.Index' (duration: 327.405481ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:20:52.322614Z","caller":"traceutil/trace.go:171","msg":"trace[978049395] transaction","detail":"{read_only:false; response_revision:8274; number_of_response:1; }","duration":"663.658527ms","start":"2026-04-06T17:20:51.658925Z","end":"2026-04-06T17:20:52.322584Z","steps":["trace[978049395] 'process raft request' (duration: 663.379141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:52.322763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:20:51.658893Z","time spent":"663.793981ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:20:52.322792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.01683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-04-06T17:20:52.322828Z","caller":"traceutil/trace.go:171","msg":"trace[365486672] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8274; }","duration":"892.087451ms","start":"2026-04-06T17:20:51.430729Z","end":"2026-04-06T17:20:52.322816Z","steps":["trace[365486672] 'agreement among raft nodes before linearized reading' (duration: 891.926427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:52.322867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:20:51.430713Z","time spent":"892.146943ms","remote":"127.0.0.1:45356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-06T17:20:52.496326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.302302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T17:20:52.496496Z","caller":"traceutil/trace.go:171","msg":"trace[755769301] linearizableReadLoop","detail":"{readStateIndex:8561; appliedIndex:8560; }","duration":"173.843901ms","start":"2026-04-06T17:20:52.322628Z","end":"2026-04-06T17:20:52.496472Z","steps":["trace[755769301] 'read index received' (duration: 69.000636ms)","trace[755769301] 'applied index is now lower than readState.Index' (duration: 104.839915ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:20:52.49654Z","caller":"traceutil/trace.go:171","msg":"trace[424063356] transaction","detail":"{read_only:false; response_revision:8275; number_of_response:1; }","duration":"500.280329ms","start":"2026-04-06T17:20:51.99621Z","end":"2026-04-06T17:20:52.49649Z","steps":["trace[424063356] 'process raft request' (duration: 395.530527ms)","trace[424063356] 'compare' (duration: 104.092777ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:20:52.496675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.839521ms","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":"warn","ts":"2026-04-06T17:20:52.496715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:20:51.996189Z","time spent":"500.449433ms","remote":"127.0.0.1:45188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T17:20:52.496714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.226657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-06T17:20:52.496803Z","caller":"traceutil/trace.go:171","msg":"trace[1167106240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8275; }","duration":"944.36805ms","start":"2026-04-06T17:20:51.552412Z","end":"2026-04-06T17:20:52.49678Z","steps":["trace[1167106240] 'agreement among raft nodes before linearized reading' (duration: 944.073873ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:20:52.496746Z","caller":"traceutil/trace.go:171","msg":"trace[1070028023] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8275; }","duration":"653.962694ms","start":"2026-04-06T17:20:51.842765Z","end":"2026-04-06T17:20:52.496727Z","steps":["trace[1070028023] 'agreement among raft nodes before linearized reading' (duration: 653.715688ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:52.496858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:20:51.552384Z","time spent":"944.463892ms","remote":"127.0.0.1:45448","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-04-06T17:20:52.49686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.906275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19407"} {"level":"warn","ts":"2026-04-06T17:20:52.49691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:20:51.842744Z","time spent":"654.146597ms","remote":"127.0.0.1:45448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-06T17:20:52.496919Z","caller":"traceutil/trace.go:171","msg":"trace[356017280] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8275; }","duration":"171.985548ms","start":"2026-04-06T17:20:52.324917Z","end":"2026-04-06T17:20:52.496902Z","steps":["trace[356017280] 'agreement among raft nodes before linearized reading' (duration: 171.772123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:52.496693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062918401s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-d4g96\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-06T17:20:52.497044Z","caller":"traceutil/trace.go:171","msg":"trace[729779677] range","detail":"{range_begin:/registry/pods/openstack/keepalived-d4g96; range_end:; response_count:1; response_revision:8275; }","duration":"1.063307731s","start":"2026-04-06T17:20:51.43372Z","end":"2026-04-06T17:20:52.497028Z","steps":["trace[729779677] 'agreement among raft nodes before linearized reading' (duration: 1.06283638s)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:52.497103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T17:20:51.433698Z","time spent":"1.063389213s","remote":"127.0.0.1:45356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-d4g96\" "} {"level":"info","ts":"2026-04-06T17:20:52.820206Z","caller":"traceutil/trace.go:171","msg":"trace[1787591572] transaction","detail":"{read_only:false; response_revision:8280; number_of_response:1; }","duration":"155.986187ms","start":"2026-04-06T17:20:52.664196Z","end":"2026-04-06T17:20:52.820182Z","steps":["trace[1787591572] 'process raft request' (duration: 155.871225ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:20:54.66137Z","caller":"traceutil/trace.go:171","msg":"trace[833717077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8284; }","duration":"100.018383ms","start":"2026-04-06T17:20:54.561324Z","end":"2026-04-06T17:20:54.661342Z","steps":["trace[833717077] 'range keys from in-memory index tree' (duration: 99.796928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:54.661491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.540555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T17:20:54.661564Z","caller":"traceutil/trace.go:171","msg":"trace[158561626] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8284; }","duration":"100.621067ms","start":"2026-04-06T17:20:54.560925Z","end":"2026-04-06T17:20:54.661546Z","steps":["trace[158561626] 'range keys from in-memory index tree' (duration: 100.446523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:20:54.661966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.452859ms","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-04-06T17:20:54.662029Z","caller":"traceutil/trace.go:171","msg":"trace[2087636095] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8284; }","duration":"134.572902ms","start":"2026-04-06T17:20:54.527436Z","end":"2026-04-06T17:20:54.662009Z","steps":["trace[2087636095] 'range keys from in-memory index tree' (duration: 133.724862ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:20:54.907345Z","caller":"traceutil/trace.go:171","msg":"trace[72798128] transaction","detail":"{read_only:false; response_revision:8286; number_of_response:1; }","duration":"241.323201ms","start":"2026-04-06T17:20:54.665996Z","end":"2026-04-06T17:20:54.907319Z","steps":["trace[72798128] 'process raft request' (duration: 194.14866ms)","trace[72798128] 'compare' (duration: 46.953106ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:20:54.907525Z","caller":"traceutil/trace.go:171","msg":"trace[167712994] transaction","detail":"{read_only:false; response_revision:8287; number_of_response:1; }","duration":"241.374583ms","start":"2026-04-06T17:20:54.666122Z","end":"2026-04-06T17:20:54.907497Z","steps":["trace[167712994] 'process raft request' (duration: 241.121117ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:20:54.91441Z","caller":"traceutil/trace.go:171","msg":"trace[821943546] transaction","detail":"{read_only:false; response_revision:8288; number_of_response:1; }","duration":"161.456644ms","start":"2026-04-06T17:20:54.752929Z","end":"2026-04-06T17:20:54.914385Z","steps":["trace[821943546] 'process raft request' (duration: 161.265649ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:20:58.169657Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6133} {"level":"info","ts":"2026-04-06T17:20:58.264474Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6133,"took":"92.93245ms","hash":3021638442,"current-db-size-bytes":58806272,"current-db-size":"59 MB","current-db-size-in-use-bytes":23793664,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-06T17:20:58.264543Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3021638442,"revision":6133,"compact-revision":3006} {"level":"warn","ts":"2026-04-06T17:21:19.705009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.523113ms","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-04-06T17:21:19.705121Z","caller":"traceutil/trace.go:171","msg":"trace[1050905173] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8473; }","duration":"205.648375ms","start":"2026-04-06T17:21:19.499448Z","end":"2026-04-06T17:21:19.705097Z","steps":["trace[1050905173] 'range keys from in-memory index tree' (duration: 205.296088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:21:19.705245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.412868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/rook-ceph/rook-ceph-csi-config\" ","response":"range_response_count:1 size:512"} {"level":"info","ts":"2026-04-06T17:21:19.705334Z","caller":"traceutil/trace.go:171","msg":"trace[979064938] range","detail":"{range_begin:/registry/configmaps/rook-ceph/rook-ceph-csi-config; range_end:; response_count:1; response_revision:8473; }","duration":"185.585972ms","start":"2026-04-06T17:21:19.519728Z","end":"2026-04-06T17:21:19.705314Z","steps":["trace[979064938] 'range keys from in-memory index tree' (duration: 185.231793ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:21:19.865254Z","caller":"traceutil/trace.go:171","msg":"trace[1790877289] linearizableReadLoop","detail":"{readStateIndex:8768; appliedIndex:8767; }","duration":"146.801495ms","start":"2026-04-06T17:21:19.718435Z","end":"2026-04-06T17:21:19.865237Z","steps":["trace[1790877289] 'read index received' (duration: 146.656402ms)","trace[1790877289] 'applied index is now lower than readState.Index' (duration: 144.433µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:21:19.865312Z","caller":"traceutil/trace.go:171","msg":"trace[1354700531] transaction","detail":"{read_only:false; response_revision:8474; number_of_response:1; }","duration":"155.475526ms","start":"2026-04-06T17:21:19.709806Z","end":"2026-04-06T17:21:19.865281Z","steps":["trace[1354700531] 'process raft request' (duration: 155.324073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:21:19.865488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.040631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19383"} {"level":"info","ts":"2026-04-06T17:21:19.865536Z","caller":"traceutil/trace.go:171","msg":"trace[1492490083] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:8474; }","duration":"147.084672ms","start":"2026-04-06T17:21:19.718426Z","end":"2026-04-06T17:21:19.865511Z","steps":["trace[1492490083] 'agreement among raft nodes before linearized reading' (duration: 146.881957ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:21:21.208517Z","caller":"traceutil/trace.go:171","msg":"trace[1329704911] linearizableReadLoop","detail":"{readStateIndex:8777; appliedIndex:8776; }","duration":"166.931351ms","start":"2026-04-06T17:21:21.041565Z","end":"2026-04-06T17:21:21.208497Z","steps":["trace[1329704911] 'read index received' (duration: 158.612558ms)","trace[1329704911] 'applied index is now lower than readState.Index' (duration: 8.315223ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:21:21.208532Z","caller":"traceutil/trace.go:171","msg":"trace[974254116] transaction","detail":"{read_only:false; response_revision:8483; number_of_response:1; }","duration":"177.416394ms","start":"2026-04-06T17:21:21.031092Z","end":"2026-04-06T17:21:21.208508Z","steps":["trace[974254116] 'process raft request' (duration: 169.165213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:21:21.208693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.989335ms","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-04-06T17:21:21.208717Z","caller":"traceutil/trace.go:171","msg":"trace[1722921179] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8483; }","duration":"155.052737ms","start":"2026-04-06T17:21:21.053657Z","end":"2026-04-06T17:21:21.20871Z","steps":["trace[1722921179] 'agreement among raft nodes before linearized reading' (duration: 154.936874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:21:21.208715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.124085ms","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-04-06T17:21:21.208785Z","caller":"traceutil/trace.go:171","msg":"trace[1827942047] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8483; }","duration":"167.215538ms","start":"2026-04-06T17:21:21.041547Z","end":"2026-04-06T17:21:21.208763Z","steps":["trace[1827942047] 'agreement among raft nodes before linearized reading' (duration: 167.014573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:21:21.209318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.223301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-7ffff65c59-62r4n.18a3d43bb918b4b2\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-04-06T17:21:21.209459Z","caller":"traceutil/trace.go:171","msg":"trace[1019583429] range","detail":"{range_begin:/registry/events/openstack/barbican-api-7ffff65c59-62r4n.18a3d43bb918b4b2; range_end:; response_count:1; response_revision:8483; }","duration":"120.397415ms","start":"2026-04-06T17:21:21.089033Z","end":"2026-04-06T17:21:21.209431Z","steps":["trace[1019583429] 'agreement among raft nodes before linearized reading' (duration: 119.584996ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:21:22.603811Z","caller":"traceutil/trace.go:171","msg":"trace[105639830] transaction","detail":"{read_only:false; response_revision:8489; number_of_response:1; }","duration":"169.777517ms","start":"2026-04-06T17:21:22.434009Z","end":"2026-04-06T17:21:22.603787Z","steps":["trace[105639830] 'process raft request' (duration: 169.378828ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:21:24.996755Z","caller":"traceutil/trace.go:171","msg":"trace[581866869] transaction","detail":"{read_only:false; response_revision:8500; number_of_response:1; }","duration":"132.73372ms","start":"2026-04-06T17:21:24.864003Z","end":"2026-04-06T17:21:24.996737Z","steps":["trace[581866869] 'process raft request' (duration: 132.626447ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:21:25.137916Z","caller":"traceutil/trace.go:171","msg":"trace[1555523041] transaction","detail":"{read_only:false; response_revision:8502; number_of_response:1; }","duration":"108.956429ms","start":"2026-04-06T17:21:25.028936Z","end":"2026-04-06T17:21:25.137892Z","steps":["trace[1555523041] 'process raft request' (duration: 108.902698ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:21:25.137926Z","caller":"traceutil/trace.go:171","msg":"trace[20022840] transaction","detail":"{read_only:false; response_revision:8501; number_of_response:1; }","duration":"131.48911ms","start":"2026-04-06T17:21:25.006405Z","end":"2026-04-06T17:21:25.137894Z","steps":["trace[20022840] 'process raft request' (duration: 67.704075ms)","trace[20022840] 'compare' (duration: 63.604341ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:21:25.456307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.105381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-06T17:21:25.456344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.298974ms","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-04-06T17:21:25.456374Z","caller":"traceutil/trace.go:171","msg":"trace[1637598115] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:8502; }","duration":"172.204213ms","start":"2026-04-06T17:21:25.284153Z","end":"2026-04-06T17:21:25.456357Z","steps":["trace[1637598115] 'count revisions from in-memory index tree' (duration: 172.044ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:21:25.45642Z","caller":"traceutil/trace.go:171","msg":"trace[1400323024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8502; }","duration":"213.424756ms","start":"2026-04-06T17:21:25.242982Z","end":"2026-04-06T17:21:25.456407Z","steps":["trace[1400323024] 'range keys from in-memory index tree' (duration: 213.14829ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:21:25.456422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.03276ms","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-04-06T17:21:25.456494Z","caller":"traceutil/trace.go:171","msg":"trace[1969648248] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8502; }","duration":"153.131751ms","start":"2026-04-06T17:21:25.303342Z","end":"2026-04-06T17:21:25.456474Z","steps":["trace[1969648248] 'range keys from in-memory index tree' (duration: 152.810584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:21:25.457018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.288888ms","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-04-06T17:21:25.457093Z","caller":"traceutil/trace.go:171","msg":"trace[726933524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8502; }","duration":"154.414201ms","start":"2026-04-06T17:21:25.302662Z","end":"2026-04-06T17:21:25.457076Z","steps":["trace[726933524] 'range keys from in-memory index tree' (duration: 153.474719ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:21:35.723996Z","caller":"traceutil/trace.go:171","msg":"trace[1073302662] transaction","detail":"{read_only:false; response_revision:8601; number_of_response:1; }","duration":"137.683854ms","start":"2026-04-06T17:21:35.586284Z","end":"2026-04-06T17:21:35.723968Z","steps":["trace[1073302662] 'process raft request' (duration: 109.335218ms)","trace[1073302662] 'compare' (duration: 28.173782ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:21:36.639352Z","caller":"traceutil/trace.go:171","msg":"trace[510281638] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"251.379304ms","start":"2026-04-06T17:21:36.387949Z","end":"2026-04-06T17:21:36.639328Z","steps":["trace[510281638] 'process raft request' (duration: 246.398129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T17:21:36.799508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.162929ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-06T17:21:36.799607Z","caller":"traceutil/trace.go:171","msg":"trace[1345800654] linearizableReadLoop","detail":"{readStateIndex:8908; appliedIndex:8907; }","duration":"140.226154ms","start":"2026-04-06T17:21:36.659364Z","end":"2026-04-06T17:21:36.79959Z","steps":["trace[1345800654] 'read index received' (duration: 16.563283ms)","trace[1345800654] 'applied index is now lower than readState.Index' (duration: 123.661211ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T17:21:36.79976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.393227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-06T17:21:36.799797Z","caller":"traceutil/trace.go:171","msg":"trace[1025485678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8608; }","duration":"140.467739ms","start":"2026-04-06T17:21:36.659317Z","end":"2026-04-06T17:21:36.799785Z","steps":["trace[1025485678] 'agreement among raft nodes before linearized reading' (duration: 140.318566ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T17:21:52.268662Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000236f.wal"} {"level":"info","ts":"2026-04-06T17:22:50.600681Z","caller":"traceutil/trace.go:171","msg":"trace[638883551] transaction","detail":"{read_only:false; response_revision:9336; number_of_response:1; }","duration":"126.118601ms","start":"2026-04-06T17:22:50.474535Z","end":"2026-04-06T17:22:50.600654Z","steps":["trace[638883551] 'process raft request' (duration: 96.95871ms)","trace[638883551] 'compare' (duration: 28.928575ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T17:23:36.790565Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"791a7e25b533cd26","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-06T17:23:36.797Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-06T17:23:36.797073Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-06T17:25:58.177762Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8309} {"level":"info","ts":"2026-04-06T17:25:58.262943Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8309,"took":"82.929166ms","hash":965879706,"current-db-size-bytes":58806272,"current-db-size":"59 MB","current-db-size-in-use-bytes":24834048,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-06T17:25:58.263015Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":965879706,"revision":8309,"compact-revision":6133} {"level":"info","ts":"2026-04-06T17:30:58.183167Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10696} {"level":"info","ts":"2026-04-06T17:30:58.264459Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10696,"took":"79.616243ms","hash":157729949,"current-db-size-bytes":58806272,"current-db-size":"59 MB","current-db-size-in-use-bytes":20484096,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-06T17:30:58.264526Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":157729949,"revision":10696,"compact-revision":8309}