{"level":"warn","ts":"2026-04-14T16:05:19.458873Z","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-14T16:05:19.458952Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-14T16:05:19.459017Z","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-14T16:05:19.459025Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-04-14T16:05:19.459045Z","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-14T16:05:19.459625Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-04-14T16:05:19.459706Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-14T16:05:19.46464Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.720212ms"} {"level":"info","ts":"2026-04-14T16:05:19.474801Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-04-14T16:05:19.474873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T16:05:19.474907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-04-14T16:05:19.474917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T16:05:19.474934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-04-14T16:05:19.475019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-04-14T16:05:19.480238Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T16:05:19.481549Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T16:05:19.483273Z","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-14T16:05:19.486163Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T16:05:19.486801Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T16:05:19.486911Z","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-14T16:05:19.487034Z","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-14T16:05:19.487056Z","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-14T16:05:19.488945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-04-14T16:05:19.489672Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-04-14T16:05:19.491868Z","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-14T16:05:19.492377Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-04-14T16:05:19.492423Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-04-14T16:05:19.492425Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T16:05:19.492494Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T16:05:19.57618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T16:05:19.57641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T16:05:19.576478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-04-14T16:05:19.576546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-04-14T16:05:19.57658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-04-14T16:05:19.57661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-04-14T16:05:19.576672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-04-14T16:05:19.577667Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T16:05:19.577796Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T16:05:19.577716Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T16:05:19.577862Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T16:05:19.578093Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T16:05:19.578193Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-14T16:05:19.578789Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T16:05:19.578976Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T16:05:19.579042Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T16:05:19.581221Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-14T16:05:19.581678Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-04-14T16:05:50.103495Z","caller":"traceutil/trace.go:171","msg":"trace[1234525869] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"108.757681ms","start":"2026-04-14T16:05:49.994706Z","end":"2026-04-14T16:05:50.103463Z","steps":["trace[1234525869] 'process raft request' (duration: 86.160687ms)","trace[1234525869] 'compare' (duration: 22.289085ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:05:55.754511Z","caller":"traceutil/trace.go:171","msg":"trace[12652790] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"127.960308ms","start":"2026-04-14T16:05:55.626515Z","end":"2026-04-14T16:05:55.754475Z","steps":["trace[12652790] 'process raft request' (duration: 127.869085ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:05:55.754581Z","caller":"traceutil/trace.go:171","msg":"trace[278312281] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"129.090239ms","start":"2026-04-14T16:05:55.625445Z","end":"2026-04-14T16:05:55.754535Z","steps":["trace[278312281] 'process raft request' (duration: 49.435026ms)","trace[278312281] 'compare' (duration: 79.337564ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:05:55.967398Z","caller":"traceutil/trace.go:171","msg":"trace[431885430] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"136.769585ms","start":"2026-04-14T16:05:55.830579Z","end":"2026-04-14T16:05:55.967349Z","steps":["trace[431885430] 'process raft request' (duration: 134.711278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:05:56.442235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.069991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T16:05:56.442469Z","caller":"traceutil/trace.go:171","msg":"trace[37561403] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"411.907041ms","start":"2026-04-14T16:05:56.030509Z","end":"2026-04-14T16:05:56.442416Z","steps":["trace[37561403] 'process raft request' (duration: 205.788102ms)","trace[37561403] 'compare' (duration: 204.803032ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:05:56.442681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:05:56.03049Z","time spent":"412.091066ms","remote":"127.0.0.1:50244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T16:06:11.069247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.408495ms","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-14T16:06:11.069349Z","caller":"traceutil/trace.go:171","msg":"trace[1396951968] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:731; }","duration":"118.556869ms","start":"2026-04-14T16:06:10.95077Z","end":"2026-04-14T16:06:11.069327Z","steps":["trace[1396951968] 'range keys from in-memory index tree' (duration: 117.951672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:06:59.506016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.857287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:06:59.506105Z","caller":"traceutil/trace.go:171","msg":"trace[154685746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1386; }","duration":"123.98792ms","start":"2026-04-14T16:06:59.382099Z","end":"2026-04-14T16:06:59.506087Z","steps":["trace[154685746] 'range keys from in-memory index tree' (duration: 123.759093ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:07:27.743662Z","caller":"traceutil/trace.go:171","msg":"trace[392117510] linearizableReadLoop","detail":"{readStateIndex:1886; appliedIndex:1885; }","duration":"276.19649ms","start":"2026-04-14T16:07:27.467441Z","end":"2026-04-14T16:07:27.743637Z","steps":["trace[392117510] 'read index received' (duration: 276.107958ms)","trace[392117510] 'applied index is now lower than readState.Index' (duration: 87.652µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:07:27.743737Z","caller":"traceutil/trace.go:171","msg":"trace[1828733958] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"305.144036ms","start":"2026-04-14T16:07:27.438549Z","end":"2026-04-14T16:07:27.743693Z","steps":["trace[1828733958] 'process raft request' (duration: 304.914329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:07:27.743912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.460818ms","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-14T16:07:27.743953Z","caller":"traceutil/trace.go:171","msg":"trace[2144385819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1814; }","duration":"276.554101ms","start":"2026-04-14T16:07:27.467384Z","end":"2026-04-14T16:07:27.743938Z","steps":["trace[2144385819] 'agreement among raft nodes before linearized reading' (duration: 276.378776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:07:27.744344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:07:27.438522Z","time spent":"305.310331ms","remote":"127.0.0.1:49990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10969,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T16:07:27.757422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.411366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:07:27.757486Z","caller":"traceutil/trace.go:171","msg":"trace[1474545364] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1815; }","duration":"164.541729ms","start":"2026-04-14T16:07:27.592932Z","end":"2026-04-14T16:07:27.757474Z","steps":["trace[1474545364] 'agreement among raft nodes before linearized reading' (duration: 164.417406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:07:27.757485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.226186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b1b8a8e4-1090-43b4-825b-65da0e27f156\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-14T16:07:27.757553Z","caller":"traceutil/trace.go:171","msg":"trace[1514067514] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b1b8a8e4-1090-43b4-825b-65da0e27f156; range_end:; response_count:1; response_revision:1815; }","duration":"162.319658ms","start":"2026-04-14T16:07:27.595217Z","end":"2026-04-14T16:07:27.757537Z","steps":["trace[1514067514] 'agreement among raft nodes before linearized reading' (duration: 162.087191ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:07:27.757449Z","caller":"traceutil/trace.go:171","msg":"trace[1008326423] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"197.368234ms","start":"2026-04-14T16:07:27.560055Z","end":"2026-04-14T16:07:27.757423Z","steps":["trace[1008326423] 'process raft request' (duration: 197.029374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:07:28.090657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.255767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T16:07:28.091057Z","caller":"traceutil/trace.go:171","msg":"trace[1918909829] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"342.374814ms","start":"2026-04-14T16:07:27.748633Z","end":"2026-04-14T16:07:28.091008Z","steps":["trace[1918909829] 'process raft request' (duration: 180.624653ms)","trace[1918909829] 'compare' (duration: 160.387643ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:07:28.091317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:07:27.748608Z","time spent":"342.549539ms","remote":"127.0.0.1:50074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T16:07:28.091288Z","caller":"traceutil/trace.go:171","msg":"trace[1716522852] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"337.928268ms","start":"2026-04-14T16:07:27.753295Z","end":"2026-04-14T16:07:28.091223Z","steps":["trace[1716522852] 'process raft request' (duration: 337.617549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:07:28.091509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:07:27.75328Z","time spent":"338.113104ms","remote":"127.0.0.1:49970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T16:07:28.091999Z","caller":"traceutil/trace.go:171","msg":"trace[1158362658] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"338.583107ms","start":"2026-04-14T16:07:27.753365Z","end":"2026-04-14T16:07:28.091948Z","steps":["trace[1158362658] 'process raft request' (duration: 337.681191ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:07:28.092125Z","caller":"traceutil/trace.go:171","msg":"trace[1490876820] linearizableReadLoop","detail":"{readStateIndex:1892; appliedIndex:1887; }","duration":"334.873533ms","start":"2026-04-14T16:07:27.75722Z","end":"2026-04-14T16:07:28.092094Z","steps":["trace[1490876820] 'read index received' (duration: 172.089733ms)","trace[1490876820] 'applied index is now lower than readState.Index' (duration: 162.78227ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:07:28.092191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:07:27.753351Z","time spent":"338.741812ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T16:07:28.092254Z","caller":"traceutil/trace.go:171","msg":"trace[1604118331] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"338.511185ms","start":"2026-04-14T16:07:27.753722Z","end":"2026-04-14T16:07:28.092234Z","steps":["trace[1604118331] 'process raft request' (duration: 338.140775ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:07:28.092274Z","caller":"traceutil/trace.go:171","msg":"trace[443266680] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"338.195436ms","start":"2026-04-14T16:07:27.75401Z","end":"2026-04-14T16:07:28.092206Z","steps":["trace[443266680] 'process raft request' (duration: 337.939489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:07:28.092392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.637484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T16:07:28.092393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.555798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b1b8a8e4-1090-43b4-825b-65da0e27f156\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-04-14T16:07:28.092477Z","caller":"traceutil/trace.go:171","msg":"trace[444451075] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1820; }","duration":"228.812818ms","start":"2026-04-14T16:07:27.863651Z","end":"2026-04-14T16:07:28.092464Z","steps":["trace[444451075] 'agreement among raft nodes before linearized reading' (duration: 228.647194ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:07:28.0925Z","caller":"traceutil/trace.go:171","msg":"trace[1543444942] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b1b8a8e4-1090-43b4-825b-65da0e27f156; range_end:; response_count:1; response_revision:1820; }","duration":"343.679961ms","start":"2026-04-14T16:07:27.748802Z","end":"2026-04-14T16:07:28.092481Z","steps":["trace[1543444942] 'agreement among raft nodes before linearized reading' (duration: 343.450054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:07:28.092596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:07:27.748783Z","time spent":"343.748273ms","remote":"127.0.0.1:49990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b1b8a8e4-1090-43b4-825b-65da0e27f156\" "} {"level":"warn","ts":"2026-04-14T16:07:28.092359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:07:27.75371Z","time spent":"338.603218ms","remote":"127.0.0.1:49970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T16:07:28.092683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.133996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-14T16:07:28.092741Z","caller":"traceutil/trace.go:171","msg":"trace[768436320] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1820; }","duration":"208.224289ms","start":"2026-04-14T16:07:27.884499Z","end":"2026-04-14T16:07:28.092723Z","steps":["trace[768436320] 'agreement among raft nodes before linearized reading' (duration: 207.990592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:07:28.092822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:07:27.754Z","time spent":"338.418383ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T16:08:22.952939Z","caller":"traceutil/trace.go:171","msg":"trace[1075218055] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"119.607296ms","start":"2026-04-14T16:08:22.8333Z","end":"2026-04-14T16:08:22.952907Z","steps":["trace[1075218055] 'process raft request' (duration: 118.867545ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:08:23.25559Z","caller":"traceutil/trace.go:171","msg":"trace[905528877] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"136.930903ms","start":"2026-04-14T16:08:23.118631Z","end":"2026-04-14T16:08:23.255562Z","steps":["trace[905528877] 'process raft request' (duration: 136.751658ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:08:23.37792Z","caller":"traceutil/trace.go:171","msg":"trace[60896795] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2253; }","duration":"164.275413ms","start":"2026-04-14T16:08:23.213617Z","end":"2026-04-14T16:08:23.377892Z","steps":["trace[60896795] 'read index received' (duration: 41.781526ms)","trace[60896795] 'applied index is now lower than readState.Index' (duration: 122.492667ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:08:23.378109Z","caller":"traceutil/trace.go:171","msg":"trace[2134920915] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"186.383854ms","start":"2026-04-14T16:08:23.191698Z","end":"2026-04-14T16:08:23.378082Z","steps":["trace[2134920915] 'process raft request' (duration: 185.143949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:08:23.378141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.510659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-14T16:08:23.378313Z","caller":"traceutil/trace.go:171","msg":"trace[752908873] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2171; }","duration":"164.744155ms","start":"2026-04-14T16:08:23.213555Z","end":"2026-04-14T16:08:23.378299Z","steps":["trace[752908873] 'agreement among raft nodes before linearized reading' (duration: 164.439917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:09:31.647771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.393872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T16:09:31.647981Z","caller":"traceutil/trace.go:171","msg":"trace[98797831] transaction","detail":"{read_only:false; response_revision:2739; number_of_response:1; }","duration":"144.852318ms","start":"2026-04-14T16:09:31.503091Z","end":"2026-04-14T16:09:31.647944Z","steps":["trace[98797831] 'process raft request' (duration: 14.151998ms)","trace[98797831] 'compare' (duration: 130.194096ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:12:02.389399Z","caller":"traceutil/trace.go:171","msg":"trace[1947496917] transaction","detail":"{read_only:false; response_revision:3734; number_of_response:1; }","duration":"100.99629ms","start":"2026-04-14T16:12:02.28837Z","end":"2026-04-14T16:12:02.389366Z","steps":["trace[1947496917] 'process raft request' (duration: 100.820115ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:12:02.415335Z","caller":"traceutil/trace.go:171","msg":"trace[1748949157] transaction","detail":"{read_only:false; response_revision:3735; number_of_response:1; }","duration":"108.622596ms","start":"2026-04-14T16:12:02.306678Z","end":"2026-04-14T16:12:02.4153Z","steps":["trace[1748949157] 'process raft request' (duration: 108.352839ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:13:27.853154Z","caller":"traceutil/trace.go:171","msg":"trace[1518712245] linearizableReadLoop","detail":"{readStateIndex:5113; appliedIndex:5112; }","duration":"118.598334ms","start":"2026-04-14T16:13:27.734538Z","end":"2026-04-14T16:13:27.853136Z","steps":["trace[1518712245] 'read index received' (duration: 118.540322ms)","trace[1518712245] 'applied index is now lower than readState.Index' (duration: 57.092µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:13:27.853341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.788509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-de8227cb-0ea4-42fb-8f13-21c2b20467ce\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-14T16:13:27.853303Z","caller":"traceutil/trace.go:171","msg":"trace[1888265061] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"126.784994ms","start":"2026-04-14T16:13:27.72647Z","end":"2026-04-14T16:13:27.853255Z","steps":["trace[1888265061] 'process raft request' (duration: 126.560568ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:13:27.853379Z","caller":"traceutil/trace.go:171","msg":"trace[1698782068] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-de8227cb-0ea4-42fb-8f13-21c2b20467ce; range_end:; response_count:1; response_revision:4924; }","duration":"118.875281ms","start":"2026-04-14T16:13:27.734494Z","end":"2026-04-14T16:13:27.85337Z","steps":["trace[1698782068] 'agreement among raft nodes before linearized reading' (duration: 118.759248ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:13:28.155728Z","caller":"traceutil/trace.go:171","msg":"trace[1933158043] transaction","detail":"{read_only:false; response_revision:4926; number_of_response:1; }","duration":"107.791389ms","start":"2026-04-14T16:13:28.047902Z","end":"2026-04-14T16:13:28.155693Z","steps":["trace[1933158043] 'process raft request' (duration: 59.166678ms)","trace[1933158043] 'compare' (duration: 48.347583ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:13:28.243525Z","caller":"traceutil/trace.go:171","msg":"trace[1300794399] linearizableReadLoop","detail":"{readStateIndex:5116; appliedIndex:5114; }","duration":"151.655075ms","start":"2026-04-14T16:13:28.091836Z","end":"2026-04-14T16:13:28.243492Z","steps":["trace[1300794399] 'read index received' (duration: 15.258281ms)","trace[1300794399] 'applied index is now lower than readState.Index' (duration: 136.394954ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:13:28.243865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.013226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413130"} {"level":"info","ts":"2026-04-14T16:13:28.243949Z","caller":"traceutil/trace.go:171","msg":"trace[872379720] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:4926; }","duration":"152.14451ms","start":"2026-04-14T16:13:28.091787Z","end":"2026-04-14T16:13:28.243931Z","steps":["trace[872379720] 'agreement among raft nodes before linearized reading' (duration: 151.820011ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:13:45.511056Z","caller":"traceutil/trace.go:171","msg":"trace[299237832] linearizableReadLoop","detail":"{readStateIndex:5494; appliedIndex:5493; }","duration":"129.846516ms","start":"2026-04-14T16:13:45.381175Z","end":"2026-04-14T16:13:45.511021Z","steps":["trace[299237832] 'read index received' (duration: 43.195956ms)","trace[299237832] 'applied index is now lower than readState.Index' (duration: 86.64914ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:13:45.511148Z","caller":"traceutil/trace.go:171","msg":"trace[185767421] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"166.28082ms","start":"2026-04-14T16:13:45.344837Z","end":"2026-04-14T16:13:45.511118Z","steps":["trace[185767421] 'process raft request' (duration: 79.61881ms)","trace[185767421] 'compare' (duration: 86.2933ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:13:45.51125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.050422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:13:45.511297Z","caller":"traceutil/trace.go:171","msg":"trace[585057789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5294; }","duration":"130.184875ms","start":"2026-04-14T16:13:45.3811Z","end":"2026-04-14T16:13:45.511285Z","steps":["trace[585057789] 'agreement among raft nodes before linearized reading' (duration: 130.083462ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:13:45.687797Z","caller":"traceutil/trace.go:171","msg":"trace[1564593195] linearizableReadLoop","detail":"{readStateIndex:5495; appliedIndex:5494; }","duration":"100.370879ms","start":"2026-04-14T16:13:45.587393Z","end":"2026-04-14T16:13:45.687764Z","steps":["trace[1564593195] 'read index received' (duration: 48.104425ms)","trace[1564593195] 'applied index is now lower than readState.Index' (duration: 52.264954ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:13:45.687872Z","caller":"traceutil/trace.go:171","msg":"trace[664208319] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"170.638554ms","start":"2026-04-14T16:13:45.517202Z","end":"2026-04-14T16:13:45.68784Z","steps":["trace[664208319] 'process raft request' (duration: 118.313848ms)","trace[664208319] 'compare' (duration: 52.111919ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:13:45.68811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.692578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-14T16:13:45.688246Z","caller":"traceutil/trace.go:171","msg":"trace[1969692827] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5295; }","duration":"100.897535ms","start":"2026-04-14T16:13:45.587331Z","end":"2026-04-14T16:13:45.688228Z","steps":["trace[1969692827] 'agreement among raft nodes before linearized reading' (duration: 100.604866ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:15:19.933548Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3004} {"level":"info","ts":"2026-04-14T16:15:20.020705Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3004,"took":"85.453167ms","hash":3269381968,"current-db-size-bytes":59269120,"current-db-size":"59 MB","current-db-size-in-use-bytes":32014336,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-14T16:15:20.020787Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3269381968,"revision":3004,"compact-revision":-1} {"level":"info","ts":"2026-04-14T16:17:01.58045Z","caller":"traceutil/trace.go:171","msg":"trace[1888904477] transaction","detail":"{read_only:false; response_revision:6717; number_of_response:1; }","duration":"144.131399ms","start":"2026-04-14T16:17:01.436292Z","end":"2026-04-14T16:17:01.580423Z","steps":["trace[1888904477] 'process raft request' (duration: 125.095782ms)","trace[1888904477] 'compare' (duration: 18.688747ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:17:01.580504Z","caller":"traceutil/trace.go:171","msg":"trace[521411773] transaction","detail":"{read_only:false; response_revision:6718; number_of_response:1; }","duration":"138.247203ms","start":"2026-04-14T16:17:01.442233Z","end":"2026-04-14T16:17:01.58048Z","steps":["trace[521411773] 'process raft request' (duration: 137.962975ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:17:01.580492Z","caller":"traceutil/trace.go:171","msg":"trace[1941617236] linearizableReadLoop","detail":"{readStateIndex:6963; appliedIndex:6961; }","duration":"137.077789ms","start":"2026-04-14T16:17:01.443391Z","end":"2026-04-14T16:17:01.580469Z","steps":["trace[1941617236] 'read index received' (duration: 118.009952ms)","trace[1941617236] 'applied index is now lower than readState.Index' (duration: 19.066427ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:17:01.580678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.277306ms","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-14T16:17:01.580718Z","caller":"traceutil/trace.go:171","msg":"trace[293005416] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6718; }","duration":"137.356138ms","start":"2026-04-14T16:17:01.443351Z","end":"2026-04-14T16:17:01.580707Z","steps":["trace[293005416] 'agreement among raft nodes before linearized reading' (duration: 137.189602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:17:01.580865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.622761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-14T16:17:01.580946Z","caller":"traceutil/trace.go:171","msg":"trace[379207666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6718; }","duration":"134.736154ms","start":"2026-04-14T16:17:01.446189Z","end":"2026-04-14T16:17:01.580926Z","steps":["trace[379207666] 'agreement among raft nodes before linearized reading' (duration: 134.483007ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:17:03.060858Z","caller":"traceutil/trace.go:171","msg":"trace[106444439] transaction","detail":"{read_only:false; response_revision:6727; number_of_response:1; }","duration":"118.935119ms","start":"2026-04-14T16:17:02.941891Z","end":"2026-04-14T16:17:03.060826Z","steps":["trace[106444439] 'process raft request' (duration: 118.592869ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:17:07.728028Z","caller":"traceutil/trace.go:171","msg":"trace[2035321190] transaction","detail":"{read_only:false; response_revision:6758; number_of_response:1; }","duration":"129.349513ms","start":"2026-04-14T16:17:07.598642Z","end":"2026-04-14T16:17:07.727991Z","steps":["trace[2035321190] 'process raft request' (duration: 129.171468ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:17:07.73157Z","caller":"traceutil/trace.go:171","msg":"trace[1901600026] linearizableReadLoop","detail":"{readStateIndex:7005; appliedIndex:7003; }","duration":"125.37642ms","start":"2026-04-14T16:17:07.606178Z","end":"2026-04-14T16:17:07.731554Z","steps":["trace[1901600026] 'read index received' (duration: 121.654555ms)","trace[1901600026] 'applied index is now lower than readState.Index' (duration: 3.720945ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:17:07.731671Z","caller":"traceutil/trace.go:171","msg":"trace[1867744791] transaction","detail":"{read_only:false; response_revision:6759; number_of_response:1; }","duration":"132.039178ms","start":"2026-04-14T16:17:07.599623Z","end":"2026-04-14T16:17:07.731662Z","steps":["trace[1867744791] 'process raft request' (duration: 131.854663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:17:07.731888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.70204ms","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-14T16:17:07.731926Z","caller":"traceutil/trace.go:171","msg":"trace[852795454] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6759; }","duration":"125.790552ms","start":"2026-04-14T16:17:07.606126Z","end":"2026-04-14T16:17:07.731917Z","steps":["trace[852795454] 'agreement among raft nodes before linearized reading' (duration: 125.645658ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:17:08.727707Z","caller":"traceutil/trace.go:171","msg":"trace[1796485170] linearizableReadLoop","detail":"{readStateIndex:7008; appliedIndex:7007; }","duration":"347.547963ms","start":"2026-04-14T16:17:08.380139Z","end":"2026-04-14T16:17:08.727687Z","steps":["trace[1796485170] 'read index received' (duration: 344.462496ms)","trace[1796485170] 'applied index is now lower than readState.Index' (duration: 3.084617ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:17:08.727841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.686457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:17:08.727863Z","caller":"traceutil/trace.go:171","msg":"trace[1578668849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6761; }","duration":"347.753059ms","start":"2026-04-14T16:17:08.380104Z","end":"2026-04-14T16:17:08.727857Z","steps":["trace[1578668849] 'agreement among raft nodes before linearized reading' (duration: 347.694387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:17:08.727888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:17:08.380087Z","time spent":"347.796041ms","remote":"127.0.0.1:49856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-14T16:17:08.728051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.405986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T16:17:08.728069Z","caller":"traceutil/trace.go:171","msg":"trace[1510428928] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:6761; }","duration":"298.445538ms","start":"2026-04-14T16:17:08.429619Z","end":"2026-04-14T16:17:08.728065Z","steps":["trace[1510428928] 'agreement among raft nodes before linearized reading' (duration: 298.410567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:17:08.728177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.231151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:17:08.728195Z","caller":"traceutil/trace.go:171","msg":"trace[1813623714] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:6761; }","duration":"293.259751ms","start":"2026-04-14T16:17:08.434931Z","end":"2026-04-14T16:17:08.728191Z","steps":["trace[1813623714] 'agreement among raft nodes before linearized reading' (duration: 293.23113ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:18:28.277335Z","caller":"traceutil/trace.go:171","msg":"trace[1370936568] transaction","detail":"{read_only:false; response_revision:7415; number_of_response:1; }","duration":"247.231531ms","start":"2026-04-14T16:18:28.030083Z","end":"2026-04-14T16:18:28.277315Z","steps":["trace[1370936568] 'process raft request' (duration: 247.072447ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:18:29.564283Z","caller":"traceutil/trace.go:171","msg":"trace[607452566] transaction","detail":"{read_only:false; response_revision:7429; number_of_response:1; }","duration":"428.19944ms","start":"2026-04-14T16:18:29.136042Z","end":"2026-04-14T16:18:29.564241Z","steps":["trace[607452566] 'process raft request' (duration: 427.859901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:18:29.564567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:18:29.136006Z","time spent":"428.405316ms","remote":"127.0.0.1:50074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T16:18:29.564549Z","caller":"traceutil/trace.go:171","msg":"trace[345192002] linearizableReadLoop","detail":"{readStateIndex:7698; appliedIndex:7698; }","duration":"309.8642ms","start":"2026-04-14T16:18:29.254642Z","end":"2026-04-14T16:18:29.564506Z","steps":["trace[345192002] 'read index received' (duration: 309.846149ms)","trace[345192002] 'applied index is now lower than readState.Index' (duration: 14.21µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:18:29.564956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.271971ms","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-14T16:18:29.565061Z","caller":"traceutil/trace.go:171","msg":"trace[79539758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7429; }","duration":"310.445315ms","start":"2026-04-14T16:18:29.254596Z","end":"2026-04-14T16:18:29.565041Z","steps":["trace[79539758] 'agreement among raft nodes before linearized reading' (duration: 310.104656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:18:29.565304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:18:29.254574Z","time spent":"310.530238ms","remote":"127.0.0.1:50074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T16:18:29.570604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.295735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:18:29.570667Z","caller":"traceutil/trace.go:171","msg":"trace[1657258648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7431; }","duration":"189.405239ms","start":"2026-04-14T16:18:29.381245Z","end":"2026-04-14T16:18:29.57065Z","steps":["trace[1657258648] 'agreement among raft nodes before linearized reading' (duration: 189.294436ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:18:29.570761Z","caller":"traceutil/trace.go:171","msg":"trace[387116767] transaction","detail":"{read_only:false; response_revision:7431; number_of_response:1; }","duration":"119.215447ms","start":"2026-04-14T16:18:29.45146Z","end":"2026-04-14T16:18:29.570676Z","steps":["trace[387116767] 'process raft request' (duration: 118.859807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:18:29.571043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.443343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:18:29.571077Z","caller":"traceutil/trace.go:171","msg":"trace[1499351795] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:7432; }","duration":"102.511104ms","start":"2026-04-14T16:18:29.468554Z","end":"2026-04-14T16:18:29.571065Z","steps":["trace[1499351795] 'agreement among raft nodes before linearized reading' (duration: 102.461713ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:18:29.571136Z","caller":"traceutil/trace.go:171","msg":"trace[1297718245] transaction","detail":"{read_only:false; response_revision:7430; number_of_response:1; }","duration":"186.356681ms","start":"2026-04-14T16:18:29.384765Z","end":"2026-04-14T16:18:29.571122Z","steps":["trace[1297718245] 'process raft request' (duration: 184.929611ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:18:36.728428Z","caller":"traceutil/trace.go:171","msg":"trace[2024782063] linearizableReadLoop","detail":"{readStateIndex:7766; appliedIndex:7765; }","duration":"320.261103ms","start":"2026-04-14T16:18:36.408137Z","end":"2026-04-14T16:18:36.728398Z","steps":["trace[2024782063] 'read index received' (duration: 320.112979ms)","trace[2024782063] 'applied index is now lower than readState.Index' (duration: 147.184µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:18:36.728489Z","caller":"traceutil/trace.go:171","msg":"trace[763915469] transaction","detail":"{read_only:false; response_revision:7495; number_of_response:1; }","duration":"343.357436ms","start":"2026-04-14T16:18:36.3851Z","end":"2026-04-14T16:18:36.728457Z","steps":["trace[763915469] 'process raft request' (duration: 343.14691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:18:36.728737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.582362ms","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-14T16:18:36.728802Z","caller":"traceutil/trace.go:171","msg":"trace[1977384101] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7495; }","duration":"320.698815ms","start":"2026-04-14T16:18:36.408083Z","end":"2026-04-14T16:18:36.728782Z","steps":["trace[1977384101] 'agreement among raft nodes before linearized reading' (duration: 320.431027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:18:36.728886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:18:36.408064Z","time spent":"320.804648ms","remote":"127.0.0.1:50074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T16:18:36.729223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:18:36.385076Z","time spent":"343.485949ms","remote":"127.0.0.1:50074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T16:19:58.042659Z","caller":"traceutil/trace.go:171","msg":"trace[1708058546] transaction","detail":"{read_only:false; response_revision:8161; number_of_response:1; }","duration":"135.415238ms","start":"2026-04-14T16:19:57.907205Z","end":"2026-04-14T16:19:58.04262Z","steps":["trace[1708058546] 'process raft request' (duration: 135.253233ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:19:58.042673Z","caller":"traceutil/trace.go:171","msg":"trace[1083349305] transaction","detail":"{read_only:false; response_revision:8162; number_of_response:1; }","duration":"135.415228ms","start":"2026-04-14T16:19:57.907221Z","end":"2026-04-14T16:19:58.042636Z","steps":["trace[1083349305] 'process raft request' (duration: 135.343936ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:20:09.7606Z","caller":"traceutil/trace.go:171","msg":"trace[1455454011] linearizableReadLoop","detail":"{readStateIndex:8519; appliedIndex:8518; }","duration":"233.722845ms","start":"2026-04-14T16:20:09.52685Z","end":"2026-04-14T16:20:09.760573Z","steps":["trace[1455454011] 'read index received' (duration: 233.55142ms)","trace[1455454011] 'applied index is now lower than readState.Index' (duration: 170.145µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:20:09.760671Z","caller":"traceutil/trace.go:171","msg":"trace[326751554] transaction","detail":"{read_only:false; response_revision:8225; number_of_response:1; }","duration":"250.367226ms","start":"2026-04-14T16:20:09.510286Z","end":"2026-04-14T16:20:09.760654Z","steps":["trace[326751554] 'process raft request' (duration: 250.082448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:20:09.760779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.893169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T16:20:09.760808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.594028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:20:09.760868Z","caller":"traceutil/trace.go:171","msg":"trace[1727363871] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:8225; }","duration":"199.700281ms","start":"2026-04-14T16:20:09.561156Z","end":"2026-04-14T16:20:09.760856Z","steps":["trace[1727363871] 'agreement among raft nodes before linearized reading' (duration: 199.590398ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:20:09.760874Z","caller":"traceutil/trace.go:171","msg":"trace[1465509165] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:8225; }","duration":"234.034414ms","start":"2026-04-14T16:20:09.526818Z","end":"2026-04-14T16:20:09.760852Z","steps":["trace[1465509165] 'agreement among raft nodes before linearized reading' (duration: 233.865729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:20:10.656627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.986932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:20:10.656701Z","caller":"traceutil/trace.go:171","msg":"trace[849979436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8230; }","duration":"276.115476ms","start":"2026-04-14T16:20:10.380567Z","end":"2026-04-14T16:20:10.656683Z","steps":["trace[849979436] 'range keys from in-memory index tree' (duration: 275.863069ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:20:10.676775Z","caller":"traceutil/trace.go:171","msg":"trace[2028644222] transaction","detail":"{read_only:false; response_revision:8231; number_of_response:1; }","duration":"236.580555ms","start":"2026-04-14T16:20:10.440173Z","end":"2026-04-14T16:20:10.676753Z","steps":["trace[2028644222] 'process raft request' (duration: 236.435111ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:20:10.69568Z","caller":"traceutil/trace.go:171","msg":"trace[828920395] transaction","detail":"{read_only:false; response_revision:8232; number_of_response:1; }","duration":"131.848877ms","start":"2026-04-14T16:20:10.563777Z","end":"2026-04-14T16:20:10.695626Z","steps":["trace[828920395] 'process raft request' (duration: 131.586079ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:20:10.69574Z","caller":"traceutil/trace.go:171","msg":"trace[1344058655] transaction","detail":"{read_only:false; response_revision:8233; number_of_response:1; }","duration":"122.513293ms","start":"2026-04-14T16:20:10.573205Z","end":"2026-04-14T16:20:10.695718Z","steps":["trace[1344058655] 'process raft request' (duration: 122.295507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:20:11.510794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.882011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:20:11.510882Z","caller":"traceutil/trace.go:171","msg":"trace[2093584113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8235; }","duration":"130.025405ms","start":"2026-04-14T16:20:11.380837Z","end":"2026-04-14T16:20:11.510863Z","steps":["trace[2093584113] 'range keys from in-memory index tree' (duration: 129.724117ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:20:19.941652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6134} {"level":"info","ts":"2026-04-14T16:20:20.045658Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6134,"took":"101.306511ms","hash":3443059546,"current-db-size-bytes":59269120,"current-db-size":"59 MB","current-db-size-in-use-bytes":22884352,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-14T16:20:20.04575Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3443059546,"revision":6134,"compact-revision":3004} {"level":"info","ts":"2026-04-14T16:20:38.919731Z","caller":"traceutil/trace.go:171","msg":"trace[1805174315] transaction","detail":"{read_only:false; response_revision:8438; number_of_response:1; }","duration":"121.310449ms","start":"2026-04-14T16:20:38.79839Z","end":"2026-04-14T16:20:38.919701Z","steps":["trace[1805174315] 'process raft request' (duration: 121.102923ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:20:38.936765Z","caller":"traceutil/trace.go:171","msg":"trace[119205966] transaction","detail":"{read_only:false; response_revision:8439; number_of_response:1; }","duration":"138.039742ms","start":"2026-04-14T16:20:38.798698Z","end":"2026-04-14T16:20:38.936738Z","steps":["trace[119205966] 'process raft request' (duration: 137.900448ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:20:42.438011Z","caller":"traceutil/trace.go:171","msg":"trace[2131699653] linearizableReadLoop","detail":"{readStateIndex:8757; appliedIndex:8756; }","duration":"109.525584ms","start":"2026-04-14T16:20:42.328463Z","end":"2026-04-14T16:20:42.437989Z","steps":["trace[2131699653] 'read index received' (duration: 28.747004ms)","trace[2131699653] 'applied index is now lower than readState.Index' (duration: 80.77771ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:20:42.438227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.751251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-14T16:20:42.438284Z","caller":"traceutil/trace.go:171","msg":"trace[1525782201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8454; }","duration":"109.859384ms","start":"2026-04-14T16:20:42.328411Z","end":"2026-04-14T16:20:42.43827Z","steps":["trace[1525782201] 'agreement among raft nodes before linearized reading' (duration: 109.677919ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:20:42.43823Z","caller":"traceutil/trace.go:171","msg":"trace[559548442] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"208.741606ms","start":"2026-04-14T16:20:42.229321Z","end":"2026-04-14T16:20:42.438062Z","steps":["trace[559548442] 'process raft request' (duration: 127.935775ms)","trace[559548442] 'compare' (duration: 80.612726ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:20:42.688233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.673315ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T16:20:42.688432Z","caller":"traceutil/trace.go:171","msg":"trace[657473646] transaction","detail":"{read_only:false; response_revision:8457; number_of_response:1; }","duration":"244.713016ms","start":"2026-04-14T16:20:42.4437Z","end":"2026-04-14T16:20:42.688413Z","steps":["trace[657473646] 'process raft request' (duration: 244.642264ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:20:42.688481Z","caller":"traceutil/trace.go:171","msg":"trace[2054515107] transaction","detail":"{read_only:false; response_revision:8456; number_of_response:1; }","duration":"244.789738ms","start":"2026-04-14T16:20:42.443651Z","end":"2026-04-14T16:20:42.688441Z","steps":["trace[2054515107] 'process raft request' (duration: 102.812594ms)","trace[2054515107] 'compare' (duration: 141.525971ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:20:52.534495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.394768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:20:52.534587Z","caller":"traceutil/trace.go:171","msg":"trace[22578351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8548; }","duration":"153.536412ms","start":"2026-04-14T16:20:52.381032Z","end":"2026-04-14T16:20:52.534569Z","steps":["trace[22578351] 'range keys from in-memory index tree' (duration: 153.214093ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:20:52.534574Z","caller":"traceutil/trace.go:171","msg":"trace[2094333546] linearizableReadLoop","detail":"{readStateIndex:8858; appliedIndex:8857; }","duration":"116.660256ms","start":"2026-04-14T16:20:52.417887Z","end":"2026-04-14T16:20:52.534547Z","steps":["trace[2094333546] 'read index received' (duration: 81.112688ms)","trace[2094333546] 'applied index is now lower than readState.Index' (duration: 35.546558ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T16:20:52.534692Z","caller":"traceutil/trace.go:171","msg":"trace[1817723439] transaction","detail":"{read_only:false; response_revision:8549; number_of_response:1; }","duration":"116.912084ms","start":"2026-04-14T16:20:52.417744Z","end":"2026-04-14T16:20:52.534657Z","steps":["trace[1817723439] 'process raft request' (duration: 81.247393ms)","trace[1817723439] 'compare' (duration: 35.420615ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:20:52.534803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.895323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-7prf2\" ","response":"range_response_count:1 size:4647"} {"level":"info","ts":"2026-04-14T16:20:52.534846Z","caller":"traceutil/trace.go:171","msg":"trace[1330812797] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-7prf2; range_end:; response_count:1; response_revision:8549; }","duration":"116.995745ms","start":"2026-04-14T16:20:52.417838Z","end":"2026-04-14T16:20:52.534834Z","steps":["trace[1330812797] 'agreement among raft nodes before linearized reading' (duration: 116.824441ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:20:52.577982Z","caller":"traceutil/trace.go:171","msg":"trace[1789072823] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"109.554835ms","start":"2026-04-14T16:20:52.468401Z","end":"2026-04-14T16:20:52.577956Z","steps":["trace[1789072823] 'process raft request' (duration: 109.227916ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:20:52.578251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.672208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T16:20:52.578376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.544432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T16:20:52.578387Z","caller":"traceutil/trace.go:171","msg":"trace[962319114] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8550; }","duration":"109.855763ms","start":"2026-04-14T16:20:52.468501Z","end":"2026-04-14T16:20:52.578356Z","steps":["trace[962319114] 'agreement among raft nodes before linearized reading' (duration: 109.661828ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:20:52.578428Z","caller":"traceutil/trace.go:171","msg":"trace[1242700292] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:8550; }","duration":"141.645555ms","start":"2026-04-14T16:20:52.436766Z","end":"2026-04-14T16:20:52.578412Z","steps":["trace[1242700292] 'agreement among raft nodes before linearized reading' (duration: 141.48542ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:21:28.474743Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002403.wal"} {"level":"warn","ts":"2026-04-14T16:21:38.344143Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219121061462558643,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-14T16:21:38.417367Z","caller":"traceutil/trace.go:171","msg":"trace[592072643] transaction","detail":"{read_only:false; response_revision:8960; number_of_response:1; }","duration":"662.387362ms","start":"2026-04-14T16:21:37.754954Z","end":"2026-04-14T16:21:38.417341Z","steps":["trace[592072643] 'process raft request' (duration: 662.121474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:21:38.41754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:21:37.754931Z","time spent":"662.523076ms","remote":"127.0.0.1:50074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T16:21:38.422625Z","caller":"traceutil/trace.go:171","msg":"trace[1417383714] linearizableReadLoop","detail":"{readStateIndex:9281; appliedIndex:9279; }","duration":"579.442909ms","start":"2026-04-14T16:21:37.84316Z","end":"2026-04-14T16:21:38.422603Z","steps":["trace[1417383714] 'read index received' (duration: 573.804468ms)","trace[1417383714] 'applied index is now lower than readState.Index' (duration: 5.637021ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:21:38.422873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.397838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"warn","ts":"2026-04-14T16:21:38.422873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.700446ms","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-14T16:21:38.42292Z","caller":"traceutil/trace.go:171","msg":"trace[849858415] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8960; }","duration":"513.48585ms","start":"2026-04-14T16:21:37.909419Z","end":"2026-04-14T16:21:38.422904Z","steps":["trace[849858415] 'agreement among raft nodes before linearized reading' (duration: 513.351567ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:21:38.422941Z","caller":"traceutil/trace.go:171","msg":"trace[83949878] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8960; }","duration":"579.775558ms","start":"2026-04-14T16:21:37.843143Z","end":"2026-04-14T16:21:38.422918Z","steps":["trace[83949878] 'agreement among raft nodes before linearized reading' (duration: 579.569072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:21:38.422966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:21:37.909397Z","time spent":"513.556922ms","remote":"127.0.0.1:49962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-14T16:21:38.423009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:21:37.843074Z","time spent":"579.920802ms","remote":"127.0.0.1:50074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T16:21:38.422935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.529897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/openstack/glance-api\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T16:21:38.423067Z","caller":"traceutil/trace.go:171","msg":"trace[1790056703] range","detail":"{range_begin:/registry/poddisruptionbudgets/openstack/glance-api; range_end:; response_count:0; response_revision:8960; }","duration":"337.688051ms","start":"2026-04-14T16:21:38.08536Z","end":"2026-04-14T16:21:38.423049Z","steps":["trace[1790056703] 'agreement among raft nodes before linearized reading' (duration: 337.508256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:21:38.423124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T16:21:38.085347Z","time spent":"337.765133ms","remote":"127.0.0.1:50112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/openstack/glance-api\" "} {"level":"warn","ts":"2026-04-14T16:22:05.129474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.18768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-14T16:22:05.129603Z","caller":"traceutil/trace.go:171","msg":"trace[223278015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9285; }","duration":"116.403476ms","start":"2026-04-14T16:22:05.013179Z","end":"2026-04-14T16:22:05.129582Z","steps":["trace[223278015] 'range keys from in-memory index tree' (duration: 115.969764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:22:05.129632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.509479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-14T16:22:05.129737Z","caller":"traceutil/trace.go:171","msg":"trace[1301732418] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9285; }","duration":"222.685244ms","start":"2026-04-14T16:22:04.90702Z","end":"2026-04-14T16:22:05.129705Z","steps":["trace[1301732418] 'range keys from in-memory index tree' (duration: 222.188449ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:22:05.252159Z","caller":"traceutil/trace.go:171","msg":"trace[323678929] transaction","detail":"{read_only:false; response_revision:9286; number_of_response:1; }","duration":"116.621823ms","start":"2026-04-14T16:22:05.135513Z","end":"2026-04-14T16:22:05.252135Z","steps":["trace[323678929] 'process raft request' (duration: 116.451059ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:22:05.252574Z","caller":"traceutil/trace.go:171","msg":"trace[304537930] linearizableReadLoop","detail":"{readStateIndex:9614; appliedIndex:9614; }","duration":"115.565823ms","start":"2026-04-14T16:22:05.136976Z","end":"2026-04-14T16:22:05.252542Z","steps":["trace[304537930] 'read index received' (duration: 115.550702ms)","trace[304537930] 'applied index is now lower than readState.Index' (duration: 12.081µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T16:22:05.252837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.85055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19336"} {"level":"info","ts":"2026-04-14T16:22:05.252895Z","caller":"traceutil/trace.go:171","msg":"trace[1463361683] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:9286; }","duration":"115.984364ms","start":"2026-04-14T16:22:05.136896Z","end":"2026-04-14T16:22:05.25288Z","steps":["trace[1463361683] 'agreement among raft nodes before linearized reading' (duration: 115.704397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T16:22:05.261666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.688711ms","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-14T16:22:05.261714Z","caller":"traceutil/trace.go:171","msg":"trace[618007431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9287; }","duration":"118.741483ms","start":"2026-04-14T16:22:05.142961Z","end":"2026-04-14T16:22:05.261703Z","steps":["trace[618007431] 'agreement among raft nodes before linearized reading' (duration: 118.603129ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T16:22:56.883444Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fb85913747d92cac","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T16:22:56.888328Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-14T16:22:56.888406Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-14T16:25:19.949547Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8285} {"level":"info","ts":"2026-04-14T16:25:20.034197Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8285,"took":"81.969234ms","hash":869909682,"current-db-size-bytes":59269120,"current-db-size":"59 MB","current-db-size-in-use-bytes":24858624,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-14T16:25:20.034267Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":869909682,"revision":8285,"compact-revision":6134} {"level":"info","ts":"2026-04-14T16:30:19.957338Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10682} {"level":"info","ts":"2026-04-14T16:30:20.03732Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10682,"took":"77.793324ms","hash":1095722317,"current-db-size-bytes":59269120,"current-db-size":"59 MB","current-db-size-in-use-bytes":20455424,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-14T16:30:20.037379Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1095722317,"revision":10682,"compact-revision":8285}