{"level":"warn","ts":"2026-04-08T10:41:40.017759Z","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-08T10:41:40.017885Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.85:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.85:2380","--initial-cluster=instance=https://199.19.213.85:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.85:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.85: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-08T10:41:40.017968Z","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-08T10:41:40.017984Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.85:2380"]} {"level":"info","ts":"2026-04-08T10:41:40.018Z","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-08T10:41:40.018697Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.85:2379"]} {"level":"info","ts":"2026-04-08T10:41:40.01892Z","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.19.213.85:2380"],"listen-peer-urls":["https://199.19.213.85:2380"],"advertise-client-urls":["https://199.19.213.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.85: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-08T10:41:40.023259Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.071721ms"} {"level":"info","ts":"2026-04-08T10:41:40.031036Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6a0c678c4e560a44","cluster-id":"dbaaf49260b7a32b"} {"level":"info","ts":"2026-04-08T10:41:40.031105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T10:41:40.031133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became follower at term 0"} {"level":"info","ts":"2026-04-08T10:41:40.031151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6a0c678c4e560a44 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T10:41:40.031167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became follower at term 1"} {"level":"info","ts":"2026-04-08T10:41:40.031219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 switched to configuration voters=(7641596520048233028)"} {"level":"warn","ts":"2026-04-08T10:41:40.037088Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T10:41:40.03856Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T10:41:40.039898Z","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-08T10:41:40.04253Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6a0c678c4e560a44","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T10:41:40.043053Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6a0c678c4e560a44","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T10:41:40.043243Z","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-08T10:41:40.045216Z","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-08T10:41:40.045876Z","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-08T10:41:40.048779Z","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-08T10:41:40.049147Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6a0c678c4e560a44","initial-advertise-peer-urls":["https://199.19.213.85:2380"],"listen-peer-urls":["https://199.19.213.85:2380"],"advertise-client-urls":["https://199.19.213.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T10:41:40.049189Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T10:41:40.049005Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.85:2380"} {"level":"info","ts":"2026-04-08T10:41:40.049295Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.85:2380"} {"level":"info","ts":"2026-04-08T10:41:40.049614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 switched to configuration voters=(7641596520048233028)"} {"level":"info","ts":"2026-04-08T10:41:40.049905Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dbaaf49260b7a32b","local-member-id":"6a0c678c4e560a44","added-peer-id":"6a0c678c4e560a44","added-peer-peer-urls":["https://199.19.213.85:2380"]} {"level":"info","ts":"2026-04-08T10:41:40.231931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T10:41:40.232032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T10:41:40.23207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 received MsgPreVoteResp from 6a0c678c4e560a44 at term 1"} {"level":"info","ts":"2026-04-08T10:41:40.232096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became candidate at term 2"} {"level":"info","ts":"2026-04-08T10:41:40.232108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 received MsgVoteResp from 6a0c678c4e560a44 at term 2"} {"level":"info","ts":"2026-04-08T10:41:40.232131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6a0c678c4e560a44 became leader at term 2"} {"level":"info","ts":"2026-04-08T10:41:40.232147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6a0c678c4e560a44 elected leader 6a0c678c4e560a44 at term 2"} {"level":"info","ts":"2026-04-08T10:41:40.233475Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6a0c678c4e560a44","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.85:2379]}","request-path":"/0/members/6a0c678c4e560a44/attributes","cluster-id":"dbaaf49260b7a32b","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T10:41:40.234154Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T10:41:40.234847Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T10:41:40.236484Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T10:41:40.238061Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dbaaf49260b7a32b","local-member-id":"6a0c678c4e560a44","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T10:41:40.238304Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T10:41:40.238383Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T10:41:40.24373Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T10:41:40.244021Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T10:41:40.247885Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.85:2379"} {"level":"info","ts":"2026-04-08T10:41:40.249505Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T10:42:16.452292Z","caller":"traceutil/trace.go:171","msg":"trace[800830410] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"175.993869ms","start":"2026-04-08T10:42:16.27625Z","end":"2026-04-08T10:42:16.452244Z","steps":["trace[800830410] 'process raft request' (duration: 175.743717ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:42:16.45275Z","caller":"traceutil/trace.go:171","msg":"trace[111383050] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:600; }","duration":"149.547715ms","start":"2026-04-08T10:42:16.303149Z","end":"2026-04-08T10:42:16.452697Z","steps":["trace[111383050] 'read index received' (duration: 149.532945ms)","trace[111383050] 'applied index is now lower than readState.Index' (duration: 11.52µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:42:16.452981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.815407ms","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-08T10:42:16.453129Z","caller":"traceutil/trace.go:171","msg":"trace[1437070771] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"150.048379ms","start":"2026-04-08T10:42:16.303061Z","end":"2026-04-08T10:42:16.453109Z","steps":["trace[1437070771] 'agreement among raft nodes before linearized reading' (duration: 149.722516ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:42:16.601716Z","caller":"traceutil/trace.go:171","msg":"trace[1235276148] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"190.406521ms","start":"2026-04-08T10:42:16.411264Z","end":"2026-04-08T10:42:16.60167Z","steps":["trace[1235276148] 'process raft request' (duration: 188.809058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:42:16.602081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.411913ms","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-08T10:42:16.602178Z","caller":"traceutil/trace.go:171","msg":"trace[1260135572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"215.578095ms","start":"2026-04-08T10:42:16.386576Z","end":"2026-04-08T10:42:16.602154Z","steps":["trace[1260135572] 'agreement among raft nodes before linearized reading' (duration: 215.328803ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:42:16.60226Z","caller":"traceutil/trace.go:171","msg":"trace[789892349] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"148.750688ms","start":"2026-04-08T10:42:16.452847Z","end":"2026-04-08T10:42:16.601598Z","steps":["trace[789892349] 'read index received' (duration: 146.679182ms)","trace[789892349] 'applied index is now lower than readState.Index' (duration: 2.070836ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:42:16.607034Z","caller":"traceutil/trace.go:171","msg":"trace[1682109511] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"148.348536ms","start":"2026-04-08T10:42:16.45866Z","end":"2026-04-08T10:42:16.607008Z","steps":["trace[1682109511] 'process raft request' (duration: 148.245675ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:42:17.071503Z","caller":"traceutil/trace.go:171","msg":"trace[346795908] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"396.902961ms","start":"2026-04-08T10:42:16.674551Z","end":"2026-04-08T10:42:17.071454Z","steps":["trace[346795908] 'process raft request' (duration: 348.645251ms)","trace[346795908] 'compare' (duration: 46.658237ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:42:17.072306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:42:16.67452Z","time spent":"397.080562ms","remote":"127.0.0.1:51298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T10:43:24.788793Z","caller":"traceutil/trace.go:171","msg":"trace[1280885246] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"112.704038ms","start":"2026-04-08T10:43:24.676061Z","end":"2026-04-08T10:43:24.788765Z","steps":["trace[1280885246] 'process raft request' (duration: 112.549275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:43:47.215052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.491225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T10:43:47.215168Z","caller":"traceutil/trace.go:171","msg":"trace[2045081536] linearizableReadLoop","detail":"{readStateIndex:1865; appliedIndex:1864; }","duration":"308.192792ms","start":"2026-04-08T10:43:46.906959Z","end":"2026-04-08T10:43:47.215152Z","steps":["trace[2045081536] 'read index received' (duration: 63.098726ms)","trace[2045081536] 'applied index is now lower than readState.Index' (duration: 245.092446ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:43:47.215254Z","caller":"traceutil/trace.go:171","msg":"trace[447810985] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"314.887168ms","start":"2026-04-08T10:43:46.900286Z","end":"2026-04-08T10:43:47.215173Z","steps":["trace[447810985] 'process raft request' (duration: 69.870863ms)","trace[447810985] 'compare' (duration: 244.002776ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:43:47.21548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:43:46.900261Z","time spent":"315.101352ms","remote":"127.0.0.1:51068","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-08T10:43:47.215496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.551809ms","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-08T10:43:47.215593Z","caller":"traceutil/trace.go:171","msg":"trace[1808573631] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1789; }","duration":"308.700762ms","start":"2026-04-08T10:43:46.906872Z","end":"2026-04-08T10:43:47.215573Z","steps":["trace[1808573631] 'agreement among raft nodes before linearized reading' (duration: 308.405297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:43:47.215656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:43:46.906855Z","time spent":"308.791113ms","remote":"127.0.0.1:51068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-08T10:43:47.222726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.412518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-08T10:43:47.22282Z","caller":"traceutil/trace.go:171","msg":"trace[531096641] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1790; }","duration":"105.52481ms","start":"2026-04-08T10:43:47.11728Z","end":"2026-04-08T10:43:47.222805Z","steps":["trace[531096641] 'agreement among raft nodes before linearized reading' (duration: 105.320487ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:43:47.222798Z","caller":"traceutil/trace.go:171","msg":"trace[1468526102] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"197.52243ms","start":"2026-04-08T10:43:47.025228Z","end":"2026-04-08T10:43:47.222751Z","steps":["trace[1468526102] 'process raft request' (duration: 197.240675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:43:47.222835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.10642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-08T10:43:47.222956Z","caller":"traceutil/trace.go:171","msg":"trace[301759844] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1790; }","duration":"211.242033ms","start":"2026-04-08T10:43:47.01166Z","end":"2026-04-08T10:43:47.222902Z","steps":["trace[301759844] 'agreement among raft nodes before linearized reading' (duration: 211.05424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:43:47.223205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.242335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-08T10:43:47.223346Z","caller":"traceutil/trace.go:171","msg":"trace[1927557582] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1790; }","duration":"314.382577ms","start":"2026-04-08T10:43:46.908931Z","end":"2026-04-08T10:43:47.223314Z","steps":["trace[1927557582] 'agreement among raft nodes before linearized reading' (duration: 313.657053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:43:47.223411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:43:46.908921Z","time spent":"314.477529ms","remote":"127.0.0.1:50732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-08T10:43:47.222948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.082888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-08T10:43:47.223653Z","caller":"traceutil/trace.go:171","msg":"trace[1268082473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1790; }","duration":"107.846773ms","start":"2026-04-08T10:43:47.115788Z","end":"2026-04-08T10:43:47.223634Z","steps":["trace[1268082473] 'agreement among raft nodes before linearized reading' (duration: 106.909765ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:44:21.662694Z","caller":"traceutil/trace.go:171","msg":"trace[414576273] linearizableReadLoop","detail":"{readStateIndex:2120; appliedIndex:2119; }","duration":"113.768159ms","start":"2026-04-08T10:44:21.548906Z","end":"2026-04-08T10:44:21.662674Z","steps":["trace[414576273] 'read index received' (duration: 81.53497ms)","trace[414576273] 'applied index is now lower than readState.Index' (duration: 32.232339ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:44:21.663014Z","caller":"traceutil/trace.go:171","msg":"trace[1789728372] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"114.53825ms","start":"2026-04-08T10:44:21.548459Z","end":"2026-04-08T10:44:21.662997Z","steps":["trace[1789728372] 'process raft request' (duration: 82.074458ms)","trace[1789728372] 'compare' (duration: 32.045797ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:44:21.663303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.374377ms","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-08T10:44:21.66339Z","caller":"traceutil/trace.go:171","msg":"trace[1221495694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2036; }","duration":"114.48701ms","start":"2026-04-08T10:44:21.548883Z","end":"2026-04-08T10:44:21.66337Z","steps":["trace[1221495694] 'agreement among raft nodes before linearized reading' (duration: 114.226406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:44:21.663458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.205401ms","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-08T10:44:21.663509Z","caller":"traceutil/trace.go:171","msg":"trace[503248331] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2036; }","duration":"113.287873ms","start":"2026-04-08T10:44:21.550208Z","end":"2026-04-08T10:44:21.663496Z","steps":["trace[503248331] 'agreement among raft nodes before linearized reading' (duration: 113.13984ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:44:22.082684Z","caller":"traceutil/trace.go:171","msg":"trace[1934055519] linearizableReadLoop","detail":"{readStateIndex:2124; appliedIndex:2123; }","duration":"189.145485ms","start":"2026-04-08T10:44:21.893516Z","end":"2026-04-08T10:44:22.082661Z","steps":["trace[1934055519] 'read index received' (duration: 142.100619ms)","trace[1934055519] 'applied index is now lower than readState.Index' (duration: 47.043926ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:44:22.082843Z","caller":"traceutil/trace.go:171","msg":"trace[2087987] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"202.771009ms","start":"2026-04-08T10:44:21.880055Z","end":"2026-04-08T10:44:22.082826Z","steps":["trace[2087987] 'process raft request' (duration: 155.607432ms)","trace[2087987] 'compare' (duration: 46.874443ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:44:22.082986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.459459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:44:22.083045Z","caller":"traceutil/trace.go:171","msg":"trace[344052488] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2040; }","duration":"189.580501ms","start":"2026-04-08T10:44:21.893451Z","end":"2026-04-08T10:44:22.083031Z","steps":["trace[344052488] 'agreement among raft nodes before linearized reading' (duration: 189.476949ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:55.791139Z","caller":"traceutil/trace.go:171","msg":"trace[771095905] linearizableReadLoop","detail":"{readStateIndex:4806; appliedIndex:4805; }","duration":"118.246916ms","start":"2026-04-08T10:49:55.672878Z","end":"2026-04-08T10:49:55.791124Z","steps":["trace[771095905] 'read index received' (duration: 117.924801ms)","trace[771095905] 'applied index is now lower than readState.Index' (duration: 321.715µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T10:49:55.791317Z","caller":"traceutil/trace.go:171","msg":"trace[2136592054] transaction","detail":"{read_only:false; response_revision:4627; number_of_response:1; }","duration":"135.710595ms","start":"2026-04-08T10:49:55.655601Z","end":"2026-04-08T10:49:55.791311Z","steps":["trace[2136592054] 'process raft request' (duration: 135.267227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:49:55.79149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.599662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-08T10:49:55.79151Z","caller":"traceutil/trace.go:171","msg":"trace[108130498] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4627; }","duration":"118.633993ms","start":"2026-04-08T10:49:55.672868Z","end":"2026-04-08T10:49:55.791502Z","steps":["trace[108130498] 'agreement among raft nodes before linearized reading' (duration: 118.546681ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:56.031935Z","caller":"traceutil/trace.go:171","msg":"trace[1008978094] transaction","detail":"{read_only:false; response_revision:4629; number_of_response:1; }","duration":"234.692422ms","start":"2026-04-08T10:49:55.797192Z","end":"2026-04-08T10:49:56.031885Z","steps":["trace[1008978094] 'process raft request' (duration: 234.529609ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:56.032154Z","caller":"traceutil/trace.go:171","msg":"trace[1360644941] linearizableReadLoop","detail":"{readStateIndex:4808; appliedIndex:4808; }","duration":"207.138282ms","start":"2026-04-08T10:49:55.824997Z","end":"2026-04-08T10:49:56.032135Z","steps":["trace[1360644941] 'read index received' (duration: 207.128242ms)","trace[1360644941] 'applied index is now lower than readState.Index' (duration: 7.34µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T10:49:56.032334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.417727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-89990bfc-b27f-4f76-9382-187025ad64ac\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-04-08T10:49:56.032367Z","caller":"traceutil/trace.go:171","msg":"trace[593787233] range","detail":"{range_begin:/registry/persistentvolumes/pvc-89990bfc-b27f-4f76-9382-187025ad64ac; range_end:; response_count:1; response_revision:4629; }","duration":"234.478148ms","start":"2026-04-08T10:49:55.797877Z","end":"2026-04-08T10:49:56.032355Z","steps":["trace[593787233] 'agreement among raft nodes before linearized reading' (duration: 234.334265ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:56.085741Z","caller":"traceutil/trace.go:171","msg":"trace[1338102493] transaction","detail":"{read_only:false; response_revision:4630; number_of_response:1; }","duration":"249.034983ms","start":"2026-04-08T10:49:55.836682Z","end":"2026-04-08T10:49:56.085716Z","steps":["trace[1338102493] 'process raft request' (duration: 248.80808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:49:56.085995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.147395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-08T10:49:56.085979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.331621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:49:56.086023Z","caller":"traceutil/trace.go:171","msg":"trace[1286330841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4631; }","duration":"195.252187ms","start":"2026-04-08T10:49:55.890763Z","end":"2026-04-08T10:49:56.086015Z","steps":["trace[1286330841] 'agreement among raft nodes before linearized reading' (duration: 195.128655ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:49:56.086045Z","caller":"traceutil/trace.go:171","msg":"trace[1775274091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4631; }","duration":"252.442952ms","start":"2026-04-08T10:49:55.833587Z","end":"2026-04-08T10:49:56.08603Z","steps":["trace[1775274091] 'agreement among raft nodes before linearized reading' (duration: 252.30247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T10:49:56.086055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.375142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/secretgen-controller/\" range_end:\"/registry/secrets/secretgen-controller0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T10:49:56.086168Z","caller":"traceutil/trace.go:171","msg":"trace[2049378436] range","detail":"{range_begin:/registry/secrets/secretgen-controller/; range_end:/registry/secrets/secretgen-controller0; response_count:0; response_revision:4631; }","duration":"204.561945ms","start":"2026-04-08T10:49:55.881588Z","end":"2026-04-08T10:49:56.08615Z","steps":["trace[2049378436] 'agreement among raft nodes before linearized reading' (duration: 204.345372ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T10:50:32.692429Z","caller":"traceutil/trace.go:171","msg":"trace[394033938] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"123.164652ms","start":"2026-04-08T10:50:32.569242Z","end":"2026-04-08T10:50:32.692407Z","steps":["trace[394033938] 'process raft request' (duration: 122.990439ms)"],"step_count":1}