{"level":"warn","ts":"2026-10-09T05:11:03.77668Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T05:11:03.777123Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.149:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.149:2380","--initial-cluster=instance=https://199.204.45.149:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.149:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.149: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-10-09T05:11:03.777249Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-09T05:11:03.777293Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.149:2380"]} {"level":"info","ts":"2026-10-09T05:11:03.777318Z","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-10-09T05:11:03.779069Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"]} {"level":"info","ts":"2026-10-09T05:11:03.779247Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.149:2380"],"listen-peer-urls":["https://199.204.45.149:2380"],"advertise-client-urls":["https://199.204.45.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.149: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-10-09T05:11:03.785963Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.848822ms"} {"level":"info","ts":"2026-10-09T05:11:03.793382Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b1b675f124322401","cluster-id":"913dfbf24a38e0aa"} {"level":"info","ts":"2026-10-09T05:11:03.793457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=()"} {"level":"info","ts":"2026-10-09T05:11:03.793476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became follower at term 0"} {"level":"info","ts":"2026-10-09T05:11:03.793483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b1b675f124322401 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-09T05:11:03.793491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became follower at term 1"} {"level":"info","ts":"2026-10-09T05:11:03.793519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=(12805552269029417985)"} {"level":"warn","ts":"2026-10-09T05:11:03.801983Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-09T05:11:03.803419Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-09T05:11:03.804508Z","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-10-09T05:11:03.806897Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b1b675f124322401","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-09T05:11:03.80705Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b1b675f124322401","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-09T05:11:03.808101Z","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-10-09T05:11:03.808225Z","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-10-09T05:11:03.80827Z","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-10-09T05:11:03.809015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 switched to configuration voters=(12805552269029417985)"} {"level":"info","ts":"2026-10-09T05:11:03.8091Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"913dfbf24a38e0aa","local-member-id":"b1b675f124322401","added-peer-id":"b1b675f124322401","added-peer-peer-urls":["https://199.204.45.149:2380"]} {"level":"info","ts":"2026-10-09T05:11:03.810119Z","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-10-09T05:11:03.810162Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.149:2380"} {"level":"info","ts":"2026-10-09T05:11:03.810182Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.149:2380"} {"level":"info","ts":"2026-10-09T05:11:03.81036Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b1b675f124322401","initial-advertise-peer-urls":["https://199.204.45.149:2380"],"listen-peer-urls":["https://199.204.45.149:2380"],"advertise-client-urls":["https://199.204.45.149:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.149:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-09T05:11:03.810405Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-09T05:11:03.994762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 is starting a new election at term 1"} {"level":"info","ts":"2026-10-09T05:11:03.994817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-09T05:11:03.994855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 received MsgPreVoteResp from b1b675f124322401 at term 1"} {"level":"info","ts":"2026-10-09T05:11:03.995079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became candidate at term 2"} {"level":"info","ts":"2026-10-09T05:11:03.995104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 received MsgVoteResp from b1b675f124322401 at term 2"} {"level":"info","ts":"2026-10-09T05:11:03.995195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b1b675f124322401 became leader at term 2"} {"level":"info","ts":"2026-10-09T05:11:03.995218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b1b675f124322401 elected leader b1b675f124322401 at term 2"} {"level":"info","ts":"2026-10-09T05:11:03.996199Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b1b675f124322401","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.149:2379]}","request-path":"/0/members/b1b675f124322401/attributes","cluster-id":"913dfbf24a38e0aa","publish-timeout":"7s"} {"level":"info","ts":"2026-10-09T05:11:03.997185Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:11:03.99735Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T05:11:03.997813Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-09T05:11:03.9979Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-09T05:11:03.997945Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-09T05:11:03.999567Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"913dfbf24a38e0aa","local-member-id":"b1b675f124322401","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:11:04.003647Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:11:04.007364Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-09T05:11:04.012705Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.149:2379"} {"level":"info","ts":"2026-10-09T05:11:04.014397Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-10-09T05:11:07.320174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.488523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-09T05:11:07.320298Z","caller":"traceutil/trace.go:171","msg":"trace[150549044] transaction","detail":"{read_only:false; response_revision:131; number_of_response:1; }","duration":"379.025516ms","start":"2026-10-09T05:11:06.941255Z","end":"2026-10-09T05:11:07.32028Z","steps":["trace[150549044] 'process raft request' (duration: 107.916637ms)","trace[150549044] 'compare' (duration: 270.363ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:11:07.320508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:11:06.941229Z","time spent":"379.246761ms","remote":"127.0.0.1:51718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1127,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T05:11:07.640976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.513163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-09T05:11:07.641097Z","caller":"traceutil/trace.go:171","msg":"trace[947699554] transaction","detail":"{read_only:false; response_revision:132; number_of_response:1; }","duration":"314.846187ms","start":"2026-10-09T05:11:07.326229Z","end":"2026-10-09T05:11:07.641075Z","steps":["trace[947699554] 'process raft request' (duration: 158.159779ms)","trace[947699554] 'compare' (duration: 156.37682ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:11:07.641148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:11:07.326215Z","time spent":"314.913709ms","remote":"127.0.0.1:51718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":713,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-09T05:11:55.089489Z","caller":"traceutil/trace.go:171","msg":"trace[2086145345] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"109.300618ms","start":"2026-10-09T05:11:54.980144Z","end":"2026-10-09T05:11:55.089445Z","steps":["trace[2086145345] 'process raft request' (duration: 109.127845ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:11:55.547554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.079577ms","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-10-09T05:11:55.547652Z","caller":"traceutil/trace.go:171","msg":"trace[434880827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"208.23196ms","start":"2026-10-09T05:11:55.339397Z","end":"2026-10-09T05:11:55.547629Z","steps":["trace[434880827] 'range keys from in-memory index tree' (duration: 207.813421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:11:56.293989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.35036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-09T05:11:56.294351Z","caller":"traceutil/trace.go:171","msg":"trace[1415360757] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"187.060119ms","start":"2026-10-09T05:11:56.107272Z","end":"2026-10-09T05:11:56.294332Z","steps":["trace[1415360757] 'read index received' (duration: 98.722µs)","trace[1415360757] 'applied index is now lower than readState.Index' (duration: 186.959577ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:11:56.294387Z","caller":"traceutil/trace.go:171","msg":"trace[1679472115] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"660.051796ms","start":"2026-10-09T05:11:55.634286Z","end":"2026-10-09T05:11:56.294338Z","steps":["trace[1679472115] 'process raft request' (duration: 376.786006ms)","trace[1679472115] 'compare' (duration: 282.201677ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:11:56.294635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:11:55.634242Z","time spent":"660.27618ms","remote":"127.0.0.1:51876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T05:11:56.29484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.370124ms","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-10-09T05:11:56.295322Z","caller":"traceutil/trace.go:171","msg":"trace[1262102374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:651; }","duration":"154.927635ms","start":"2026-10-09T05:11:56.140379Z","end":"2026-10-09T05:11:56.295307Z","steps":["trace[1262102374] 'agreement among raft nodes before linearized reading' (duration: 154.18138ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:11:56.294835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.539589ms","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-10-09T05:11:56.295619Z","caller":"traceutil/trace.go:171","msg":"trace[684898398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"188.362006ms","start":"2026-10-09T05:11:56.107186Z","end":"2026-10-09T05:11:56.295548Z","steps":["trace[684898398] 'agreement among raft nodes before linearized reading' (duration: 187.286444ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:11:56.850064Z","caller":"traceutil/trace.go:171","msg":"trace[30633220] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"131.221512ms","start":"2026-10-09T05:11:56.71881Z","end":"2026-10-09T05:11:56.850031Z","steps":["trace[30633220] 'process raft request' (duration: 130.626661ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:11:56.851772Z","caller":"traceutil/trace.go:171","msg":"trace[2134964707] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"124.981656ms","start":"2026-10-09T05:11:56.726766Z","end":"2026-10-09T05:11:56.851747Z","steps":["trace[2134964707] 'process raft request' (duration: 124.872664ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:12:11.395812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.977385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-gmbgx\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-10-09T05:12:11.395899Z","caller":"traceutil/trace.go:171","msg":"trace[915795601] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-gmbgx; range_end:; response_count:1; response_revision:813; }","duration":"225.081676ms","start":"2026-10-09T05:12:11.170797Z","end":"2026-10-09T05:12:11.395879Z","steps":["trace[915795601] 'range keys from in-memory index tree' (duration: 224.783052ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:12:11.395915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.807121ms","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-10-09T05:12:11.395998Z","caller":"traceutil/trace.go:171","msg":"trace[1776797388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:813; }","duration":"187.934703ms","start":"2026-10-09T05:12:11.208044Z","end":"2026-10-09T05:12:11.395979Z","steps":["trace[1776797388] 'range keys from in-memory index tree' (duration: 187.655399ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:11.448796Z","caller":"traceutil/trace.go:171","msg":"trace[1478525089] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"197.827345ms","start":"2026-10-09T05:12:11.25094Z","end":"2026-10-09T05:12:11.448768Z","steps":["trace[1478525089] 'process raft request' (duration: 197.660762ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:42.897884Z","caller":"traceutil/trace.go:171","msg":"trace[830417128] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"253.804711ms","start":"2026-10-09T05:12:42.644044Z","end":"2026-10-09T05:12:42.897849Z","steps":["trace[830417128] 'process raft request' (duration: 253.74523ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:12:42.898334Z","caller":"traceutil/trace.go:171","msg":"trace[1335520304] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"255.003483ms","start":"2026-10-09T05:12:42.643286Z","end":"2026-10-09T05:12:42.898289Z","steps":["trace[1335520304] 'process raft request' (duration: 224.289037ms)","trace[1335520304] 'compare' (duration: 30.016224ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:13:32.3994Z","caller":"traceutil/trace.go:171","msg":"trace[508271464] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"352.419769ms","start":"2026-10-09T05:13:32.046952Z","end":"2026-10-09T05:13:32.399372Z","steps":["trace[508271464] 'process raft request' (duration: 337.822935ms)","trace[508271464] 'compare' (duration: 14.179907ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:13:32.399812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:13:32.046937Z","time spent":"352.573751ms","remote":"127.0.0.1:51624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T05:13:32.761168Z","caller":"traceutil/trace.go:171","msg":"trace[1807296783] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"177.11101ms","start":"2026-10-09T05:13:32.584035Z","end":"2026-10-09T05:13:32.761146Z","steps":["trace[1807296783] 'process raft request' (duration: 176.964067ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:13:32.785086Z","caller":"traceutil/trace.go:171","msg":"trace[1912930589] linearizableReadLoop","detail":"{readStateIndex:1979; appliedIndex:1977; }","duration":"186.216514ms","start":"2026-10-09T05:13:32.598835Z","end":"2026-10-09T05:13:32.785051Z","steps":["trace[1912930589] 'read index received' (duration: 162.1773ms)","trace[1912930589] 'applied index is now lower than readState.Index' (duration: 24.037994ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:13:32.785156Z","caller":"traceutil/trace.go:171","msg":"trace[438800163] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"196.409948ms","start":"2026-10-09T05:13:32.588706Z","end":"2026-10-09T05:13:32.785116Z","steps":["trace[438800163] 'process raft request' (duration: 196.253185ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:13:32.785266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.418467ms","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-10-09T05:13:32.785309Z","caller":"traceutil/trace.go:171","msg":"trace[1024463152] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1902; }","duration":"186.505259ms","start":"2026-10-09T05:13:32.598789Z","end":"2026-10-09T05:13:32.785294Z","steps":["trace[1024463152] 'agreement among raft nodes before linearized reading' (duration: 186.361536ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:14:07.124981Z","caller":"traceutil/trace.go:171","msg":"trace[2070183270] linearizableReadLoop","detail":"{readStateIndex:2229; appliedIndex:2228; }","duration":"116.713974ms","start":"2026-10-09T05:14:07.008243Z","end":"2026-10-09T05:14:07.124957Z","steps":["trace[2070183270] 'read index received' (duration: 116.543481ms)","trace[2070183270] 'applied index is now lower than readState.Index' (duration: 169.603µs)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:14:07.125195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.933987ms","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-10-09T05:14:07.125229Z","caller":"traceutil/trace.go:171","msg":"trace[1253123030] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2145; }","duration":"117.004558ms","start":"2026-10-09T05:14:07.008214Z","end":"2026-10-09T05:14:07.125218Z","steps":["trace[1253123030] 'agreement among raft nodes before linearized reading' (duration: 116.833895ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:14:07.125519Z","caller":"traceutil/trace.go:171","msg":"trace[1369660122] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"150.16639ms","start":"2026-10-09T05:14:06.975342Z","end":"2026-10-09T05:14:07.125509Z","steps":["trace[1369660122] 'process raft request' (duration: 149.49602ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:14:07.28642Z","caller":"traceutil/trace.go:171","msg":"trace[531726083] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"144.893968ms","start":"2026-10-09T05:14:07.141508Z","end":"2026-10-09T05:14:07.286402Z","steps":["trace[531726083] 'process raft request' (duration: 81.590681ms)","trace[531726083] 'compare' (duration: 63.230376ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:14:07.518629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.959986ms","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-10-09T05:14:07.518722Z","caller":"traceutil/trace.go:171","msg":"trace[1879444220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2147; }","duration":"117.123649ms","start":"2026-10-09T05:14:07.401577Z","end":"2026-10-09T05:14:07.518701Z","steps":["trace[1879444220] 'range keys from in-memory index tree' (duration: 116.756913ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:14:07.51881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.274472ms","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-10-09T05:14:07.518856Z","caller":"traceutil/trace.go:171","msg":"trace[924761981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2147; }","duration":"106.367082ms","start":"2026-10-09T05:14:07.412477Z","end":"2026-10-09T05:14:07.518845Z","steps":["trace[924761981] 'range keys from in-memory index tree' (duration: 106.143509ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:14:07.734273Z","caller":"traceutil/trace.go:171","msg":"trace[1008355351] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"211.372525ms","start":"2026-10-09T05:14:07.522866Z","end":"2026-10-09T05:14:07.734239Z","steps":["trace[1008355351] 'process raft request' (duration: 211.209502ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:14:07.735335Z","caller":"traceutil/trace.go:171","msg":"trace[1136245348] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"211.133121ms","start":"2026-10-09T05:14:07.524154Z","end":"2026-10-09T05:14:07.735287Z","steps":["trace[1136245348] 'process raft request' (duration: 210.910108ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:14:07.735526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.691749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:14:07.735218Z","caller":"traceutil/trace.go:171","msg":"trace[723306369] linearizableReadLoop","detail":"{readStateIndex:2233; appliedIndex:2231; }","duration":"198.410135ms","start":"2026-10-09T05:14:07.53679Z","end":"2026-10-09T05:14:07.7352Z","steps":["trace[723306369] 'read index received' (duration: 197.405549ms)","trace[723306369] 'applied index is now lower than readState.Index' (duration: 1.003566ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:14:07.735584Z","caller":"traceutil/trace.go:171","msg":"trace[1643232839] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:2150; }","duration":"198.81804ms","start":"2026-10-09T05:14:07.53675Z","end":"2026-10-09T05:14:07.735568Z","steps":["trace[1643232839] 'agreement among raft nodes before linearized reading' (duration: 198.660738ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:15:44.646644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.529905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-09T05:15:44.646805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.247655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-10-09T05:15:44.646917Z","caller":"traceutil/trace.go:171","msg":"trace[1490825274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2779; }","duration":"130.39237ms","start":"2026-10-09T05:15:44.5165Z","end":"2026-10-09T05:15:44.646892Z","steps":["trace[1490825274] 'range keys from in-memory index tree' (duration: 130.084771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:15:44.647083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.39029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:15:44.647206Z","caller":"traceutil/trace.go:171","msg":"trace[568792807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2779; }","duration":"140.581205ms","start":"2026-10-09T05:15:44.506593Z","end":"2026-10-09T05:15:44.647175Z","steps":["trace[568792807] 'range keys from in-memory index tree' (duration: 140.218874ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:15:44.646829Z","caller":"traceutil/trace.go:171","msg":"trace[490922127] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2779; }","duration":"139.801123ms","start":"2026-10-09T05:15:44.506999Z","end":"2026-10-09T05:15:44.6468Z","steps":["trace[490922127] 'count revisions from in-memory index tree' (duration: 139.393201ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:15:49.940012Z","caller":"traceutil/trace.go:171","msg":"trace[1375571375] transaction","detail":"{read_only:false; response_revision:2809; number_of_response:1; }","duration":"146.173512ms","start":"2026-10-09T05:15:49.793815Z","end":"2026-10-09T05:15:49.939989Z","steps":["trace[1375571375] 'process raft request' (duration: 146.022968ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:19:13.821856Z","caller":"traceutil/trace.go:171","msg":"trace[2056503276] transaction","detail":"{read_only:false; response_revision:3970; number_of_response:1; }","duration":"109.394795ms","start":"2026-10-09T05:19:13.712431Z","end":"2026-10-09T05:19:13.821826Z","steps":["trace[2056503276] 'process raft request' (duration: 109.238122ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:20:03.806654Z","caller":"traceutil/trace.go:171","msg":"trace[1698121099] transaction","detail":"{read_only:false; response_revision:4688; number_of_response:1; }","duration":"296.894031ms","start":"2026-10-09T05:20:03.509731Z","end":"2026-10-09T05:20:03.806625Z","steps":["trace[1698121099] 'process raft request' (duration: 275.192612ms)","trace[1698121099] 'compare' (duration: 21.570866ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:20:03.859861Z","caller":"traceutil/trace.go:171","msg":"trace[1558712621] transaction","detail":"{read_only:false; response_revision:4689; number_of_response:1; }","duration":"346.510256ms","start":"2026-10-09T05:20:03.513325Z","end":"2026-10-09T05:20:03.859835Z","steps":["trace[1558712621] 'process raft request' (duration: 346.267022ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:20:03.860054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:20:03.513311Z","time spent":"346.639279ms","remote":"127.0.0.1:51526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5146,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-09T05:20:03.860167Z","caller":"traceutil/trace.go:171","msg":"trace[386699780] linearizableReadLoop","detail":"{readStateIndex:4873; appliedIndex:4871; }","duration":"335.206732ms","start":"2026-10-09T05:20:03.524938Z","end":"2026-10-09T05:20:03.860145Z","steps":["trace[386699780] 'read index received' (duration: 260.007814ms)","trace[386699780] 'applied index is now lower than readState.Index' (duration: 75.197908ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:20:03.860266Z","caller":"traceutil/trace.go:171","msg":"trace[1977629204] transaction","detail":"{read_only:false; response_revision:4691; number_of_response:1; }","duration":"326.870405ms","start":"2026-10-09T05:20:03.533385Z","end":"2026-10-09T05:20:03.860256Z","steps":["trace[1977629204] 'process raft request' (duration: 326.627051ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:20:03.860307Z","caller":"traceutil/trace.go:171","msg":"trace[1342327597] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"334.53568ms","start":"2026-10-09T05:20:03.525761Z","end":"2026-10-09T05:20:03.860296Z","steps":["trace[1342327597] 'process raft request' (duration: 333.99238ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:20:03.860353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:20:03.525739Z","time spent":"334.589952ms","remote":"127.0.0.1:51826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11968,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T05:20:03.860356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:20:03.533364Z","time spent":"326.931916ms","remote":"127.0.0.1:51432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":253172,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T05:20:03.860651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.703162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:46623"} {"level":"info","ts":"2026-10-09T05:20:03.860689Z","caller":"traceutil/trace.go:171","msg":"trace[339166536] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4691; }","duration":"335.769394ms","start":"2026-10-09T05:20:03.52491Z","end":"2026-10-09T05:20:03.86068Z","steps":["trace[339166536] 'agreement among raft nodes before linearized reading' (duration: 335.550469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:20:03.860714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:20:03.524896Z","time spent":"335.811474ms","remote":"127.0.0.1:51526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":6,"response size":46647,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"warn","ts":"2026-10-09T05:20:03.867893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.847007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:20:03.867972Z","caller":"traceutil/trace.go:171","msg":"trace[2118308539] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4692; }","duration":"342.960008ms","start":"2026-10-09T05:20:03.524992Z","end":"2026-10-09T05:20:03.867952Z","steps":["trace[2118308539] 'agreement among raft nodes before linearized reading' (duration: 342.825896ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:20:03.86804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:20:03.524975Z","time spent":"343.035789ms","remote":"127.0.0.1:51418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" "} {"level":"warn","ts":"2026-10-09T05:20:03.868353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.765307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-09T05:20:03.868394Z","caller":"traceutil/trace.go:171","msg":"trace[2081007301] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4692; }","duration":"157.826577ms","start":"2026-10-09T05:20:03.710555Z","end":"2026-10-09T05:20:03.868382Z","steps":["trace[2081007301] 'agreement among raft nodes before linearized reading' (duration: 157.692515ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:20:03.868723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.68371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-10-09T05:20:03.868774Z","caller":"traceutil/trace.go:171","msg":"trace[2042403950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4692; }","duration":"161.773861ms","start":"2026-10-09T05:20:03.706991Z","end":"2026-10-09T05:20:03.868764Z","steps":["trace[2042403950] 'agreement among raft nodes before linearized reading' (duration: 161.633458ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:20:11.06095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.465464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T05:20:11.061028Z","caller":"traceutil/trace.go:171","msg":"trace[972057392] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:4872; }","duration":"128.592055ms","start":"2026-10-09T05:20:10.932419Z","end":"2026-10-09T05:20:11.061011Z","steps":["trace[972057392] 'count revisions from in-memory index tree' (duration: 128.379841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:20:11.061114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.605728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T05:20:11.061194Z","caller":"traceutil/trace.go:171","msg":"trace[1048524716] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4872; }","duration":"226.75087ms","start":"2026-10-09T05:20:10.834425Z","end":"2026-10-09T05:20:11.061176Z","steps":["trace[1048524716] 'count revisions from in-memory index tree' (duration: 226.444514ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:20:11.117141Z","caller":"traceutil/trace.go:171","msg":"trace[1381864949] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"110.130609ms","start":"2026-10-09T05:20:11.006982Z","end":"2026-10-09T05:20:11.117112Z","steps":["trace[1381864949] 'process raft request' (duration: 109.401295ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:20:14.672026Z","caller":"traceutil/trace.go:171","msg":"trace[148833184] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"108.969043ms","start":"2026-10-09T05:20:14.563031Z","end":"2026-10-09T05:20:14.672Z","steps":["trace[148833184] 'process raft request' (duration: 108.644727ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:20:17.1159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.922752ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-09T05:20:17.116137Z","caller":"traceutil/trace.go:171","msg":"trace[2018226345] linearizableReadLoop","detail":"{readStateIndex:5166; appliedIndex:5164; }","duration":"353.936028ms","start":"2026-10-09T05:20:16.76218Z","end":"2026-10-09T05:20:17.116116Z","steps":["trace[2018226345] 'read index received' (duration: 42.773561ms)","trace[2018226345] 'applied index is now lower than readState.Index' (duration: 311.161127ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:20:17.116341Z","caller":"traceutil/trace.go:171","msg":"trace[1214539068] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"408.51687ms","start":"2026-10-09T05:20:16.707805Z","end":"2026-10-09T05:20:17.116322Z","steps":["trace[1214539068] 'process raft request' (duration: 408.213784ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:20:17.116327Z","caller":"traceutil/trace.go:171","msg":"trace[354986700] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"412.454935ms","start":"2026-10-09T05:20:16.703838Z","end":"2026-10-09T05:20:17.116293Z","steps":["trace[354986700] 'process raft request' (duration: 101.051233ms)","trace[354986700] 'compare' (duration: 310.465114ms)"],"step_count":2} {"level":"warn","ts":"2026-10-09T05:20:17.11645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:20:16.707745Z","time spent":"408.635692ms","remote":"127.0.0.1:51396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":936,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-09T05:20:17.116506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:20:16.703821Z","time spent":"412.577597ms","remote":"127.0.0.1:51624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T05:20:17.117111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.058041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-09T05:20:17.11717Z","caller":"traceutil/trace.go:171","msg":"trace[342626602] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:4975; }","duration":"355.014629ms","start":"2026-10-09T05:20:16.762138Z","end":"2026-10-09T05:20:17.117153Z","steps":["trace[342626602] 'agreement among raft nodes before linearized reading' (duration: 354.048131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:20:17.117219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:20:16.762118Z","time spent":"355.0835ms","remote":"127.0.0.1:51876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":3,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-10-09T05:20:17.394489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.07844ms","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-10-09T05:20:17.394557Z","caller":"traceutil/trace.go:171","msg":"trace[429335288] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4975; }","duration":"157.192772ms","start":"2026-10-09T05:20:17.237344Z","end":"2026-10-09T05:20:17.394537Z","steps":["trace[429335288] 'range keys from in-memory index tree' (duration: 156.884117ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:20:28.915922Z","caller":"traceutil/trace.go:171","msg":"trace[138701219] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"160.458377ms","start":"2026-10-09T05:20:28.755435Z","end":"2026-10-09T05:20:28.915893Z","steps":["trace[138701219] 'process raft request' (duration: 160.311764ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:20:29.242967Z","caller":"traceutil/trace.go:171","msg":"trace[786673254] linearizableReadLoop","detail":"{readStateIndex:5325; appliedIndex:5323; }","duration":"406.329534ms","start":"2026-10-09T05:20:28.836607Z","end":"2026-10-09T05:20:29.242937Z","steps":["trace[786673254] 'read index received' (duration: 79.010031ms)","trace[786673254] 'applied index is now lower than readState.Index' (duration: 327.318203ms)"],"step_count":2} {"level":"info","ts":"2026-10-09T05:20:29.243235Z","caller":"traceutil/trace.go:171","msg":"trace[1391262656] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"478.387446ms","start":"2026-10-09T05:20:28.764823Z","end":"2026-10-09T05:20:29.24321Z","steps":["trace[1391262656] 'process raft request' (duration: 475.346449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:20:29.243373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:20:28.764802Z","time spent":"478.483357ms","remote":"127.0.0.1:51526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-09T05:20:29.243449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.819113ms","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-10-09T05:20:29.24357Z","caller":"traceutil/trace.go:171","msg":"trace[65945746] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5128; }","duration":"406.981766ms","start":"2026-10-09T05:20:28.836568Z","end":"2026-10-09T05:20:29.24355Z","steps":["trace[65945746] 'agreement among raft nodes before linearized reading' (duration: 406.683341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-09T05:20:29.243635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-09T05:20:28.836554Z","time spent":"407.071928ms","remote":"127.0.0.1:51492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-10-09T05:20:29.243694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.302608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-09T05:20:29.24378Z","caller":"traceutil/trace.go:171","msg":"trace[633640674] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:5128; }","duration":"128.42564ms","start":"2026-10-09T05:20:29.115336Z","end":"2026-10-09T05:20:29.243762Z","steps":["trace[633640674] 'agreement among raft nodes before linearized reading' (duration: 128.243517ms)"],"step_count":1} {"level":"info","ts":"2026-10-09T05:21:04.154743Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2883} {"level":"info","ts":"2026-10-09T05:21:04.22974Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2883,"took":"73.647121ms","hash":421935700,"current-db-size-bytes":52441088,"current-db-size":"52 MB","current-db-size-in-use-bytes":27848704,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-09T05:21:04.229807Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":421935700,"revision":2883,"compact-revision":-1} {"level":"info","ts":"2026-10-09T05:26:04.162398Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5368} {"level":"info","ts":"2026-10-09T05:26:04.242842Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5368,"took":"78.687997ms","hash":3666222487,"current-db-size-bytes":52441088,"current-db-size":"52 MB","current-db-size-in-use-bytes":18534400,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-10-09T05:26:04.242908Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3666222487,"revision":5368,"compact-revision":2883}