{"level":"warn","ts":"2026-03-19T08:58:49.958152Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T08:58:49.958259Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.181:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.181:2380","--initial-cluster=instance=https://199.19.213.181:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.181:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.181: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-03-19T08:58:49.961502Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T08:58:49.961526Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.181:2380"]} {"level":"info","ts":"2026-03-19T08:58:49.961563Z","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-03-19T08:58:49.962307Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"]} {"level":"info","ts":"2026-03-19T08:58:49.962388Z","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.19.213.181:2380"],"listen-peer-urls":["https://199.19.213.181:2380"],"advertise-client-urls":["https://199.19.213.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.181: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-03-19T08:58:49.9672Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.551028ms"} {"level":"info","ts":"2026-03-19T08:58:49.974532Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1a532052a1358225","cluster-id":"b34324311008a1d3"} {"level":"info","ts":"2026-03-19T08:58:49.974629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T08:58:49.974654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became follower at term 0"} {"level":"info","ts":"2026-03-19T08:58:49.974688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1a532052a1358225 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T08:58:49.974707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became follower at term 1"} {"level":"info","ts":"2026-03-19T08:58:49.97475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=(1896895407317156389)"} {"level":"warn","ts":"2026-03-19T08:58:49.981674Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T08:58:49.983176Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T08:58:49.985974Z","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-03-19T08:58:49.993708Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1a532052a1358225","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T08:58:49.993868Z","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-03-19T08:58:49.993988Z","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-03-19T08:58:49.994001Z","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-03-19T08:58:49.993991Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1a532052a1358225","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T08:58:49.997497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 switched to configuration voters=(1896895407317156389)"} {"level":"info","ts":"2026-03-19T08:58:49.997692Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b34324311008a1d3","local-member-id":"1a532052a1358225","added-peer-id":"1a532052a1358225","added-peer-peer-urls":["https://199.19.213.181:2380"]} {"level":"info","ts":"2026-03-19T08:58:49.999462Z","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-03-19T08:58:49.99974Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1a532052a1358225","initial-advertise-peer-urls":["https://199.19.213.181:2380"],"listen-peer-urls":["https://199.19.213.181:2380"],"advertise-client-urls":["https://199.19.213.181:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.181:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T08:58:49.999775Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T08:58:49.999869Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.181:2380"} {"level":"info","ts":"2026-03-19T08:58:49.999897Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.181:2380"} {"level":"info","ts":"2026-03-19T08:58:50.075838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T08:58:50.075907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T08:58:50.07595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 received MsgPreVoteResp from 1a532052a1358225 at term 1"} {"level":"info","ts":"2026-03-19T08:58:50.075972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became candidate at term 2"} {"level":"info","ts":"2026-03-19T08:58:50.075984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 received MsgVoteResp from 1a532052a1358225 at term 2"} {"level":"info","ts":"2026-03-19T08:58:50.076186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1a532052a1358225 became leader at term 2"} {"level":"info","ts":"2026-03-19T08:58:50.076215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1a532052a1358225 elected leader 1a532052a1358225 at term 2"} {"level":"info","ts":"2026-03-19T08:58:50.077545Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1a532052a1358225","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.181:2379]}","request-path":"/0/members/1a532052a1358225/attributes","cluster-id":"b34324311008a1d3","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T08:58:50.07755Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T08:58:50.07756Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T08:58:50.077593Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T08:58:50.078149Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T08:58:50.078314Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T08:58:50.078935Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b34324311008a1d3","local-member-id":"1a532052a1358225","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T08:58:50.079128Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T08:58:50.079228Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T08:58:50.081524Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-19T08:58:50.08244Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.181:2379"} {"level":"warn","ts":"2026-03-19T08:59:07.147363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.236491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T08:59:07.147716Z","caller":"traceutil/trace.go:171","msg":"trace[161345849] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"304.587706ms","start":"2026-03-19T08:59:06.843086Z","end":"2026-03-19T08:59:07.147674Z","steps":["trace[161345849] 'process raft request' (duration: 158.252878ms)","trace[161345849] 'compare' (duration: 144.843952ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T08:59:07.147929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:59:06.843066Z","time spent":"304.75512ms","remote":"127.0.0.1:36528","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-03-19T08:59:07.480001Z","caller":"traceutil/trace.go:171","msg":"trace[998182934] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"284.481279ms","start":"2026-03-19T08:59:07.195496Z","end":"2026-03-19T08:59:07.479977Z","steps":["trace[998182934] 'read index received' (duration: 284.247714ms)","trace[998182934] 'applied index is now lower than readState.Index' (duration: 232.425µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:59:07.480087Z","caller":"traceutil/trace.go:171","msg":"trace[571299711] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"314.696134ms","start":"2026-03-19T08:59:07.165348Z","end":"2026-03-19T08:59:07.480044Z","steps":["trace[571299711] 'process raft request' (duration: 314.473349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:59:07.480205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.679444ms","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-03-19T08:59:07.480278Z","caller":"traceutil/trace.go:171","msg":"trace[1296645650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:420; }","duration":"284.770236ms","start":"2026-03-19T08:59:07.195487Z","end":"2026-03-19T08:59:07.480257Z","steps":["trace[1296645650] 'agreement among raft nodes before linearized reading' (duration: 284.626862ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:59:07.480288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T08:59:07.165281Z","time spent":"314.892509ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T08:59:27.719655Z","caller":"traceutil/trace.go:171","msg":"trace[378622859] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"110.246684ms","start":"2026-03-19T08:59:27.609374Z","end":"2026-03-19T08:59:27.71962Z","steps":["trace[378622859] 'read index received' (duration: 110.09693ms)","trace[378622859] 'applied index is now lower than readState.Index' (duration: 148.264µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T08:59:27.71971Z","caller":"traceutil/trace.go:171","msg":"trace[620257299] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"131.121346ms","start":"2026-03-19T08:59:27.588539Z","end":"2026-03-19T08:59:27.71966Z","steps":["trace[620257299] 'process raft request' (duration: 130.88433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:59:27.720072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.672604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dxt9t\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-19T08:59:27.720195Z","caller":"traceutil/trace.go:171","msg":"trace[1399355097] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dxt9t; range_end:; response_count:1; response_revision:557; }","duration":"110.859369ms","start":"2026-03-19T08:59:27.609304Z","end":"2026-03-19T08:59:27.720163Z","steps":["trace[1399355097] 'agreement among raft nodes before linearized reading' (duration: 110.448479ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:59:27.93987Z","caller":"traceutil/trace.go:171","msg":"trace[1120976833] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"205.331296ms","start":"2026-03-19T08:59:27.734496Z","end":"2026-03-19T08:59:27.939827Z","steps":["trace[1120976833] 'process raft request' (duration: 205.098091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T08:59:29.891454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.384053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T08:59:29.891697Z","caller":"traceutil/trace.go:171","msg":"trace[1441168238] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"124.073736ms","start":"2026-03-19T08:59:29.767602Z","end":"2026-03-19T08:59:29.891676Z","steps":["trace[1441168238] 'process raft request' (duration: 124.029505ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:59:29.891741Z","caller":"traceutil/trace.go:171","msg":"trace[71065527] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"125.456908ms","start":"2026-03-19T08:59:29.766223Z","end":"2026-03-19T08:59:29.89168Z","steps":["trace[71065527] 'compare' (duration: 114.183588ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:59:29.891784Z","caller":"traceutil/trace.go:171","msg":"trace[1136908183] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"125.389967ms","start":"2026-03-19T08:59:29.766348Z","end":"2026-03-19T08:59:29.891738Z","steps":["trace[1136908183] 'process raft request' (duration: 125.225753ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:59:29.894171Z","caller":"traceutil/trace.go:171","msg":"trace[1124442217] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"100.92449ms","start":"2026-03-19T08:59:29.793182Z","end":"2026-03-19T08:59:29.894106Z","steps":["trace[1124442217] 'process raft request' (duration: 100.522751ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T08:59:49.964804Z","caller":"traceutil/trace.go:171","msg":"trace[1976853288] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"101.846463ms","start":"2026-03-19T08:59:49.86293Z","end":"2026-03-19T08:59:49.964777Z","steps":["trace[1976853288] 'process raft request' (duration: 101.700629ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:00:41.911058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.07654ms","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-03-19T09:00:41.911162Z","caller":"traceutil/trace.go:171","msg":"trace[240976096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1598; }","duration":"188.278125ms","start":"2026-03-19T09:00:41.722864Z","end":"2026-03-19T09:00:41.911142Z","steps":["trace[240976096] 'range keys from in-memory index tree' (duration: 187.869665ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:26.637602Z","caller":"traceutil/trace.go:171","msg":"trace[1158877741] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"116.02043ms","start":"2026-03-19T09:01:26.521552Z","end":"2026-03-19T09:01:26.637573Z","steps":["trace[1158877741] 'process raft request' (duration: 66.974393ms)","trace[1158877741] 'compare' (duration: 48.953115ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:01:32.555503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.957979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-03-19T09:01:32.55558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.654521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:01:32.555614Z","caller":"traceutil/trace.go:171","msg":"trace[858120305] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1905; }","duration":"118.169034ms","start":"2026-03-19T09:01:32.437425Z","end":"2026-03-19T09:01:32.555594Z","steps":["trace[858120305] 'range keys from in-memory index tree' (duration: 117.697803ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:32.555689Z","caller":"traceutil/trace.go:171","msg":"trace[1004468830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1905; }","duration":"113.812925ms","start":"2026-03-19T09:01:32.441846Z","end":"2026-03-19T09:01:32.555659Z","steps":["trace[1004468830] 'range keys from in-memory index tree' (duration: 113.566339ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:01:32.677433Z","caller":"traceutil/trace.go:171","msg":"trace[852212391] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"116.526087ms","start":"2026-03-19T09:01:32.560858Z","end":"2026-03-19T09:01:32.677384Z","steps":["trace[852212391] 'process raft request' (duration: 97.435157ms)","trace[852212391] 'compare' (duration: 18.869565ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:49.691572Z","caller":"traceutil/trace.go:171","msg":"trace[1233766223] transaction","detail":"{read_only:false; response_revision:2337; number_of_response:1; }","duration":"179.709737ms","start":"2026-03-19T09:02:49.511832Z","end":"2026-03-19T09:02:49.691541Z","steps":["trace[1233766223] 'process raft request' (duration: 179.548352ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:02:55.329264Z","caller":"traceutil/trace.go:171","msg":"trace[1692695618] linearizableReadLoop","detail":"{readStateIndex:2452; appliedIndex:2451; }","duration":"129.113758ms","start":"2026-03-19T09:02:55.200113Z","end":"2026-03-19T09:02:55.329227Z","steps":["trace[1692695618] 'read index received' (duration: 90.486249ms)","trace[1692695618] 'applied index is now lower than readState.Index' (duration: 38.626039ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:02:55.32967Z","caller":"traceutil/trace.go:171","msg":"trace[1308922165] transaction","detail":"{read_only:false; response_revision:2362; number_of_response:1; }","duration":"156.768664ms","start":"2026-03-19T09:02:55.172885Z","end":"2026-03-19T09:02:55.329654Z","steps":["trace[1308922165] 'process raft request' (duration: 118.29855ms)","trace[1308922165] 'compare' (duration: 37.892479ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:02:55.330051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.921789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-19T09:02:55.330095Z","caller":"traceutil/trace.go:171","msg":"trace[1298491412] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2362; }","duration":"130.035143ms","start":"2026-03-19T09:02:55.200049Z","end":"2026-03-19T09:02:55.330084Z","steps":["trace[1298491412] 'agreement among raft nodes before linearized reading' (duration: 129.836097ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:07:05.698555Z","caller":"traceutil/trace.go:171","msg":"trace[1533929813] transaction","detail":"{read_only:false; response_revision:4439; number_of_response:1; }","duration":"267.217526ms","start":"2026-03-19T09:07:05.431306Z","end":"2026-03-19T09:07:05.698523Z","steps":["trace[1533929813] 'process raft request' (duration: 267.035452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:07:05.699045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.474039ms","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-03-19T09:07:05.699115Z","caller":"traceutil/trace.go:171","msg":"trace[1701105473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4439; }","duration":"266.600542ms","start":"2026-03-19T09:07:05.432501Z","end":"2026-03-19T09:07:05.699101Z","steps":["trace[1701105473] 'agreement among raft nodes before linearized reading' (duration: 266.344676ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:07:05.698808Z","caller":"traceutil/trace.go:171","msg":"trace[1164248047] linearizableReadLoop","detail":"{readStateIndex:4608; appliedIndex:4608; }","duration":"266.222574ms","start":"2026-03-19T09:07:05.432554Z","end":"2026-03-19T09:07:05.698776Z","steps":["trace[1164248047] 'read index received' (duration: 266.193933ms)","trace[1164248047] 'applied index is now lower than readState.Index' (duration: 26.161µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:07:05.73389Z","caller":"traceutil/trace.go:171","msg":"trace[1997938174] transaction","detail":"{read_only:false; response_revision:4440; number_of_response:1; }","duration":"240.269592ms","start":"2026-03-19T09:07:05.49359Z","end":"2026-03-19T09:07:05.73386Z","steps":["trace[1997938174] 'process raft request' (duration: 240.034997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:07:05.733932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.969861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-da548ffa-3600-404c-9cc3-1fd170f69b29\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-19T09:07:05.734088Z","caller":"traceutil/trace.go:171","msg":"trace[146762833] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-da548ffa-3600-404c-9cc3-1fd170f69b29; range_end:; response_count:1; response_revision:4440; }","duration":"221.187406ms","start":"2026-03-19T09:07:05.512877Z","end":"2026-03-19T09:07:05.734065Z","steps":["trace[146762833] 'agreement among raft nodes before linearized reading' (duration: 220.88642ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:07:07.362495Z","caller":"traceutil/trace.go:171","msg":"trace[1974485243] linearizableReadLoop","detail":"{readStateIndex:4673; appliedIndex:4672; }","duration":"145.605318ms","start":"2026-03-19T09:07:07.21686Z","end":"2026-03-19T09:07:07.362466Z","steps":["trace[1974485243] 'read index received' (duration: 51.093057ms)","trace[1974485243] 'applied index is now lower than readState.Index' (duration: 94.511271ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:07:07.362526Z","caller":"traceutil/trace.go:171","msg":"trace[528593881] transaction","detail":"{read_only:false; response_revision:4500; number_of_response:1; }","duration":"148.993361ms","start":"2026-03-19T09:07:07.213493Z","end":"2026-03-19T09:07:07.362487Z","steps":["trace[528593881] 'process raft request' (duration: 54.538961ms)","trace[528593881] 'compare' (duration: 94.190124ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:07:07.362719Z","caller":"traceutil/trace.go:171","msg":"trace[2135764933] transaction","detail":"{read_only:false; response_revision:4501; number_of_response:1; }","duration":"144.351242ms","start":"2026-03-19T09:07:07.21832Z","end":"2026-03-19T09:07:07.362671Z","steps":["trace[2135764933] 'process raft request' (duration: 144.081066ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:07:07.362881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.832767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/goldpinger\" ","response":"range_response_count:1 size:799"} {"level":"info","ts":"2026-03-19T09:07:07.362943Z","caller":"traceutil/trace.go:171","msg":"trace[423851555] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/goldpinger; range_end:; response_count:1; response_revision:4501; }","duration":"140.96034ms","start":"2026-03-19T09:07:07.22197Z","end":"2026-03-19T09:07:07.36293Z","steps":["trace[423851555] 'agreement among raft nodes before linearized reading' (duration: 140.738845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:07:07.363119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.240211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-03-19T09:07:07.363187Z","caller":"traceutil/trace.go:171","msg":"trace[1662768701] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4501; }","duration":"146.344103ms","start":"2026-03-19T09:07:07.216825Z","end":"2026-03-19T09:07:07.363169Z","steps":["trace[1662768701] 'agreement among raft nodes before linearized reading' (duration: 145.73877ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:07:15.976624Z","caller":"traceutil/trace.go:171","msg":"trace[733798950] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"106.130878ms","start":"2026-03-19T09:07:15.870462Z","end":"2026-03-19T09:07:15.976593Z","steps":["trace[733798950] 'process raft request' (duration: 35.76884ms)","trace[733798950] 'compare' (duration: 70.163843ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:07:23.398874Z","caller":"traceutil/trace.go:171","msg":"trace[127289826] linearizableReadLoop","detail":"{readStateIndex:5005; appliedIndex:5004; }","duration":"176.602517ms","start":"2026-03-19T09:07:23.22223Z","end":"2026-03-19T09:07:23.398832Z","steps":["trace[127289826] 'read index received' (duration: 176.306231ms)","trace[127289826] 'applied index is now lower than readState.Index' (duration: 294.796µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:07:23.399148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.202376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ebd85052-4428-4a9e-b960-8867e82238f7\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-19T09:07:23.399194Z","caller":"traceutil/trace.go:171","msg":"trace[1997218361] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ebd85052-4428-4a9e-b960-8867e82238f7; range_end:; response_count:1; response_revision:4824; }","duration":"174.284777ms","start":"2026-03-19T09:07:23.224897Z","end":"2026-03-19T09:07:23.399182Z","steps":["trace[1997218361] 'agreement among raft nodes before linearized reading' (duration: 174.115844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:07:23.399188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.941364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cplh8\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-03-19T09:07:23.399211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.117264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:07:23.399282Z","caller":"traceutil/trace.go:171","msg":"trace[1016325326] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:4824; }","duration":"134.192065ms","start":"2026-03-19T09:07:23.265071Z","end":"2026-03-19T09:07:23.399263Z","steps":["trace[1016325326] 'agreement among raft nodes before linearized reading' (duration: 134.087773ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:07:23.399301Z","caller":"traceutil/trace.go:171","msg":"trace[382640408] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cplh8; range_end:; response_count:1; response_revision:4824; }","duration":"177.082187ms","start":"2026-03-19T09:07:23.222179Z","end":"2026-03-19T09:07:23.399261Z","steps":["trace[382640408] 'agreement among raft nodes before linearized reading' (duration: 176.836762ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:07:23.399295Z","caller":"traceutil/trace.go:171","msg":"trace[874481634] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"185.512357ms","start":"2026-03-19T09:07:23.213737Z","end":"2026-03-19T09:07:23.399249Z","steps":["trace[874481634] 'process raft request' (duration: 184.904634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:07:23.40047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.564703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2366191"} {"level":"info","ts":"2026-03-19T09:07:23.400597Z","caller":"traceutil/trace.go:171","msg":"trace[673743570] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:4824; }","duration":"111.712467ms","start":"2026-03-19T09:07:23.288863Z","end":"2026-03-19T09:07:23.400575Z","steps":["trace[673743570] 'agreement among raft nodes before linearized reading' (duration: 110.267505ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:07:23.597637Z","caller":"traceutil/trace.go:171","msg":"trace[575468691] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"190.082224ms","start":"2026-03-19T09:07:23.407528Z","end":"2026-03-19T09:07:23.597611Z","steps":["trace[575468691] 'process raft request' (duration: 189.905211ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:07:23.626372Z","caller":"traceutil/trace.go:171","msg":"trace[319965063] linearizableReadLoop","detail":"{readStateIndex:5007; appliedIndex:5005; }","duration":"195.357405ms","start":"2026-03-19T09:07:23.430992Z","end":"2026-03-19T09:07:23.626349Z","steps":["trace[319965063] 'read index received' (duration: 166.496472ms)","trace[319965063] 'applied index is now lower than readState.Index' (duration: 28.859693ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:07:23.62647Z","caller":"traceutil/trace.go:171","msg":"trace[1474605968] transaction","detail":"{read_only:false; response_revision:4826; number_of_response:1; }","duration":"197.188125ms","start":"2026-03-19T09:07:23.429236Z","end":"2026-03-19T09:07:23.626425Z","steps":["trace[1474605968] 'process raft request' (duration: 196.96833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:07:23.626569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.54895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T09:07:23.626621Z","caller":"traceutil/trace.go:171","msg":"trace[1640765531] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:4826; }","duration":"195.649152ms","start":"2026-03-19T09:07:23.430964Z","end":"2026-03-19T09:07:23.626613Z","steps":["trace[1640765531] 'agreement among raft nodes before linearized reading' (duration: 195.518729ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:07:23.626782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.218902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T09:07:23.626853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.85898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-03-19T09:07:23.626875Z","caller":"traceutil/trace.go:171","msg":"trace[1462685007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4826; }","duration":"186.369105ms","start":"2026-03-19T09:07:23.44049Z","end":"2026-03-19T09:07:23.626859Z","steps":["trace[1462685007] 'agreement among raft nodes before linearized reading' (duration: 186.188381ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:07:23.626933Z","caller":"traceutil/trace.go:171","msg":"trace[1491253107] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4826; }","duration":"190.987333ms","start":"2026-03-19T09:07:23.435929Z","end":"2026-03-19T09:07:23.626916Z","steps":["trace[1491253107] 'agreement among raft nodes before linearized reading' (duration: 190.611495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:07:23.903915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.414507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.181\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-19T09:07:23.903898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.444609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3524"} {"level":"info","ts":"2026-03-19T09:07:23.904001Z","caller":"traceutil/trace.go:171","msg":"trace[1997314120] range","detail":"{range_begin:/registry/masterleases/199.19.213.181; range_end:; response_count:1; response_revision:4826; }","duration":"242.5576ms","start":"2026-03-19T09:07:23.661422Z","end":"2026-03-19T09:07:23.90398Z","steps":["trace[1997314120] 'range keys from in-memory index tree' (duration: 242.198382ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:07:23.904045Z","caller":"traceutil/trace.go:171","msg":"trace[2138689463] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4826; }","duration":"251.654443ms","start":"2026-03-19T09:07:23.652349Z","end":"2026-03-19T09:07:23.904003Z","steps":["trace[2138689463] 'range keys from in-memory index tree' (duration: 251.180523ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:07:31.561398Z","caller":"traceutil/trace.go:171","msg":"trace[2058039341] linearizableReadLoop","detail":"{readStateIndex:5127; appliedIndex:5126; }","duration":"111.925902ms","start":"2026-03-19T09:07:31.449419Z","end":"2026-03-19T09:07:31.561344Z","steps":["trace[2058039341] 'read index received' (duration: 88.165806ms)","trace[2058039341] 'applied index is now lower than readState.Index' (duration: 23.758226ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:07:31.561461Z","caller":"traceutil/trace.go:171","msg":"trace[179985629] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"113.424193ms","start":"2026-03-19T09:07:31.448Z","end":"2026-03-19T09:07:31.561424Z","steps":["trace[179985629] 'process raft request' (duration: 89.560185ms)","trace[179985629] 'compare' (duration: 23.560822ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:07:31.561738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.284849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cplh8\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-19T09:07:31.561842Z","caller":"traceutil/trace.go:171","msg":"trace[1750077665] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cplh8; range_end:; response_count:1; response_revision:4944; }","duration":"112.431522ms","start":"2026-03-19T09:07:31.449389Z","end":"2026-03-19T09:07:31.561821Z","steps":["trace[1750077665] 'agreement among raft nodes before linearized reading' (duration: 112.103945ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:07:35.516562Z","caller":"traceutil/trace.go:171","msg":"trace[1814121330] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"109.993291ms","start":"2026-03-19T09:07:35.406522Z","end":"2026-03-19T09:07:35.516515Z","steps":["trace[1814121330] 'process raft request' (duration: 109.605663ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:08:50.468917Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2625} {"level":"info","ts":"2026-03-19T09:08:50.530177Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2625,"took":"59.266572ms","hash":2353582224,"current-db-size-bytes":40558592,"current-db-size":"41 MB","current-db-size-in-use-bytes":30015488,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-19T09:08:50.530275Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2353582224,"revision":2625,"compact-revision":-1} {"level":"info","ts":"2026-03-19T09:12:57.231104Z","caller":"traceutil/trace.go:171","msg":"trace[1407786256] transaction","detail":"{read_only:false; response_revision:7160; number_of_response:1; }","duration":"101.12863ms","start":"2026-03-19T09:12:57.129938Z","end":"2026-03-19T09:12:57.231066Z","steps":["trace[1407786256] 'process raft request' (duration: 67.516046ms)","trace[1407786256] 'compare' (duration: 33.38353ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:12:58.635869Z","caller":"traceutil/trace.go:171","msg":"trace[429609305] transaction","detail":"{read_only:false; response_revision:7169; number_of_response:1; }","duration":"136.651306ms","start":"2026-03-19T09:12:58.499189Z","end":"2026-03-19T09:12:58.63584Z","steps":["trace[429609305] 'process raft request' (duration: 136.426071ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:13:01.032063Z","caller":"traceutil/trace.go:171","msg":"trace[1834896137] linearizableReadLoop","detail":"{readStateIndex:7466; appliedIndex:7465; }","duration":"132.408764ms","start":"2026-03-19T09:13:00.899627Z","end":"2026-03-19T09:13:01.032035Z","steps":["trace[1834896137] 'read index received' (duration: 116.998677ms)","trace[1834896137] 'applied index is now lower than readState.Index' (duration: 15.408957ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:13:01.032211Z","caller":"traceutil/trace.go:171","msg":"trace[1348495847] transaction","detail":"{read_only:false; response_revision:7204; number_of_response:1; }","duration":"133.203561ms","start":"2026-03-19T09:13:00.898967Z","end":"2026-03-19T09:13:01.032171Z","steps":["trace[1348495847] 'process raft request' (duration: 117.58027ms)","trace[1348495847] 'compare' (duration: 15.291374ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:13:01.03234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.683171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/barbican-api-certs-6pnfd\" ","response":"range_response_count:1 size:2474"} {"level":"info","ts":"2026-03-19T09:13:01.032387Z","caller":"traceutil/trace.go:171","msg":"trace[927953256] range","detail":"{range_begin:/registry/secrets/openstack/barbican-api-certs-6pnfd; range_end:; response_count:1; response_revision:7204; }","duration":"132.768834ms","start":"2026-03-19T09:13:00.899606Z","end":"2026-03-19T09:13:01.032374Z","steps":["trace[927953256] 'agreement among raft nodes before linearized reading' (duration: 132.559778ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:13:01.052215Z","caller":"traceutil/trace.go:171","msg":"trace[994742106] transaction","detail":"{read_only:false; response_revision:7205; number_of_response:1; }","duration":"145.393951ms","start":"2026-03-19T09:13:00.906797Z","end":"2026-03-19T09:13:01.052191Z","steps":["trace[994742106] 'process raft request' (duration: 145.116385ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:13:01.052318Z","caller":"traceutil/trace.go:171","msg":"trace[1340265726] transaction","detail":"{read_only:false; response_revision:7206; number_of_response:1; }","duration":"133.455978ms","start":"2026-03-19T09:13:00.918837Z","end":"2026-03-19T09:13:01.052293Z","steps":["trace[1340265726] 'process raft request' (duration: 133.228123ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:13:01.052539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.931035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3524"} {"level":"warn","ts":"2026-03-19T09:13:01.052568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.650443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"warn","ts":"2026-03-19T09:13:01.05261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.675047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-19T09:13:01.052637Z","caller":"traceutil/trace.go:171","msg":"trace[589448916] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:7206; }","duration":"116.082028ms","start":"2026-03-19T09:13:00.936531Z","end":"2026-03-19T09:13:01.052613Z","steps":["trace[589448916] 'agreement among raft nodes before linearized reading' (duration: 115.67778ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:13:01.052671Z","caller":"traceutil/trace.go:171","msg":"trace[984181708] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:7206; }","duration":"150.771595ms","start":"2026-03-19T09:13:00.901874Z","end":"2026-03-19T09:13:01.052645Z","steps":["trace[984181708] 'agreement among raft nodes before linearized reading' (duration: 150.479779ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:13:01.052687Z","caller":"traceutil/trace.go:171","msg":"trace[781415601] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7206; }","duration":"145.80525ms","start":"2026-03-19T09:13:00.906862Z","end":"2026-03-19T09:13:01.052668Z","steps":["trace[781415601] 'agreement among raft nodes before linearized reading' (duration: 145.427442ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:13:01.053052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.529991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/barbican-api-certs\" ","response":"range_response_count:1 size:4338"} {"level":"info","ts":"2026-03-19T09:13:01.0534Z","caller":"traceutil/trace.go:171","msg":"trace[31792377] range","detail":"{range_begin:/registry/secrets/openstack/barbican-api-certs; range_end:; response_count:1; response_revision:7206; }","duration":"151.92067ms","start":"2026-03-19T09:13:00.901462Z","end":"2026-03-19T09:13:01.053383Z","steps":["trace[31792377] 'agreement among raft nodes before linearized reading' (duration: 150.690754ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:13:01.551837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.076304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T09:13:01.551955Z","caller":"traceutil/trace.go:171","msg":"trace[1956335021] linearizableReadLoop","detail":"{readStateIndex:7474; appliedIndex:7473; }","duration":"348.343745ms","start":"2026-03-19T09:13:01.203593Z","end":"2026-03-19T09:13:01.551937Z","steps":["trace[1956335021] 'read index received' (duration: 60.993586ms)","trace[1956335021] 'applied index is now lower than readState.Index' (duration: 287.348769ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:13:01.552065Z","caller":"traceutil/trace.go:171","msg":"trace[401482793] transaction","detail":"{read_only:false; response_revision:7212; number_of_response:1; }","duration":"351.987864ms","start":"2026-03-19T09:13:01.200063Z","end":"2026-03-19T09:13:01.552051Z","steps":["trace[401482793] 'process raft request' (duration: 64.600304ms)","trace[401482793] 'compare' (duration: 286.953951ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:13:01.552129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:13:01.200045Z","time spent":"352.046325ms","remote":"127.0.0.1:40060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T09:13:01.552219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.615852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T09:13:01.552291Z","caller":"traceutil/trace.go:171","msg":"trace[386874419] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:7212; }","duration":"285.743004ms","start":"2026-03-19T09:13:01.266532Z","end":"2026-03-19T09:13:01.552275Z","steps":["trace[386874419] 'agreement among raft nodes before linearized reading' (duration: 285.561331ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:13:01.55229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.660722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"warn","ts":"2026-03-19T09:13:01.55231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.393938ms","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-03-19T09:13:01.552379Z","caller":"traceutil/trace.go:171","msg":"trace[1071737355] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:7212; }","duration":"348.781394ms","start":"2026-03-19T09:13:01.203573Z","end":"2026-03-19T09:13:01.552354Z","steps":["trace[1071737355] 'agreement among raft nodes before linearized reading' (duration: 348.503388ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:13:01.552398Z","caller":"traceutil/trace.go:171","msg":"trace[603371766] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7212; }","duration":"103.50425ms","start":"2026-03-19T09:13:01.448872Z","end":"2026-03-19T09:13:01.552376Z","steps":["trace[603371766] 'agreement among raft nodes before linearized reading' (duration: 103.240045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:13:01.55243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:13:01.203564Z","time spent":"348.857425ms","remote":"127.0.0.1:36370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1154,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"warn","ts":"2026-03-19T09:13:01.552483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.553502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:13:01.552665Z","caller":"traceutil/trace.go:171","msg":"trace[1112577816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7212; }","duration":"111.800867ms","start":"2026-03-19T09:13:01.440837Z","end":"2026-03-19T09:13:01.552638Z","steps":["trace[1112577816] 'agreement among raft nodes before linearized reading' (duration: 111.508801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:13:01.55313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.01979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2366191"} {"level":"info","ts":"2026-03-19T09:13:01.553191Z","caller":"traceutil/trace.go:171","msg":"trace[1924413811] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:7212; }","duration":"111.116321ms","start":"2026-03-19T09:13:01.44206Z","end":"2026-03-19T09:13:01.553176Z","steps":["trace[1924413811] 'agreement among raft nodes before linearized reading' (duration: 110.038319ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:13:07.579974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.29092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:13:07.580085Z","caller":"traceutil/trace.go:171","msg":"trace[1280787431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7247; }","duration":"138.474934ms","start":"2026-03-19T09:13:07.441586Z","end":"2026-03-19T09:13:07.580061Z","steps":["trace[1280787431] 'range keys from in-memory index tree' (duration: 138.145537ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:13:11.48949Z","caller":"traceutil/trace.go:171","msg":"trace[172297343] transaction","detail":"{read_only:false; response_revision:7271; number_of_response:1; }","duration":"162.123745ms","start":"2026-03-19T09:13:11.32733Z","end":"2026-03-19T09:13:11.489453Z","steps":["trace[172297343] 'process raft request' (duration: 99.502954ms)","trace[172297343] 'compare' (duration: 62.448258ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:13:11.765843Z","caller":"traceutil/trace.go:171","msg":"trace[27028648] linearizableReadLoop","detail":"{readStateIndex:7536; appliedIndex:7534; }","duration":"413.02954ms","start":"2026-03-19T09:13:11.352744Z","end":"2026-03-19T09:13:11.765773Z","steps":["trace[27028648] 'read index received' (duration: 74.103465ms)","trace[27028648] 'applied index is now lower than readState.Index' (duration: 338.924545ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:13:11.766024Z","caller":"traceutil/trace.go:171","msg":"trace[1182249390] transaction","detail":"{read_only:false; response_revision:7272; number_of_response:1; }","duration":"433.71577ms","start":"2026-03-19T09:13:11.332288Z","end":"2026-03-19T09:13:11.766004Z","steps":["trace[1182249390] 'process raft request' (duration: 395.430176ms)","trace[1182249390] 'compare' (duration: 37.760273ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:13:11.766182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.665512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T09:13:11.766182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:13:11.332272Z","time spent":"433.799122ms","remote":"127.0.0.1:36528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:13:11.7663Z","caller":"traceutil/trace.go:171","msg":"trace[1383079450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7272; }","duration":"324.815785ms","start":"2026-03-19T09:13:11.441457Z","end":"2026-03-19T09:13:11.766272Z","steps":["trace[1383079450] 'agreement among raft nodes before linearized reading' (duration: 324.662062ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:13:11.766318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.54852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-19T09:13:11.766354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:13:11.441436Z","time spent":"324.906166ms","remote":"127.0.0.1:36270","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-03-19T09:13:11.766387Z","caller":"traceutil/trace.go:171","msg":"trace[1462484061] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7272; }","duration":"413.684423ms","start":"2026-03-19T09:13:11.352688Z","end":"2026-03-19T09:13:11.766372Z","steps":["trace[1462484061] 'agreement among raft nodes before linearized reading' (duration: 413.467789ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:13:11.766425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:13:11.352663Z","time spent":"413.754855ms","remote":"127.0.0.1:36528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T09:13:11.766714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.656893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-cplh8\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-03-19T09:13:11.766787Z","caller":"traceutil/trace.go:171","msg":"trace[723813808] range","detail":"{range_begin:/registry/pods/openstack/keepalived-cplh8; range_end:; response_count:1; response_revision:7272; }","duration":"135.777765ms","start":"2026-03-19T09:13:11.630993Z","end":"2026-03-19T09:13:11.766771Z","steps":["trace[723813808] 'agreement among raft nodes before linearized reading' (duration: 135.317366ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:13:19.07339Z","caller":"traceutil/trace.go:171","msg":"trace[185759402] linearizableReadLoop","detail":"{readStateIndex:7701; appliedIndex:7700; }","duration":"324.346625ms","start":"2026-03-19T09:13:18.749004Z","end":"2026-03-19T09:13:19.073351Z","steps":["trace[185759402] 'read index received' (duration: 324.016339ms)","trace[185759402] 'applied index is now lower than readState.Index' (duration: 328.546µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:13:19.073536Z","caller":"traceutil/trace.go:171","msg":"trace[794075235] transaction","detail":"{read_only:false; response_revision:7436; number_of_response:1; }","duration":"482.698813ms","start":"2026-03-19T09:13:18.5908Z","end":"2026-03-19T09:13:19.073499Z","steps":["trace[794075235] 'process raft request' (duration: 482.287574ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:13:19.073702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:13:18.590782Z","time spent":"482.832825ms","remote":"127.0.0.1:36528","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-03-19T09:13:19.073716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.671142ms","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-03-19T09:13:19.073766Z","caller":"traceutil/trace.go:171","msg":"trace[1223424774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7436; }","duration":"324.802235ms","start":"2026-03-19T09:13:18.748948Z","end":"2026-03-19T09:13:19.073751Z","steps":["trace[1223424774] 'agreement among raft nodes before linearized reading' (duration: 324.55788ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:13:19.073828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:13:18.748929Z","time spent":"324.889717ms","remote":"127.0.0.1:36528","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":"info","ts":"2026-03-19T09:13:31.299857Z","caller":"traceutil/trace.go:171","msg":"trace[1622715669] transaction","detail":"{read_only:false; response_revision:7494; number_of_response:1; }","duration":"162.569918ms","start":"2026-03-19T09:13:31.137256Z","end":"2026-03-19T09:13:31.299826Z","steps":["trace[1622715669] 'process raft request' (duration: 162.395554ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:13:31.42922Z","caller":"traceutil/trace.go:171","msg":"trace[605965446] linearizableReadLoop","detail":"{readStateIndex:7764; appliedIndex:7762; }","duration":"237.259773ms","start":"2026-03-19T09:13:31.191931Z","end":"2026-03-19T09:13:31.429191Z","steps":["trace[605965446] 'read index received' (duration: 107.777813ms)","trace[605965446] 'applied index is now lower than readState.Index' (duration: 129.48059ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:13:31.429415Z","caller":"traceutil/trace.go:171","msg":"trace[1778990830] transaction","detail":"{read_only:false; response_revision:7495; number_of_response:1; }","duration":"290.316992ms","start":"2026-03-19T09:13:31.13905Z","end":"2026-03-19T09:13:31.429367Z","steps":["trace[1778990830] 'process raft request' (duration: 289.969384ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:13:31.429516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.568879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T09:13:31.429574Z","caller":"traceutil/trace.go:171","msg":"trace[1345882161] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:7495; }","duration":"237.716013ms","start":"2026-03-19T09:13:31.191844Z","end":"2026-03-19T09:13:31.42956Z","steps":["trace[1345882161] 'agreement among raft nodes before linearized reading' (duration: 237.541659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:13:31.429563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.039737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T09:13:31.429658Z","caller":"traceutil/trace.go:171","msg":"trace[1185583454] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:7495; }","duration":"125.2185ms","start":"2026-03-19T09:13:31.304417Z","end":"2026-03-19T09:13:31.429636Z","steps":["trace[1185583454] 'agreement among raft nodes before linearized reading' (duration: 125.022366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:13:32.006667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.17334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-19T09:13:32.006731Z","caller":"traceutil/trace.go:171","msg":"trace[258567618] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7498; }","duration":"107.285602ms","start":"2026-03-19T09:13:31.899432Z","end":"2026-03-19T09:13:32.006718Z","steps":["trace[258567618] 'range keys from in-memory index tree' (duration: 107.012577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:13:32.006717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.013387ms","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-03-19T09:13:32.006807Z","caller":"traceutil/trace.go:171","msg":"trace[1955947340] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7498; }","duration":"108.164311ms","start":"2026-03-19T09:13:31.898622Z","end":"2026-03-19T09:13:32.006787Z","steps":["trace[1955947340] 'range keys from in-memory index tree' (duration: 107.776613ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:13:32.300271Z","caller":"traceutil/trace.go:171","msg":"trace[1492866221] linearizableReadLoop","detail":"{readStateIndex:7768; appliedIndex:7767; }","duration":"288.620546ms","start":"2026-03-19T09:13:32.011623Z","end":"2026-03-19T09:13:32.300243Z","steps":["trace[1492866221] 'read index received' (duration: 288.461663ms)","trace[1492866221] 'applied index is now lower than readState.Index' (duration: 157.273µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:13:32.300303Z","caller":"traceutil/trace.go:171","msg":"trace[1650839215] transaction","detail":"{read_only:false; response_revision:7499; number_of_response:1; }","duration":"288.825161ms","start":"2026-03-19T09:13:32.011442Z","end":"2026-03-19T09:13:32.300267Z","steps":["trace[1650839215] 'process raft request' (duration: 288.625217ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:13:32.300766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.127077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:24 size:207050"} {"level":"info","ts":"2026-03-19T09:13:32.300814Z","caller":"traceutil/trace.go:171","msg":"trace[1992335249] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:24; response_revision:7499; }","duration":"289.214099ms","start":"2026-03-19T09:13:32.011588Z","end":"2026-03-19T09:13:32.300802Z","steps":["trace[1992335249] 'agreement among raft nodes before linearized reading' (duration: 288.77897ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:13:32.383431Z","caller":"traceutil/trace.go:171","msg":"trace[2056395715] transaction","detail":"{read_only:false; response_revision:7501; number_of_response:1; }","duration":"371.262161ms","start":"2026-03-19T09:13:32.012148Z","end":"2026-03-19T09:13:32.383411Z","steps":["trace[2056395715] 'process raft request' (duration: 371.063777ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:13:32.383423Z","caller":"traceutil/trace.go:171","msg":"trace[677221225] transaction","detail":"{read_only:false; response_revision:7500; number_of_response:1; }","duration":"371.517955ms","start":"2026-03-19T09:13:32.011873Z","end":"2026-03-19T09:13:32.383391Z","steps":["trace[677221225] 'process raft request' (duration: 371.204869ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:13:32.383512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.978859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-19T09:13:32.383545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:13:32.012137Z","time spent":"371.347092ms","remote":"127.0.0.1:36528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:13:32.383572Z","caller":"traceutil/trace.go:171","msg":"trace[326283594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7501; }","duration":"164.108542ms","start":"2026-03-19T09:13:32.219448Z","end":"2026-03-19T09:13:32.383556Z","steps":["trace[326283594] 'agreement among raft nodes before linearized reading' (duration: 163.854217ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:13:32.383589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T09:13:32.011859Z","time spent":"371.645309ms","remote":"127.0.0.1:36528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T09:13:33.414272Z","caller":"traceutil/trace.go:171","msg":"trace[1540485563] transaction","detail":"{read_only:false; response_revision:7505; number_of_response:1; }","duration":"102.932971ms","start":"2026-03-19T09:13:33.311317Z","end":"2026-03-19T09:13:33.41425Z","steps":["trace[1540485563] 'process raft request' (duration: 102.765908ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:13:50.475998Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5574} {"level":"info","ts":"2026-03-19T09:13:50.567799Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5574,"took":"88.857164ms","hash":1723866377,"current-db-size-bytes":42717184,"current-db-size":"43 MB","current-db-size-in-use-bytes":22347776,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-03-19T09:13:50.567895Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1723866377,"revision":5574,"compact-revision":2625} {"level":"warn","ts":"2026-03-19T09:13:59.31449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.844988ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-19T09:14:12.361712Z","caller":"traceutil/trace.go:171","msg":"trace[1586357588] transaction","detail":"{read_only:false; response_revision:7788; number_of_response:1; }","duration":"121.80763ms","start":"2026-03-19T09:14:12.239869Z","end":"2026-03-19T09:14:12.361677Z","steps":["trace[1586357588] 'process raft request' (duration: 121.542274ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T09:14:13.11796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.259964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T09:14:13.118115Z","caller":"traceutil/trace.go:171","msg":"trace[701648980] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:7793; }","duration":"137.50803ms","start":"2026-03-19T09:14:12.980578Z","end":"2026-03-19T09:14:13.118086Z","steps":["trace[701648980] 'count revisions from in-memory index tree' (duration: 137.082961ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:14:13.988825Z","caller":"traceutil/trace.go:171","msg":"trace[1489619053] transaction","detail":"{read_only:false; response_revision:7799; number_of_response:1; }","duration":"120.744187ms","start":"2026-03-19T09:14:13.868039Z","end":"2026-03-19T09:14:13.988784Z","steps":["trace[1489619053] 'process raft request' (duration: 39.178347ms)","trace[1489619053] 'compare' (duration: 81.404266ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:15:21.122463Z","caller":"traceutil/trace.go:171","msg":"trace[1162529872] linearizableReadLoop","detail":"{readStateIndex:8703; appliedIndex:8702; }","duration":"104.948523ms","start":"2026-03-19T09:15:21.01748Z","end":"2026-03-19T09:15:21.122428Z","steps":["trace[1162529872] 'read index received' (duration: 59.418563ms)","trace[1162529872] 'applied index is now lower than readState.Index' (duration: 45.52877ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T09:15:21.122851Z","caller":"traceutil/trace.go:171","msg":"trace[598182912] transaction","detail":"{read_only:false; response_revision:8402; number_of_response:1; }","duration":"106.546927ms","start":"2026-03-19T09:15:21.016269Z","end":"2026-03-19T09:15:21.122816Z","steps":["trace[598182912] 'process raft request' (duration: 60.610158ms)","trace[598182912] 'compare' (duration: 45.427388ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T09:15:21.123013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.519275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:28 size:244650"} {"level":"info","ts":"2026-03-19T09:15:21.123063Z","caller":"traceutil/trace.go:171","msg":"trace[1268504955] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:28; response_revision:8402; }","duration":"105.637829ms","start":"2026-03-19T09:15:21.017413Z","end":"2026-03-19T09:15:21.123051Z","steps":["trace[1268504955] 'agreement among raft nodes before linearized reading' (duration: 105.151507ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T09:18:07.042271Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1a532052a1358225","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-19T09:18:07.04832Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-19T09:18:07.048443Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-19T09:18:50.482705Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7593} {"level":"info","ts":"2026-03-19T09:18:50.551868Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7593,"took":"66.872112ms","hash":1568233128,"current-db-size-bytes":42717184,"current-db-size":"43 MB","current-db-size-in-use-bytes":25268224,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-19T09:18:50.551931Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1568233128,"revision":7593,"compact-revision":5574} {"level":"info","ts":"2026-03-19T09:23:50.488348Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9877} {"level":"info","ts":"2026-03-19T09:23:50.560321Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9877,"took":"70.2458ms","hash":4206985363,"current-db-size-bytes":42717184,"current-db-size":"43 MB","current-db-size-in-use-bytes":16580608,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-19T09:23:50.560379Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4206985363,"revision":9877,"compact-revision":7593}