{"level":"warn","ts":"2026-04-08T10:41:49.405389Z","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-08T10:41:49.405501Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.151: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.151:2380","--initial-cluster=instance=https://199.19.213.151:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.151:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.151: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-08T10:41:49.405585Z","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-08T10:41:49.405599Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.151:2380"]}
{"level":"info","ts":"2026-04-08T10:41:49.405622Z","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-08T10:41:49.406321Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.151:2379"]}
{"level":"info","ts":"2026-04-08T10:41:49.406395Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.151:2380"],"listen-peer-urls":["https://199.19.213.151:2380"],"advertise-client-urls":["https://199.19.213.151:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.151:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.151: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-08T10:41:49.411657Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.316808ms"}
{"level":"info","ts":"2026-04-08T10:41:49.41994Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9abf795dae4ebc2c","cluster-id":"8a186cf11cef5bce"}
{"level":"info","ts":"2026-04-08T10:41:49.420061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c switched to configuration voters=()"}
{"level":"info","ts":"2026-04-08T10:41:49.420097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became follower at term 0"}
{"level":"info","ts":"2026-04-08T10:41:49.420113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9abf795dae4ebc2c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-04-08T10:41:49.42013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became follower at term 1"}
{"level":"info","ts":"2026-04-08T10:41:49.420184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c switched to configuration voters=(11150764645655952428)"}
{"level":"warn","ts":"2026-04-08T10:41:49.428386Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-04-08T10:41:49.431027Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-04-08T10:41:49.43397Z","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-08T10:41:49.437017Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9abf795dae4ebc2c","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-04-08T10:41:49.437187Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9abf795dae4ebc2c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-04-08T10:41:49.437225Z","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-08T10:41:49.437303Z","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-08T10:41:49.43732Z","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-08T10:41:49.439162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c switched to configuration voters=(11150764645655952428)"}
{"level":"info","ts":"2026-04-08T10:41:49.439418Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8a186cf11cef5bce","local-member-id":"9abf795dae4ebc2c","added-peer-id":"9abf795dae4ebc2c","added-peer-peer-urls":["https://199.19.213.151:2380"]}
{"level":"info","ts":"2026-04-08T10:41:49.442058Z","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-08T10:41:49.442254Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.151:2380"}
{"level":"info","ts":"2026-04-08T10:41:49.442303Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.151:2380"}
{"level":"info","ts":"2026-04-08T10:41:49.443516Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9abf795dae4ebc2c","initial-advertise-peer-urls":["https://199.19.213.151:2380"],"listen-peer-urls":["https://199.19.213.151:2380"],"advertise-client-urls":["https://199.19.213.151:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.151:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-04-08T10:41:49.443549Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-04-08T10:41:50.220705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c is starting a new election at term 1"}
{"level":"info","ts":"2026-04-08T10:41:50.2208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became pre-candidate at term 1"}
{"level":"info","ts":"2026-04-08T10:41:50.22085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c received MsgPreVoteResp from 9abf795dae4ebc2c at term 1"}
{"level":"info","ts":"2026-04-08T10:41:50.220871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became candidate at term 2"}
{"level":"info","ts":"2026-04-08T10:41:50.220882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c received MsgVoteResp from 9abf795dae4ebc2c at term 2"}
{"level":"info","ts":"2026-04-08T10:41:50.22095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became leader at term 2"}
{"level":"info","ts":"2026-04-08T10:41:50.220961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9abf795dae4ebc2c elected leader 9abf795dae4ebc2c at term 2"}
{"level":"info","ts":"2026-04-08T10:41:50.222031Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-08T10:41:50.222212Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9abf795dae4ebc2c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.151:2379]}","request-path":"/0/members/9abf795dae4ebc2c/attributes","cluster-id":"8a186cf11cef5bce","publish-timeout":"7s"}
{"level":"info","ts":"2026-04-08T10:41:50.222293Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-08T10:41:50.222355Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-08T10:41:50.222634Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-04-08T10:41:50.222707Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-04-08T10:41:50.223164Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8a186cf11cef5bce","local-member-id":"9abf795dae4ebc2c","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-08T10:41:50.223281Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-08T10:41:50.223329Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-08T10:41:50.225212Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-04-08T10:41:50.226042Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.151:2379"}
{"level":"info","ts":"2026-04-08T10:42:02.142615Z","caller":"traceutil/trace.go:171","msg":"trace[91725196] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"277.796589ms","start":"2026-04-08T10:42:01.864795Z","end":"2026-04-08T10:42:02.142592Z","steps":["trace[91725196] 'process raft request'  (duration: 277.600878ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:42:02.165403Z","caller":"traceutil/trace.go:171","msg":"trace[638644494] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:411; }","duration":"230.927084ms","start":"2026-04-08T10:42:01.934443Z","end":"2026-04-08T10:42:02.16537Z","steps":["trace[638644494] 'read index received'  (duration: 207.996839ms)","trace[638644494] 'applied index is now lower than readState.Index'  (duration: 22.929755ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-08T10:42:02.165715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.239845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-04-08T10:42:02.165776Z","caller":"traceutil/trace.go:171","msg":"trace[1268692030] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:403; }","duration":"231.374675ms","start":"2026-04-08T10:42:01.934389Z","end":"2026-04-08T10:42:02.165763Z","steps":["trace[1268692030] 'agreement among raft nodes before linearized reading'  (duration: 231.089924ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:42:02.165853Z","caller":"traceutil/trace.go:171","msg":"trace[1387578088] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"298.253675ms","start":"2026-04-08T10:42:01.867299Z","end":"2026-04-08T10:42:02.165553Z","steps":["trace[1387578088] 'process raft request'  (duration: 297.931383ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:42:02.355966Z","caller":"traceutil/trace.go:171","msg":"trace[115150471] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"187.293071ms","start":"2026-04-08T10:42:02.168643Z","end":"2026-04-08T10:42:02.355936Z","steps":["trace[115150471] 'process raft request'  (duration: 187.04035ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:42:02.415965Z","caller":"traceutil/trace.go:171","msg":"trace[1104061307] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"247.271042ms","start":"2026-04-08T10:42:02.16866Z","end":"2026-04-08T10:42:02.415931Z","steps":["trace[1104061307] 'process raft request'  (duration: 247.036411ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:42:02.416053Z","caller":"traceutil/trace.go:171","msg":"trace[104681953] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"245.210814ms","start":"2026-04-08T10:42:02.170821Z","end":"2026-04-08T10:42:02.416032Z","steps":["trace[104681953] 'process raft request'  (duration: 245.023504ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:42:25.434815Z","caller":"traceutil/trace.go:171","msg":"trace[490190292] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"105.399565ms","start":"2026-04-08T10:42:25.329388Z","end":"2026-04-08T10:42:25.434787Z","steps":["trace[490190292] 'process raft request'  (duration: 105.264235ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T10:42:26.065432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.390304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-04-08T10:42:26.065518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.35486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tfr5q\" ","response":"range_response_count:1 size:16965"}
{"level":"info","ts":"2026-04-08T10:42:26.065539Z","caller":"traceutil/trace.go:171","msg":"trace[1155098535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"392.584196ms","start":"2026-04-08T10:42:25.67293Z","end":"2026-04-08T10:42:26.065514Z","steps":["trace[1155098535] 'range keys from in-memory index tree'  (duration: 392.330674ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:42:26.065587Z","caller":"traceutil/trace.go:171","msg":"trace[1393748899] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tfr5q; range_end:; response_count:1; response_revision:620; }","duration":"189.455751ms","start":"2026-04-08T10:42:25.876108Z","end":"2026-04-08T10:42:26.065564Z","steps":["trace[1393748899] 'range keys from in-memory index tree'  (duration: 189.18918ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T10:42:26.065592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:42:25.672917Z","time spent":"392.661346ms","remote":"127.0.0.1:38282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-04-08T10:44:00.222773Z","caller":"traceutil/trace.go:171","msg":"trace[570082683] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"104.692094ms","start":"2026-04-08T10:44:00.118062Z","end":"2026-04-08T10:44:00.222754Z","steps":["trace[570082683] 'process raft request'  (duration: 104.5449ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:44:00.685364Z","caller":"traceutil/trace.go:171","msg":"trace[683169848] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"110.079711ms","start":"2026-04-08T10:44:00.575253Z","end":"2026-04-08T10:44:00.685333Z","steps":["trace[683169848] 'process raft request'  (duration: 109.647618ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:44:34.651707Z","caller":"traceutil/trace.go:171","msg":"trace[782801258] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"151.330871ms","start":"2026-04-08T10:44:34.500331Z","end":"2026-04-08T10:44:34.651662Z","steps":["trace[782801258] 'process raft request'  (duration: 139.503943ms)","trace[782801258] 'compare'  (duration: 11.635894ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-08T10:44:35.026805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.524837ms","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-08T10:44:35.026882Z","caller":"traceutil/trace.go:171","msg":"trace[592978305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2099; }","duration":"104.66168ms","start":"2026-04-08T10:44:34.922201Z","end":"2026-04-08T10:44:35.026863Z","steps":["trace[592978305] 'range keys from in-memory index tree'  (duration: 104.349263ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T10:44:35.026861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.316183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-04-08T10:44:35.026967Z","caller":"traceutil/trace.go:171","msg":"trace[2129294339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2099; }","duration":"104.501516ms","start":"2026-04-08T10:44:34.922442Z","end":"2026-04-08T10:44:35.026943Z","steps":["trace[2129294339] 'range keys from in-memory index tree'  (duration: 103.983155ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:44:35.193897Z","caller":"traceutil/trace.go:171","msg":"trace[15756193] linearizableReadLoop","detail":"{readStateIndex:2181; appliedIndex:2180; }","duration":"107.380215ms","start":"2026-04-08T10:44:35.086478Z","end":"2026-04-08T10:44:35.193859Z","steps":["trace[15756193] 'read index received'  (duration: 41.899298ms)","trace[15756193] 'applied index is now lower than readState.Index'  (duration: 65.479497ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-08T10:44:35.194143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.646521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"}
{"level":"info","ts":"2026-04-08T10:44:35.194217Z","caller":"traceutil/trace.go:171","msg":"trace[1961265877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2101; }","duration":"107.768524ms","start":"2026-04-08T10:44:35.086437Z","end":"2026-04-08T10:44:35.194206Z","steps":["trace[1961265877] 'agreement among raft nodes before linearized reading'  (duration: 107.533899ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:44:35.193934Z","caller":"traceutil/trace.go:171","msg":"trace[1678248428] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"161.889826ms","start":"2026-04-08T10:44:35.031975Z","end":"2026-04-08T10:44:35.193864Z","steps":["trace[1678248428] 'process raft request'  (duration: 96.404828ms)","trace[1678248428] 'compare'  (duration: 65.227442ms)"],"step_count":2}
{"level":"info","ts":"2026-04-08T10:44:35.451678Z","caller":"traceutil/trace.go:171","msg":"trace[110293918] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"177.020862ms","start":"2026-04-08T10:44:35.274599Z","end":"2026-04-08T10:44:35.45162Z","steps":["trace[110293918] 'process raft request'  (duration: 176.760927ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T10:44:35.451913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.611727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"}
{"level":"info","ts":"2026-04-08T10:44:35.451972Z","caller":"traceutil/trace.go:171","msg":"trace[1638046752] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2103; }","duration":"105.73882ms","start":"2026-04-08T10:44:35.346218Z","end":"2026-04-08T10:44:35.451956Z","steps":["trace[1638046752] 'agreement among raft nodes before linearized reading'  (duration: 105.451033ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:44:35.451617Z","caller":"traceutil/trace.go:171","msg":"trace[1912246025] linearizableReadLoop","detail":"{readStateIndex:2183; appliedIndex:2182; }","duration":"105.278009ms","start":"2026-04-08T10:44:35.346285Z","end":"2026-04-08T10:44:35.451563Z","steps":["trace[1912246025] 'read index received'  (duration: 105.107526ms)","trace[1912246025] 'applied index is now lower than readState.Index'  (duration: 169.003µs)"],"step_count":2}
{"level":"warn","ts":"2026-04-08T10:45:39.721413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.980074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-04-08T10:45:39.721497Z","caller":"traceutil/trace.go:171","msg":"trace[1270233640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2550; }","duration":"214.098196ms","start":"2026-04-08T10:45:39.507383Z","end":"2026-04-08T10:45:39.721481Z","steps":["trace[1270233640] 'range keys from in-memory index tree'  (duration: 213.853952ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T10:45:39.721508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.154088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-04-08T10:45:39.721595Z","caller":"traceutil/trace.go:171","msg":"trace[468486748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2550; }","duration":"187.279649ms","start":"2026-04-08T10:45:39.534295Z","end":"2026-04-08T10:45:39.721575Z","steps":["trace[468486748] 'range keys from in-memory index tree'  (duration: 186.953214ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:46:12.053434Z","caller":"traceutil/trace.go:171","msg":"trace[1672597284] linearizableReadLoop","detail":"{readStateIndex:2830; appliedIndex:2829; }","duration":"152.471664ms","start":"2026-04-08T10:46:11.900931Z","end":"2026-04-08T10:46:12.053403Z","steps":["trace[1672597284] 'read index received'  (duration: 152.287182ms)","trace[1672597284] 'applied index is now lower than readState.Index'  (duration: 183.392µs)"],"step_count":2}
{"level":"info","ts":"2026-04-08T10:46:12.053467Z","caller":"traceutil/trace.go:171","msg":"trace[341503677] transaction","detail":"{read_only:false; response_revision:2728; number_of_response:1; }","duration":"153.356766ms","start":"2026-04-08T10:46:11.900067Z","end":"2026-04-08T10:46:12.053423Z","steps":["trace[341503677] 'process raft request'  (duration: 153.177264ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T10:46:12.053735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.199432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-04-08T10:46:12.053777Z","caller":"traceutil/trace.go:171","msg":"trace[1754068815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2728; }","duration":"136.257373ms","start":"2026-04-08T10:46:11.917507Z","end":"2026-04-08T10:46:12.053765Z","steps":["trace[1754068815] 'agreement among raft nodes before linearized reading'  (duration: 136.074231ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T10:46:12.053935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.972011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"info","ts":"2026-04-08T10:46:12.054077Z","caller":"traceutil/trace.go:171","msg":"trace[1484234642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2728; }","duration":"153.147494ms","start":"2026-04-08T10:46:11.900908Z","end":"2026-04-08T10:46:12.054056Z","steps":["trace[1484234642] 'agreement among raft nodes before linearized reading'  (duration: 152.589647ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:46:12.190189Z","caller":"traceutil/trace.go:171","msg":"trace[663413973] transaction","detail":"{read_only:false; response_revision:2731; number_of_response:1; }","duration":"103.673964ms","start":"2026-04-08T10:46:12.086487Z","end":"2026-04-08T10:46:12.190161Z","steps":["trace[663413973] 'process raft request'  (duration: 91.950165ms)","trace[663413973] 'compare'  (duration: 11.616768ms)"],"step_count":2}
{"level":"info","ts":"2026-04-08T10:50:17.602496Z","caller":"traceutil/trace.go:171","msg":"trace[567870023] transaction","detail":"{read_only:false; response_revision:4782; number_of_response:1; }","duration":"121.951928ms","start":"2026-04-08T10:50:17.480518Z","end":"2026-04-08T10:50:17.60247Z","steps":["trace[567870023] 'process raft request'  (duration: 121.813346ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:50:29.835873Z","caller":"traceutil/trace.go:171","msg":"trace[1818198844] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"130.011436ms","start":"2026-04-08T10:50:29.705106Z","end":"2026-04-08T10:50:29.835117Z","steps":["trace[1818198844] 'process raft request'  (duration: 129.781032ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:50:29.845201Z","caller":"traceutil/trace.go:171","msg":"trace[517033345] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"139.990886ms","start":"2026-04-08T10:50:29.705179Z","end":"2026-04-08T10:50:29.84517Z","steps":["trace[517033345] 'process raft request'  (duration: 139.799832ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:51:23.502473Z","caller":"traceutil/trace.go:171","msg":"trace[12918033] transaction","detail":"{read_only:false; response_revision:5406; number_of_response:1; }","duration":"103.548923ms","start":"2026-04-08T10:51:23.398895Z","end":"2026-04-08T10:51:23.502444Z","steps":["trace[12918033] 'process raft request'  (duration: 103.36739ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:51:50.263379Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2929}
{"level":"info","ts":"2026-04-08T10:51:50.334928Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2929,"took":"70.359016ms","hash":2169693688,"current-db-size-bytes":53743616,"current-db-size":"54 MB","current-db-size-in-use-bytes":28991488,"current-db-size-in-use":"29 MB"}
{"level":"info","ts":"2026-04-08T10:51:50.335012Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2169693688,"revision":2929,"compact-revision":-1}
{"level":"warn","ts":"2026-04-08T10:56:43.662207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:56:43.328829Z","time spent":"333.370201ms","remote":"127.0.0.1:38336","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"info","ts":"2026-04-08T10:56:43.724553Z","caller":"traceutil/trace.go:171","msg":"trace[1434914366] transaction","detail":"{read_only:false; response_revision:7418; number_of_response:1; }","duration":"362.484088ms","start":"2026-04-08T10:56:43.362042Z","end":"2026-04-08T10:56:43.724526Z","steps":["trace[1434914366] 'process raft request'  (duration: 352.776013ms)"],"step_count":1}
{"level":"info","ts":"2026-04-08T10:56:43.724729Z","caller":"traceutil/trace.go:171","msg":"trace[505151951] linearizableReadLoop","detail":"{readStateIndex:7687; appliedIndex:7685; }","duration":"358.429549ms","start":"2026-04-08T10:56:43.366281Z","end":"2026-04-08T10:56:43.724711Z","steps":["trace[505151951] 'read index received'  (duration: 295.841115ms)","trace[505151951] 'applied index is now lower than readState.Index'  (duration: 62.587374ms)"],"step_count":2}
{"level":"info","ts":"2026-04-08T10:56:43.724803Z","caller":"traceutil/trace.go:171","msg":"trace[6997455] transaction","detail":"{read_only:false; response_revision:7419; number_of_response:1; }","duration":"208.015996ms","start":"2026-04-08T10:56:43.516779Z","end":"2026-04-08T10:56:43.724795Z","steps":["trace[6997455] 'process raft request'  (duration: 207.688574ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T10:56:43.725171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.653425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-04-08T10:56:43.725215Z","caller":"traceutil/trace.go:171","msg":"trace[1781767196] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7420; }","duration":"329.723207ms","start":"2026-04-08T10:56:43.395482Z","end":"2026-04-08T10:56:43.725205Z","steps":["trace[1781767196] 'agreement among raft nodes before linearized reading'  (duration: 329.469568ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T10:56:43.725253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:56:43.39546Z","time spent":"329.78552ms","remote":"127.0.0.1:38602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-04-08T10:56:43.725965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:56:43.362012Z","time spent":"362.628484ms","remote":"127.0.0.1:38602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:7408 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-04-08T10:56:43.72708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.773916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/ingress-nginx\" ","response":"range_response_count:1 size:1075"}
{"level":"info","ts":"2026-04-08T10:56:43.727133Z","caller":"traceutil/trace.go:171","msg":"trace[15406112] range","detail":"{range_begin:/registry/clusterrolebindings/ingress-nginx; range_end:; response_count:1; response_revision:7420; }","duration":"360.87559ms","start":"2026-04-08T10:56:43.366246Z","end":"2026-04-08T10:56:43.727121Z","steps":["trace[15406112] 'agreement among raft nodes before linearized reading'  (duration: 358.824174ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-08T10:56:43.72717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T10:56:43.366237Z","time spent":"360.925101ms","remote":"127.0.0.1:38680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":1099,"request content":"key:\"/registry/clusterrolebindings/ingress-nginx\" "}
{"level":"info","ts":"2026-04-08T10:56:50.268584Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5602}
{"level":"info","ts":"2026-04-08T10:56:50.35683Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5602,"took":"86.796617ms","hash":1320746919,"current-db-size-bytes":53743616,"current-db-size":"54 MB","current-db-size-in-use-bytes":18423808,"current-db-size-in-use":"18 MB"}
{"level":"info","ts":"2026-04-08T10:56:50.356879Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1320746919,"revision":5602,"compact-revision":2929}