{"level":"warn","ts":"2026-03-18T00:45:51.272097Z","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-18T00:45:51.272207Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.212: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://162.253.55.212:2380","--initial-cluster=instance=https://162.253.55.212:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.212:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.212: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-18T00:45:51.272283Z","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-18T00:45:51.272298Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-03-18T00:45:51.27232Z","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-18T00:45:51.273205Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"]} {"level":"info","ts":"2026-03-18T00:45:51.273302Z","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://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.212: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-18T00:45:51.276458Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.918461ms"} {"level":"info","ts":"2026-03-18T00:45:51.282205Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d078b1e39c21939c","cluster-id":"cdc9ba8de57e3589"} {"level":"info","ts":"2026-03-18T00:45:51.282326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T00:45:51.282382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 0"} {"level":"info","ts":"2026-03-18T00:45:51.282404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d078b1e39c21939c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T00:45:51.282458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 1"} {"level":"info","ts":"2026-03-18T00:45:51.282513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"warn","ts":"2026-03-18T00:45:51.289984Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T00:45:51.291509Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T00:45:51.292681Z","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-18T00:45:51.294964Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d078b1e39c21939c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T00:45:51.295083Z","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-18T00:45:51.295194Z","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-18T00:45:51.295207Z","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-18T00:45:51.295159Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d078b1e39c21939c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T00:45:51.296811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"info","ts":"2026-03-18T00:45:51.297057Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","added-peer-id":"d078b1e39c21939c","added-peer-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-03-18T00:45:51.298351Z","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-18T00:45:51.298443Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-03-18T00:45:51.298524Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-03-18T00:45:51.298584Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d078b1e39c21939c","initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T00:45:51.298622Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T00:45:51.982752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T00:45:51.982837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T00:45:51.982883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgPreVoteResp from d078b1e39c21939c at term 1"} {"level":"info","ts":"2026-03-18T00:45:51.982906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became candidate at term 2"} {"level":"info","ts":"2026-03-18T00:45:51.982919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgVoteResp from d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-03-18T00:45:51.982935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became leader at term 2"} {"level":"info","ts":"2026-03-18T00:45:51.982956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d078b1e39c21939c elected leader d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-03-18T00:45:51.984401Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T00:45:51.984971Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T00:45:51.984954Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d078b1e39c21939c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.212:2379]}","request-path":"/0/members/d078b1e39c21939c/attributes","cluster-id":"cdc9ba8de57e3589","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T00:45:51.985007Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T00:45:51.985452Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T00:45:51.98548Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-18T00:45:51.986252Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T00:45:51.98642Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T00:45:51.986454Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T00:45:51.988955Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.212:2379"} {"level":"info","ts":"2026-03-18T00:45:51.989113Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-18T00:46:07.233899Z","caller":"traceutil/trace.go:171","msg":"trace[1879380145] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"134.294926ms","start":"2026-03-18T00:46:07.099572Z","end":"2026-03-18T00:46:07.233867Z","steps":["trace[1879380145] 'read index received' (duration: 134.185833ms)","trace[1879380145] 'applied index is now lower than readState.Index' (duration: 107.983µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:46:07.23394Z","caller":"traceutil/trace.go:171","msg":"trace[2111531768] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"220.275917ms","start":"2026-03-18T00:46:07.013633Z","end":"2026-03-18T00:46:07.233909Z","steps":["trace[2111531768] 'process raft request' (duration: 220.074361ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:07.23412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.498271ms","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-18T00:46:07.234241Z","caller":"traceutil/trace.go:171","msg":"trace[873980498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:407; }","duration":"134.680306ms","start":"2026-03-18T00:46:07.099541Z","end":"2026-03-18T00:46:07.234221Z","steps":["trace[873980498] 'agreement among raft nodes before linearized reading' (duration: 134.492401ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:07.341222Z","caller":"traceutil/trace.go:171","msg":"trace[1679927662] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"103.070267ms","start":"2026-03-18T00:46:07.238128Z","end":"2026-03-18T00:46:07.341198Z","steps":["trace[1679927662] 'process raft request' (duration: 102.998654ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:07.341353Z","caller":"traceutil/trace.go:171","msg":"trace[592876493] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"149.911941ms","start":"2026-03-18T00:46:07.191432Z","end":"2026-03-18T00:46:07.341344Z","steps":["trace[592876493] 'process raft request' (duration: 144.932672ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:07.341521Z","caller":"traceutil/trace.go:171","msg":"trace[1772794111] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"107.46747ms","start":"2026-03-18T00:46:07.234007Z","end":"2026-03-18T00:46:07.341474Z","steps":["trace[1772794111] 'read index received' (duration: 102.372818ms)","trace[1772794111] 'applied index is now lower than readState.Index' (duration: 5.093552ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:46:07.341708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.19456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4373"} {"level":"info","ts":"2026-03-18T00:46:07.341803Z","caller":"traceutil/trace.go:171","msg":"trace[954622587] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:409; }","duration":"108.380725ms","start":"2026-03-18T00:46:07.233407Z","end":"2026-03-18T00:46:07.341788Z","steps":["trace[954622587] 'agreement among raft nodes before linearized reading' (duration: 108.155669ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:28.013432Z","caller":"traceutil/trace.go:171","msg":"trace[1312546727] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"151.902336ms","start":"2026-03-18T00:46:27.861481Z","end":"2026-03-18T00:46:28.013383Z","steps":["trace[1312546727] 'process raft request' (duration: 82.643859ms)","trace[1312546727] 'compare' (duration: 68.992369ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:46:28.236105Z","caller":"traceutil/trace.go:171","msg":"trace[1304778235] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"130.439228ms","start":"2026-03-18T00:46:28.105627Z","end":"2026-03-18T00:46:28.236067Z","steps":["trace[1304778235] 'process raft request' (duration: 130.15508ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:46:28.587621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.520321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-18T00:46:28.587734Z","caller":"traceutil/trace.go:171","msg":"trace[744935572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"259.694776ms","start":"2026-03-18T00:46:28.328011Z","end":"2026-03-18T00:46:28.587706Z","steps":["trace[744935572] 'range keys from in-memory index tree' (duration: 259.386688ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:28.776539Z","caller":"traceutil/trace.go:171","msg":"trace[1785902857] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"178.358523ms","start":"2026-03-18T00:46:28.598155Z","end":"2026-03-18T00:46:28.776513Z","steps":["trace[1785902857] 'read index received' (duration: 178.133286ms)","trace[1785902857] 'applied index is now lower than readState.Index' (duration: 224.237µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:46:28.776695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.522847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T00:46:28.776744Z","caller":"traceutil/trace.go:171","msg":"trace[1873359380] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:556; }","duration":"178.62601ms","start":"2026-03-18T00:46:28.598104Z","end":"2026-03-18T00:46:28.77673Z","steps":["trace[1873359380] 'agreement among raft nodes before linearized reading' (duration: 178.509146ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:46:28.776651Z","caller":"traceutil/trace.go:171","msg":"trace[1763195706] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"185.981194ms","start":"2026-03-18T00:46:28.590633Z","end":"2026-03-18T00:46:28.776614Z","steps":["trace[1763195706] 'process raft request' (duration: 185.736748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:47:35.339998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.181358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9c638a2c-cacb-467b-90fd-d11e99add432\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-03-18T00:47:35.340042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.457461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-18T00:47:35.340101Z","caller":"traceutil/trace.go:171","msg":"trace[1853148887] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9c638a2c-cacb-467b-90fd-d11e99add432; range_end:; response_count:1; response_revision:1589; }","duration":"216.353453ms","start":"2026-03-18T00:47:35.123716Z","end":"2026-03-18T00:47:35.340069Z","steps":["trace[1853148887] 'range keys from in-memory index tree' (duration: 215.950052ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:35.340145Z","caller":"traceutil/trace.go:171","msg":"trace[483600686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1589; }","duration":"230.597375ms","start":"2026-03-18T00:47:35.109511Z","end":"2026-03-18T00:47:35.340108Z","steps":["trace[483600686] 'range keys from in-memory index tree' (duration: 230.245285ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:35.387392Z","caller":"traceutil/trace.go:171","msg":"trace[1101386743] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"199.159542ms","start":"2026-03-18T00:47:35.188197Z","end":"2026-03-18T00:47:35.387357Z","steps":["trace[1101386743] 'process raft request' (duration: 198.931836ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:47:52.050825Z","caller":"traceutil/trace.go:171","msg":"trace[719337355] transaction","detail":"{read_only:false; response_revision:1717; number_of_response:1; }","duration":"193.060413ms","start":"2026-03-18T00:47:51.857737Z","end":"2026-03-18T00:47:52.050797Z","steps":["trace[719337355] 'process raft request' (duration: 192.728244ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:24.721722Z","caller":"traceutil/trace.go:171","msg":"trace[1822456901] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"110.94505ms","start":"2026-03-18T00:48:24.610751Z","end":"2026-03-18T00:48:24.721696Z","steps":["trace[1822456901] 'process raft request' (duration: 110.832587ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:30.918692Z","caller":"traceutil/trace.go:171","msg":"trace[274694020] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"214.359387ms","start":"2026-03-18T00:48:30.704309Z","end":"2026-03-18T00:48:30.918668Z","steps":["trace[274694020] 'process raft request' (duration: 214.223043ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:30.918743Z","caller":"traceutil/trace.go:171","msg":"trace[211666054] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1984; }","duration":"177.591886ms","start":"2026-03-18T00:48:30.741117Z","end":"2026-03-18T00:48:30.918709Z","steps":["trace[211666054] 'read index received' (duration: 177.574676ms)","trace[211666054] 'applied index is now lower than readState.Index' (duration: 14.79µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:48:30.919169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.030807ms","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-18T00:48:30.919299Z","caller":"traceutil/trace.go:171","msg":"trace[1289345821] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1911; }","duration":"178.214362ms","start":"2026-03-18T00:48:30.741064Z","end":"2026-03-18T00:48:30.919278Z","steps":["trace[1289345821] 'agreement among raft nodes before linearized reading' (duration: 177.799121ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:30.98348Z","caller":"traceutil/trace.go:171","msg":"trace[1810953780] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"209.831695ms","start":"2026-03-18T00:48:30.773621Z","end":"2026-03-18T00:48:30.983453Z","steps":["trace[1810953780] 'process raft request' (duration: 209.494536ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:30.983551Z","caller":"traceutil/trace.go:171","msg":"trace[282745988] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"209.821184ms","start":"2026-03-18T00:48:30.773707Z","end":"2026-03-18T00:48:30.983528Z","steps":["trace[282745988] 'process raft request' (duration: 209.664959ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:30.983585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.349195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T00:48:30.983807Z","caller":"traceutil/trace.go:171","msg":"trace[1979494157] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1913; }","duration":"198.637282ms","start":"2026-03-18T00:48:30.785142Z","end":"2026-03-18T00:48:30.983779Z","steps":["trace[1979494157] 'agreement among raft nodes before linearized reading' (duration: 198.288563ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:48:31.122938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.345219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T00:48:31.123269Z","caller":"traceutil/trace.go:171","msg":"trace[638242583] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"199.803173ms","start":"2026-03-18T00:48:30.923356Z","end":"2026-03-18T00:48:31.123159Z","steps":["trace[638242583] 'process raft request' (duration: 67.587501ms)","trace[638242583] 'compare' (duration: 131.204284ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:48:31.123528Z","caller":"traceutil/trace.go:171","msg":"trace[1559171196] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"135.45393ms","start":"2026-03-18T00:48:30.988036Z","end":"2026-03-18T00:48:31.12349Z","steps":["trace[1559171196] 'process raft request' (duration: 135.001718ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:48:31.329216Z","caller":"traceutil/trace.go:171","msg":"trace[389330035] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"182.679202ms","start":"2026-03-18T00:48:31.146509Z","end":"2026-03-18T00:48:31.329188Z","steps":["trace[389330035] 'process raft request' (duration: 182.527168ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:49:39.406912Z","caller":"traceutil/trace.go:171","msg":"trace[892098956] transaction","detail":"{read_only:false; response_revision:2448; number_of_response:1; }","duration":"133.486428ms","start":"2026-03-18T00:49:39.2734Z","end":"2026-03-18T00:49:39.406886Z","steps":["trace[892098956] 'process raft request' (duration: 128.958595ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:49:39.583705Z","caller":"traceutil/trace.go:171","msg":"trace[1562411147] transaction","detail":"{read_only:false; response_revision:2449; number_of_response:1; }","duration":"121.721271ms","start":"2026-03-18T00:49:39.461951Z","end":"2026-03-18T00:49:39.583672Z","steps":["trace[1562411147] 'process raft request' (duration: 99.834571ms)","trace[1562411147] 'compare' (duration: 21.813698ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:52:10.433693Z","caller":"traceutil/trace.go:171","msg":"trace[1830451290] linearizableReadLoop","detail":"{readStateIndex:3496; appliedIndex:3495; }","duration":"130.775823ms","start":"2026-03-18T00:52:10.302883Z","end":"2026-03-18T00:52:10.433659Z","steps":["trace[1830451290] 'read index received' (duration: 56.829408ms)","trace[1830451290] 'applied index is now lower than readState.Index' (duration: 73.944165ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:52:10.433949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.042141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-18T00:52:10.433991Z","caller":"traceutil/trace.go:171","msg":"trace[517062250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3365; }","duration":"131.112782ms","start":"2026-03-18T00:52:10.302869Z","end":"2026-03-18T00:52:10.433981Z","steps":["trace[517062250] 'agreement among raft nodes before linearized reading' (duration: 130.909357ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:53:35.670295Z","caller":"traceutil/trace.go:171","msg":"trace[589028575] linearizableReadLoop","detail":"{readStateIndex:4562; appliedIndex:4560; }","duration":"134.813082ms","start":"2026-03-18T00:53:35.53545Z","end":"2026-03-18T00:53:35.670263Z","steps":["trace[589028575] 'read index received' (duration: 36.566907ms)","trace[589028575] 'applied index is now lower than readState.Index' (duration: 98.244885ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:53:35.670399Z","caller":"traceutil/trace.go:171","msg":"trace[1846408576] transaction","detail":"{read_only:false; response_revision:4388; number_of_response:1; }","duration":"135.599383ms","start":"2026-03-18T00:53:35.534769Z","end":"2026-03-18T00:53:35.670368Z","steps":["trace[1846408576] 'process raft request' (duration: 135.446059ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:53:35.670416Z","caller":"traceutil/trace.go:171","msg":"trace[750644021] transaction","detail":"{read_only:false; response_revision:4387; number_of_response:1; }","duration":"137.069643ms","start":"2026-03-18T00:53:35.533296Z","end":"2026-03-18T00:53:35.670366Z","steps":["trace[750644021] 'process raft request' (duration: 38.750235ms)","trace[750644021] 'compare' (duration: 98.01073ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:53:35.670593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.12273ms","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-18T00:53:35.670965Z","caller":"traceutil/trace.go:171","msg":"trace[119386830] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4388; }","duration":"135.519281ms","start":"2026-03-18T00:53:35.535404Z","end":"2026-03-18T00:53:35.670923Z","steps":["trace[119386830] 'agreement among raft nodes before linearized reading' (duration: 135.048408ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:53:40.15054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.39765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9a20f880-8e9f-41a6-a0ed-bb09a81549ae\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-03-18T00:53:40.15067Z","caller":"traceutil/trace.go:171","msg":"trace[2017771874] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9a20f880-8e9f-41a6-a0ed-bb09a81549ae; range_end:; response_count:1; response_revision:4535; }","duration":"159.521104ms","start":"2026-03-18T00:53:39.991103Z","end":"2026-03-18T00:53:40.150624Z","steps":["trace[2017771874] 'range keys from in-memory index tree' (duration: 159.097512ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:54:00.52207Z","caller":"traceutil/trace.go:171","msg":"trace[2019702928] linearizableReadLoop","detail":"{readStateIndex:5115; appliedIndex:5114; }","duration":"232.716259ms","start":"2026-03-18T00:54:00.289318Z","end":"2026-03-18T00:54:00.522034Z","steps":["trace[2019702928] 'read index received' (duration: 46.860293ms)","trace[2019702928] 'applied index is now lower than readState.Index' (duration: 185.854486ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:54:00.522208Z","caller":"traceutil/trace.go:171","msg":"trace[397583089] transaction","detail":"{read_only:false; response_revision:4927; number_of_response:1; }","duration":"234.443394ms","start":"2026-03-18T00:54:00.287705Z","end":"2026-03-18T00:54:00.522148Z","steps":["trace[397583089] 'process raft request' (duration: 234.132116ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:54:00.522484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.14941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b8e34861-27d5-48b5-97fc-678d6f48efaf\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-18T00:54:00.522538Z","caller":"traceutil/trace.go:171","msg":"trace[2118576286] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b8e34861-27d5-48b5-97fc-678d6f48efaf; range_end:; response_count:1; response_revision:4927; }","duration":"233.283354ms","start":"2026-03-18T00:54:00.289239Z","end":"2026-03-18T00:54:00.522522Z","steps":["trace[2118576286] 'agreement among raft nodes before linearized reading' (duration: 232.966476ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:54:00.721273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.960123ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-18T00:54:00.72143Z","caller":"traceutil/trace.go:171","msg":"trace[494279844] linearizableReadLoop","detail":"{readStateIndex:5116; appliedIndex:5115; }","duration":"199.232584ms","start":"2026-03-18T00:54:00.522172Z","end":"2026-03-18T00:54:00.721404Z","steps":["trace[494279844] 'read index received' (duration: 60.111256ms)","trace[494279844] 'applied index is now lower than readState.Index' (duration: 139.119218ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:54:00.7216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.227185ms","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-18T00:54:00.72187Z","caller":"traceutil/trace.go:171","msg":"trace[2088746370] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:4927; }","duration":"400.516423ms","start":"2026-03-18T00:54:00.321335Z","end":"2026-03-18T00:54:00.721851Z","steps":["trace[2088746370] 'agreement among raft nodes before linearized reading' (duration: 400.149033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:54:00.721902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T00:54:00.321265Z","time spent":"400.629408ms","remote":"127.0.0.1:46336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":25,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-03-18T00:54:00.722226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.92419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-18T00:54:00.722266Z","caller":"traceutil/trace.go:171","msg":"trace[1141697851] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:4927; }","duration":"143.034202ms","start":"2026-03-18T00:54:00.57922Z","end":"2026-03-18T00:54:00.722254Z","steps":["trace[1141697851] 'agreement among raft nodes before linearized reading' (duration: 142.329553ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T00:54:00.887913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.956972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b8e34861-27d5-48b5-97fc-678d6f48efaf\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-18T00:54:00.887943Z","caller":"traceutil/trace.go:171","msg":"trace[93076967] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"111.09954ms","start":"2026-03-18T00:54:00.776792Z","end":"2026-03-18T00:54:00.887891Z","steps":["trace[93076967] 'process raft request' (duration: 46.893084ms)","trace[93076967] 'compare' (duration: 64.036131ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T00:54:00.888001Z","caller":"traceutil/trace.go:171","msg":"trace[318924592] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"110.716779ms","start":"2026-03-18T00:54:00.77725Z","end":"2026-03-18T00:54:00.887967Z","steps":["trace[318924592] 'process raft request' (duration: 110.645127ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:54:00.888017Z","caller":"traceutil/trace.go:171","msg":"trace[730138067] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b8e34861-27d5-48b5-97fc-678d6f48efaf; range_end:; response_count:1; response_revision:4928; }","duration":"112.110816ms","start":"2026-03-18T00:54:00.775882Z","end":"2026-03-18T00:54:00.887993Z","steps":["trace[730138067] 'range keys from in-memory index tree' (duration: 111.758606ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:54:00.88801Z","caller":"traceutil/trace.go:171","msg":"trace[1357073799] linearizableReadLoop","detail":"{readStateIndex:5118; appliedIndex:5117; }","duration":"110.835313ms","start":"2026-03-18T00:54:00.777133Z","end":"2026-03-18T00:54:00.887968Z","steps":["trace[1357073799] 'read index received' (duration: 46.693339ms)","trace[1357073799] 'applied index is now lower than readState.Index' (duration: 64.139074ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T00:54:00.888278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.11787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-18T00:54:00.888328Z","caller":"traceutil/trace.go:171","msg":"trace[160643701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4930; }","duration":"111.230483ms","start":"2026-03-18T00:54:00.777083Z","end":"2026-03-18T00:54:00.888313Z","steps":["trace[160643701] 'agreement among raft nodes before linearized reading' (duration: 111.010197ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T00:55:52.01636Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2802} {"level":"info","ts":"2026-03-18T00:55:52.075085Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2802,"took":"56.693809ms","hash":500424327,"current-db-size-bytes":42188800,"current-db-size":"42 MB","current-db-size-in-use-bytes":31686656,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-03-18T00:55:52.075159Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":500424327,"revision":2802,"compact-revision":-1} {"level":"info","ts":"2026-03-18T01:00:12.74563Z","caller":"traceutil/trace.go:171","msg":"trace[1027093905] transaction","detail":"{read_only:false; response_revision:7590; number_of_response:1; }","duration":"122.702545ms","start":"2026-03-18T01:00:12.6229Z","end":"2026-03-18T01:00:12.745602Z","steps":["trace[1027093905] 'process raft request' (duration: 122.520061ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T01:00:23.088203Z","caller":"traceutil/trace.go:171","msg":"trace[213499795] linearizableReadLoop","detail":"{readStateIndex:7920; appliedIndex:7919; }","duration":"262.089091ms","start":"2026-03-18T01:00:22.826083Z","end":"2026-03-18T01:00:23.088172Z","steps":["trace[213499795] 'read index received' (duration: 261.933377ms)","trace[213499795] 'applied index is now lower than readState.Index' (duration: 154.454µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T01:00:23.088267Z","caller":"traceutil/trace.go:171","msg":"trace[2093401927] transaction","detail":"{read_only:false; response_revision:7639; number_of_response:1; }","duration":"291.996134ms","start":"2026-03-18T01:00:22.79622Z","end":"2026-03-18T01:00:23.088216Z","steps":["trace[2093401927] 'process raft request' (duration: 291.717897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T01:00:23.088474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.322177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-18T01:00:23.088556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.700938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-86457c8f9b-wkcc8.189dc9b3f37a0d81\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-03-18T01:00:23.088571Z","caller":"traceutil/trace.go:171","msg":"trace[355130116] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7639; }","duration":"262.493952ms","start":"2026-03-18T01:00:22.826053Z","end":"2026-03-18T01:00:23.088547Z","steps":["trace[355130116] 'agreement among raft nodes before linearized reading' (duration: 262.276585ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T01:00:23.088631Z","caller":"traceutil/trace.go:171","msg":"trace[1356054092] range","detail":"{range_begin:/registry/events/openstack/barbican-api-86457c8f9b-wkcc8.189dc9b3f37a0d81; range_end:; response_count:1; response_revision:7639; }","duration":"112.851033ms","start":"2026-03-18T01:00:22.975757Z","end":"2026-03-18T01:00:23.088608Z","steps":["trace[1356054092] 'agreement among raft nodes before linearized reading' (duration: 112.596305ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T01:00:51.974673Z","caller":"traceutil/trace.go:171","msg":"trace[1994393110] transaction","detail":"{read_only:false; response_revision:7836; number_of_response:1; }","duration":"144.327448ms","start":"2026-03-18T01:00:51.830321Z","end":"2026-03-18T01:00:51.974648Z","steps":["trace[1994393110] 'process raft request' (duration: 144.118163ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T01:00:52.175698Z","caller":"traceutil/trace.go:171","msg":"trace[598483475] transaction","detail":"{read_only:false; response_revision:7837; number_of_response:1; }","duration":"157.134992ms","start":"2026-03-18T01:00:52.018531Z","end":"2026-03-18T01:00:52.175666Z","steps":["trace[598483475] 'process raft request' (duration: 154.249345ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T01:00:52.178542Z","caller":"traceutil/trace.go:171","msg":"trace[110742380] transaction","detail":"{read_only:false; response_revision:7838; number_of_response:1; }","duration":"144.706618ms","start":"2026-03-18T01:00:52.033816Z","end":"2026-03-18T01:00:52.178523Z","steps":["trace[110742380] 'process raft request' (duration: 144.592595ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T01:00:52.181292Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5813} {"level":"info","ts":"2026-03-18T01:00:52.268941Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5813,"took":"84.915171ms","hash":3485281094,"current-db-size-bytes":43794432,"current-db-size":"44 MB","current-db-size-in-use-bytes":23547904,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-18T01:00:52.269007Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3485281094,"revision":5813,"compact-revision":2802} {"level":"warn","ts":"2026-03-18T01:00:55.861987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.671568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-03-18T01:00:55.863827Z","caller":"traceutil/trace.go:171","msg":"trace[1311689510] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:7856; }","duration":"232.579908ms","start":"2026-03-18T01:00:55.631218Z","end":"2026-03-18T01:00:55.863798Z","steps":["trace[1311689510] 'range keys from in-memory index tree' (duration: 230.432421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T01:01:05.390188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.288601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T01:01:05.390424Z","caller":"traceutil/trace.go:171","msg":"trace[740276343] transaction","detail":"{read_only:false; response_revision:7943; number_of_response:1; }","duration":"208.868082ms","start":"2026-03-18T01:01:05.181516Z","end":"2026-03-18T01:01:05.390384Z","steps":["trace[740276343] 'process raft request' (duration: 107.207311ms)","trace[740276343] 'compare' (duration: 101.055475ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T01:01:05.554196Z","caller":"traceutil/trace.go:171","msg":"trace[786540574] transaction","detail":"{read_only:false; response_revision:7945; number_of_response:1; }","duration":"138.613533ms","start":"2026-03-18T01:01:05.415558Z","end":"2026-03-18T01:01:05.554172Z","steps":["trace[786540574] 'process raft request' (duration: 138.153811ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T01:01:05.554346Z","caller":"traceutil/trace.go:171","msg":"trace[909353144] transaction","detail":"{read_only:false; response_revision:7947; number_of_response:1; }","duration":"103.577123ms","start":"2026-03-18T01:01:05.450725Z","end":"2026-03-18T01:01:05.554302Z","steps":["trace[909353144] 'process raft request' (duration: 103.211513ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T01:01:05.554583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.751721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-s5lcn\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-03-18T01:01:05.554648Z","caller":"traceutil/trace.go:171","msg":"trace[661860692] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-s5lcn; range_end:; response_count:1; response_revision:7947; }","duration":"140.885135ms","start":"2026-03-18T01:01:05.413749Z","end":"2026-03-18T01:01:05.554635Z","steps":["trace[661860692] 'agreement among raft nodes before linearized reading' (duration: 140.634028ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T01:01:05.554563Z","caller":"traceutil/trace.go:171","msg":"trace[976761008] transaction","detail":"{read_only:false; response_revision:7946; number_of_response:1; }","duration":"127.238048ms","start":"2026-03-18T01:01:05.427294Z","end":"2026-03-18T01:01:05.554532Z","steps":["trace[976761008] 'process raft request' (duration: 126.57663ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T01:01:05.55475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.257007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-18T01:01:05.555003Z","caller":"traceutil/trace.go:171","msg":"trace[1323003610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7947; }","duration":"137.539725ms","start":"2026-03-18T01:01:05.417432Z","end":"2026-03-18T01:01:05.554972Z","steps":["trace[1323003610] 'agreement among raft nodes before linearized reading' (duration: 137.03147ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T01:01:05.556061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.414507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-18T01:01:05.55614Z","caller":"traceutil/trace.go:171","msg":"trace[724190700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7947; }","duration":"148.554321ms","start":"2026-03-18T01:01:05.407567Z","end":"2026-03-18T01:01:05.556121Z","steps":["trace[724190700] 'agreement among raft nodes before linearized reading' (duration: 147.06072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T01:01:06.497203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.555366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8sp2t\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-03-18T01:01:06.497254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.891069ms","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-18T01:01:06.497337Z","caller":"traceutil/trace.go:171","msg":"trace[1113711720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7955; }","duration":"107.997252ms","start":"2026-03-18T01:01:06.389317Z","end":"2026-03-18T01:01:06.497314Z","steps":["trace[1113711720] 'range keys from in-memory index tree' (duration: 107.675683ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T01:01:06.497337Z","caller":"traceutil/trace.go:171","msg":"trace[1264223359] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8sp2t; range_end:; response_count:1; response_revision:7955; }","duration":"143.771593ms","start":"2026-03-18T01:01:06.353535Z","end":"2026-03-18T01:01:06.497307Z","steps":["trace[1264223359] 'range keys from in-memory index tree' (duration: 143.404152ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T01:02:23.996882Z","caller":"traceutil/trace.go:171","msg":"trace[1727312053] transaction","detail":"{read_only:false; response_revision:8664; number_of_response:1; }","duration":"105.389963ms","start":"2026-03-18T01:02:23.891445Z","end":"2026-03-18T01:02:23.996835Z","steps":["trace[1727312053] 'process raft request' (duration: 86.834544ms)","trace[1727312053] 'compare' (duration: 18.189589ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T01:04:42.707946Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d078b1e39c21939c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-18T01:04:42.715363Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-18T01:04:42.715584Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-18T01:05:52.188614Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7837} {"level":"info","ts":"2026-03-18T01:05:52.261126Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7837,"took":"69.496727ms","hash":3692265405,"current-db-size-bytes":43794432,"current-db-size":"44 MB","current-db-size-in-use-bytes":26198016,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-18T01:05:52.261186Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3692265405,"revision":7837,"compact-revision":5813} {"level":"info","ts":"2026-03-18T01:10:52.196342Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10035} {"level":"info","ts":"2026-03-18T01:10:52.28559Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10035,"took":"86.945994ms","hash":2807090895,"current-db-size-bytes":43794432,"current-db-size":"44 MB","current-db-size-in-use-bytes":17379328,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-18T01:10:52.28566Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2807090895,"revision":10035,"compact-revision":7837}