{"level":"warn","ts":"2026-06-12T10:21:49.735934Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-12T10:21:49.736076Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.152: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.152:2380","--initial-cluster=instance=https://199.19.213.152:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.152:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.152: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-06-12T10:21:49.736169Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-12T10:21:49.73619Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.152:2380"]} {"level":"info","ts":"2026-06-12T10:21:49.736211Z","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-06-12T10:21:49.736801Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.152:2379"]} {"level":"info","ts":"2026-06-12T10:21:49.736871Z","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.152:2380"],"listen-peer-urls":["https://199.19.213.152:2380"],"advertise-client-urls":["https://199.19.213.152:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.152:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.152: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-06-12T10:21:49.74142Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.34233ms"} {"level":"info","ts":"2026-06-12T10:21:49.750521Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"68d6d88687e6b830","cluster-id":"8a5d84e8c26d99a1"} {"level":"info","ts":"2026-06-12T10:21:49.750607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 switched to configuration voters=()"} {"level":"info","ts":"2026-06-12T10:21:49.75064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became follower at term 0"} {"level":"info","ts":"2026-06-12T10:21:49.750677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 68d6d88687e6b830 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-12T10:21:49.750694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became follower at term 1"} {"level":"info","ts":"2026-06-12T10:21:49.750746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 switched to configuration voters=(7554463497277847600)"} {"level":"warn","ts":"2026-06-12T10:21:49.756695Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-12T10:21:49.75829Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-12T10:21:49.759648Z","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-06-12T10:21:49.762288Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"68d6d88687e6b830","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-12T10:21:49.762398Z","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-06-12T10:21:49.762496Z","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-06-12T10:21:49.762514Z","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-06-12T10:21:49.76244Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"68d6d88687e6b830","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-12T10:21:49.763431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 switched to configuration voters=(7554463497277847600)"} {"level":"info","ts":"2026-06-12T10:21:49.763573Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8a5d84e8c26d99a1","local-member-id":"68d6d88687e6b830","added-peer-id":"68d6d88687e6b830","added-peer-peer-urls":["https://199.19.213.152:2380"]} {"level":"info","ts":"2026-06-12T10:21:49.766814Z","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-06-12T10:21:49.766929Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.152:2380"} {"level":"info","ts":"2026-06-12T10:21:49.766974Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.152:2380"} {"level":"info","ts":"2026-06-12T10:21:49.767064Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"68d6d88687e6b830","initial-advertise-peer-urls":["https://199.19.213.152:2380"],"listen-peer-urls":["https://199.19.213.152:2380"],"advertise-client-urls":["https://199.19.213.152:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.152:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-12T10:21:49.767107Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-12T10:21:50.151513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 is starting a new election at term 1"} {"level":"info","ts":"2026-06-12T10:21:50.151592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-12T10:21:50.15162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 received MsgPreVoteResp from 68d6d88687e6b830 at term 1"} {"level":"info","ts":"2026-06-12T10:21:50.151644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became candidate at term 2"} {"level":"info","ts":"2026-06-12T10:21:50.151669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 received MsgVoteResp from 68d6d88687e6b830 at term 2"} {"level":"info","ts":"2026-06-12T10:21:50.151689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"68d6d88687e6b830 became leader at term 2"} {"level":"info","ts":"2026-06-12T10:21:50.151706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 68d6d88687e6b830 elected leader 68d6d88687e6b830 at term 2"} {"level":"info","ts":"2026-06-12T10:21:50.153008Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"68d6d88687e6b830","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.152:2379]}","request-path":"/0/members/68d6d88687e6b830/attributes","cluster-id":"8a5d84e8c26d99a1","publish-timeout":"7s"} {"level":"info","ts":"2026-06-12T10:21:50.154295Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-12T10:21:50.15434Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-12T10:21:50.154676Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-12T10:21:50.155331Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-12T10:21:50.155426Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-12T10:21:50.155992Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-12T10:21:50.158059Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.152:2379"} {"level":"info","ts":"2026-06-12T10:21:50.15878Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8a5d84e8c26d99a1","local-member-id":"68d6d88687e6b830","cluster-version":"3.5"} {"level":"info","ts":"2026-06-12T10:21:50.158901Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-12T10:21:50.15895Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-12T10:22:12.663871Z","caller":"traceutil/trace.go:171","msg":"trace[1854136089] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"115.276452ms","start":"2026-06-12T10:22:12.548562Z","end":"2026-06-12T10:22:12.663838Z","steps":["trace[1854136089] 'process raft request' (duration: 70.204095ms)","trace[1854136089] 'compare' (duration: 44.976175ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:22:17.918514Z","caller":"traceutil/trace.go:171","msg":"trace[914595092] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"139.552722ms","start":"2026-06-12T10:22:17.778921Z","end":"2026-06-12T10:22:17.918474Z","steps":["trace[914595092] 'process raft request' (duration: 139.284525ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:22:18.072827Z","caller":"traceutil/trace.go:171","msg":"trace[1157105028] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"149.353433ms","start":"2026-06-12T10:22:17.923436Z","end":"2026-06-12T10:22:18.07279Z","steps":["trace[1157105028] 'process raft request' (duration: 149.166067ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:22:18.074637Z","caller":"traceutil/trace.go:171","msg":"trace[1378368317] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"151.114605ms","start":"2026-06-12T10:22:17.923497Z","end":"2026-06-12T10:22:18.074611Z","steps":["trace[1378368317] 'process raft request' (duration: 151.001431ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:22:32.107279Z","caller":"traceutil/trace.go:171","msg":"trace[739167543] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"128.824305ms","start":"2026-06-12T10:22:31.978425Z","end":"2026-06-12T10:22:32.107249Z","steps":["trace[739167543] 'read index received' (duration: 106.787992ms)","trace[739167543] 'applied index is now lower than readState.Index' (duration: 22.035223ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:22:32.107423Z","caller":"traceutil/trace.go:171","msg":"trace[1990689703] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"149.765466ms","start":"2026-06-12T10:22:31.957616Z","end":"2026-06-12T10:22:32.107382Z","steps":["trace[1990689703] 'process raft request' (duration: 127.848386ms)","trace[1990689703] 'compare' (duration: 21.385654ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:22:32.10798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.536926ms","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-06-12T10:22:32.108091Z","caller":"traceutil/trace.go:171","msg":"trace[679652432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"129.705951ms","start":"2026-06-12T10:22:31.978366Z","end":"2026-06-12T10:22:32.108072Z","steps":["trace[679652432] 'agreement among raft nodes before linearized reading' (duration: 129.00506ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:22:32.324541Z","caller":"traceutil/trace.go:171","msg":"trace[1460194464] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"168.672085ms","start":"2026-06-12T10:22:32.155841Z","end":"2026-06-12T10:22:32.324513Z","steps":["trace[1460194464] 'process raft request' (duration: 147.668374ms)","trace[1460194464] 'compare' (duration: 20.799085ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:22:32.324598Z","caller":"traceutil/trace.go:171","msg":"trace[1277958928] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"127.543837ms","start":"2026-06-12T10:22:32.19703Z","end":"2026-06-12T10:22:32.324573Z","steps":["trace[1277958928] 'process raft request' (duration: 127.472165ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:22:32.324648Z","caller":"traceutil/trace.go:171","msg":"trace[400151924] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"131.719751ms","start":"2026-06-12T10:22:32.192897Z","end":"2026-06-12T10:22:32.324617Z","steps":["trace[400151924] 'read index received' (duration: 110.624787ms)","trace[400151924] 'applied index is now lower than readState.Index' (duration: 21.092384ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:22:32.324815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.685395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T10:22:32.324841Z","caller":"traceutil/trace.go:171","msg":"trace[776675475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"115.722847ms","start":"2026-06-12T10:22:32.209111Z","end":"2026-06-12T10:22:32.324834Z","steps":["trace[776675475] 'agreement among raft nodes before linearized reading' (duration: 115.640954ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:32.324887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.178791ms","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-06-12T10:22:32.324928Z","caller":"traceutil/trace.go:171","msg":"trace[1308086108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:627; }","duration":"191.238293ms","start":"2026-06-12T10:22:32.133679Z","end":"2026-06-12T10:22:32.324917Z","steps":["trace[1308086108] 'agreement among raft nodes before linearized reading' (duration: 191.056748ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:32.795701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.677196ms","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-06-12T10:22:32.798416Z","caller":"traceutil/trace.go:171","msg":"trace[1976483106] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"245.404816ms","start":"2026-06-12T10:22:32.552952Z","end":"2026-06-12T10:22:32.798356Z","steps":["trace[1976483106] 'range keys from in-memory index tree' (duration: 241.019537ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:22:47.38951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.864127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T10:22:47.389603Z","caller":"traceutil/trace.go:171","msg":"trace[2098282849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"179.014231ms","start":"2026-06-12T10:22:47.210571Z","end":"2026-06-12T10:22:47.389585Z","steps":["trace[2098282849] 'range keys from in-memory index tree' (duration: 178.788074ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:24:01.201205Z","caller":"traceutil/trace.go:171","msg":"trace[1412086169] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1937; }","duration":"204.318491ms","start":"2026-06-12T10:24:00.996858Z","end":"2026-06-12T10:24:01.201176Z","steps":["trace[1412086169] 'read index received' (duration: 204.130235ms)","trace[1412086169] 'applied index is now lower than readState.Index' (duration: 187.445µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:24:01.201258Z","caller":"traceutil/trace.go:171","msg":"trace[8419623] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"291.725379ms","start":"2026-06-12T10:24:00.909506Z","end":"2026-06-12T10:24:01.201232Z","steps":["trace[8419623] 'process raft request' (duration: 291.525993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:24:01.201445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.567949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cbd61890-86ee-45ca-be95-6d703f1cb46d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-12T10:24:01.201508Z","caller":"traceutil/trace.go:171","msg":"trace[722057380] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cbd61890-86ee-45ca-be95-6d703f1cb46d; range_end:; response_count:1; response_revision:1863; }","duration":"204.690982ms","start":"2026-06-12T10:24:00.996802Z","end":"2026-06-12T10:24:01.201493Z","steps":["trace[722057380] 'agreement among raft nodes before linearized reading' (duration: 204.486636ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:24:01.592822Z","caller":"traceutil/trace.go:171","msg":"trace[1070194935] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"231.699441ms","start":"2026-06-12T10:24:01.361075Z","end":"2026-06-12T10:24:01.592775Z","steps":["trace[1070194935] 'process raft request' (duration: 230.750204ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:24:22.77843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.850569ms","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-06-12T10:24:22.778552Z","caller":"traceutil/trace.go:171","msg":"trace[768705379] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2023; }","duration":"273.018575ms","start":"2026-06-12T10:24:22.505515Z","end":"2026-06-12T10:24:22.778533Z","steps":["trace[768705379] 'range keys from in-memory index tree' (duration: 272.677244ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:25:00.554385Z","caller":"traceutil/trace.go:171","msg":"trace[1727206847] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"116.860099ms","start":"2026-06-12T10:25:00.437496Z","end":"2026-06-12T10:25:00.554356Z","steps":["trace[1727206847] 'process raft request' (duration: 116.671044ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:25:01.349065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.798104ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T10:25:01.349196Z","caller":"traceutil/trace.go:171","msg":"trace[809982847] linearizableReadLoop","detail":"{readStateIndex:2324; appliedIndex:2322; }","duration":"297.278021ms","start":"2026-06-12T10:25:01.051898Z","end":"2026-06-12T10:25:01.349176Z","steps":["trace[809982847] 'read index received' (duration: 38.495787ms)","trace[809982847] 'applied index is now lower than readState.Index' (duration: 258.780184ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:25:01.349411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.520739ms","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-06-12T10:25:01.349466Z","caller":"traceutil/trace.go:171","msg":"trace[1747130110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2235; }","duration":"297.621281ms","start":"2026-06-12T10:25:01.051832Z","end":"2026-06-12T10:25:01.349453Z","steps":["trace[1747130110] 'agreement among raft nodes before linearized reading' (duration: 297.451096ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:25:01.349434Z","caller":"traceutil/trace.go:171","msg":"trace[1728630139] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"352.849193ms","start":"2026-06-12T10:25:00.996353Z","end":"2026-06-12T10:25:01.349202Z","steps":["trace[1728630139] 'process raft request' (duration: 211.162703ms)","trace[1728630139] 'compare' (duration: 140.363041ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:25:01.349562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.383751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-12T10:25:01.349647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.464803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-12T10:25:01.349687Z","caller":"traceutil/trace.go:171","msg":"trace[1895281214] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2235; }","duration":"290.506715ms","start":"2026-06-12T10:25:01.059146Z","end":"2026-06-12T10:25:01.349652Z","steps":["trace[1895281214] 'agreement among raft nodes before linearized reading' (duration: 290.227927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:25:01.349668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:25:00.996333Z","time spent":"353.212024ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T10:25:01.349749Z","caller":"traceutil/trace.go:171","msg":"trace[326534661] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2235; }","duration":"261.602847ms","start":"2026-06-12T10:25:01.088114Z","end":"2026-06-12T10:25:01.349717Z","steps":["trace[326534661] 'agreement among raft nodes before linearized reading' (duration: 261.325309ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:30:53.014086Z","caller":"traceutil/trace.go:171","msg":"trace[319249307] linearizableReadLoop","detail":"{readStateIndex:5405; appliedIndex:5402; }","duration":"254.833091ms","start":"2026-06-12T10:30:52.759225Z","end":"2026-06-12T10:30:53.014058Z","steps":["trace[319249307] 'read index received' (duration: 155.362987ms)","trace[319249307] 'applied index is now lower than readState.Index' (duration: 99.469314ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:30:53.014203Z","caller":"traceutil/trace.go:171","msg":"trace[436247147] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"381.517846ms","start":"2026-06-12T10:30:52.632672Z","end":"2026-06-12T10:30:53.01419Z","steps":["trace[436247147] 'process raft request' (duration: 281.788574ms)","trace[436247147] 'compare' (duration: 99.401203ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:30:53.014286Z","caller":"traceutil/trace.go:171","msg":"trace[133238165] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"264.991715ms","start":"2026-06-12T10:30:52.749244Z","end":"2026-06-12T10:30:53.014236Z","steps":["trace[133238165] 'process raft request' (duration: 264.777219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:30:53.014321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:30:52.632631Z","time spent":"381.597598ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T10:30:53.014323Z","caller":"traceutil/trace.go:171","msg":"trace[344775528] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"281.011771ms","start":"2026-06-12T10:30:52.733273Z","end":"2026-06-12T10:30:53.014285Z","steps":["trace[344775528] 'process raft request' (duration: 280.697812ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:30:53.014463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.218002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-12T10:30:53.014532Z","caller":"traceutil/trace.go:171","msg":"trace[928623702] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5205; }","duration":"255.332545ms","start":"2026-06-12T10:30:52.759186Z","end":"2026-06-12T10:30:53.014518Z","steps":["trace[928623702] 'agreement among raft nodes before linearized reading' (duration: 255.126349ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:30:53.07771Z","caller":"traceutil/trace.go:171","msg":"trace[89608422] transaction","detail":"{read_only:false; response_revision:5206; number_of_response:1; }","duration":"122.188195ms","start":"2026-06-12T10:30:52.955483Z","end":"2026-06-12T10:30:53.077672Z","steps":["trace[89608422] 'process raft request' (duration: 122.01686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:30:53.077813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.182119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-12T10:30:53.077873Z","caller":"traceutil/trace.go:171","msg":"trace[2103126791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5206; }","duration":"158.280771ms","start":"2026-06-12T10:30:52.919578Z","end":"2026-06-12T10:30:53.077859Z","steps":["trace[2103126791] 'agreement among raft nodes before linearized reading' (duration: 158.101446ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:30:53.078072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.056447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728895"} {"level":"info","ts":"2026-06-12T10:30:53.078126Z","caller":"traceutil/trace.go:171","msg":"trace[418068498] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5206; }","duration":"236.137099ms","start":"2026-06-12T10:30:52.841975Z","end":"2026-06-12T10:30:53.078112Z","steps":["trace[418068498] 'agreement among raft nodes before linearized reading' (duration: 235.659455ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:30:53.409881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.685358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T10:30:53.41007Z","caller":"traceutil/trace.go:171","msg":"trace[995572909] linearizableReadLoop","detail":"{readStateIndex:5412; appliedIndex:5411; }","duration":"173.433911ms","start":"2026-06-12T10:30:53.236617Z","end":"2026-06-12T10:30:53.410051Z","steps":["trace[995572909] 'read index received' (duration: 69.388402ms)","trace[995572909] 'applied index is now lower than readState.Index' (duration: 104.043779ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:30:53.410133Z","caller":"traceutil/trace.go:171","msg":"trace[2036609897] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"179.137386ms","start":"2026-06-12T10:30:53.23097Z","end":"2026-06-12T10:30:53.410108Z","steps":["trace[2036609897] 'process raft request' (duration: 75.134878ms)","trace[2036609897] 'compare' (duration: 103.571355ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:30:53.410283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.626036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.152\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-12T10:30:53.410338Z","caller":"traceutil/trace.go:171","msg":"trace[986222581] range","detail":"{range_begin:/registry/masterleases/199.19.213.152; range_end:; response_count:1; response_revision:5212; }","duration":"173.739279ms","start":"2026-06-12T10:30:53.236584Z","end":"2026-06-12T10:30:53.410323Z","steps":["trace[986222581] 'agreement among raft nodes before linearized reading' (duration: 173.551494ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:30:53.410496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.035888ms","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-06-12T10:30:53.410531Z","caller":"traceutil/trace.go:171","msg":"trace[1124451357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5212; }","duration":"135.10997ms","start":"2026-06-12T10:30:53.275414Z","end":"2026-06-12T10:30:53.410524Z","steps":["trace[1124451357] 'agreement among raft nodes before linearized reading' (duration: 134.988267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:30:53.410629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.164155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8d0c1eb1-265c-4bff-9bf2-e85e4945baca\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-06-12T10:30:53.410648Z","caller":"traceutil/trace.go:171","msg":"trace[593846388] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8d0c1eb1-265c-4bff-9bf2-e85e4945baca; range_end:; response_count:1; response_revision:5212; }","duration":"121.211636ms","start":"2026-06-12T10:30:53.289429Z","end":"2026-06-12T10:30:53.410641Z","steps":["trace[593846388] 'agreement among raft nodes before linearized reading' (duration: 121.159255ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:30:53.410783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.048221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-swx9j-tls\" ","response":"range_response_count:1 size:2184"} {"level":"info","ts":"2026-06-12T10:30:53.410836Z","caller":"traceutil/trace.go:171","msg":"trace[381209807] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-swx9j-tls; range_end:; response_count:1; response_revision:5212; }","duration":"131.123334ms","start":"2026-06-12T10:30:53.279697Z","end":"2026-06-12T10:30:53.41082Z","steps":["trace[381209807] 'agreement among raft nodes before linearized reading' (duration: 130.977119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:31:06.954526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.413525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T10:31:06.954622Z","caller":"traceutil/trace.go:171","msg":"trace[1875804064] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5483; }","duration":"160.547439ms","start":"2026-06-12T10:31:06.794053Z","end":"2026-06-12T10:31:06.954601Z","steps":["trace[1875804064] 'count revisions from in-memory index tree' (duration: 160.334853ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:31:06.954772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.805969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T10:31:06.954887Z","caller":"traceutil/trace.go:171","msg":"trace[1711760259] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"159.068646ms","start":"2026-06-12T10:31:06.795798Z","end":"2026-06-12T10:31:06.954866Z","steps":["trace[1711760259] 'process raft request' (duration: 34.067791ms)","trace[1711760259] 'compare' (duration: 124.367556ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:31:15.261876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.910566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T10:31:15.261972Z","caller":"traceutil/trace.go:171","msg":"trace[1733346665] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:5661; }","duration":"231.03053ms","start":"2026-06-12T10:31:15.030922Z","end":"2026-06-12T10:31:15.261953Z","steps":["trace[1733346665] 'count revisions from in-memory index tree' (duration: 230.813684ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:31:15.262702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.330651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2224963"} {"level":"info","ts":"2026-06-12T10:31:15.262761Z","caller":"traceutil/trace.go:171","msg":"trace[180887300] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5661; }","duration":"162.438174ms","start":"2026-06-12T10:31:15.10031Z","end":"2026-06-12T10:31:15.262749Z","steps":["trace[180887300] 'range keys from in-memory index tree' (duration: 161.440056ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:31:15.325303Z","caller":"traceutil/trace.go:171","msg":"trace[603026642] transaction","detail":"{read_only:false; response_revision:5662; number_of_response:1; }","duration":"199.651505ms","start":"2026-06-12T10:31:15.125628Z","end":"2026-06-12T10:31:15.325279Z","steps":["trace[603026642] 'process raft request' (duration: 199.48089ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:31:15.343513Z","caller":"traceutil/trace.go:171","msg":"trace[1138047222] linearizableReadLoop","detail":"{readStateIndex:5875; appliedIndex:5873; }","duration":"131.550915ms","start":"2026-06-12T10:31:15.211935Z","end":"2026-06-12T10:31:15.343486Z","steps":["trace[1138047222] 'read index received' (duration: 113.291794ms)","trace[1138047222] 'applied index is now lower than readState.Index' (duration: 18.258261ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:31:15.343733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.772112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-12T10:31:15.343748Z","caller":"traceutil/trace.go:171","msg":"trace[1942337030] transaction","detail":"{read_only:false; response_revision:5663; number_of_response:1; }","duration":"183.258319ms","start":"2026-06-12T10:31:15.160431Z","end":"2026-06-12T10:31:15.343689Z","steps":["trace[1942337030] 'process raft request' (duration: 182.92031ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:31:15.343808Z","caller":"traceutil/trace.go:171","msg":"trace[125195913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5663; }","duration":"131.884825ms","start":"2026-06-12T10:31:15.211903Z","end":"2026-06-12T10:31:15.343787Z","steps":["trace[125195913] 'agreement among raft nodes before linearized reading' (duration: 131.740741ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:31:15.458528Z","caller":"traceutil/trace.go:171","msg":"trace[642225180] transaction","detail":"{read_only:false; response_revision:5664; number_of_response:1; }","duration":"109.36143ms","start":"2026-06-12T10:31:15.349098Z","end":"2026-06-12T10:31:15.458459Z","steps":["trace[642225180] 'process raft request' (duration: 25.624195ms)","trace[642225180] 'compare' (duration: 83.356124ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:31:15.458689Z","caller":"traceutil/trace.go:171","msg":"trace[899838421] transaction","detail":"{read_only:false; response_revision:5666; number_of_response:1; }","duration":"108.771034ms","start":"2026-06-12T10:31:15.349889Z","end":"2026-06-12T10:31:15.45866Z","steps":["trace[899838421] 'process raft request' (duration: 108.536567ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:31:15.458755Z","caller":"traceutil/trace.go:171","msg":"trace[1973191975] transaction","detail":"{read_only:false; response_revision:5665; number_of_response:1; }","duration":"109.02159ms","start":"2026-06-12T10:31:15.349724Z","end":"2026-06-12T10:31:15.458745Z","steps":["trace[1973191975] 'process raft request' (duration: 108.614678ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:31:15.458922Z","caller":"traceutil/trace.go:171","msg":"trace[252103314] transaction","detail":"{read_only:false; response_revision:5667; number_of_response:1; }","duration":"108.939158ms","start":"2026-06-12T10:31:15.349954Z","end":"2026-06-12T10:31:15.458893Z","steps":["trace[252103314] 'process raft request' (duration: 108.521906ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:31:15.650597Z","caller":"traceutil/trace.go:171","msg":"trace[1622787420] transaction","detail":"{read_only:false; response_revision:5668; number_of_response:1; }","duration":"150.58463ms","start":"2026-06-12T10:31:15.499992Z","end":"2026-06-12T10:31:15.650576Z","steps":["trace[1622787420] 'process raft request' (duration: 150.456396ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:31:15.65098Z","caller":"traceutil/trace.go:171","msg":"trace[949028000] linearizableReadLoop","detail":"{readStateIndex:5880; appliedIndex:5880; }","duration":"116.807537ms","start":"2026-06-12T10:31:15.534166Z","end":"2026-06-12T10:31:15.650973Z","steps":["trace[949028000] 'read index received' (duration: 116.804957ms)","trace[949028000] 'applied index is now lower than readState.Index' (duration: 2.16µs)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:31:15.651042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.87136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-12T10:31:15.651068Z","caller":"traceutil/trace.go:171","msg":"trace[139047246] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:5668; }","duration":"116.942641ms","start":"2026-06-12T10:31:15.534117Z","end":"2026-06-12T10:31:15.65106Z","steps":["trace[139047246] 'agreement among raft nodes before linearized reading' (duration: 116.890189ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:31:15.934028Z","caller":"traceutil/trace.go:171","msg":"trace[170925358] linearizableReadLoop","detail":"{readStateIndex:5881; appliedIndex:5880; }","duration":"282.573627ms","start":"2026-06-12T10:31:15.651413Z","end":"2026-06-12T10:31:15.933986Z","steps":["trace[170925358] 'read index received' (duration: 212.16594ms)","trace[170925358] 'applied index is now lower than readState.Index' (duration: 70.400167ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:31:15.934101Z","caller":"traceutil/trace.go:171","msg":"trace[971073069] transaction","detail":"{read_only:false; response_revision:5669; number_of_response:1; }","duration":"286.086479ms","start":"2026-06-12T10:31:15.647972Z","end":"2026-06-12T10:31:15.934058Z","steps":["trace[971073069] 'process raft request' (duration: 215.6157ms)","trace[971073069] 'compare' (duration: 69.910653ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:31:15.93453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.958148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-06-12T10:31:15.93458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.553557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6f2mc\" ","response":"range_response_count:1 size:6522"} {"level":"warn","ts":"2026-06-12T10:31:15.934575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.99818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f851433c-f8f0-4613-bf5a-4a15ead478c5\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-12T10:31:15.934733Z","caller":"traceutil/trace.go:171","msg":"trace[2021710696] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6f2mc; range_end:; response_count:1; response_revision:5669; }","duration":"282.65296ms","start":"2026-06-12T10:31:15.651979Z","end":"2026-06-12T10:31:15.934632Z","steps":["trace[2021710696] 'agreement among raft nodes before linearized reading' (duration: 282.429283ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:31:15.934712Z","caller":"traceutil/trace.go:171","msg":"trace[1339921139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5669; }","duration":"283.241637ms","start":"2026-06-12T10:31:15.651382Z","end":"2026-06-12T10:31:15.934624Z","steps":["trace[1339921139] 'agreement among raft nodes before linearized reading' (duration: 282.748692ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:31:15.934785Z","caller":"traceutil/trace.go:171","msg":"trace[858074208] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f851433c-f8f0-4613-bf5a-4a15ead478c5; range_end:; response_count:1; response_revision:5669; }","duration":"283.233646ms","start":"2026-06-12T10:31:15.651524Z","end":"2026-06-12T10:31:15.934758Z","steps":["trace[858074208] 'agreement among raft nodes before linearized reading' (duration: 282.67562ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:31:50.319061Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3015} {"level":"info","ts":"2026-06-12T10:31:50.406316Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3015,"took":"85.239279ms","hash":2534562258,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":30044160,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-12T10:31:50.406397Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2534562258,"revision":3015,"compact-revision":-1} {"level":"info","ts":"2026-06-12T10:32:07.168647Z","caller":"traceutil/trace.go:171","msg":"trace[1073939690] transaction","detail":"{read_only:false; response_revision:6142; number_of_response:1; }","duration":"144.445345ms","start":"2026-06-12T10:32:07.024171Z","end":"2026-06-12T10:32:07.168617Z","steps":["trace[1073939690] 'process raft request' (duration: 144.220269ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:36:50.325205Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5953} {"level":"info","ts":"2026-06-12T10:36:50.400566Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5953,"took":"73.870174ms","hash":1484731162,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":25780224,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-12T10:36:50.400624Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1484731162,"revision":5953,"compact-revision":3015} {"level":"info","ts":"2026-06-12T10:37:30.680913Z","caller":"traceutil/trace.go:171","msg":"trace[1580027610] transaction","detail":"{read_only:false; response_revision:8520; number_of_response:1; }","duration":"108.745954ms","start":"2026-06-12T10:37:30.572127Z","end":"2026-06-12T10:37:30.680873Z","steps":["trace[1580027610] 'process raft request' (duration: 108.434895ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:37:42.074111Z","caller":"traceutil/trace.go:171","msg":"trace[13442422] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"193.9334ms","start":"2026-06-12T10:37:41.880152Z","end":"2026-06-12T10:37:42.074085Z","steps":["trace[13442422] 'process raft request' (duration: 193.792486ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:37:42.94768Z","caller":"traceutil/trace.go:171","msg":"trace[1904389090] transaction","detail":"{read_only:false; response_revision:8587; number_of_response:1; }","duration":"139.512396ms","start":"2026-06-12T10:37:42.808146Z","end":"2026-06-12T10:37:42.947659Z","steps":["trace[1904389090] 'process raft request' (duration: 139.123144ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:37:43.02303Z","caller":"traceutil/trace.go:171","msg":"trace[2040693543] transaction","detail":"{read_only:false; response_revision:8588; number_of_response:1; }","duration":"207.445186ms","start":"2026-06-12T10:37:42.815553Z","end":"2026-06-12T10:37:43.022999Z","steps":["trace[2040693543] 'process raft request' (duration: 207.24924ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:37:43.75639Z","caller":"traceutil/trace.go:171","msg":"trace[1812848283] linearizableReadLoop","detail":"{readStateIndex:8900; appliedIndex:8899; }","duration":"157.592816ms","start":"2026-06-12T10:37:43.598769Z","end":"2026-06-12T10:37:43.756362Z","steps":["trace[1812848283] 'read index received' (duration: 157.355729ms)","trace[1812848283] 'applied index is now lower than readState.Index' (duration: 236.187µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:37:43.756489Z","caller":"traceutil/trace.go:171","msg":"trace[1305494128] transaction","detail":"{read_only:false; response_revision:8593; number_of_response:1; }","duration":"164.848089ms","start":"2026-06-12T10:37:43.591617Z","end":"2026-06-12T10:37:43.756465Z","steps":["trace[1305494128] 'process raft request' (duration: 164.591921ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:37:43.756655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.872484ms","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-06-12T10:37:43.756697Z","caller":"traceutil/trace.go:171","msg":"trace[1885899920] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8593; }","duration":"157.967476ms","start":"2026-06-12T10:37:43.598719Z","end":"2026-06-12T10:37:43.756687Z","steps":["trace[1885899920] 'agreement among raft nodes before linearized reading' (duration: 157.815292ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:37:43.939531Z","caller":"traceutil/trace.go:171","msg":"trace[666636600] transaction","detail":"{read_only:false; response_revision:8595; number_of_response:1; }","duration":"177.797077ms","start":"2026-06-12T10:37:43.761698Z","end":"2026-06-12T10:37:43.939495Z","steps":["trace[666636600] 'process raft request' (duration: 91.901492ms)","trace[666636600] 'compare' (duration: 85.584516ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:37:44.451338Z","caller":"traceutil/trace.go:171","msg":"trace[1028666967] transaction","detail":"{read_only:false; response_revision:8597; number_of_response:1; }","duration":"134.679274ms","start":"2026-06-12T10:37:44.316635Z","end":"2026-06-12T10:37:44.451315Z","steps":["trace[1028666967] 'process raft request' (duration: 134.52417ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:38:12.015003Z","caller":"traceutil/trace.go:171","msg":"trace[61913495] linearizableReadLoop","detail":"{readStateIndex:9110; appliedIndex:9109; }","duration":"421.627319ms","start":"2026-06-12T10:38:11.593346Z","end":"2026-06-12T10:38:12.014973Z","steps":["trace[61913495] 'read index received' (duration: 354.612627ms)","trace[61913495] 'applied index is now lower than readState.Index' (duration: 67.013292ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:38:12.015141Z","caller":"traceutil/trace.go:171","msg":"trace[1236572024] transaction","detail":"{read_only:false; response_revision:8796; number_of_response:1; }","duration":"457.703707ms","start":"2026-06-12T10:38:11.557414Z","end":"2026-06-12T10:38:12.015118Z","steps":["trace[1236572024] 'process raft request' (duration: 390.601421ms)","trace[1236572024] 'compare' (duration: 66.826878ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:38:12.015648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:38:11.557391Z","time spent":"458.14779ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T10:38:12.016058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.115618ms","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-06-12T10:38:12.016137Z","caller":"traceutil/trace.go:171","msg":"trace[65768819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8796; }","duration":"120.228622ms","start":"2026-06-12T10:38:11.895892Z","end":"2026-06-12T10:38:12.01612Z","steps":["trace[65768819] 'agreement among raft nodes before linearized reading' (duration: 120.027266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:38:12.015269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.912887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-12T10:38:12.016399Z","caller":"traceutil/trace.go:171","msg":"trace[1380102722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8796; }","duration":"423.087262ms","start":"2026-06-12T10:38:11.593293Z","end":"2026-06-12T10:38:12.01638Z","steps":["trace[1380102722] 'agreement among raft nodes before linearized reading' (duration: 421.891956ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:38:12.01646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:38:11.593267Z","time spent":"423.176095ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-12T10:38:25.294979Z","caller":"traceutil/trace.go:171","msg":"trace[2126915395] transaction","detail":"{read_only:false; response_revision:8912; number_of_response:1; }","duration":"118.545992ms","start":"2026-06-12T10:38:25.1764Z","end":"2026-06-12T10:38:25.294946Z","steps":["trace[2126915395] 'process raft request' (duration: 94.913459ms)","trace[2126915395] 'compare' (duration: 23.299374ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:38:25.294993Z","caller":"traceutil/trace.go:171","msg":"trace[185093712] transaction","detail":"{read_only:false; response_revision:8913; number_of_response:1; }","duration":"104.452469ms","start":"2026-06-12T10:38:25.190509Z","end":"2026-06-12T10:38:25.294962Z","steps":["trace[185093712] 'process raft request' (duration: 104.355056ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:38:25.779433Z","caller":"traceutil/trace.go:171","msg":"trace[1098461621] transaction","detail":"{read_only:false; response_revision:8916; number_of_response:1; }","duration":"170.094983ms","start":"2026-06-12T10:38:25.609308Z","end":"2026-06-12T10:38:25.779403Z","steps":["trace[1098461621] 'process raft request' (duration: 169.932858ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:38:39.345761Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002494.wal"} {"level":"info","ts":"2026-06-12T10:39:41.561243Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"68d6d88687e6b830","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-12T10:39:41.566679Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-12T10:39:41.566773Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-12T10:41:50.330668Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8132} {"level":"info","ts":"2026-06-12T10:41:50.392498Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8132,"took":"59.484476ms","hash":372232632,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":28614656,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-12T10:41:50.392572Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":372232632,"revision":8132,"compact-revision":5953} {"level":"info","ts":"2026-06-12T10:45:22.463771Z","caller":"traceutil/trace.go:171","msg":"trace[1799799331] transaction","detail":"{read_only:false; response_revision:12106; number_of_response:1; }","duration":"120.702376ms","start":"2026-06-12T10:45:22.343027Z","end":"2026-06-12T10:45:22.463729Z","steps":["trace[1799799331] 'process raft request' (duration: 107.49028ms)","trace[1799799331] 'compare' (duration: 13.089472ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:45:22.499989Z","caller":"traceutil/trace.go:171","msg":"trace[1867862787] transaction","detail":"{read_only:false; response_revision:12107; number_of_response:1; }","duration":"156.398009ms","start":"2026-06-12T10:45:22.343513Z","end":"2026-06-12T10:45:22.499911Z","steps":["trace[1867862787] 'process raft request' (duration: 156.218793ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:45:23.657914Z","caller":"traceutil/trace.go:171","msg":"trace[1568861384] transaction","detail":"{read_only:false; response_revision:12114; number_of_response:1; }","duration":"181.326536ms","start":"2026-06-12T10:45:23.476548Z","end":"2026-06-12T10:45:23.657874Z","steps":["trace[1568861384] 'process raft request' (duration: 153.738641ms)","trace[1568861384] 'compare' (duration: 27.070701ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:45:23.658124Z","caller":"traceutil/trace.go:171","msg":"trace[420475940] transaction","detail":"{read_only:false; response_revision:12115; number_of_response:1; }","duration":"142.433291ms","start":"2026-06-12T10:45:23.515643Z","end":"2026-06-12T10:45:23.658076Z","steps":["trace[420475940] 'process raft request' (duration: 142.165624ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:46:50.337718Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10547} {"level":"info","ts":"2026-06-12T10:46:50.424952Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10547,"took":"85.130237ms","hash":2670166683,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":25849856,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-12T10:46:50.425049Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2670166683,"revision":10547,"compact-revision":8132} {"level":"info","ts":"2026-06-12T10:51:50.34742Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12675} {"level":"info","ts":"2026-06-12T10:51:50.421665Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12675,"took":"72.216204ms","hash":4271570609,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":26914816,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-12T10:51:50.421742Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4271570609,"revision":12675,"compact-revision":10547} {"level":"warn","ts":"2026-06-12T10:52:08.63263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.897734ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-12T10:52:08.632971Z","caller":"traceutil/trace.go:171","msg":"trace[524966444] transaction","detail":"{read_only:false; response_revision:14807; number_of_response:1; }","duration":"148.331025ms","start":"2026-06-12T10:52:08.4846Z","end":"2026-06-12T10:52:08.632931Z","steps":["trace[524966444] 'process raft request' (duration: 148.15492ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:52:09.548115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.376872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-12T10:52:09.548195Z","caller":"traceutil/trace.go:171","msg":"trace[1161497287] transaction","detail":"{read_only:false; response_revision:14814; number_of_response:1; }","duration":"283.607477ms","start":"2026-06-12T10:52:09.264571Z","end":"2026-06-12T10:52:09.548179Z","steps":["trace[1161497287] 'process raft request' (duration: 143.103662ms)","trace[1161497287] 'compare' (duration: 140.206196ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:52:11.358785Z","caller":"traceutil/trace.go:171","msg":"trace[1737580237] transaction","detail":"{read_only:false; response_revision:14829; number_of_response:1; }","duration":"107.103487ms","start":"2026-06-12T10:52:11.25164Z","end":"2026-06-12T10:52:11.358743Z","steps":["trace[1737580237] 'process raft request' (duration: 37.934261ms)","trace[1737580237] 'compare' (duration: 69.048923ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:52:19.074997Z","caller":"traceutil/trace.go:171","msg":"trace[442408391] transaction","detail":"{read_only:false; response_revision:14980; number_of_response:1; }","duration":"347.552391ms","start":"2026-06-12T10:52:18.727414Z","end":"2026-06-12T10:52:19.074967Z","steps":["trace[442408391] 'process raft request' (duration: 347.372335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:52:19.075168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:52:18.727396Z","time spent":"347.691725ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T10:52:19.241294Z","caller":"traceutil/trace.go:171","msg":"trace[619722309] linearizableReadLoop","detail":"{readStateIndex:15500; appliedIndex:15498; }","duration":"452.802073ms","start":"2026-06-12T10:52:18.788449Z","end":"2026-06-12T10:52:19.241252Z","steps":["trace[619722309] 'read index received' (duration: 286.318146ms)","trace[619722309] 'applied index is now lower than readState.Index' (duration: 166.482427ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:52:19.241343Z","caller":"traceutil/trace.go:171","msg":"trace[1469018571] transaction","detail":"{read_only:false; response_revision:14981; number_of_response:1; }","duration":"457.888533ms","start":"2026-06-12T10:52:18.783403Z","end":"2026-06-12T10:52:19.241292Z","steps":["trace[1469018571] 'process raft request' (duration: 400.997206ms)","trace[1469018571] 'compare' (duration: 56.67675ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:52:19.24159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.130504ms","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-06-12T10:52:19.241651Z","caller":"traceutil/trace.go:171","msg":"trace[1546689258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14981; }","duration":"453.236997ms","start":"2026-06-12T10:52:18.788401Z","end":"2026-06-12T10:52:19.241638Z","steps":["trace[1546689258] 'agreement among raft nodes before linearized reading' (duration: 452.963879ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:52:19.241694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:52:18.788383Z","time spent":"453.302348ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-12T10:52:19.241671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:52:18.783379Z","time spent":"458.107789ms","remote":"127.0.0.1:56052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":854,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-12T10:52:19.241692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.316823ms","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-06-12T10:52:19.241969Z","caller":"traceutil/trace.go:171","msg":"trace[2008387775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14981; }","duration":"446.642752ms","start":"2026-06-12T10:52:18.7953Z","end":"2026-06-12T10:52:19.241942Z","steps":["trace[2008387775] 'agreement among raft nodes before linearized reading' (duration: 446.158098ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:52:19.242032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:52:18.795282Z","time spent":"446.732975ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-12T10:52:19.242793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.92036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6f2mc\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-06-12T10:52:19.243187Z","caller":"traceutil/trace.go:171","msg":"trace[1760247445] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6f2mc; range_end:; response_count:1; response_revision:14981; }","duration":"419.272392ms","start":"2026-06-12T10:52:18.823853Z","end":"2026-06-12T10:52:19.243126Z","steps":["trace[1760247445] 'agreement among raft nodes before linearized reading' (duration: 418.345294ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:52:19.244111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:52:18.823808Z","time spent":"420.200468ms","remote":"127.0.0.1:56144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6545,"request content":"key:\"/registry/pods/openstack/keepalived-6f2mc\" "} {"level":"info","ts":"2026-06-12T10:53:59.921004Z","caller":"traceutil/trace.go:171","msg":"trace[2100113192] linearizableReadLoop","detail":"{readStateIndex:16624; appliedIndex:16622; }","duration":"123.379104ms","start":"2026-06-12T10:53:59.797601Z","end":"2026-06-12T10:53:59.92098Z","steps":["trace[2100113192] 'read index received' (duration: 22.055616ms)","trace[2100113192] 'applied index is now lower than readState.Index' (duration: 101.322358ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:53:59.921074Z","caller":"traceutil/trace.go:171","msg":"trace[2136067355] transaction","detail":"{read_only:false; response_revision:16073; number_of_response:1; }","duration":"129.465372ms","start":"2026-06-12T10:53:59.791578Z","end":"2026-06-12T10:53:59.921043Z","steps":["trace[2136067355] 'process raft request' (duration: 121.037495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:53:59.921192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.59863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-t5bbg\" ","response":"range_response_count:1 size:9791"} {"level":"info","ts":"2026-06-12T10:53:59.922351Z","caller":"traceutil/trace.go:171","msg":"trace[159954723] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-t5bbg; range_end:; response_count:1; response_revision:16073; }","duration":"124.799036ms","start":"2026-06-12T10:53:59.797533Z","end":"2026-06-12T10:53:59.922332Z","steps":["trace[159954723] 'agreement among raft nodes before linearized reading' (duration: 123.561109ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:54:01.064308Z","caller":"traceutil/trace.go:171","msg":"trace[568164686] transaction","detail":"{read_only:false; response_revision:16074; number_of_response:1; }","duration":"382.418981ms","start":"2026-06-12T10:54:00.681863Z","end":"2026-06-12T10:54:01.064282Z","steps":["trace[568164686] 'process raft request' (duration: 382.227966ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:54:01.064528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:54:00.681834Z","time spent":"382.548965ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T10:54:01.068812Z","caller":"traceutil/trace.go:171","msg":"trace[1351417251] transaction","detail":"{read_only:false; response_revision:16075; number_of_response:1; }","duration":"369.598627ms","start":"2026-06-12T10:54:00.699192Z","end":"2026-06-12T10:54:01.068791Z","steps":["trace[1351417251] 'process raft request' (duration: 369.445302ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:54:01.068942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:54:00.699172Z","time spent":"369.701539ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T10:54:01.068989Z","caller":"traceutil/trace.go:171","msg":"trace[918367309] linearizableReadLoop","detail":"{readStateIndex:16626; appliedIndex:16624; }","duration":"287.241854ms","start":"2026-06-12T10:54:00.781641Z","end":"2026-06-12T10:54:01.068883Z","steps":["trace[918367309] 'read index received' (duration: 282.458504ms)","trace[918367309] 'applied index is now lower than readState.Index' (duration: 4.78231ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:54:01.06913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.491962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-12T10:54:01.069158Z","caller":"traceutil/trace.go:171","msg":"trace[1053421872] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16076; }","duration":"287.553304ms","start":"2026-06-12T10:54:00.781597Z","end":"2026-06-12T10:54:01.069151Z","steps":["trace[1053421872] 'agreement among raft nodes before linearized reading' (duration: 287.448551ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:54:01.071646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.561518ms","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-06-12T10:54:01.071698Z","caller":"traceutil/trace.go:171","msg":"trace[1952543105] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16076; }","duration":"183.614949ms","start":"2026-06-12T10:54:00.888069Z","end":"2026-06-12T10:54:01.071684Z","steps":["trace[1952543105] 'agreement among raft nodes before linearized reading' (duration: 181.27553ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:54:01.07171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.66847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-kbmh6\" ","response":"range_response_count:1 size:22093"} {"level":"warn","ts":"2026-06-12T10:54:01.07165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.856724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-kbmh6\" ","response":"range_response_count:1 size:22093"} {"level":"info","ts":"2026-06-12T10:54:01.071782Z","caller":"traceutil/trace.go:171","msg":"trace[1182761769] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-kbmh6; range_end:; response_count:1; response_revision:16076; }","duration":"112.768253ms","start":"2026-06-12T10:54:00.958997Z","end":"2026-06-12T10:54:01.071765Z","steps":["trace[1182761769] 'agreement among raft nodes before linearized reading' (duration: 110.355062ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:54:01.071824Z","caller":"traceutil/trace.go:171","msg":"trace[528619933] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-kbmh6; range_end:; response_count:1; response_revision:16076; }","duration":"116.027859ms","start":"2026-06-12T10:54:00.955744Z","end":"2026-06-12T10:54:01.071772Z","steps":["trace[528619933] 'agreement among raft nodes before linearized reading' (duration: 113.613308ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:56:50.353738Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14698} {"level":"info","ts":"2026-06-12T10:56:50.424119Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14698,"took":"67.956019ms","hash":2589393670,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":32256000,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-12T10:56:50.424174Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2589393670,"revision":14698,"compact-revision":12675} {"level":"info","ts":"2026-06-12T10:57:14.70629Z","caller":"traceutil/trace.go:171","msg":"trace[2126917308] linearizableReadLoop","detail":"{readStateIndex:18208; appliedIndex:18207; }","duration":"172.490697ms","start":"2026-06-12T10:57:14.533776Z","end":"2026-06-12T10:57:14.706267Z","steps":["trace[2126917308] 'read index received' (duration: 172.285711ms)","trace[2126917308] 'applied index is now lower than readState.Index' (duration: 203.835µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:57:14.70638Z","caller":"traceutil/trace.go:171","msg":"trace[1030054926] transaction","detail":"{read_only:false; response_revision:17608; number_of_response:1; }","duration":"204.266662ms","start":"2026-06-12T10:57:14.502078Z","end":"2026-06-12T10:57:14.706345Z","steps":["trace[1030054926] 'process raft request' (duration: 204.042075ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:57:14.706471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.681792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-12T10:57:14.706548Z","caller":"traceutil/trace.go:171","msg":"trace[789779162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17608; }","duration":"172.842737ms","start":"2026-06-12T10:57:14.533695Z","end":"2026-06-12T10:57:14.706538Z","steps":["trace[789779162] 'agreement among raft nodes before linearized reading' (duration: 172.666402ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:59:37.026811Z","caller":"traceutil/trace.go:171","msg":"trace[429606736] linearizableReadLoop","detail":"{readStateIndex:19180; appliedIndex:19179; }","duration":"196.457361ms","start":"2026-06-12T10:59:36.830327Z","end":"2026-06-12T10:59:37.026784Z","steps":["trace[429606736] 'read index received' (duration: 196.276286ms)","trace[429606736] 'applied index is now lower than readState.Index' (duration: 179.725µs)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:59:37.026971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.539244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-12T10:59:37.027044Z","caller":"traceutil/trace.go:171","msg":"trace[816015311] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:18548; }","duration":"196.719719ms","start":"2026-06-12T10:59:36.83031Z","end":"2026-06-12T10:59:37.027029Z","steps":["trace[816015311] 'agreement among raft nodes before linearized reading' (duration: 196.510513ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:59:37.027228Z","caller":"traceutil/trace.go:171","msg":"trace[585877067] transaction","detail":"{read_only:false; response_revision:18548; number_of_response:1; }","duration":"206.348812ms","start":"2026-06-12T10:59:36.820867Z","end":"2026-06-12T10:59:37.027216Z","steps":["trace[585877067] 'process raft request' (duration: 205.754775ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:59:37.044704Z","caller":"traceutil/trace.go:171","msg":"trace[284572669] transaction","detail":"{read_only:false; response_revision:18549; number_of_response:1; }","duration":"142.252467ms","start":"2026-06-12T10:59:36.902432Z","end":"2026-06-12T10:59:37.044684Z","steps":["trace[284572669] 'process raft request' (duration: 142.01883ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:59:43.779091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.793857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-06-12T10:59:43.779109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.285494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-12T10:59:43.779175Z","caller":"traceutil/trace.go:171","msg":"trace[1829917802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18603; }","duration":"222.91684ms","start":"2026-06-12T10:59:43.556242Z","end":"2026-06-12T10:59:43.779158Z","steps":["trace[1829917802] 'range keys from in-memory index tree' (duration: 222.62056ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:59:43.779211Z","caller":"traceutil/trace.go:171","msg":"trace[1109850817] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:18603; }","duration":"305.415488ms","start":"2026-06-12T10:59:43.47377Z","end":"2026-06-12T10:59:43.779186Z","steps":["trace[1109850817] 'count revisions from in-memory index tree' (duration: 305.12603ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:59:43.779254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T10:59:43.473753Z","time spent":"305.489751ms","remote":"127.0.0.1:56272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":18,"response size":31,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-06-12T10:59:43.779256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.592991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-12T10:59:43.77932Z","caller":"traceutil/trace.go:171","msg":"trace[540721169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18603; }","duration":"291.707645ms","start":"2026-06-12T10:59:43.4876Z","end":"2026-06-12T10:59:43.779307Z","steps":["trace[540721169] 'range keys from in-memory index tree' (duration: 291.257472ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:59:44.031512Z","caller":"traceutil/trace.go:171","msg":"trace[2043127621] transaction","detail":"{read_only:false; response_revision:18604; number_of_response:1; }","duration":"247.584627ms","start":"2026-06-12T10:59:43.783901Z","end":"2026-06-12T10:59:44.031485Z","steps":["trace[2043127621] 'process raft request' (duration: 247.428472ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:59:44.035439Z","caller":"traceutil/trace.go:171","msg":"trace[856590550] linearizableReadLoop","detail":"{readStateIndex:19239; appliedIndex:19237; }","duration":"210.843864ms","start":"2026-06-12T10:59:43.824573Z","end":"2026-06-12T10:59:44.035417Z","steps":["trace[856590550] 'read index received' (duration: 206.737654ms)","trace[856590550] 'applied index is now lower than readState.Index' (duration: 4.10544ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:59:44.03571Z","caller":"traceutil/trace.go:171","msg":"trace[2032291544] transaction","detail":"{read_only:false; response_revision:18605; number_of_response:1; }","duration":"251.327876ms","start":"2026-06-12T10:59:43.784373Z","end":"2026-06-12T10:59:44.0357Z","steps":["trace[2032291544] 'process raft request' (duration: 250.917435ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:59:44.035747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.160834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-12T10:59:44.035867Z","caller":"traceutil/trace.go:171","msg":"trace[1020337361] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18605; }","duration":"211.330069ms","start":"2026-06-12T10:59:43.824519Z","end":"2026-06-12T10:59:44.035849Z","steps":["trace[1020337361] 'agreement among raft nodes before linearized reading' (duration: 211.058291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:59:44.035968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.830231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-t5bbg\" ","response":"range_response_count:1 size:9792"} {"level":"info","ts":"2026-06-12T10:59:44.036099Z","caller":"traceutil/trace.go:171","msg":"trace[519306304] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-t5bbg; range_end:; response_count:1; response_revision:18605; }","duration":"182.026657ms","start":"2026-06-12T10:59:43.854046Z","end":"2026-06-12T10:59:44.036073Z","steps":["trace[519306304] 'agreement among raft nodes before linearized reading' (duration: 181.630946ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T10:59:44.259531Z","caller":"traceutil/trace.go:171","msg":"trace[226249475] linearizableReadLoop","detail":"{readStateIndex:19240; appliedIndex:19239; }","duration":"217.264935ms","start":"2026-06-12T10:59:44.042232Z","end":"2026-06-12T10:59:44.259497Z","steps":["trace[226249475] 'read index received' (duration: 186.623243ms)","trace[226249475] 'applied index is now lower than readState.Index' (duration: 30.640202ms)"],"step_count":2} {"level":"info","ts":"2026-06-12T10:59:44.259555Z","caller":"traceutil/trace.go:171","msg":"trace[736931776] transaction","detail":"{read_only:false; response_revision:18606; number_of_response:1; }","duration":"217.606724ms","start":"2026-06-12T10:59:44.041904Z","end":"2026-06-12T10:59:44.259511Z","steps":["trace[736931776] 'process raft request' (duration: 187.000533ms)","trace[736931776] 'compare' (duration: 30.307762ms)"],"step_count":2} {"level":"warn","ts":"2026-06-12T10:59:44.260691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.973629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-txk5p\" ","response":"range_response_count:1 size:20850"} {"level":"info","ts":"2026-06-12T10:59:44.260746Z","caller":"traceutil/trace.go:171","msg":"trace[1465745062] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-txk5p; range_end:; response_count:1; response_revision:18606; }","duration":"158.042091ms","start":"2026-06-12T10:59:44.102689Z","end":"2026-06-12T10:59:44.260731Z","steps":["trace[1465745062] 'agreement among raft nodes before linearized reading' (duration: 156.971009ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T10:59:44.260846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.581544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:941298"} {"level":"info","ts":"2026-06-12T10:59:44.260955Z","caller":"traceutil/trace.go:171","msg":"trace[295926821] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:18606; }","duration":"218.745128ms","start":"2026-06-12T10:59:44.042186Z","end":"2026-06-12T10:59:44.260931Z","steps":["trace[295926821] 'agreement among raft nodes before linearized reading' (duration: 217.407368ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T11:01:36.611883Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"68d6d88687e6b830","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-12T11:01:36.619518Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-12T11:01:36.619723Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-12T11:01:50.359976Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17292} {"level":"info","ts":"2026-06-12T11:01:50.465759Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17292,"took":"100.964899ms","hash":702177504,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":31588352,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-12T11:01:50.465841Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":702177504,"revision":17292,"compact-revision":14698} {"level":"info","ts":"2026-06-12T11:03:43.720985Z","caller":"traceutil/trace.go:171","msg":"trace[2024793432] linearizableReadLoop","detail":"{readStateIndex:20684; appliedIndex:20683; }","duration":"466.443969ms","start":"2026-06-12T11:03:43.254521Z","end":"2026-06-12T11:03:43.720965Z","steps":["trace[2024793432] 'read index received' (duration: 466.274993ms)","trace[2024793432] 'applied index is now lower than readState.Index' (duration: 167.796µs)"],"step_count":2} {"level":"info","ts":"2026-06-12T11:03:43.721036Z","caller":"traceutil/trace.go:171","msg":"trace[1144758099] transaction","detail":"{read_only:false; response_revision:19995; number_of_response:1; }","duration":"473.385242ms","start":"2026-06-12T11:03:43.24763Z","end":"2026-06-12T11:03:43.721016Z","steps":["trace[1144758099] 'process raft request' (duration: 473.167224ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T11:03:43.721209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.668066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-12T11:03:43.721277Z","caller":"traceutil/trace.go:171","msg":"trace[108028069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19995; }","duration":"466.772789ms","start":"2026-06-12T11:03:43.254486Z","end":"2026-06-12T11:03:43.721258Z","steps":["trace[108028069] 'agreement among raft nodes before linearized reading' (duration: 466.573313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T11:03:43.721302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T11:03:43.254471Z","time spent":"466.82631ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-12T11:03:43.721302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T11:03:43.247608Z","time spent":"473.568797ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-12T11:03:43.723593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.071316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-12T11:03:43.723626Z","caller":"traceutil/trace.go:171","msg":"trace[418239704] transaction","detail":"{read_only:false; response_revision:19996; number_of_response:1; }","duration":"468.20111ms","start":"2026-06-12T11:03:43.255407Z","end":"2026-06-12T11:03:43.723608Z","steps":["trace[418239704] 'process raft request' (duration: 467.990954ms)"],"step_count":1} {"level":"info","ts":"2026-06-12T11:03:43.723642Z","caller":"traceutil/trace.go:171","msg":"trace[575088307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19996; }","duration":"468.151759ms","start":"2026-06-12T11:03:43.255478Z","end":"2026-06-12T11:03:43.72363Z","steps":["trace[575088307] 'agreement among raft nodes before linearized reading' (duration: 468.010564ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T11:03:43.723668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T11:03:43.255467Z","time spent":"468.196039ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-12T11:03:43.723659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.865174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-06-12T11:03:43.723697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T11:03:43.255382Z","time spent":"468.275452ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-12T11:03:43.723702Z","caller":"traceutil/trace.go:171","msg":"trace[383003348] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19996; }","duration":"246.943936ms","start":"2026-06-12T11:03:43.476744Z","end":"2026-06-12T11:03:43.723688Z","steps":["trace[383003348] 'agreement among raft nodes before linearized reading' (duration: 246.818143ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T11:03:43.723699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.206661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.152\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-12T11:03:43.723766Z","caller":"traceutil/trace.go:171","msg":"trace[1825753537] range","detail":"{range_begin:/registry/masterleases/199.19.213.152; range_end:; response_count:1; response_revision:19996; }","duration":"353.323015ms","start":"2026-06-12T11:03:43.370428Z","end":"2026-06-12T11:03:43.723751Z","steps":["trace[1825753537] 'agreement among raft nodes before linearized reading' (duration: 353.097908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T11:03:43.723807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T11:03:43.370411Z","time spent":"353.388336ms","remote":"127.0.0.1:56014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/199.19.213.152\" "} {"level":"warn","ts":"2026-06-12T11:03:43.724019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.12132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-12T11:03:43.724162Z","caller":"traceutil/trace.go:171","msg":"trace[58948887] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:19996; }","duration":"365.311325ms","start":"2026-06-12T11:03:43.358827Z","end":"2026-06-12T11:03:43.724139Z","steps":["trace[58948887] 'agreement among raft nodes before linearized reading' (duration: 364.891692ms)"],"step_count":1} {"level":"warn","ts":"2026-06-12T11:03:43.724264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-12T11:03:43.358805Z","time spent":"365.436979ms","remote":"127.0.0.1:52864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"info","ts":"2026-06-12T11:06:50.367647Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19413} {"level":"info","ts":"2026-06-12T11:06:50.455369Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19413,"took":"85.477914ms","hash":2418358300,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":25989120,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-12T11:06:50.455418Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2418358300,"revision":19413,"compact-revision":17292}