{"level":"warn","ts":"2026-04-18T09:11:31.830858Z","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-18T09:11:31.830952Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113: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.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-18T09:11:31.831013Z","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-18T09:11:31.831023Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-18T09:11:31.831048Z","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-18T09:11:31.83167Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-04-18T09:11:31.831744Z","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.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-18T09:11:31.835669Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.718875ms"} {"level":"info","ts":"2026-04-18T09:11:31.841404Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-04-18T09:11:31.841477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-04-18T09:11:31.841499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-04-18T09:11:31.841514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-18T09:11:31.841527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-04-18T09:11:31.841569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-04-18T09:11:31.848383Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-18T09:11:31.84962Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-18T09:11:31.851465Z","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-18T09:11:31.853715Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-18T09:11:31.853942Z","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-18T09:11:31.854058Z","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-18T09:11:31.854083Z","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-18T09:11:31.854072Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-18T09:11:31.854945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-04-18T09:11:31.855187Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-18T09:11:31.856273Z","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-18T09:11:31.856377Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-18T09:11:31.856403Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-18T09:11:31.856463Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-18T09:11:31.85651Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-18T09:11:32.042029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-04-18T09:11:32.042108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-04-18T09:11:32.042129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-04-18T09:11:32.042144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-04-18T09:11:32.042171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-18T09:11:32.042289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-04-18T09:11:32.042309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-18T09:11:32.043224Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:11:32.045697Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-04-18T09:11:32.04574Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T09:11:32.045776Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T09:11:32.046119Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:11:32.046139Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-18T09:11:32.046249Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-18T09:11:32.046331Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:11:32.046384Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:11:32.047909Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-18T09:11:32.049485Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-04-18T09:12:08.529725Z","caller":"traceutil/trace.go:171","msg":"trace[1881243478] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"131.383976ms","start":"2026-04-18T09:12:08.398318Z","end":"2026-04-18T09:12:08.529702Z","steps":["trace[1881243478] 'process raft request' (duration: 131.217482ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:08.788318Z","caller":"traceutil/trace.go:171","msg":"trace[1026361225] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"359.066445ms","start":"2026-04-18T09:12:08.429224Z","end":"2026-04-18T09:12:08.788291Z","steps":["trace[1026361225] 'read index received' (duration: 100.458163ms)","trace[1026361225] 'applied index is now lower than readState.Index' (duration: 258.606962ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:12:08.788407Z","caller":"traceutil/trace.go:171","msg":"trace[360812628] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"378.036178ms","start":"2026-04-18T09:12:08.410339Z","end":"2026-04-18T09:12:08.788375Z","steps":["trace[360812628] 'process raft request' (duration: 376.729133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:08.788531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.280629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vfhl8\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-18T09:12:08.788623Z","caller":"traceutil/trace.go:171","msg":"trace[650005999] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vfhl8; range_end:; response_count:1; response_revision:626; }","duration":"359.412372ms","start":"2026-04-18T09:12:08.429197Z","end":"2026-04-18T09:12:08.78861Z","steps":["trace[650005999] 'agreement among raft nodes before linearized reading' (duration: 359.198588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:08.788663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:08.429188Z","time spent":"359.464173ms","remote":"127.0.0.1:41406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-vfhl8\" "} {"level":"warn","ts":"2026-04-18T09:12:08.789137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:08.410312Z","time spent":"378.170501ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:12:09.298025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.903205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:12:09.298197Z","caller":"traceutil/trace.go:171","msg":"trace[1753338708] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"370.243199ms","start":"2026-04-18T09:12:08.927894Z","end":"2026-04-18T09:12:09.298137Z","steps":["trace[1753338708] 'read index received' (duration: 202.016068ms)","trace[1753338708] 'applied index is now lower than readState.Index' (duration: 168.225111ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:12:09.298203Z","caller":"traceutil/trace.go:171","msg":"trace[1513830562] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"450.7136ms","start":"2026-04-18T09:12:08.847421Z","end":"2026-04-18T09:12:09.298134Z","steps":["trace[1513830562] 'process raft request' (duration: 282.506019ms)","trace[1513830562] 'compare' (duration: 167.790533ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:12:09.29841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:08.847392Z","time spent":"450.932066ms","remote":"127.0.0.1:41714","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-18T09:12:09.298514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.558636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:12:09.298609Z","caller":"traceutil/trace.go:171","msg":"trace[11042914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"370.75144ms","start":"2026-04-18T09:12:08.927838Z","end":"2026-04-18T09:12:09.298589Z","steps":["trace[11042914] 'agreement among raft nodes before linearized reading' (duration: 370.453734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:09.298701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:12:08.927815Z","time spent":"370.869241ms","remote":"127.0.0.1:41236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T09:12:10.817092Z","caller":"traceutil/trace.go:171","msg":"trace[108335150] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"154.474668ms","start":"2026-04-18T09:12:10.66259Z","end":"2026-04-18T09:12:10.817065Z","steps":["trace[108335150] 'process raft request' (duration: 154.278914ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:11.497314Z","caller":"traceutil/trace.go:171","msg":"trace[1979924845] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"120.139671ms","start":"2026-04-18T09:12:11.377151Z","end":"2026-04-18T09:12:11.497291Z","steps":["trace[1979924845] 'process raft request' (duration: 119.959488ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:49.994001Z","caller":"traceutil/trace.go:171","msg":"trace[237744305] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"119.650291ms","start":"2026-04-18T09:12:49.874326Z","end":"2026-04-18T09:12:49.993977Z","steps":["trace[237744305] 'process raft request' (duration: 119.523978ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:50.182507Z","caller":"traceutil/trace.go:171","msg":"trace[373828137] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"182.886192ms","start":"2026-04-18T09:12:49.999594Z","end":"2026-04-18T09:12:50.18248Z","steps":["trace[373828137] 'process raft request' (duration: 180.484316ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:13:10.924117Z","caller":"traceutil/trace.go:171","msg":"trace[967513444] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"140.542122ms","start":"2026-04-18T09:13:10.783542Z","end":"2026-04-18T09:13:10.924084Z","steps":["trace[967513444] 'process raft request' (duration: 140.341659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:13:33.70695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.308583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-df1a2730-974f-4da7-801d-09737408a905\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-18T09:13:33.707055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.083923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T09:13:33.707258Z","caller":"traceutil/trace.go:171","msg":"trace[1191222113] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1841; }","duration":"171.362109ms","start":"2026-04-18T09:13:33.535875Z","end":"2026-04-18T09:13:33.707237Z","steps":["trace[1191222113] 'count revisions from in-memory index tree' (duration: 170.85953ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:13:33.70718Z","caller":"traceutil/trace.go:171","msg":"trace[880587282] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-df1a2730-974f-4da7-801d-09737408a905; range_end:; response_count:1; response_revision:1841; }","duration":"147.600479ms","start":"2026-04-18T09:13:33.559546Z","end":"2026-04-18T09:13:33.707147Z","steps":["trace[880587282] 'range keys from in-memory index tree' (duration: 147.075109ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:13:46.485353Z","caller":"traceutil/trace.go:171","msg":"trace[1336493203] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"187.381472ms","start":"2026-04-18T09:13:46.297936Z","end":"2026-04-18T09:13:46.485317Z","steps":["trace[1336493203] 'process raft request' (duration: 187.151528ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:13:46.529604Z","caller":"traceutil/trace.go:171","msg":"trace[1888583585] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"130.885401ms","start":"2026-04-18T09:13:46.398685Z","end":"2026-04-18T09:13:46.529571Z","steps":["trace[1888583585] 'process raft request' (duration: 96.854306ms)","trace[1888583585] 'compare' (duration: 33.886493ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:13:49.262478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.090981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-18T09:13:49.262671Z","caller":"traceutil/trace.go:171","msg":"trace[902345762] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1964; }","duration":"107.344565ms","start":"2026-04-18T09:13:49.155307Z","end":"2026-04-18T09:13:49.262651Z","steps":["trace[902345762] 'range keys from in-memory index tree' (duration: 106.891917ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:14:29.064669Z","caller":"traceutil/trace.go:171","msg":"trace[1082985428] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"186.14899ms","start":"2026-04-18T09:14:28.878487Z","end":"2026-04-18T09:14:29.064636Z","steps":["trace[1082985428] 'process raft request' (duration: 185.971816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:14:29.341906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.596356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:14:29.342246Z","caller":"traceutil/trace.go:171","msg":"trace[1447936990] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"219.91617ms","start":"2026-04-18T09:14:29.122294Z","end":"2026-04-18T09:14:29.34221Z","steps":["trace[1447936990] 'process raft request' (duration: 117.922327ms)","trace[1447936990] 'compare' (duration: 101.459253ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:14:29.342336Z","caller":"traceutil/trace.go:171","msg":"trace[1661432412] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"173.365546ms","start":"2026-04-18T09:14:29.168933Z","end":"2026-04-18T09:14:29.342299Z","steps":["trace[1661432412] 'process raft request' (duration: 173.173883ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:14:29.3423Z","caller":"traceutil/trace.go:171","msg":"trace[2052084517] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"117.003758ms","start":"2026-04-18T09:14:29.225273Z","end":"2026-04-18T09:14:29.342276Z","steps":["trace[2052084517] 'process raft request' (duration: 116.928597ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:18:03.490283Z","caller":"traceutil/trace.go:171","msg":"trace[598906674] transaction","detail":"{read_only:false; response_revision:3730; number_of_response:1; }","duration":"156.052586ms","start":"2026-04-18T09:18:03.334204Z","end":"2026-04-18T09:18:03.490256Z","steps":["trace[598906674] 'process raft request' (duration: 155.871713ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:18:03.492343Z","caller":"traceutil/trace.go:171","msg":"trace[499533605] transaction","detail":"{read_only:false; response_revision:3731; number_of_response:1; }","duration":"157.636175ms","start":"2026-04-18T09:18:03.334665Z","end":"2026-04-18T09:18:03.492301Z","steps":["trace[499533605] 'process raft request' (duration: 157.326739ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:19:30.648313Z","caller":"traceutil/trace.go:171","msg":"trace[587060601] transaction","detail":"{read_only:false; response_revision:4912; number_of_response:1; }","duration":"208.188755ms","start":"2026-04-18T09:19:30.440062Z","end":"2026-04-18T09:19:30.648251Z","steps":["trace[587060601] 'process raft request' (duration: 207.94403ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:19:30.675908Z","caller":"traceutil/trace.go:171","msg":"trace[361572355] linearizableReadLoop","detail":"{readStateIndex:5096; appliedIndex:5094; }","duration":"180.16345ms","start":"2026-04-18T09:19:30.495714Z","end":"2026-04-18T09:19:30.675878Z","steps":["trace[361572355] 'read index received' (duration: 152.369168ms)","trace[361572355] 'applied index is now lower than readState.Index' (duration: 27.793002ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:19:30.676042Z","caller":"traceutil/trace.go:171","msg":"trace[1527795047] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"220.817207ms","start":"2026-04-18T09:19:30.455215Z","end":"2026-04-18T09:19:30.676032Z","steps":["trace[1527795047] 'process raft request' (duration: 220.46976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:19:30.676166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.296637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:19:30.676246Z","caller":"traceutil/trace.go:171","msg":"trace[2040968424] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:4913; }","duration":"173.437059ms","start":"2026-04-18T09:19:30.502792Z","end":"2026-04-18T09:19:30.676229Z","steps":["trace[2040968424] 'agreement among raft nodes before linearized reading' (duration: 173.273796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:19:30.676598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.880644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e8ffda18-d4f6-456b-b406-e5638d3e0823\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-18T09:19:30.676646Z","caller":"traceutil/trace.go:171","msg":"trace[890628075] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e8ffda18-d4f6-456b-b406-e5638d3e0823; range_end:; response_count:1; response_revision:4913; }","duration":"180.968466ms","start":"2026-04-18T09:19:30.495663Z","end":"2026-04-18T09:19:30.676632Z","steps":["trace[890628075] 'agreement among raft nodes before linearized reading' (duration: 180.782272ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:19:42.041706Z","caller":"traceutil/trace.go:171","msg":"trace[1728238648] linearizableReadLoop","detail":"{readStateIndex:5340; appliedIndex:5338; }","duration":"114.438868ms","start":"2026-04-18T09:19:41.92724Z","end":"2026-04-18T09:19:42.041679Z","steps":["trace[1728238648] 'read index received' (duration: 39.152468ms)","trace[1728238648] 'applied index is now lower than readState.Index' (duration: 75.2849ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:19:42.041816Z","caller":"traceutil/trace.go:171","msg":"trace[1587011382] transaction","detail":"{read_only:false; response_revision:5149; number_of_response:1; }","duration":"128.65777ms","start":"2026-04-18T09:19:41.913088Z","end":"2026-04-18T09:19:42.041746Z","steps":["trace[1587011382] 'process raft request' (duration: 128.385835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:19:42.041924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.636792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:19:42.042061Z","caller":"traceutil/trace.go:171","msg":"trace[270621451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5149; }","duration":"114.839706ms","start":"2026-04-18T09:19:41.927193Z","end":"2026-04-18T09:19:42.042032Z","steps":["trace[270621451] 'agreement among raft nodes before linearized reading' (duration: 114.598211ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:19:42.30064Z","caller":"traceutil/trace.go:171","msg":"trace[921058175] transaction","detail":"{read_only:false; response_revision:5150; number_of_response:1; }","duration":"251.891487ms","start":"2026-04-18T09:19:42.048713Z","end":"2026-04-18T09:19:42.300604Z","steps":["trace[921058175] 'process raft request' (duration: 224.624945ms)","trace[921058175] 'compare' (duration: 27.128729ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:19:42.328297Z","caller":"traceutil/trace.go:171","msg":"trace[1109713309] linearizableReadLoop","detail":"{readStateIndex:5344; appliedIndex:5340; }","duration":"242.379936ms","start":"2026-04-18T09:19:42.085887Z","end":"2026-04-18T09:19:42.328267Z","steps":["trace[1109713309] 'read index received' (duration: 187.458955ms)","trace[1109713309] 'applied index is now lower than readState.Index' (duration: 54.919391ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:19:42.328415Z","caller":"traceutil/trace.go:171","msg":"trace[741467476] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"279.549137ms","start":"2026-04-18T09:19:42.048852Z","end":"2026-04-18T09:19:42.328401Z","steps":["trace[741467476] 'process raft request' (duration: 279.213899ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:19:42.328426Z","caller":"traceutil/trace.go:171","msg":"trace[864039344] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"111.096856ms","start":"2026-04-18T09:19:42.217305Z","end":"2026-04-18T09:19:42.328401Z","steps":["trace[864039344] 'process raft request' (duration: 110.892201ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:19:42.328403Z","caller":"traceutil/trace.go:171","msg":"trace[601219211] transaction","detail":"{read_only:false; response_revision:5153; number_of_response:1; }","duration":"279.491565ms","start":"2026-04-18T09:19:42.048852Z","end":"2026-04-18T09:19:42.328343Z","steps":["trace[601219211] 'process raft request' (duration: 279.299701ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:19:42.328301Z","caller":"traceutil/trace.go:171","msg":"trace[817592038] transaction","detail":"{read_only:false; response_revision:5151; number_of_response:1; }","duration":"279.454464ms","start":"2026-04-18T09:19:42.048785Z","end":"2026-04-18T09:19:42.328239Z","steps":["trace[817592038] 'process raft request' (duration: 279.097387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:19:42.328647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.734542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-18T09:19:42.328648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.211251ms","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-04-18T09:19:42.328701Z","caller":"traceutil/trace.go:171","msg":"trace[1250253938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5154; }","duration":"242.852945ms","start":"2026-04-18T09:19:42.085835Z","end":"2026-04-18T09:19:42.328688Z","steps":["trace[1250253938] 'agreement among raft nodes before linearized reading' (duration: 242.637331ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:19:42.328714Z","caller":"traceutil/trace.go:171","msg":"trace[4588892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5154; }","duration":"242.302064ms","start":"2026-04-18T09:19:42.086394Z","end":"2026-04-18T09:19:42.328696Z","steps":["trace[4588892] 'agreement among raft nodes before linearized reading' (duration: 242.10658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:19:42.328733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.503764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-18T09:19:42.328789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.847986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7db8l\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-18T09:19:42.328839Z","caller":"traceutil/trace.go:171","msg":"trace[1575209820] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7db8l; range_end:; response_count:1; response_revision:5154; }","duration":"240.970558ms","start":"2026-04-18T09:19:42.087854Z","end":"2026-04-18T09:19:42.328825Z","steps":["trace[1575209820] 'agreement among raft nodes before linearized reading' (duration: 240.747284ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:19:42.328833Z","caller":"traceutil/trace.go:171","msg":"trace[397540394] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5154; }","duration":"167.683557ms","start":"2026-04-18T09:19:42.161126Z","end":"2026-04-18T09:19:42.328809Z","steps":["trace[397540394] 'agreement among raft nodes before linearized reading' (duration: 167.482953ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:21:32.446622Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3061} {"level":"info","ts":"2026-04-18T09:21:32.535921Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3061,"took":"87.601168ms","hash":944154534,"current-db-size-bytes":59514880,"current-db-size":"60 MB","current-db-size-in-use-bytes":32403456,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-18T09:21:32.536022Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":944154534,"revision":3061,"compact-revision":-1} {"level":"info","ts":"2026-04-18T09:26:17.804707Z","caller":"traceutil/trace.go:171","msg":"trace[1441470640] linearizableReadLoop","detail":"{readStateIndex:8549; appliedIndex:8548; }","duration":"208.131078ms","start":"2026-04-18T09:26:17.596545Z","end":"2026-04-18T09:26:17.804676Z","steps":["trace[1441470640] 'read index received' (duration: 207.898484ms)","trace[1441470640] 'applied index is now lower than readState.Index' (duration: 231.554µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:26:17.804842Z","caller":"traceutil/trace.go:171","msg":"trace[667997577] transaction","detail":"{read_only:false; response_revision:8260; number_of_response:1; }","duration":"224.60181ms","start":"2026-04-18T09:26:17.580201Z","end":"2026-04-18T09:26:17.804803Z","steps":["trace[667997577] 'process raft request' (duration: 224.272294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:26:17.804904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.338142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T09:26:17.804985Z","caller":"traceutil/trace.go:171","msg":"trace[67827076] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:8260; }","duration":"208.447664ms","start":"2026-04-18T09:26:17.596524Z","end":"2026-04-18T09:26:17.804972Z","steps":["trace[67827076] 'agreement among raft nodes before linearized reading' (duration: 208.274881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:26:18.842312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.740258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:26:18.84242Z","caller":"traceutil/trace.go:171","msg":"trace[1134043568] linearizableReadLoop","detail":"{readStateIndex:8555; appliedIndex:8554; }","duration":"375.595888ms","start":"2026-04-18T09:26:18.466804Z","end":"2026-04-18T09:26:18.8424Z","steps":["trace[1134043568] 'read index received' (duration: 17.832456ms)","trace[1134043568] 'applied index is now lower than readState.Index' (duration: 357.762212ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:26:18.842506Z","caller":"traceutil/trace.go:171","msg":"trace[147866427] transaction","detail":"{read_only:false; response_revision:8266; number_of_response:1; }","duration":"376.378804ms","start":"2026-04-18T09:26:18.466115Z","end":"2026-04-18T09:26:18.842493Z","steps":["trace[147866427] 'process raft request' (duration: 166.278329ms)","trace[147866427] 'compare' (duration: 209.604306ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:26:18.842579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:26:18.466102Z","time spent":"376.438895ms","remote":"127.0.0.1:41484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:26:18.842624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.191929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T09:26:18.842661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.817902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:26:18.842712Z","caller":"traceutil/trace.go:171","msg":"trace[965947253] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:8266; }","duration":"279.344872ms","start":"2026-04-18T09:26:18.563348Z","end":"2026-04-18T09:26:18.842693Z","steps":["trace[965947253] 'agreement among raft nodes before linearized reading' (duration: 279.190799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:26:18.84271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.288075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7db8l\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-18T09:26:18.842768Z","caller":"traceutil/trace.go:171","msg":"trace[1210411703] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7db8l; range_end:; response_count:1; response_revision:8266; }","duration":"151.382407ms","start":"2026-04-18T09:26:18.691368Z","end":"2026-04-18T09:26:18.842751Z","steps":["trace[1210411703] 'agreement among raft nodes before linearized reading' (duration: 151.192333ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:26:18.842765Z","caller":"traceutil/trace.go:171","msg":"trace[1338437598] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:8266; }","duration":"375.980336ms","start":"2026-04-18T09:26:18.466766Z","end":"2026-04-18T09:26:18.842747Z","steps":["trace[1338437598] 'agreement among raft nodes before linearized reading' (duration: 375.761692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:26:18.842907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:26:18.466749Z","time spent":"376.142158ms","remote":"127.0.0.1:44988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"info","ts":"2026-04-18T09:26:32.453003Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6221} {"level":"info","ts":"2026-04-18T09:26:32.552763Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6221,"took":"97.385517ms","hash":2230509382,"current-db-size-bytes":59514880,"current-db-size":"60 MB","current-db-size-in-use-bytes":23007232,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-18T09:26:32.552816Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2230509382,"revision":6221,"compact-revision":3061} {"level":"info","ts":"2026-04-18T09:26:47.179774Z","caller":"traceutil/trace.go:171","msg":"trace[1577531779] linearizableReadLoop","detail":"{readStateIndex:8772; appliedIndex:8771; }","duration":"158.841727ms","start":"2026-04-18T09:26:47.020898Z","end":"2026-04-18T09:26:47.17974Z","steps":["trace[1577531779] 'read index received' (duration: 158.561372ms)","trace[1577531779] 'applied index is now lower than readState.Index' (duration: 278.855µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:26:47.179839Z","caller":"traceutil/trace.go:171","msg":"trace[1738052846] transaction","detail":"{read_only:false; response_revision:8473; number_of_response:1; }","duration":"195.255505ms","start":"2026-04-18T09:26:46.984536Z","end":"2026-04-18T09:26:47.179792Z","steps":["trace[1738052846] 'process raft request' (duration: 195.00344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:26:47.180047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.136953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7db8l\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-18T09:26:47.180121Z","caller":"traceutil/trace.go:171","msg":"trace[193442029] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7db8l; range_end:; response_count:1; response_revision:8473; }","duration":"159.271676ms","start":"2026-04-18T09:26:47.020829Z","end":"2026-04-18T09:26:47.180101Z","steps":["trace[193442029] 'agreement among raft nodes before linearized reading' (duration: 159.049732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:26:51.27895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.267374ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-18T09:27:00.149425Z","caller":"traceutil/trace.go:171","msg":"trace[1331621620] transaction","detail":"{read_only:false; response_revision:8580; number_of_response:1; }","duration":"172.359973ms","start":"2026-04-18T09:26:59.97703Z","end":"2026-04-18T09:27:00.14939Z","steps":["trace[1331621620] 'process raft request' (duration: 125.108961ms)","trace[1331621620] 'compare' (duration: 47.091249ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:27:00.167649Z","caller":"traceutil/trace.go:171","msg":"trace[1018810321] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"169.134762ms","start":"2026-04-18T09:26:59.998489Z","end":"2026-04-18T09:27:00.167624Z","steps":["trace[1018810321] 'process raft request' (duration: 168.813346ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:27:00.854085Z","caller":"traceutil/trace.go:171","msg":"trace[1726810113] linearizableReadLoop","detail":"{readStateIndex:8891; appliedIndex:8890; }","duration":"181.279821ms","start":"2026-04-18T09:27:00.672779Z","end":"2026-04-18T09:27:00.854058Z","steps":["trace[1726810113] 'read index received' (duration: 181.111808ms)","trace[1726810113] 'applied index is now lower than readState.Index' (duration: 166.523µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:27:00.854189Z","caller":"traceutil/trace.go:171","msg":"trace[32398635] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"189.787161ms","start":"2026-04-18T09:27:00.664329Z","end":"2026-04-18T09:27:00.854116Z","steps":["trace[32398635] 'process raft request' (duration: 189.553027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:27:00.854389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.595317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-7qfxl\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-04-18T09:27:00.854477Z","caller":"traceutil/trace.go:171","msg":"trace[1891967288] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-7qfxl; range_end:; response_count:1; response_revision:8585; }","duration":"181.75456ms","start":"2026-04-18T09:27:00.672701Z","end":"2026-04-18T09:27:00.854455Z","steps":["trace[1891967288] 'agreement among raft nodes before linearized reading' (duration: 181.490644ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:27:00.927292Z","caller":"traceutil/trace.go:171","msg":"trace[1597615402] transaction","detail":"{read_only:false; response_revision:8586; number_of_response:1; }","duration":"253.594596ms","start":"2026-04-18T09:27:00.673666Z","end":"2026-04-18T09:27:00.927261Z","steps":["trace[1597615402] 'process raft request' (duration: 220.4572ms)","trace[1597615402] 'compare' (duration: 32.664947ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:27:00.927294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.25817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-18T09:27:00.927305Z","caller":"traceutil/trace.go:171","msg":"trace[226886041] transaction","detail":"{read_only:false; response_revision:8587; number_of_response:1; }","duration":"247.149525ms","start":"2026-04-18T09:27:00.680096Z","end":"2026-04-18T09:27:00.927245Z","steps":["trace[226886041] 'process raft request' (duration: 246.892789ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:27:00.927392Z","caller":"traceutil/trace.go:171","msg":"trace[1555408413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8587; }","duration":"227.382172ms","start":"2026-04-18T09:27:00.699981Z","end":"2026-04-18T09:27:00.927364Z","steps":["trace[1555408413] 'agreement among raft nodes before linearized reading' (duration: 227.147648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:27:00.928383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.589229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T09:27:00.928533Z","caller":"traceutil/trace.go:171","msg":"trace[588011886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8587; }","duration":"223.802594ms","start":"2026-04-18T09:27:00.704692Z","end":"2026-04-18T09:27:00.928495Z","steps":["trace[588011886] 'agreement among raft nodes before linearized reading' (duration: 222.422968ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:27:01.082545Z","caller":"traceutil/trace.go:171","msg":"trace[1232151729] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"150.203595ms","start":"2026-04-18T09:27:00.932293Z","end":"2026-04-18T09:27:01.082497Z","steps":["trace[1232151729] 'process raft request' (duration: 105.790777ms)","trace[1232151729] 'compare' (duration: 44.151102ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:27:01.082627Z","caller":"traceutil/trace.go:171","msg":"trace[857302292] transaction","detail":"{read_only:false; response_revision:8589; number_of_response:1; }","duration":"149.027153ms","start":"2026-04-18T09:27:00.933578Z","end":"2026-04-18T09:27:01.082605Z","steps":["trace[857302292] 'process raft request' (duration: 148.85296ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:27:01.082696Z","caller":"traceutil/trace.go:171","msg":"trace[1824959648] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"147.036285ms","start":"2026-04-18T09:27:00.935625Z","end":"2026-04-18T09:27:01.082661Z","steps":["trace[1824959648] 'process raft request' (duration: 146.866112ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:27:01.111209Z","caller":"traceutil/trace.go:171","msg":"trace[4486229] linearizableReadLoop","detail":"{readStateIndex:8896; appliedIndex:8893; }","duration":"165.87067ms","start":"2026-04-18T09:27:00.94531Z","end":"2026-04-18T09:27:01.11118Z","steps":["trace[4486229] 'read index received' (duration: 92.796671ms)","trace[4486229] 'applied index is now lower than readState.Index' (duration: 73.072089ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:27:01.111396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.061094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:27:01.111482Z","caller":"traceutil/trace.go:171","msg":"trace[189791216] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:8590; }","duration":"166.171916ms","start":"2026-04-18T09:27:00.945292Z","end":"2026-04-18T09:27:01.111464Z","steps":["trace[189791216] 'agreement among raft nodes before linearized reading' (duration: 165.988793ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:27:24.379114Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023c6.wal"} {"level":"info","ts":"2026-04-18T09:28:19.619375Z","caller":"traceutil/trace.go:171","msg":"trace[876164381] transaction","detail":"{read_only:false; response_revision:9345; number_of_response:1; }","duration":"115.241685ms","start":"2026-04-18T09:28:19.504113Z","end":"2026-04-18T09:28:19.619355Z","steps":["trace[876164381] 'process raft request' (duration: 115.111012ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:28:19.669061Z","caller":"traceutil/trace.go:171","msg":"trace[1067168604] transaction","detail":"{read_only:false; response_revision:9346; number_of_response:1; }","duration":"164.854501ms","start":"2026-04-18T09:28:19.504184Z","end":"2026-04-18T09:28:19.669039Z","steps":["trace[1067168604] 'process raft request' (duration: 164.755739ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:29:02.991388Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a31e123290fcf0df","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-18T09:29:02.996752Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-18T09:29:02.996834Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-18T09:31:32.461131Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8343} {"level":"info","ts":"2026-04-18T09:31:32.541785Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8343,"took":"77.89099ms","hash":15930539,"current-db-size-bytes":59514880,"current-db-size":"60 MB","current-db-size-in-use-bytes":24911872,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-18T09:31:32.541861Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":15930539,"revision":8343,"compact-revision":6221} {"level":"info","ts":"2026-04-18T09:36:32.468507Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10732} {"level":"info","ts":"2026-04-18T09:36:32.543384Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10732,"took":"72.506557ms","hash":2915457113,"current-db-size-bytes":59514880,"current-db-size":"60 MB","current-db-size-in-use-bytes":20504576,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-18T09:36:32.54346Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2915457113,"revision":10732,"compact-revision":8343}