{"level":"warn","ts":"2026-05-05T06:41:19.465098Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T06:41:19.465189Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242: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.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242: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-05-05T06:41:19.465253Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T06:41:19.465265Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-05-05T06:41:19.465283Z","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-05-05T06:41:19.466118Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-05-05T06:41:19.466195Z","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.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242: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-05-05T06:41:20.001811Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"373.161241ms"} {"level":"info","ts":"2026-05-05T06:41:20.028892Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-05-05T06:41:20.0291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T06:41:20.029197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-05-05T06:41:20.02929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T06:41:20.029385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-05-05T06:41:20.029461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-05-05T06:41:20.037146Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T06:41:20.038639Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T06:41:20.039912Z","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-05-05T06:41:20.042035Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T06:41:20.042232Z","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-05-05T06:41:20.042347Z","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-05-05T06:41:20.0424Z","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-05-05T06:41:20.042291Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T06:41:20.053581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-05-05T06:41:20.053709Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-05-05T06:41:20.054801Z","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-05-05T06:41:20.054999Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T06:41:20.055023Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T06:41:20.055064Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-05-05T06:41:20.055084Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-05-05T06:41:20.630394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T06:41:20.630458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T06:41:20.630501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-05-05T06:41:20.630522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-05-05T06:41:20.630535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-05-05T06:41:20.630564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-05-05T06:41:20.630579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-05-05T06:41:20.631591Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T06:41:20.631769Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T06:41:20.631887Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T06:41:20.63189Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:41:20.632997Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:41:20.633139Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:41:20.633171Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T06:41:20.635732Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T06:41:20.635903Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-05-05T06:41:20.64185Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T06:41:20.641875Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T06:41:52.656676Z","caller":"traceutil/trace.go:171","msg":"trace[1939480154] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"156.87055ms","start":"2026-05-05T06:41:52.499777Z","end":"2026-05-05T06:41:52.656648Z","steps":["trace[1939480154] 'process raft request' (duration: 144.727345ms)","trace[1939480154] 'compare' (duration: 11.85899ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:41:52.659759Z","caller":"traceutil/trace.go:171","msg":"trace[1624595844] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"112.370551ms","start":"2026-05-05T06:41:52.547374Z","end":"2026-05-05T06:41:52.659744Z","steps":["trace[1624595844] 'process raft request' (duration: 110.502549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:41:52.913619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.188135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-05-05T06:41:52.913683Z","caller":"traceutil/trace.go:171","msg":"trace[1204125043] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:617; }","duration":"148.284814ms","start":"2026-05-05T06:41:52.765386Z","end":"2026-05-05T06:41:52.913671Z","steps":["trace[1204125043] 'range keys from in-memory index tree' (duration: 148.045646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:41:52.914575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.359098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:41:52.914812Z","caller":"traceutil/trace.go:171","msg":"trace[702890530] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"129.476914ms","start":"2026-05-05T06:41:52.785323Z","end":"2026-05-05T06:41:52.9148Z","steps":["trace[702890530] 'compare' (duration: 122.21914ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:41:59.987012Z","caller":"traceutil/trace.go:171","msg":"trace[1220437533] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"181.241497ms","start":"2026-05-05T06:41:59.805744Z","end":"2026-05-05T06:41:59.986986Z","steps":["trace[1220437533] 'read index received' (duration: 181.072479ms)","trace[1220437533] 'applied index is now lower than readState.Index' (duration: 167.468µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:41:59.987308Z","caller":"traceutil/trace.go:171","msg":"trace[297082694] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"462.380927ms","start":"2026-05-05T06:41:59.524916Z","end":"2026-05-05T06:41:59.987297Z","steps":["trace[297082694] 'process raft request' (duration: 461.858314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:41:59.987423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:41:59.52489Z","time spent":"462.445716ms","remote":"127.0.0.1:54608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:41:59.987722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.974007ms","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-05-05T06:41:59.987765Z","caller":"traceutil/trace.go:171","msg":"trace[285242537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:683; }","duration":"182.056215ms","start":"2026-05-05T06:41:59.805698Z","end":"2026-05-05T06:41:59.987755Z","steps":["trace[285242537] 'agreement among raft nodes before linearized reading' (duration: 181.896837ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:42:42.581941Z","caller":"traceutil/trace.go:171","msg":"trace[391596467] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1218; }","duration":"348.909353ms","start":"2026-05-05T06:42:42.233008Z","end":"2026-05-05T06:42:42.581918Z","steps":["trace[391596467] 'read index received' (duration: 348.754115ms)","trace[391596467] 'applied index is now lower than readState.Index' (duration: 153.798µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:42:42.58208Z","caller":"traceutil/trace.go:171","msg":"trace[89032775] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"351.815066ms","start":"2026-05-05T06:42:42.230233Z","end":"2026-05-05T06:42:42.582048Z","steps":["trace[89032775] 'process raft request' (duration: 351.53791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:42:42.582266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.242853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-05T06:42:42.582283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:42:42.230205Z","time spent":"351.981905ms","remote":"127.0.0.1:54686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:42:42.58233Z","caller":"traceutil/trace.go:171","msg":"trace[176687315] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1191; }","duration":"349.341118ms","start":"2026-05-05T06:42:42.232978Z","end":"2026-05-05T06:42:42.582319Z","steps":["trace[176687315] 'agreement among raft nodes before linearized reading' (duration: 349.160978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:42:42.582368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:42:42.232966Z","time spent":"349.392571ms","remote":"127.0.0.1:54686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T06:42:42.58233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.041592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:42:42.582446Z","caller":"traceutil/trace.go:171","msg":"trace[10883184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1191; }","duration":"164.225773ms","start":"2026-05-05T06:42:42.418193Z","end":"2026-05-05T06:42:42.582419Z","steps":["trace[10883184] 'agreement among raft nodes before linearized reading' (duration: 164.004881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:42:42.586097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.202773ms","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-05-05T06:42:42.586163Z","caller":"traceutil/trace.go:171","msg":"trace[2096827678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1191; }","duration":"102.31013ms","start":"2026-05-05T06:42:42.483839Z","end":"2026-05-05T06:42:42.586149Z","steps":["trace[2096827678] 'agreement among raft nodes before linearized reading' (duration: 98.867489ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:29.077986Z","caller":"traceutil/trace.go:171","msg":"trace[676406611] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"116.383533ms","start":"2026-05-05T06:43:28.961567Z","end":"2026-05-05T06:43:29.077951Z","steps":["trace[676406611] 'process raft request' (duration: 85.638506ms)","trace[676406611] 'compare' (duration: 30.564892ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:43:29.078053Z","caller":"traceutil/trace.go:171","msg":"trace[14716028] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"111.35491ms","start":"2026-05-05T06:43:28.966659Z","end":"2026-05-05T06:43:29.078013Z","steps":["trace[14716028] 'process raft request' (duration: 111.250667ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:29.730319Z","caller":"traceutil/trace.go:171","msg":"trace[1760560957] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"581.786535ms","start":"2026-05-05T06:43:29.148507Z","end":"2026-05-05T06:43:29.730293Z","steps":["trace[1760560957] 'process raft request' (duration: 581.61064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:29.730509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:29.148475Z","time spent":"581.933109ms","remote":"127.0.0.1:54608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10929,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:43:29.732617Z","caller":"traceutil/trace.go:171","msg":"trace[1039049248] linearizableReadLoop","detail":"{readStateIndex:1924; appliedIndex:1922; }","duration":"477.056542ms","start":"2026-05-05T06:43:29.255527Z","end":"2026-05-05T06:43:29.732584Z","steps":["trace[1039049248] 'read index received' (duration: 474.601309ms)","trace[1039049248] 'applied index is now lower than readState.Index' (duration: 2.453233ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:43:29.732993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.856828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T06:43:29.733046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.672664ms","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-05-05T06:43:29.733068Z","caller":"traceutil/trace.go:171","msg":"trace[1364216213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1859; }","duration":"309.004421ms","start":"2026-05-05T06:43:29.424047Z","end":"2026-05-05T06:43:29.733051Z","steps":["trace[1364216213] 'agreement among raft nodes before linearized reading' (duration: 308.859668ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:29.733166Z","caller":"traceutil/trace.go:171","msg":"trace[1829794553] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1859; }","duration":"118.764806ms","start":"2026-05-05T06:43:29.614352Z","end":"2026-05-05T06:43:29.733117Z","steps":["trace[1829794553] 'agreement among raft nodes before linearized reading' (duration: 118.510369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:29.733045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.319633ms","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-05-05T06:43:29.733276Z","caller":"traceutil/trace.go:171","msg":"trace[445527582] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1859; }","duration":"437.5944ms","start":"2026-05-05T06:43:29.295667Z","end":"2026-05-05T06:43:29.733262Z","steps":["trace[445527582] 'agreement among raft nodes before linearized reading' (duration: 437.18093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:29.733651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:29.295623Z","time spent":"437.748094ms","remote":"127.0.0.1:54686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-05T06:43:29.733804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:29.424021Z","time spent":"309.168566ms","remote":"127.0.0.1:54398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T06:43:29.734783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.388461ms","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-05-05T06:43:29.736902Z","caller":"traceutil/trace.go:171","msg":"trace[731991887] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1859; }","duration":"481.366884ms","start":"2026-05-05T06:43:29.255513Z","end":"2026-05-05T06:43:29.73688Z","steps":["trace[731991887] 'agreement among raft nodes before linearized reading' (duration: 477.228747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:29.736959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:43:29.255478Z","time spent":"481.465386ms","remote":"127.0.0.1:59278","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":"info","ts":"2026-05-05T06:43:42.033452Z","caller":"traceutil/trace.go:171","msg":"trace[195021676] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"140.436461ms","start":"2026-05-05T06:43:41.892981Z","end":"2026-05-05T06:43:42.033417Z","steps":["trace[195021676] 'process raft request' (duration: 140.248827ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:42.033526Z","caller":"traceutil/trace.go:171","msg":"trace[2042795455] linearizableReadLoop","detail":"{readStateIndex:2033; appliedIndex:2032; }","duration":"128.162401ms","start":"2026-05-05T06:43:41.905193Z","end":"2026-05-05T06:43:42.033355Z","steps":["trace[2042795455] 'read index received' (duration: 127.997327ms)","trace[2042795455] 'applied index is now lower than readState.Index' (duration: 164.054µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:43:42.033748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.528938ms","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-05-05T06:43:42.033845Z","caller":"traceutil/trace.go:171","msg":"trace[1139858088] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1965; }","duration":"128.705802ms","start":"2026-05-05T06:43:41.905117Z","end":"2026-05-05T06:43:42.033823Z","steps":["trace[1139858088] 'agreement among raft nodes before linearized reading' (duration: 128.370875ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:43:45.190516Z","caller":"traceutil/trace.go:171","msg":"trace[800941035] linearizableReadLoop","detail":"{readStateIndex:2057; appliedIndex:2056; }","duration":"136.139413ms","start":"2026-05-05T06:43:45.054357Z","end":"2026-05-05T06:43:45.190496Z","steps":["trace[800941035] 'read index received' (duration: 136.065221ms)","trace[800941035] 'applied index is now lower than readState.Index' (duration: 73.252µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:43:45.190658Z","caller":"traceutil/trace.go:171","msg":"trace[39206720] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"163.842242ms","start":"2026-05-05T06:43:45.026779Z","end":"2026-05-05T06:43:45.190621Z","steps":["trace[39206720] 'process raft request' (duration: 163.593628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:43:45.190806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.427428ms","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-05-05T06:43:45.19086Z","caller":"traceutil/trace.go:171","msg":"trace[889403574] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1988; }","duration":"136.540801ms","start":"2026-05-05T06:43:45.054306Z","end":"2026-05-05T06:43:45.190847Z","steps":["trace[889403574] 'agreement among raft nodes before linearized reading' (duration: 136.294176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:44:19.170637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.433097ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T06:44:19.171131Z","caller":"traceutil/trace.go:171","msg":"trace[1445702017] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"405.193541ms","start":"2026-05-05T06:44:18.765907Z","end":"2026-05-05T06:44:19.1711Z","steps":["trace[1445702017] 'process raft request' (duration: 404.839397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:44:19.171247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:44:18.765882Z","time spent":"405.302332ms","remote":"127.0.0.1:54686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T06:45:00.674273Z","caller":"traceutil/trace.go:171","msg":"trace[1784201364] transaction","detail":"{read_only:false; response_revision:2510; number_of_response:1; }","duration":"156.492431ms","start":"2026-05-05T06:45:00.517755Z","end":"2026-05-05T06:45:00.674247Z","steps":["trace[1784201364] 'process raft request' (duration: 156.31814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:45:32.056105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.835819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T06:45:32.056272Z","caller":"traceutil/trace.go:171","msg":"trace[1286382704] linearizableReadLoop","detail":"{readStateIndex:2775; appliedIndex:2774; }","duration":"358.551292ms","start":"2026-05-05T06:45:31.697655Z","end":"2026-05-05T06:45:32.056206Z","steps":["trace[1286382704] 'read index received' (duration: 17.58µs)","trace[1286382704] 'applied index is now lower than readState.Index' (duration: 358.532482ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:45:32.056638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.969873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:45:32.056607Z","caller":"traceutil/trace.go:171","msg":"trace[1759990385] transaction","detail":"{read_only:false; response_revision:2682; number_of_response:1; }","duration":"375.95626ms","start":"2026-05-05T06:45:31.680599Z","end":"2026-05-05T06:45:32.056555Z","steps":["trace[1759990385] 'process raft request' (duration: 14.58818ms)","trace[1759990385] 'compare' (duration: 360.708359ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:45:32.056685Z","caller":"traceutil/trace.go:171","msg":"trace[1602285661] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:2682; }","duration":"359.042023ms","start":"2026-05-05T06:45:31.69763Z","end":"2026-05-05T06:45:32.056672Z","steps":["trace[1602285661] 'agreement among raft nodes before linearized reading' (duration: 358.937583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:45:32.056761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:45:31.697623Z","time spent":"359.125103ms","remote":"127.0.0.1:59408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":19,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-05-05T06:45:32.056826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:45:31.680576Z","time spent":"376.13323ms","remote":"127.0.0.1:54686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T06:45:32.057077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.761428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T06:45:32.057181Z","caller":"traceutil/trace.go:171","msg":"trace[1183255927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2682; }","duration":"258.889988ms","start":"2026-05-05T06:45:31.798263Z","end":"2026-05-05T06:45:32.057153Z","steps":["trace[1183255927] 'agreement among raft nodes before linearized reading' (duration: 258.619486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:45:32.057303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.535298ms","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-05-05T06:45:32.057349Z","caller":"traceutil/trace.go:171","msg":"trace[1667918665] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2682; }","duration":"251.617458ms","start":"2026-05-05T06:45:31.805717Z","end":"2026-05-05T06:45:32.057335Z","steps":["trace[1667918665] 'agreement among raft nodes before linearized reading' (duration: 251.516418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:45:32.057391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.399203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-05-05T06:45:32.057442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.215543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T06:45:32.057575Z","caller":"traceutil/trace.go:171","msg":"trace[1480987142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2682; }","duration":"290.320874ms","start":"2026-05-05T06:45:31.767177Z","end":"2026-05-05T06:45:32.057498Z","steps":["trace[1480987142] 'agreement among raft nodes before linearized reading' (duration: 290.065263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:45:32.057588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.216606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T06:45:32.057628Z","caller":"traceutil/trace.go:171","msg":"trace[1544611378] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2682; }","duration":"105.308776ms","start":"2026-05-05T06:45:31.952309Z","end":"2026-05-05T06:45:32.057618Z","steps":["trace[1544611378] 'agreement among raft nodes before linearized reading' (duration: 105.135895ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:45:32.057512Z","caller":"traceutil/trace.go:171","msg":"trace[520639839] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2682; }","duration":"264.597004ms","start":"2026-05-05T06:45:31.792896Z","end":"2026-05-05T06:45:32.057493Z","steps":["trace[520639839] 'agreement among raft nodes before linearized reading' (duration: 264.220303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:45:32.058366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.533175ms","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-05-05T06:45:32.058422Z","caller":"traceutil/trace.go:171","msg":"trace[66244648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2682; }","duration":"352.608725ms","start":"2026-05-05T06:45:31.705799Z","end":"2026-05-05T06:45:32.058408Z","steps":["trace[66244648] 'agreement among raft nodes before linearized reading' (duration: 351.966613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:45:32.058496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:45:31.705788Z","time spent":"352.698506ms","remote":"127.0.0.1:54686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T06:45:32.452818Z","caller":"traceutil/trace.go:171","msg":"trace[1083627815] linearizableReadLoop","detail":"{readStateIndex:2780; appliedIndex:2779; }","duration":"200.522458ms","start":"2026-05-05T06:45:32.252262Z","end":"2026-05-05T06:45:32.452784Z","steps":["trace[1083627815] 'read index received' (duration: 200.329148ms)","trace[1083627815] 'applied index is now lower than readState.Index' (duration: 192.27µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:45:32.452871Z","caller":"traceutil/trace.go:171","msg":"trace[203938894] transaction","detail":"{read_only:false; response_revision:2687; number_of_response:1; }","duration":"201.164019ms","start":"2026-05-05T06:45:32.251668Z","end":"2026-05-05T06:45:32.452832Z","steps":["trace[203938894] 'process raft request' (duration: 200.959499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:45:32.453142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.861919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:45:32.453247Z","caller":"traceutil/trace.go:171","msg":"trace[1232150740] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2687; }","duration":"201.07766ms","start":"2026-05-05T06:45:32.252137Z","end":"2026-05-05T06:45:32.453214Z","steps":["trace[1232150740] 'agreement among raft nodes before linearized reading' (duration: 200.797549ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:20.892415Z","caller":"traceutil/trace.go:171","msg":"trace[663525362] transaction","detail":"{read_only:false; response_revision:4597; number_of_response:1; }","duration":"125.450591ms","start":"2026-05-05T06:49:20.76694Z","end":"2026-05-05T06:49:20.89239Z","steps":["trace[663525362] 'process raft request' (duration: 125.320139ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:20.897622Z","caller":"traceutil/trace.go:171","msg":"trace[358305470] transaction","detail":"{read_only:false; response_revision:4598; number_of_response:1; }","duration":"120.78477ms","start":"2026-05-05T06:49:20.776822Z","end":"2026-05-05T06:49:20.897607Z","steps":["trace[358305470] 'process raft request' (duration: 120.682858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:26.472062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.250267ms","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-05-05T06:49:26.472175Z","caller":"traceutil/trace.go:171","msg":"trace[1730034956] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4701; }","duration":"294.43248ms","start":"2026-05-05T06:49:26.177716Z","end":"2026-05-05T06:49:26.472148Z","steps":["trace[1730034956] 'range keys from in-memory index tree' (duration: 293.994733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:26.472245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.965808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-05-05T06:49:26.472362Z","caller":"traceutil/trace.go:171","msg":"trace[945148925] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4701; }","duration":"344.08747ms","start":"2026-05-05T06:49:26.128246Z","end":"2026-05-05T06:49:26.472333Z","steps":["trace[945148925] 'range keys from in-memory index tree' (duration: 343.851377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:26.472401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:26.128216Z","time spent":"344.174952ms","remote":"127.0.0.1:54508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":1,"response size":1365,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" "} {"level":"info","ts":"2026-05-05T06:49:26.640531Z","caller":"traceutil/trace.go:171","msg":"trace[1202187970] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"147.597246ms","start":"2026-05-05T06:49:26.492908Z","end":"2026-05-05T06:49:26.640506Z","steps":["trace[1202187970] 'process raft request' (duration: 147.487184ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:26.640771Z","caller":"traceutil/trace.go:171","msg":"trace[1290722663] linearizableReadLoop","detail":"{readStateIndex:4874; appliedIndex:4874; }","duration":"129.283246ms","start":"2026-05-05T06:49:26.511448Z","end":"2026-05-05T06:49:26.640732Z","steps":["trace[1290722663] 'read index received' (duration: 129.268066ms)","trace[1290722663] 'applied index is now lower than readState.Index' (duration: 11.92µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:49:26.641049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.596593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config\" ","response":"range_response_count:1 size:696"} {"level":"info","ts":"2026-05-05T06:49:26.64109Z","caller":"traceutil/trace.go:171","msg":"trace[1990065572] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-web-config; range_end:; response_count:1; response_revision:4703; }","duration":"129.683374ms","start":"2026-05-05T06:49:26.511395Z","end":"2026-05-05T06:49:26.641078Z","steps":["trace[1990065572] 'agreement among raft nodes before linearized reading' (duration: 129.483361ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:26.653877Z","caller":"traceutil/trace.go:171","msg":"trace[875407802] transaction","detail":"{read_only:false; response_revision:4704; number_of_response:1; }","duration":"109.996181ms","start":"2026-05-05T06:49:26.543865Z","end":"2026-05-05T06:49:26.653861Z","steps":["trace[875407802] 'process raft request' (duration: 109.814708ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:26.653931Z","caller":"traceutil/trace.go:171","msg":"trace[9628766] transaction","detail":"{read_only:false; response_revision:4705; number_of_response:1; }","duration":"109.796438ms","start":"2026-05-05T06:49:26.544098Z","end":"2026-05-05T06:49:26.653895Z","steps":["trace[9628766] 'process raft request' (duration: 109.695336ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:27.102246Z","caller":"traceutil/trace.go:171","msg":"trace[706358842] linearizableReadLoop","detail":"{readStateIndex:4878; appliedIndex:4877; }","duration":"286.989255ms","start":"2026-05-05T06:49:26.815233Z","end":"2026-05-05T06:49:27.102222Z","steps":["trace[706358842] 'read index received' (duration: 259.853699ms)","trace[706358842] 'applied index is now lower than readState.Index' (duration: 27.134236ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:49:27.102539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.28948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"} {"level":"info","ts":"2026-05-05T06:49:27.102573Z","caller":"traceutil/trace.go:171","msg":"trace[1156794825] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4707; }","duration":"287.358731ms","start":"2026-05-05T06:49:26.815204Z","end":"2026-05-05T06:49:27.102563Z","steps":["trace[1156794825] 'agreement among raft nodes before linearized reading' (duration: 287.132017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:27.10259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.82703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-05T06:49:27.102751Z","caller":"traceutil/trace.go:171","msg":"trace[521112285] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4707; }","duration":"280.935231ms","start":"2026-05-05T06:49:26.821717Z","end":"2026-05-05T06:49:27.102652Z","steps":["trace[521112285] 'agreement among raft nodes before linearized reading' (duration: 280.692557ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:27.102752Z","caller":"traceutil/trace.go:171","msg":"trace[433708925] transaction","detail":"{read_only:false; response_revision:4707; number_of_response:1; }","duration":"317.445981ms","start":"2026-05-05T06:49:26.784862Z","end":"2026-05-05T06:49:27.102308Z","steps":["trace[433708925] 'process raft request' (duration: 290.156352ms)","trace[433708925] 'compare' (duration: 26.930093ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:49:27.102909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T06:49:26.784844Z","time spent":"317.99398ms","remote":"127.0.0.1:59378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2230,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T06:49:27.103087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.281049ms","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-05-05T06:49:27.103148Z","caller":"traceutil/trace.go:171","msg":"trace[1276211272] 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:4707; }","duration":"255.376001ms","start":"2026-05-05T06:49:26.847755Z","end":"2026-05-05T06:49:27.103131Z","steps":["trace[1276211272] 'agreement among raft nodes before linearized reading' (duration: 255.223888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:27.103146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.669742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-44d4a026-8fd2-4ea4-bc84-fc071402025d\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-05T06:49:27.103267Z","caller":"traceutil/trace.go:171","msg":"trace[90703537] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-44d4a026-8fd2-4ea4-bc84-fc071402025d; range_end:; response_count:1; response_revision:4707; }","duration":"266.810254ms","start":"2026-05-05T06:49:26.836438Z","end":"2026-05-05T06:49:27.103248Z","steps":["trace[90703537] 'agreement among raft nodes before linearized reading' (duration: 266.618021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:27.103368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.566912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5548bdbdc4-drmjt.18ac989ffc0e8bb5\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-05-05T06:49:27.103428Z","caller":"traceutil/trace.go:171","msg":"trace[725375609] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5548bdbdc4-drmjt.18ac989ffc0e8bb5; range_end:; response_count:1; response_revision:4707; }","duration":"281.634094ms","start":"2026-05-05T06:49:26.821776Z","end":"2026-05-05T06:49:27.10341Z","steps":["trace[725375609] 'agreement among raft nodes before linearized reading' (duration: 281.39727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:27.104203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.871746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194901"} {"level":"info","ts":"2026-05-05T06:49:27.106398Z","caller":"traceutil/trace.go:171","msg":"trace[882478678] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4707; }","duration":"171.093004ms","start":"2026-05-05T06:49:26.935277Z","end":"2026-05-05T06:49:27.10637Z","steps":["trace[882478678] 'agreement among raft nodes before linearized reading' (duration: 167.770878ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:27.292965Z","caller":"traceutil/trace.go:171","msg":"trace[934034790] linearizableReadLoop","detail":"{readStateIndex:4880; appliedIndex:4879; }","duration":"144.254475ms","start":"2026-05-05T06:49:27.148684Z","end":"2026-05-05T06:49:27.292938Z","steps":["trace[934034790] 'read index received' (duration: 132.129882ms)","trace[934034790] 'applied index is now lower than readState.Index' (duration: 12.122903ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:49:27.293097Z","caller":"traceutil/trace.go:171","msg":"trace[1132442284] transaction","detail":"{read_only:false; response_revision:4709; number_of_response:1; }","duration":"176.855403ms","start":"2026-05-05T06:49:27.11623Z","end":"2026-05-05T06:49:27.293085Z","steps":["trace[1132442284] 'process raft request' (duration: 164.647428ms)","trace[1132442284] 'compare' (duration: 11.89622ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T06:49:27.293437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.854674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-05T06:49:27.293485Z","caller":"traceutil/trace.go:171","msg":"trace[389481065] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4709; }","duration":"182.968567ms","start":"2026-05-05T06:49:27.110501Z","end":"2026-05-05T06:49:27.29347Z","steps":["trace[389481065] 'agreement among raft nodes before linearized reading' (duration: 182.761954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:27.293802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.737737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T06:49:27.293891Z","caller":"traceutil/trace.go:171","msg":"trace[1086664073] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:4709; }","duration":"102.86336ms","start":"2026-05-05T06:49:27.191005Z","end":"2026-05-05T06:49:27.293868Z","steps":["trace[1086664073] 'agreement among raft nodes before linearized reading' (duration: 102.732158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T06:49:27.29397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.207409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-05-05T06:49:27.294049Z","caller":"traceutil/trace.go:171","msg":"trace[1895676782] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4709; }","duration":"136.333182ms","start":"2026-05-05T06:49:27.157697Z","end":"2026-05-05T06:49:27.29403Z","steps":["trace[1895676782] 'agreement among raft nodes before linearized reading' (duration: 135.996536ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:29.979508Z","caller":"traceutil/trace.go:171","msg":"trace[1642726535] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"111.077255ms","start":"2026-05-05T06:49:29.868394Z","end":"2026-05-05T06:49:29.979472Z","steps":["trace[1642726535] 'process raft request' (duration: 110.913602ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:39.22366Z","caller":"traceutil/trace.go:171","msg":"trace[351235273] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"105.862478ms","start":"2026-05-05T06:49:39.117772Z","end":"2026-05-05T06:49:39.223634Z","steps":["trace[351235273] 'process raft request' (duration: 30.057681ms)","trace[351235273] 'compare' (duration: 75.474232ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T06:49:39.224165Z","caller":"traceutil/trace.go:171","msg":"trace[1134908657] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"106.240495ms","start":"2026-05-05T06:49:39.117897Z","end":"2026-05-05T06:49:39.224138Z","steps":["trace[1134908657] 'process raft request' (duration: 105.568264ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:49:39.224599Z","caller":"traceutil/trace.go:171","msg":"trace[2078019854] transaction","detail":"{read_only:false; response_revision:4880; number_of_response:1; }","duration":"105.750926ms","start":"2026-05-05T06:49:39.118735Z","end":"2026-05-05T06:49:39.224486Z","steps":["trace[2078019854] 'process raft request' (duration: 104.828781ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T06:51:20.659621Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2940} {"level":"info","ts":"2026-05-05T06:51:20.749599Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2940,"took":"88.070988ms","hash":585634101,"current-db-size-bytes":54161408,"current-db-size":"54 MB","current-db-size-in-use-bytes":29351936,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-05T06:51:20.749663Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":585634101,"revision":2940,"compact-revision":-1} {"level":"info","ts":"2026-05-05T06:56:20.666834Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5678} {"level":"info","ts":"2026-05-05T06:56:20.7551Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5678,"took":"86.927017ms","hash":543407457,"current-db-size-bytes":54161408,"current-db-size":"54 MB","current-db-size-in-use-bytes":18173952,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-05T06:56:20.755158Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":543407457,"revision":5678,"compact-revision":2940}