{"level":"warn","ts":"2026-04-11T02:06:24.373279Z","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-11T02:06:24.373375Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-11T02:06:24.373458Z","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-11T02:06:24.373471Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-04-11T02:06:24.373496Z","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-11T02:06:24.374213Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-04-11T02:06:24.374288Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-11T02:06:24.378045Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.519686ms"} {"level":"info","ts":"2026-04-11T02:06:24.384973Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-04-11T02:06:24.385122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-04-11T02:06:24.38518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-04-11T02:06:24.385202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-11T02:06:24.385211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-04-11T02:06:24.385495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-04-11T02:06:24.393146Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-11T02:06:24.394777Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-11T02:06:24.395859Z","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-11T02:06:24.398182Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-11T02:06:24.398394Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-11T02:06:24.399183Z","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-11T02:06:24.399323Z","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-11T02:06:24.399339Z","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-11T02:06:24.401183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-04-11T02:06:24.401368Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-04-11T02:06:24.405116Z","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-11T02:06:24.405299Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-04-11T02:06:24.405362Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-04-11T02:06:24.405449Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-11T02:06:24.405493Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-11T02:06:24.587252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-04-11T02:06:24.58731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-11T02:06:24.587347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-04-11T02:06:24.587367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-04-11T02:06:24.587383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-04-11T02:06:24.587403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-04-11T02:06:24.587422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-04-11T02:06:24.589581Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-11T02:06:24.589598Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T02:06:24.589673Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T02:06:24.589863Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T02:06:24.590506Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T02:06:24.590595Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T02:06:24.590648Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T02:06:24.590608Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-11T02:06:24.59074Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-11T02:06:24.59204Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-11T02:06:24.596317Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"warn","ts":"2026-04-11T02:06:42.654752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.339395ms","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-11T02:06:42.654942Z","caller":"traceutil/trace.go:171","msg":"trace[327155721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:442; }","duration":"180.619562ms","start":"2026-04-11T02:06:42.474299Z","end":"2026-04-11T02:06:42.654919Z","steps":["trace[327155721] 'range keys from in-memory index tree' (duration: 180.173229ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:06:42.848013Z","caller":"traceutil/trace.go:171","msg":"trace[2108351182] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"187.154817ms","start":"2026-04-11T02:06:42.66083Z","end":"2026-04-11T02:06:42.847984Z","steps":["trace[2108351182] 'process raft request' (duration: 186.95184ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:06:56.394024Z","caller":"traceutil/trace.go:171","msg":"trace[821759923] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"149.317747ms","start":"2026-04-11T02:06:56.244681Z","end":"2026-04-11T02:06:56.393999Z","steps":["trace[821759923] 'read index received' (duration: 149.182734ms)","trace[821759923] 'applied index is now lower than readState.Index' (duration: 133.863µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:06:56.394116Z","caller":"traceutil/trace.go:171","msg":"trace[436115808] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"158.158065ms","start":"2026-04-11T02:06:56.235914Z","end":"2026-04-11T02:06:56.394072Z","steps":["trace[436115808] 'process raft request' (duration: 157.911469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:06:56.39446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.759318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-04-11T02:06:56.394518Z","caller":"traceutil/trace.go:171","msg":"trace[1406026804] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:584; }","duration":"149.861621ms","start":"2026-04-11T02:06:56.244643Z","end":"2026-04-11T02:06:56.394504Z","steps":["trace[1406026804] 'agreement among raft nodes before linearized reading' (duration: 149.507202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:07:02.58686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.363998ms","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-11T02:07:02.58696Z","caller":"traceutil/trace.go:171","msg":"trace[1600497553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"108.501691ms","start":"2026-04-11T02:07:02.478438Z","end":"2026-04-11T02:07:02.58694Z","steps":["trace[1600497553] 'range keys from in-memory index tree' (duration: 108.190333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:07:02.817204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.555541ms","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-11T02:07:02.817344Z","caller":"traceutil/trace.go:171","msg":"trace[1570015195] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:628; }","duration":"117.703415ms","start":"2026-04-11T02:07:02.699617Z","end":"2026-04-11T02:07:02.81732Z","steps":["trace[1570015195] 'range keys from in-memory index tree' (duration: 117.541331ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:07:03.361926Z","caller":"traceutil/trace.go:171","msg":"trace[1701585698] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"393.820383ms","start":"2026-04-11T02:07:02.968081Z","end":"2026-04-11T02:07:03.361901Z","steps":["trace[1701585698] 'process raft request' (duration: 393.672649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:07:03.362488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:07:02.968061Z","time spent":"393.933916ms","remote":"127.0.0.1:36164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T02:07:03.399379Z","caller":"traceutil/trace.go:171","msg":"trace[1349964895] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"430.519319ms","start":"2026-04-11T02:07:02.968839Z","end":"2026-04-11T02:07:03.399358Z","steps":["trace[1349964895] 'process raft request' (duration: 430.403566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:07:03.399492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:07:02.968825Z","time spent":"430.61179ms","remote":"127.0.0.1:36114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T02:07:29.659144Z","caller":"traceutil/trace.go:171","msg":"trace[97433082] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"155.578148ms","start":"2026-04-11T02:07:29.50354Z","end":"2026-04-11T02:07:29.659118Z","steps":["trace[97433082] 'process raft request' (duration: 155.351174ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:07:58.368641Z","caller":"traceutil/trace.go:171","msg":"trace[2085520732] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"215.698133ms","start":"2026-04-11T02:07:58.152911Z","end":"2026-04-11T02:07:58.368609Z","steps":["trace[2085520732] 'process raft request' (duration: 171.37867ms)","trace[2085520732] 'compare' (duration: 44.067289ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:08:47.215095Z","caller":"traceutil/trace.go:171","msg":"trace[1410571327] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"159.574498ms","start":"2026-04-11T02:08:47.055488Z","end":"2026-04-11T02:08:47.215063Z","steps":["trace[1410571327] 'process raft request' (duration: 159.047882ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:08:47.570687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.582034ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T02:08:47.570826Z","caller":"traceutil/trace.go:171","msg":"trace[1536069007] linearizableReadLoop","detail":"{readStateIndex:1957; appliedIndex:1956; }","duration":"150.277232ms","start":"2026-04-11T02:08:47.420512Z","end":"2026-04-11T02:08:47.570789Z","steps":["trace[1536069007] 'read index received' (duration: 13.587303ms)","trace[1536069007] 'applied index is now lower than readState.Index' (duration: 136.688489ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:08:47.570848Z","caller":"traceutil/trace.go:171","msg":"trace[79036623] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"285.607433ms","start":"2026-04-11T02:08:47.285201Z","end":"2026-04-11T02:08:47.570808Z","steps":["trace[79036623] 'process raft request' (duration: 149.002955ms)","trace[79036623] 'compare' (duration: 135.356562ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:08:47.570983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.479624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c70cfaa7-5609-4374-9fa5-a808a864ab3c\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-11T02:08:47.571014Z","caller":"traceutil/trace.go:171","msg":"trace[1551886261] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c70cfaa7-5609-4374-9fa5-a808a864ab3c; range_end:; response_count:1; response_revision:1886; }","duration":"150.540384ms","start":"2026-04-11T02:08:47.420463Z","end":"2026-04-11T02:08:47.571004Z","steps":["trace[1551886261] 'agreement among raft nodes before linearized reading' (duration: 150.413133ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:08:47.690787Z","caller":"traceutil/trace.go:171","msg":"trace[1468334869] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"112.27623ms","start":"2026-04-11T02:08:47.578475Z","end":"2026-04-11T02:08:47.690751Z","steps":["trace[1468334869] 'process raft request' (duration: 109.298962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:09:19.616523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.067773ms","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-11T02:09:19.616674Z","caller":"traceutil/trace.go:171","msg":"trace[1222756629] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2119; }","duration":"130.274426ms","start":"2026-04-11T02:09:19.486382Z","end":"2026-04-11T02:09:19.616656Z","steps":["trace[1222756629] 'range keys from in-memory index tree' (duration: 129.81685ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:09:19.927041Z","caller":"traceutil/trace.go:171","msg":"trace[943241123] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"103.962021ms","start":"2026-04-11T02:09:19.823049Z","end":"2026-04-11T02:09:19.927011Z","steps":["trace[943241123] 'process raft request' (duration: 103.732339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:09:20.178747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.695314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-11T02:09:20.178841Z","caller":"traceutil/trace.go:171","msg":"trace[453054673] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2122; }","duration":"186.856105ms","start":"2026-04-11T02:09:19.99196Z","end":"2026-04-11T02:09:20.178816Z","steps":["trace[453054673] 'range keys from in-memory index tree' (duration: 186.40642ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:09:20.367902Z","caller":"traceutil/trace.go:171","msg":"trace[1041492860] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2201; }","duration":"180.818485ms","start":"2026-04-11T02:09:20.18705Z","end":"2026-04-11T02:09:20.367868Z","steps":["trace[1041492860] 'read index received' (duration: 180.591942ms)","trace[1041492860] 'applied index is now lower than readState.Index' (duration: 225.052µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:09:20.368464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.367132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T02:09:20.368623Z","caller":"traceutil/trace.go:171","msg":"trace[1762744010] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"185.504219ms","start":"2026-04-11T02:09:20.183056Z","end":"2026-04-11T02:09:20.36856Z","steps":["trace[1762744010] 'process raft request' (duration: 184.631608ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:09:20.369436Z","caller":"traceutil/trace.go:171","msg":"trace[296352554] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2123; }","duration":"181.500613ms","start":"2026-04-11T02:09:20.187042Z","end":"2026-04-11T02:09:20.368543Z","steps":["trace[296352554] 'agreement among raft nodes before linearized reading' (duration: 181.20624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:09:20.642714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.849051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T02:09:20.642852Z","caller":"traceutil/trace.go:171","msg":"trace[1562302552] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"268.136682ms","start":"2026-04-11T02:09:20.374695Z","end":"2026-04-11T02:09:20.642831Z","steps":["trace[1562302552] 'process raft request' (duration: 109.026187ms)","trace[1562302552] 'compare' (duration: 158.691569ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:09:20.677728Z","caller":"traceutil/trace.go:171","msg":"trace[505829677] linearizableReadLoop","detail":"{readStateIndex:2204; appliedIndex:2203; }","duration":"115.35692ms","start":"2026-04-11T02:09:20.562351Z","end":"2026-04-11T02:09:20.677708Z","steps":["trace[505829677] 'read index received' (duration: 115.085717ms)","trace[505829677] 'applied index is now lower than readState.Index' (duration: 269.873µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:09:20.677981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.615183ms","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-11T02:09:20.678007Z","caller":"traceutil/trace.go:171","msg":"trace[49227358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2125; }","duration":"115.701514ms","start":"2026-04-11T02:09:20.562299Z","end":"2026-04-11T02:09:20.678001Z","steps":["trace[49227358] 'agreement among raft nodes before linearized reading' (duration: 115.524112ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:09:20.678011Z","caller":"traceutil/trace.go:171","msg":"trace[862527720] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"123.621535ms","start":"2026-04-11T02:09:20.554177Z","end":"2026-04-11T02:09:20.677798Z","steps":["trace[862527720] 'process raft request' (duration: 123.376432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:10:34.370882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.118249ms","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-11T02:10:34.371076Z","caller":"traceutil/trace.go:171","msg":"trace[1267504763] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2621; }","duration":"189.378576ms","start":"2026-04-11T02:10:34.181671Z","end":"2026-04-11T02:10:34.37105Z","steps":["trace[1267504763] 'range keys from in-memory index tree' (duration: 188.913803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:10:34.371628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.466031ms","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-11T02:10:34.371736Z","caller":"traceutil/trace.go:171","msg":"trace[280585430] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2621; }","duration":"171.623116ms","start":"2026-04-11T02:10:34.200085Z","end":"2026-04-11T02:10:34.371708Z","steps":["trace[280585430] 'range keys from in-memory index tree' (duration: 171.201893ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:10:34.372007Z","caller":"traceutil/trace.go:171","msg":"trace[94618111] transaction","detail":"{read_only:false; response_revision:2622; number_of_response:1; }","duration":"154.369979ms","start":"2026-04-11T02:10:34.21762Z","end":"2026-04-11T02:10:34.37199Z","steps":["trace[94618111] 'process raft request' (duration: 152.489643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:11:06.67002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.788345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:11:06.670103Z","caller":"traceutil/trace.go:171","msg":"trace[647750336] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2801; }","duration":"191.141515ms","start":"2026-04-11T02:11:06.478947Z","end":"2026-04-11T02:11:06.670088Z","steps":["trace[647750336] 'range keys from in-memory index tree' (duration: 190.560439ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:11:06.670144Z","caller":"traceutil/trace.go:171","msg":"trace[1242848703] linearizableReadLoop","detail":"{readStateIndex:2905; appliedIndex:2904; }","duration":"122.975344ms","start":"2026-04-11T02:11:06.547149Z","end":"2026-04-11T02:11:06.670124Z","steps":["trace[1242848703] 'read index received' (duration: 100.848489ms)","trace[1242848703] 'applied index is now lower than readState.Index' (duration: 22.125465ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:11:06.669944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.895697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-11T02:11:06.670331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.173349ms","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-11T02:11:06.670356Z","caller":"traceutil/trace.go:171","msg":"trace[920945297] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2801; }","duration":"159.353039ms","start":"2026-04-11T02:11:06.510989Z","end":"2026-04-11T02:11:06.670342Z","steps":["trace[920945297] 'count revisions from in-memory index tree' (duration: 158.827336ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:11:06.670368Z","caller":"traceutil/trace.go:171","msg":"trace[1623806342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2802; }","duration":"123.236891ms","start":"2026-04-11T02:11:06.547121Z","end":"2026-04-11T02:11:06.670358Z","steps":["trace[1623806342] 'agreement among raft nodes before linearized reading' (duration: 123.058326ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:11:06.670452Z","caller":"traceutil/trace.go:171","msg":"trace[1935285415] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"149.569564ms","start":"2026-04-11T02:11:06.520764Z","end":"2026-04-11T02:11:06.670334Z","steps":["trace[1935285415] 'process raft request' (duration: 127.307476ms)","trace[1935285415] 'compare' (duration: 21.822867ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:13:38.385704Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225420302361464,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:38.889765Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225420302361464,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:39.390863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225420302361464,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:39.891117Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225420302361464,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:39.950716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000103223s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-11T02:13:39.950838Z","caller":"traceutil/trace.go:171","msg":"trace[150251345] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000301599s","start":"2026-04-11T02:13:37.95051Z","end":"2026-04-11T02:13:39.950812Z","steps":["trace[150251345] 'agreement among raft nodes before linearized reading' (duration: 2.000144145s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:39.950899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:37.95048Z","time spent":"2.000407691s","remote":"127.0.0.1:35928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/11 02:13:39 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-11T02:13:40.392Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225420302361464,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:40.788626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00005026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-11T02:13:40.788869Z","caller":"traceutil/trace.go:171","msg":"trace[448255575] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000359335s","start":"2026-04-11T02:13:38.788484Z","end":"2026-04-11T02:13:40.788843Z","steps":["trace[448255575] 'agreement among raft nodes before linearized reading' (duration: 2.000095391s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:40.789079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:38.788446Z","time spent":"2.000587149s","remote":"127.0.0.1:35936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/11 02:13:40 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-11T02:13:40.892694Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225420302361464,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:41.392999Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225420302361464,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:41.893434Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225420302361464,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:42.394031Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225420302361464,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:42.797659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000242824s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-11T02:13:42.797757Z","caller":"traceutil/trace.go:171","msg":"trace[1550811726] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000444557s","start":"2026-04-11T02:13:40.797291Z","end":"2026-04-11T02:13:42.797736Z","steps":["trace[1550811726] 'agreement among raft nodes before linearized reading' (duration: 2.000280204s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:42.797808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:40.797267Z","time spent":"2.000530989s","remote":"127.0.0.1:35928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-11T02:13:42.894515Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225420302361464,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:42.909233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:37.910791Z","time spent":"4.998429144s","remote":"127.0.0.1:36164","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/04/11 02:13:42 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-11T02:13:43.020337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999770487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-11T02:13:43.020457Z","caller":"traceutil/trace.go:171","msg":"trace[883587692] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999968661s","start":"2026-04-11T02:13:38.020463Z","end":"2026-04-11T02:13:43.020432Z","steps":["trace[883587692] 'agreement among raft nodes before linearized reading' (duration: 4.999795438s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.020513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:38.020443Z","time spent":"5.000059172s","remote":"127.0.0.1:36164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-11T02:13:43.395631Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225420302361464,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T02:13:43.467168Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"5.661752373s","expected-duration":"1s"} {"level":"info","ts":"2026-04-11T02:13:43.467685Z","caller":"traceutil/trace.go:171","msg":"trace[1452413664] transaction","detail":"{read_only:false; response_revision:3685; number_of_response:1; }","duration":"5.662394234s","start":"2026-04-11T02:13:37.805264Z","end":"2026-04-11T02:13:43.467658Z","steps":["trace[1452413664] 'process raft request' (duration: 5.662201691s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.467872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:37.805235Z","time spent":"5.662525726s","remote":"127.0.0.1:36164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T02:13:43.495285Z","caller":"traceutil/trace.go:171","msg":"trace[1305585738] transaction","detail":"{read_only:false; response_revision:3688; number_of_response:1; }","duration":"4.803467065s","start":"2026-04-11T02:13:38.691799Z","end":"2026-04-11T02:13:43.495266Z","steps":["trace[1305585738] 'process raft request' (duration: 4.803318103s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.495388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:38.691766Z","time spent":"4.803575856s","remote":"127.0.0.1:36164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T02:13:43.495874Z","caller":"traceutil/trace.go:171","msg":"trace[132368388] transaction","detail":"{read_only:false; response_revision:3690; number_of_response:1; }","duration":"3.540011343s","start":"2026-04-11T02:13:39.955804Z","end":"2026-04-11T02:13:43.495815Z","steps":["trace[132368388] 'process raft request' (duration: 3.539536665s)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:13:43.495795Z","caller":"traceutil/trace.go:171","msg":"trace[1812117887] transaction","detail":"{read_only:false; response_revision:3687; number_of_response:1; }","duration":"4.804380059s","start":"2026-04-11T02:13:38.691372Z","end":"2026-04-11T02:13:43.495752Z","steps":["trace[1812117887] 'process raft request' (duration: 4.803573586s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.495997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:39.955768Z","time spent":"3.540172455s","remote":"127.0.0.1:36000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T02:13:43.495994Z","caller":"traceutil/trace.go:171","msg":"trace[588895806] transaction","detail":"{read_only:false; response_revision:3691; number_of_response:1; }","duration":"2.534667036s","start":"2026-04-11T02:13:40.961294Z","end":"2026-04-11T02:13:43.495961Z","steps":["trace[588895806] 'process raft request' (duration: 2.534474603s)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:13:43.496075Z","caller":"traceutil/trace.go:171","msg":"trace[1724167530] transaction","detail":"{read_only:false; response_revision:3689; number_of_response:1; }","duration":"4.220056055s","start":"2026-04-11T02:13:39.275968Z","end":"2026-04-11T02:13:43.496024Z","steps":["trace[1724167530] 'process raft request' (duration: 4.219261043s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.496148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:40.961266Z","time spent":"2.534796599s","remote":"127.0.0.1:36164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T02:13:43.496172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:39.275937Z","time spent":"4.220184718s","remote":"127.0.0.1:36164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T02:13:43.496199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:38.69134Z","time spent":"4.804620913s","remote":"127.0.0.1:36102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9412,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T02:13:43.509117Z","caller":"traceutil/trace.go:171","msg":"trace[1202670651] linearizableReadLoop","detail":"{readStateIndex:3829; appliedIndex:3822; }","duration":"5.623841735s","start":"2026-04-11T02:13:37.885147Z","end":"2026-04-11T02:13:43.508989Z","steps":["trace[1202670651] 'read index received' (duration: 5.582450436s)","trace[1202670651] 'applied index is now lower than readState.Index' (duration: 41.385669ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:13:43.509291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.624133509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-client-secret\" ","response":"range_response_count:1 size:1225"} {"level":"info","ts":"2026-04-11T02:13:43.509414Z","caller":"traceutil/trace.go:171","msg":"trace[1227328218] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-client-secret; range_end:; response_count:1; response_revision:3693; }","duration":"5.624331343s","start":"2026-04-11T02:13:37.885062Z","end":"2026-04-11T02:13:43.509394Z","steps":["trace[1227328218] 'agreement among raft nodes before linearized reading' (duration: 5.623993857s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.509505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.391231092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:13:43.509596Z","caller":"traceutil/trace.go:171","msg":"trace[761124061] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:0; response_revision:3693; }","duration":"5.391373764s","start":"2026-04-11T02:13:38.118196Z","end":"2026-04-11T02:13:43.50957Z","steps":["trace[761124061] 'agreement among raft nodes before linearized reading' (duration: 5.391249262s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.510118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.389460112s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-11T02:13:43.509498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:37.885038Z","time spent":"5.624403653s","remote":"127.0.0.1:36704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":1247,"request content":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-prometheus-client-secret\" "} {"level":"warn","ts":"2026-04-11T02:13:43.510151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.943684009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-11T02:13:43.510166Z","caller":"traceutil/trace.go:171","msg":"trace[1999441265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3693; }","duration":"5.389520133s","start":"2026-04-11T02:13:38.120625Z","end":"2026-04-11T02:13:43.510146Z","steps":["trace[1999441265] 'agreement among raft nodes before linearized reading' (duration: 5.389435122s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.51021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:38.120606Z","time spent":"5.389594204s","remote":"127.0.0.1:36164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-11T02:13:43.510102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:38.118166Z","time spent":"5.391918732s","remote":"127.0.0.1:35958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.223\" "} {"level":"warn","ts":"2026-04-11T02:13:43.509814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.569506861s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-11T02:13:43.509838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.791515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-11T02:13:43.510303Z","caller":"traceutil/trace.go:171","msg":"trace[1246897442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3693; }","duration":"4.570033s","start":"2026-04-11T02:13:38.94025Z","end":"2026-04-11T02:13:43.510284Z","steps":["trace[1246897442] 'agreement among raft nodes before linearized reading' (duration: 4.569471621s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.51036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:38.940213Z","time spent":"4.570137841s","remote":"127.0.0.1:36164","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":"warn","ts":"2026-04-11T02:13:43.510044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.29101226s","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-11T02:13:43.510748Z","caller":"traceutil/trace.go:171","msg":"trace[1665523751] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3693; }","duration":"3.943772501s","start":"2026-04-11T02:13:39.566409Z","end":"2026-04-11T02:13:43.510182Z","steps":["trace[1665523751] 'agreement among raft nodes before linearized reading' (duration: 3.943658279s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.510745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.895765558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-11T02:13:43.510895Z","caller":"traceutil/trace.go:171","msg":"trace[1286372063] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3693; }","duration":"3.895943981s","start":"2026-04-11T02:13:39.614925Z","end":"2026-04-11T02:13:43.510869Z","steps":["trace[1286372063] 'agreement among raft nodes before linearized reading' (duration: 3.895126978s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.510873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:39.56638Z","time spent":"3.944464792s","remote":"127.0.0.1:36114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-11T02:13:43.51099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:39.614848Z","time spent":"3.896127103s","remote":"127.0.0.1:36164","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-11T02:13:43.509934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.824356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:13:43.511132Z","caller":"traceutil/trace.go:171","msg":"trace[2071981269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3693; }","duration":"713.056186ms","start":"2026-04-11T02:13:42.79806Z","end":"2026-04-11T02:13:43.511116Z","steps":["trace[2071981269] 'agreement among raft nodes before linearized reading' (duration: 711.835116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.511189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:42.798039Z","time spent":"713.128126ms","remote":"127.0.0.1:35936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-11T02:13:43.510781Z","caller":"traceutil/trace.go:171","msg":"trace[972667808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3693; }","duration":"4.291796653s","start":"2026-04-11T02:13:39.218943Z","end":"2026-04-11T02:13:43.51074Z","steps":["trace[972667808] 'agreement among raft nodes before linearized reading' (duration: 4.290602424s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.511298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:39.218915Z","time spent":"4.292329823s","remote":"127.0.0.1:36164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-11T02:13:43.509987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.051252009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-alertmanager-client-secret\" ","response":"range_response_count:1 size:698"} {"level":"info","ts":"2026-04-11T02:13:43.511961Z","caller":"traceutil/trace.go:171","msg":"trace[122552162] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-alertmanager-client-secret; range_end:; response_count:1; response_revision:3693; }","duration":"5.053254351s","start":"2026-04-11T02:13:38.45868Z","end":"2026-04-11T02:13:43.511934Z","steps":["trace[122552162] 'agreement among raft nodes before linearized reading' (duration: 5.051218297s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.512419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:38.458652Z","time spent":"5.053750759s","remote":"127.0.0.1:36008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":720,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-alertmanager-client-secret\" "} {"level":"warn","ts":"2026-04-11T02:13:43.510025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.656264042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-04-11T02:13:43.512624Z","caller":"traceutil/trace.go:171","msg":"trace[1216172491] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret; range_end:; response_count:1; response_revision:3693; }","duration":"1.658902765s","start":"2026-04-11T02:13:41.853708Z","end":"2026-04-11T02:13:43.512611Z","steps":["trace[1216172491] 'agreement among raft nodes before linearized reading' (duration: 1.656254992s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.512651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:41.853681Z","time spent":"1.658961776s","remote":"127.0.0.1:36704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":1251,"request content":"key:\"/registry/secretgen.k14s.io/passwords/monitoring/kube-prometheus-stack-alertmanager-client-secret\" "} {"level":"warn","ts":"2026-04-11T02:13:43.509866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.664079146s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T02:13:43.512821Z","caller":"traceutil/trace.go:171","msg":"trace[1422541230] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3693; }","duration":"4.667062504s","start":"2026-04-11T02:13:38.845747Z","end":"2026-04-11T02:13:43.512809Z","steps":["trace[1422541230] 'agreement among raft nodes before linearized reading' (duration: 4.664090225s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.512866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:38.845733Z","time spent":"4.667114255s","remote":"127.0.0.1:43126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-04-11T02:13:43.510344Z","caller":"traceutil/trace.go:171","msg":"trace[183428531] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:3693; }","duration":"599.334974ms","start":"2026-04-11T02:13:42.910996Z","end":"2026-04-11T02:13:43.510331Z","steps":["trace[183428531] 'agreement among raft nodes before linearized reading' (duration: 598.731375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.513055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:42.910971Z","time spent":"602.076188ms","remote":"127.0.0.1:36164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":548,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} {"level":"warn","ts":"2026-04-11T02:13:43.510556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.389375071s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-11T02:13:43.513278Z","caller":"traceutil/trace.go:171","msg":"trace[916973700] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3693; }","duration":"5.392601803s","start":"2026-04-11T02:13:38.120655Z","end":"2026-04-11T02:13:43.513257Z","steps":["trace[916973700] 'agreement among raft nodes before linearized reading' (duration: 5.38935805s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.513331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:38.120643Z","time spent":"5.392672985s","remote":"127.0.0.1:36164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-11T02:13:43.510693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.912825937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:13:43.513451Z","caller":"traceutil/trace.go:171","msg":"trace[1016470614] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:3693; }","duration":"4.915622912s","start":"2026-04-11T02:13:38.597815Z","end":"2026-04-11T02:13:43.513438Z","steps":["trace[1016470614] 'agreement among raft nodes before linearized reading' (duration: 4.912671185s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:13:43.513489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T02:13:38.59779Z","time spent":"4.915687853s","remote":"127.0.0.1:36048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-11T02:14:17.99798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.705699ms","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-11T02:14:17.998086Z","caller":"traceutil/trace.go:171","msg":"trace[820270124] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3891; }","duration":"103.855401ms","start":"2026-04-11T02:14:17.894201Z","end":"2026-04-11T02:14:17.998057Z","steps":["trace[820270124] 'agreement among raft nodes before linearized reading' (duration: 103.596787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:14:18.463921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.003615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/cert-manager/kube-prometheus-stack-ca\" ","response":"range_response_count:1 size:2272"} {"level":"info","ts":"2026-04-11T02:14:18.464006Z","caller":"traceutil/trace.go:171","msg":"trace[811771828] range","detail":"{range_begin:/registry/cert-manager.io/certificates/cert-manager/kube-prometheus-stack-ca; range_end:; response_count:1; response_revision:3913; }","duration":"101.115017ms","start":"2026-04-11T02:14:18.36287Z","end":"2026-04-11T02:14:18.463985Z","steps":["trace[811771828] 'agreement among raft nodes before linearized reading' (duration: 100.982765ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:15:25.915949Z","caller":"traceutil/trace.go:171","msg":"trace[1519245857] linearizableReadLoop","detail":"{readStateIndex:5064; appliedIndex:5063; }","duration":"133.434621ms","start":"2026-04-11T02:15:25.782493Z","end":"2026-04-11T02:15:25.915927Z","steps":["trace[1519245857] 'read index received' (duration: 133.274539ms)","trace[1519245857] 'applied index is now lower than readState.Index' (duration: 158.952µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:15:25.916038Z","caller":"traceutil/trace.go:171","msg":"trace[141499552] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"138.703654ms","start":"2026-04-11T02:15:25.77731Z","end":"2026-04-11T02:15:25.916013Z","steps":["trace[141499552] 'process raft request' (duration: 138.453211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:15:25.916136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.605683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T02:15:25.916168Z","caller":"traceutil/trace.go:171","msg":"trace[1446355131] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4879; }","duration":"133.689264ms","start":"2026-04-11T02:15:25.78247Z","end":"2026-04-11T02:15:25.916159Z","steps":["trace[1446355131] 'agreement among raft nodes before linearized reading' (duration: 133.591033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:15:25.91647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.027908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kube-prometheus-stack-grafana-clusterrole\" ","response":"range_response_count:1 size:958"} {"level":"info","ts":"2026-04-11T02:15:25.916512Z","caller":"traceutil/trace.go:171","msg":"trace[2063512054] range","detail":"{range_begin:/registry/clusterroles/kube-prometheus-stack-grafana-clusterrole; range_end:; response_count:1; response_revision:4879; }","duration":"106.11073ms","start":"2026-04-11T02:15:25.81039Z","end":"2026-04-11T02:15:25.9165Z","steps":["trace[2063512054] 'agreement among raft nodes before linearized reading' (duration: 105.957288ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:15:26.128984Z","caller":"traceutil/trace.go:171","msg":"trace[324922178] transaction","detail":"{read_only:false; response_revision:4881; number_of_response:1; }","duration":"200.277321ms","start":"2026-04-11T02:15:25.928682Z","end":"2026-04-11T02:15:26.12896Z","steps":["trace[324922178] 'process raft request' (duration: 200.045538ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:15:26.129235Z","caller":"traceutil/trace.go:171","msg":"trace[1182533882] transaction","detail":"{read_only:false; response_revision:4882; number_of_response:1; }","duration":"200.523023ms","start":"2026-04-11T02:15:25.928694Z","end":"2026-04-11T02:15:26.129217Z","steps":["trace[1182533882] 'process raft request' (duration: 200.202059ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:15:26.129329Z","caller":"traceutil/trace.go:171","msg":"trace[1232713219] linearizableReadLoop","detail":"{readStateIndex:5068; appliedIndex:5064; }","duration":"193.316184ms","start":"2026-04-11T02:15:25.935988Z","end":"2026-04-11T02:15:26.129304Z","steps":["trace[1232713219] 'read index received' (duration: 54.576871ms)","trace[1232713219] 'applied index is now lower than readState.Index' (duration: 138.738203ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:15:26.129524Z","caller":"traceutil/trace.go:171","msg":"trace[238556560] transaction","detail":"{read_only:false; response_revision:4883; number_of_response:1; }","duration":"200.299801ms","start":"2026-04-11T02:15:25.929091Z","end":"2026-04-11T02:15:26.129391Z","steps":["trace[238556560] 'process raft request' (duration: 200.077798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:15:26.129576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.564387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-04-11T02:15:26.129756Z","caller":"traceutil/trace.go:171","msg":"trace[2105638682] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4883; }","duration":"193.77631ms","start":"2026-04-11T02:15:25.935956Z","end":"2026-04-11T02:15:26.129732Z","steps":["trace[2105638682] 'agreement among raft nodes before linearized reading' (duration: 193.480816ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:15:26.135405Z","caller":"traceutil/trace.go:171","msg":"trace[1611340491] transaction","detail":"{read_only:false; response_revision:4884; number_of_response:1; }","duration":"138.937035ms","start":"2026-04-11T02:15:25.996454Z","end":"2026-04-11T02:15:26.135391Z","steps":["trace[1611340491] 'process raft request' (duration: 138.682082ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:15:26.45755Z","caller":"traceutil/trace.go:171","msg":"trace[932825310] linearizableReadLoop","detail":"{readStateIndex:5073; appliedIndex:5072; }","duration":"187.561363ms","start":"2026-04-11T02:15:26.269963Z","end":"2026-04-11T02:15:26.457524Z","steps":["trace[932825310] 'read index received' (duration: 187.32537ms)","trace[932825310] 'applied index is now lower than readState.Index' (duration: 234.703µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:15:26.457598Z","caller":"traceutil/trace.go:171","msg":"trace[576048934] transaction","detail":"{read_only:false; response_revision:4888; number_of_response:1; }","duration":"189.711972ms","start":"2026-04-11T02:15:26.26785Z","end":"2026-04-11T02:15:26.457562Z","steps":["trace[576048934] 'process raft request' (duration: 189.483029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:15:26.457843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.856667ms","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-11T02:15:26.457889Z","caller":"traceutil/trace.go:171","msg":"trace[1976248752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4888; }","duration":"187.937499ms","start":"2026-04-11T02:15:26.26994Z","end":"2026-04-11T02:15:26.457877Z","steps":["trace[1976248752] 'agreement among raft nodes before linearized reading' (duration: 187.747266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:15:26.457917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.486919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cert-manager-controller-approve:cert-manager-io\" ","response":"range_response_count:1 size:1207"} {"level":"info","ts":"2026-04-11T02:15:26.45825Z","caller":"traceutil/trace.go:171","msg":"trace[1986348924] range","detail":"{range_begin:/registry/clusterrolebindings/cert-manager-controller-approve:cert-manager-io; range_end:; response_count:1; response_revision:4888; }","duration":"148.860954ms","start":"2026-04-11T02:15:26.309363Z","end":"2026-04-11T02:15:26.458223Z","steps":["trace[1986348924] 'agreement among raft nodes before linearized reading' (duration: 148.408327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:15:26.45812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.450132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-04-11T02:15:26.458364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.344553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kube-prometheus-stack-kube-state-metrics\" ","response":"range_response_count:1 size:2307"} {"level":"info","ts":"2026-04-11T02:15:26.458373Z","caller":"traceutil/trace.go:171","msg":"trace[882063397] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:4888; }","duration":"134.726906ms","start":"2026-04-11T02:15:26.323629Z","end":"2026-04-11T02:15:26.458356Z","steps":["trace[882063397] 'agreement among raft nodes before linearized reading' (duration: 134.32974ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:15:26.458476Z","caller":"traceutil/trace.go:171","msg":"trace[2028991931] range","detail":"{range_begin:/registry/clusterroles/kube-prometheus-stack-kube-state-metrics; range_end:; response_count:1; response_revision:4888; }","duration":"137.483615ms","start":"2026-04-11T02:15:26.320966Z","end":"2026-04-11T02:15:26.45845Z","steps":["trace[2028991931] 'agreement among raft nodes before linearized reading' (duration: 137.142131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:15:26.458799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.592415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:125741"} {"level":"info","ts":"2026-04-11T02:15:26.458998Z","caller":"traceutil/trace.go:171","msg":"trace[337122432] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4888; }","duration":"127.187462ms","start":"2026-04-11T02:15:26.331785Z","end":"2026-04-11T02:15:26.458972Z","steps":["trace[337122432] 'agreement among raft nodes before linearized reading' (duration: 126.2873ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:16:02.786658Z","caller":"traceutil/trace.go:171","msg":"trace[419846803] linearizableReadLoop","detail":"{readStateIndex:5356; appliedIndex:5355; }","duration":"141.927614ms","start":"2026-04-11T02:16:02.644715Z","end":"2026-04-11T02:16:02.786642Z","steps":["trace[419846803] 'read index received' (duration: 128.738646ms)","trace[419846803] 'applied index is now lower than readState.Index' (duration: 13.188498ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:16:02.786905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.326579ms","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-11T02:16:02.786924Z","caller":"traceutil/trace.go:171","msg":"trace[659086147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5161; }","duration":"136.40568ms","start":"2026-04-11T02:16:02.650511Z","end":"2026-04-11T02:16:02.786917Z","steps":["trace[659086147] 'agreement among raft nodes before linearized reading' (duration: 136.308639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:16:02.786917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.174288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-04-11T02:16:02.786998Z","caller":"traceutil/trace.go:171","msg":"trace[1754379856] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:5161; }","duration":"142.268759ms","start":"2026-04-11T02:16:02.644709Z","end":"2026-04-11T02:16:02.786977Z","steps":["trace[1754379856] 'agreement among raft nodes before linearized reading' (duration: 142.041696ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:16:02.787292Z","caller":"traceutil/trace.go:171","msg":"trace[249566780] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"147.019952ms","start":"2026-04-11T02:16:02.640264Z","end":"2026-04-11T02:16:02.787284Z","steps":["trace[249566780] 'process raft request' (duration: 133.291987ms)","trace[249566780] 'compare' (duration: 12.61328ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T02:16:02.787455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.075687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-11T02:16:02.787506Z","caller":"traceutil/trace.go:171","msg":"trace[665170434] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5161; }","duration":"125.151098ms","start":"2026-04-11T02:16:02.66234Z","end":"2026-04-11T02:16:02.787491Z","steps":["trace[665170434] 'agreement among raft nodes before linearized reading' (duration: 124.972485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T02:16:02.787812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.630259ms","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-11T02:16:02.787846Z","caller":"traceutil/trace.go:171","msg":"trace[131132477] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5161; }","duration":"135.674409ms","start":"2026-04-11T02:16:02.652161Z","end":"2026-04-11T02:16:02.787835Z","steps":["trace[131132477] 'agreement among raft nodes before linearized reading' (duration: 134.909739ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:16:02.943286Z","caller":"traceutil/trace.go:171","msg":"trace[892085727] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"152.596819ms","start":"2026-04-11T02:16:02.790655Z","end":"2026-04-11T02:16:02.943252Z","steps":["trace[892085727] 'process raft request' (duration: 141.014923ms)","trace[892085727] 'compare' (duration: 11.378483ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T02:16:02.943654Z","caller":"traceutil/trace.go:171","msg":"trace[789932249] transaction","detail":"{read_only:false; response_revision:5164; number_of_response:1; }","duration":"151.829758ms","start":"2026-04-11T02:16:02.791747Z","end":"2026-04-11T02:16:02.943577Z","steps":["trace[789932249] 'process raft request' (duration: 151.482684ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:16:02.943337Z","caller":"traceutil/trace.go:171","msg":"trace[939139577] transaction","detail":"{read_only:false; response_revision:5163; number_of_response:1; }","duration":"152.443836ms","start":"2026-04-11T02:16:02.790848Z","end":"2026-04-11T02:16:02.943292Z","steps":["trace[939139577] 'process raft request' (duration: 152.338705ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T02:16:25.062042Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2898} {"level":"info","ts":"2026-04-11T02:16:25.137502Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2898,"took":"74.340165ms","hash":1452512528,"current-db-size-bytes":51691520,"current-db-size":"52 MB","current-db-size-in-use-bytes":27029504,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-11T02:16:25.13758Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1452512528,"revision":2898,"compact-revision":-1}