{"level":"warn","ts":"2026-03-19T17:52:25.342649Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T17:52:25.342781Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.185: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.185:2380","--initial-cluster=instance=https://199.19.213.185:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.185:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.185:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-19T17:52:25.34288Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T17:52:25.342906Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.185:2380"]} {"level":"info","ts":"2026-03-19T17:52:25.34293Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-19T17:52:25.345609Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.185:2379"]} {"level":"info","ts":"2026-03-19T17:52:25.345936Z","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.185:2380"],"listen-peer-urls":["https://199.19.213.185:2380"],"advertise-client-urls":["https://199.19.213.185:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.185:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.185:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-19T17:52:25.350239Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.880542ms"} {"level":"info","ts":"2026-03-19T17:52:25.362453Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"dcbda34f8b503a3","cluster-id":"2e806bc52dde46af"} {"level":"info","ts":"2026-03-19T17:52:25.362713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T17:52:25.362752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became follower at term 0"} {"level":"info","ts":"2026-03-19T17:52:25.362852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft dcbda34f8b503a3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T17:52:25.362872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became follower at term 1"} {"level":"info","ts":"2026-03-19T17:52:25.363049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 switched to configuration voters=(994128063811093411)"} {"level":"warn","ts":"2026-03-19T17:52:25.372267Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T17:52:25.377257Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T17:52:25.378706Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-19T17:52:25.380943Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"dcbda34f8b503a3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T17:52:25.381077Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"dcbda34f8b503a3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T17:52:25.381159Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-19T17:52:25.381369Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-19T17:52:25.381412Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-19T17:52:25.382374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 switched to configuration voters=(994128063811093411)"} {"level":"info","ts":"2026-03-19T17:52:25.382555Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2e806bc52dde46af","local-member-id":"dcbda34f8b503a3","added-peer-id":"dcbda34f8b503a3","added-peer-peer-urls":["https://199.19.213.185:2380"]} {"level":"info","ts":"2026-03-19T17:52:25.384056Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-19T17:52:25.384166Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.185:2380"} {"level":"info","ts":"2026-03-19T17:52:25.384218Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.185:2380"} {"level":"info","ts":"2026-03-19T17:52:25.384316Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"dcbda34f8b503a3","initial-advertise-peer-urls":["https://199.19.213.185:2380"],"listen-peer-urls":["https://199.19.213.185:2380"],"advertise-client-urls":["https://199.19.213.185:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.185:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T17:52:25.384359Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T17:52:26.363957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T17:52:26.364039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T17:52:26.364064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 received MsgPreVoteResp from dcbda34f8b503a3 at term 1"} {"level":"info","ts":"2026-03-19T17:52:26.364083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became candidate at term 2"} {"level":"info","ts":"2026-03-19T17:52:26.364097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 received MsgVoteResp from dcbda34f8b503a3 at term 2"} {"level":"info","ts":"2026-03-19T17:52:26.364113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became leader at term 2"} {"level":"info","ts":"2026-03-19T17:52:26.364127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: dcbda34f8b503a3 elected leader dcbda34f8b503a3 at term 2"} {"level":"info","ts":"2026-03-19T17:52:26.365096Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:52:26.366121Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T17:52:26.366126Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"dcbda34f8b503a3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.185:2379]}","request-path":"/0/members/dcbda34f8b503a3/attributes","cluster-id":"2e806bc52dde46af","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T17:52:26.366174Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T17:52:26.366444Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2e806bc52dde46af","local-member-id":"dcbda34f8b503a3","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:52:26.366567Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:52:26.366525Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T17:52:26.36661Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:52:26.36662Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T17:52:26.37014Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.185:2379"} {"level":"info","ts":"2026-03-19T17:52:26.37037Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-19T17:52:30.094637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.2511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-03-19T17:52:30.094797Z","caller":"traceutil/trace.go:171","msg":"trace[471713845] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:216; }","duration":"124.530607ms","start":"2026-03-19T17:52:29.97024Z","end":"2026-03-19T17:52:30.09477Z","steps":["trace[471713845] 'range keys from in-memory index tree' (duration: 124.107546ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:30.212317Z","caller":"traceutil/trace.go:171","msg":"trace[1465970963] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"109.350981ms","start":"2026-03-19T17:52:30.102935Z","end":"2026-03-19T17:52:30.212286Z","steps":["trace[1465970963] 'process raft request' (duration: 109.126255ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:30.381972Z","caller":"traceutil/trace.go:171","msg":"trace[1357940735] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"107.242685ms","start":"2026-03-19T17:52:30.274697Z","end":"2026-03-19T17:52:30.38194Z","steps":["trace[1357940735] 'process raft request' (duration: 52.502493ms)","trace[1357940735] 'compare' (duration: 54.468824ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:52:33.491131Z","caller":"traceutil/trace.go:171","msg":"trace[305737694] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"119.466275ms","start":"2026-03-19T17:52:33.371641Z","end":"2026-03-19T17:52:33.491108Z","steps":["trace[305737694] 'process raft request' (duration: 117.536194ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:34.678198Z","caller":"traceutil/trace.go:171","msg":"trace[1528201972] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"101.262927ms","start":"2026-03-19T17:52:34.576888Z","end":"2026-03-19T17:52:34.678151Z","steps":["trace[1528201972] 'process raft request' (duration: 100.97981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:35.501565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.83843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:52:35.501737Z","caller":"traceutil/trace.go:171","msg":"trace[1040113585] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"346.318588ms","start":"2026-03-19T17:52:35.155391Z","end":"2026-03-19T17:52:35.50171Z","steps":["trace[1040113585] 'process raft request' (duration: 146.666966ms)","trace[1040113585] 'compare' (duration: 198.645675ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:52:35.501844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:35.155362Z","time spent":"346.454611ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:52:35.504071Z","caller":"traceutil/trace.go:171","msg":"trace[1352374929] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:390; }","duration":"144.86487ms","start":"2026-03-19T17:52:35.359174Z","end":"2026-03-19T17:52:35.504039Z","steps":["trace[1352374929] 'read index received' (duration: 144.410948ms)","trace[1352374929] 'applied index is now lower than readState.Index' (duration: 452.662µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:52:35.504174Z","caller":"traceutil/trace.go:171","msg":"trace[705180026] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"345.437034ms","start":"2026-03-19T17:52:35.158712Z","end":"2026-03-19T17:52:35.504149Z","steps":["trace[705180026] 'process raft request' (duration: 345.077665ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:35.504264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.069204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T17:52:35.504321Z","caller":"traceutil/trace.go:171","msg":"trace[1218680521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:381; }","duration":"145.193078ms","start":"2026-03-19T17:52:35.359114Z","end":"2026-03-19T17:52:35.504307Z","steps":["trace[1218680521] 'agreement among raft nodes before linearized reading' (duration: 145.060934ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:35.504349Z","caller":"traceutil/trace.go:171","msg":"trace[1011885623] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"330.654768ms","start":"2026-03-19T17:52:35.173663Z","end":"2026-03-19T17:52:35.504318Z","steps":["trace[1011885623] 'process raft request' (duration: 330.318729ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:35.504555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:35.17364Z","time spent":"330.801052ms","remote":"127.0.0.1:60734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:52:35.504561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:35.158686Z","time spent":"345.540977ms","remote":"127.0.0.1:60434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:52:42.063744Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262225857683723912,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T17:52:42.477555Z","caller":"traceutil/trace.go:171","msg":"trace[94816845] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"939.204695ms","start":"2026-03-19T17:52:41.538324Z","end":"2026-03-19T17:52:42.477529Z","steps":["trace[94816845] 'process raft request' (duration: 939.068791ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:42.47773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:41.538304Z","time spent":"939.328418ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:52:42.732648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.033901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:52:42.733098Z","caller":"traceutil/trace.go:171","msg":"trace[177371142] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"1.170015401s","start":"2026-03-19T17:52:41.562995Z","end":"2026-03-19T17:52:42.73301Z","steps":["trace[177371142] 'read index received' (duration: 914.58113ms)","trace[177371142] 'applied index is now lower than readState.Index' (duration: 255.40856ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:52:42.734299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171242653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-03-19T17:52:42.734373Z","caller":"traceutil/trace.go:171","msg":"trace[1426687629] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:406; }","duration":"1.171401447s","start":"2026-03-19T17:52:41.562953Z","end":"2026-03-19T17:52:42.734355Z","steps":["trace[1426687629] 'agreement among raft nodes before linearized reading' (duration: 1.170143164s)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:42.733085Z","caller":"traceutil/trace.go:171","msg":"trace[297950875] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"1.032147295s","start":"2026-03-19T17:52:41.700862Z","end":"2026-03-19T17:52:42.733009Z","steps":["trace[297950875] 'process raft request' (duration: 831.954339ms)","trace[297950875] 'compare' (duration: 194.394724ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:52:42.734418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:41.562933Z","time spent":"1.171473419s","remote":"127.0.0.1:60434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-03-19T17:52:42.734538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.564286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T17:52:42.73457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.681511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-19T17:52:42.734612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.192884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-19T17:52:42.734615Z","caller":"traceutil/trace.go:171","msg":"trace[758649611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"616.671339ms","start":"2026-03-19T17:52:42.117918Z","end":"2026-03-19T17:52:42.734589Z","steps":["trace[758649611] 'agreement among raft nodes before linearized reading' (duration: 616.544276ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:42.73464Z","caller":"traceutil/trace.go:171","msg":"trace[910009399] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:406; }","duration":"959.803114ms","start":"2026-03-19T17:52:41.774816Z","end":"2026-03-19T17:52:42.734619Z","steps":["trace[910009399] 'agreement among raft nodes before linearized reading' (duration: 959.631069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:42.73466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:42.117901Z","time spent":"616.749921ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:52:42.734683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:41.774791Z","time spent":"959.883156ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-19T17:52:42.734705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.495202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T17:52:42.734774Z","caller":"traceutil/trace.go:171","msg":"trace[1597564502] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:406; }","duration":"739.269885ms","start":"2026-03-19T17:52:41.995372Z","end":"2026-03-19T17:52:42.734641Z","steps":["trace[1597564502] 'agreement among raft nodes before linearized reading' (duration: 739.098091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:52:42.734828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:41.995355Z","time spent":"739.463811ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T17:52:42.734672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:52:41.700837Z","time spent":"1.033616543s","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:52:42.734841Z","caller":"traceutil/trace.go:171","msg":"trace[895382131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:406; }","duration":"268.654326ms","start":"2026-03-19T17:52:42.466127Z","end":"2026-03-19T17:52:42.734782Z","steps":["trace[895382131] 'agreement among raft nodes before linearized reading' (duration: 268.41092ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:58.69088Z","caller":"traceutil/trace.go:171","msg":"trace[313803423] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"141.417733ms","start":"2026-03-19T17:52:58.549444Z","end":"2026-03-19T17:52:58.690861Z","steps":["trace[313803423] 'process raft request' (duration: 141.302821ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:52:58.752815Z","caller":"traceutil/trace.go:171","msg":"trace[280674303] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"197.042423ms","start":"2026-03-19T17:52:58.555752Z","end":"2026-03-19T17:52:58.752794Z","steps":["trace[280674303] 'process raft request' (duration: 196.932881ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:04.863084Z","caller":"traceutil/trace.go:171","msg":"trace[1291653221] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"139.906855ms","start":"2026-03-19T17:53:04.723155Z","end":"2026-03-19T17:53:04.863062Z","steps":["trace[1291653221] 'process raft request' (duration: 139.72632ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:05.802691Z","caller":"traceutil/trace.go:171","msg":"trace[1024900762] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"115.082867ms","start":"2026-03-19T17:53:05.687588Z","end":"2026-03-19T17:53:05.80267Z","steps":["trace[1024900762] 'read index received' (duration: 114.925223ms)","trace[1024900762] 'applied index is now lower than readState.Index' (duration: 156.664µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:53:05.80277Z","caller":"traceutil/trace.go:171","msg":"trace[397841507] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"260.613067ms","start":"2026-03-19T17:53:05.54212Z","end":"2026-03-19T17:53:05.802733Z","steps":["trace[397841507] 'process raft request' (duration: 260.428042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:05.802842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.224821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:53:05.802933Z","caller":"traceutil/trace.go:171","msg":"trace[2062291485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"115.367444ms","start":"2026-03-19T17:53:05.687546Z","end":"2026-03-19T17:53:05.802914Z","steps":["trace[2062291485] 'agreement among raft nodes before linearized reading' (duration: 115.20552ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.322928Z","caller":"traceutil/trace.go:171","msg":"trace[474344180] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"272.214535ms","start":"2026-03-19T17:53:08.050667Z","end":"2026-03-19T17:53:08.322881Z","steps":["trace[474344180] 'process raft request' (duration: 255.171497ms)","trace[474344180] 'compare' (duration: 16.785281ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:53:08.323268Z","caller":"traceutil/trace.go:171","msg":"trace[895638701] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"260.961625ms","start":"2026-03-19T17:53:08.062289Z","end":"2026-03-19T17:53:08.32325Z","steps":["trace[895638701] 'process raft request' (duration: 260.78226ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.323303Z","caller":"traceutil/trace.go:171","msg":"trace[19524088] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"260.828101ms","start":"2026-03-19T17:53:08.062445Z","end":"2026-03-19T17:53:08.323273Z","steps":["trace[19524088] 'process raft request' (duration: 260.76989ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.323413Z","caller":"traceutil/trace.go:171","msg":"trace[881584927] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"260.837322ms","start":"2026-03-19T17:53:08.062565Z","end":"2026-03-19T17:53:08.323402Z","steps":["trace[881584927] 'process raft request' (duration: 260.732449ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.323383Z","caller":"traceutil/trace.go:171","msg":"trace[1287147542] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"260.876393ms","start":"2026-03-19T17:53:08.062483Z","end":"2026-03-19T17:53:08.323359Z","steps":["trace[1287147542] 'process raft request' (duration: 260.758499ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.323348Z","caller":"traceutil/trace.go:171","msg":"trace[2110120771] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"260.885292ms","start":"2026-03-19T17:53:08.062403Z","end":"2026-03-19T17:53:08.323289Z","steps":["trace[2110120771] 'process raft request' (duration: 260.77794ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.323584Z","caller":"traceutil/trace.go:171","msg":"trace[1114547417] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"260.775001ms","start":"2026-03-19T17:53:08.06278Z","end":"2026-03-19T17:53:08.323555Z","steps":["trace[1114547417] 'process raft request' (duration: 260.582786ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.323702Z","caller":"traceutil/trace.go:171","msg":"trace[2557843] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"260.812061ms","start":"2026-03-19T17:53:08.062875Z","end":"2026-03-19T17:53:08.323687Z","steps":["trace[2557843] 'process raft request' (duration: 260.551915ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.323794Z","caller":"traceutil/trace.go:171","msg":"trace[823331512] transaction","detail":"{read_only:false; number_of_response:0; response_revision:594; }","duration":"240.742486ms","start":"2026-03-19T17:53:08.083026Z","end":"2026-03-19T17:53:08.323768Z","steps":["trace[823331512] 'process raft request' (duration: 240.560152ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.323243Z","caller":"traceutil/trace.go:171","msg":"trace[199226662] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"261.023235ms","start":"2026-03-19T17:53:08.062181Z","end":"2026-03-19T17:53:08.323204Z","steps":["trace[199226662] 'process raft request' (duration: 260.633666ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.324562Z","caller":"traceutil/trace.go:171","msg":"trace[88356539] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"261.824737ms","start":"2026-03-19T17:53:08.062718Z","end":"2026-03-19T17:53:08.324543Z","steps":["trace[88356539] 'process raft request' (duration: 260.609076ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.324569Z","caller":"traceutil/trace.go:171","msg":"trace[1597037034] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"256.689975ms","start":"2026-03-19T17:53:08.067844Z","end":"2026-03-19T17:53:08.324534Z","steps":["trace[1597037034] 'process raft request' (duration: 255.612958ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.324569Z","caller":"traceutil/trace.go:171","msg":"trace[1670762184] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"261.678614ms","start":"2026-03-19T17:53:08.062825Z","end":"2026-03-19T17:53:08.324504Z","steps":["trace[1670762184] 'process raft request' (duration: 260.576006ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.324603Z","caller":"traceutil/trace.go:171","msg":"trace[1259346431] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"261.997662ms","start":"2026-03-19T17:53:08.062542Z","end":"2026-03-19T17:53:08.32454Z","steps":["trace[1259346431] 'process raft request' (duration: 260.725949ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.324632Z","caller":"traceutil/trace.go:171","msg":"trace[1478842408] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"253.038691ms","start":"2026-03-19T17:53:08.071552Z","end":"2026-03-19T17:53:08.324591Z","steps":["trace[1478842408] 'process raft request' (duration: 251.939903ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.513271Z","caller":"traceutil/trace.go:171","msg":"trace[1901025275] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"178.499826ms","start":"2026-03-19T17:53:08.334747Z","end":"2026-03-19T17:53:08.513247Z","steps":["trace[1901025275] 'process raft request' (duration: 178.411494ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.51336Z","caller":"traceutil/trace.go:171","msg":"trace[1625987674] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"178.574369ms","start":"2026-03-19T17:53:08.33473Z","end":"2026-03-19T17:53:08.513305Z","steps":["trace[1625987674] 'process raft request' (duration: 178.118687ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.513211Z","caller":"traceutil/trace.go:171","msg":"trace[278526323] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"178.562238ms","start":"2026-03-19T17:53:08.334603Z","end":"2026-03-19T17:53:08.513165Z","steps":["trace[278526323] 'process raft request' (duration: 167.229947ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.513874Z","caller":"traceutil/trace.go:171","msg":"trace[1261747190] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"178.973029ms","start":"2026-03-19T17:53:08.334841Z","end":"2026-03-19T17:53:08.513814Z","steps":["trace[1261747190] 'process raft request' (duration: 178.366493ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.513935Z","caller":"traceutil/trace.go:171","msg":"trace[1319587241] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"179.01321ms","start":"2026-03-19T17:53:08.334899Z","end":"2026-03-19T17:53:08.513912Z","steps":["trace[1319587241] 'process raft request' (duration: 178.943979ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.513969Z","caller":"traceutil/trace.go:171","msg":"trace[362198415] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"179.01409ms","start":"2026-03-19T17:53:08.33493Z","end":"2026-03-19T17:53:08.513944Z","steps":["trace[362198415] 'process raft request' (duration: 178.945719ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.51396Z","caller":"traceutil/trace.go:171","msg":"trace[1748883285] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"179.02772ms","start":"2026-03-19T17:53:08.334884Z","end":"2026-03-19T17:53:08.513911Z","steps":["trace[1748883285] 'process raft request' (duration: 178.836525ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.514083Z","caller":"traceutil/trace.go:171","msg":"trace[745233471] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"178.968909ms","start":"2026-03-19T17:53:08.335094Z","end":"2026-03-19T17:53:08.514063Z","steps":["trace[745233471] 'process raft request' (duration: 178.832576ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.514029Z","caller":"traceutil/trace.go:171","msg":"trace[315344051] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"179.071922ms","start":"2026-03-19T17:53:08.334941Z","end":"2026-03-19T17:53:08.514013Z","steps":["trace[315344051] 'process raft request' (duration: 178.958979ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.51424Z","caller":"traceutil/trace.go:171","msg":"trace[2049940265] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"179.051621ms","start":"2026-03-19T17:53:08.335178Z","end":"2026-03-19T17:53:08.51423Z","steps":["trace[2049940265] 'process raft request' (duration: 178.772654ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.514634Z","caller":"traceutil/trace.go:171","msg":"trace[1787751448] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"176.30706ms","start":"2026-03-19T17:53:08.338299Z","end":"2026-03-19T17:53:08.514607Z","steps":["trace[1787751448] 'process raft request' (duration: 176.255089ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.515203Z","caller":"traceutil/trace.go:171","msg":"trace[1879559073] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"179.828141ms","start":"2026-03-19T17:53:08.335363Z","end":"2026-03-19T17:53:08.515191Z","steps":["trace[1879559073] 'process raft request' (duration: 178.613109ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.515922Z","caller":"traceutil/trace.go:171","msg":"trace[1360341425] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"178.058866ms","start":"2026-03-19T17:53:08.337841Z","end":"2026-03-19T17:53:08.5159Z","steps":["trace[1360341425] 'process raft request' (duration: 176.66915ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.516053Z","caller":"traceutil/trace.go:171","msg":"trace[489925100] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"179.250045ms","start":"2026-03-19T17:53:08.336758Z","end":"2026-03-19T17:53:08.516008Z","steps":["trace[489925100] 'process raft request' (duration: 177.698816ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.684183Z","caller":"traceutil/trace.go:171","msg":"trace[1204886526] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"154.798777ms","start":"2026-03-19T17:53:08.529365Z","end":"2026-03-19T17:53:08.684164Z","steps":["trace[1204886526] 'process raft request' (duration: 154.765166ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.684219Z","caller":"traceutil/trace.go:171","msg":"trace[1457709324] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"154.975282ms","start":"2026-03-19T17:53:08.529218Z","end":"2026-03-19T17:53:08.684193Z","steps":["trace[1457709324] 'process raft request' (duration: 143.658421ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.684369Z","caller":"traceutil/trace.go:171","msg":"trace[632262996] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"155.011932ms","start":"2026-03-19T17:53:08.529296Z","end":"2026-03-19T17:53:08.684308Z","steps":["trace[632262996] 'process raft request' (duration: 154.799357ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.685303Z","caller":"traceutil/trace.go:171","msg":"trace[231429124] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"148.960638ms","start":"2026-03-19T17:53:08.536319Z","end":"2026-03-19T17:53:08.68528Z","steps":["trace[231429124] 'process raft request' (duration: 148.731211ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.685511Z","caller":"traceutil/trace.go:171","msg":"trace[1087911123] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:632; }","duration":"142.483491ms","start":"2026-03-19T17:53:08.543011Z","end":"2026-03-19T17:53:08.685494Z","steps":["trace[1087911123] 'read index received' (duration: 129.897838ms)","trace[1087911123] 'applied index is now lower than readState.Index' (duration: 12.584393ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:53:08.685697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.672846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xthzf\" ","response":"range_response_count:1 size:17247"} {"level":"info","ts":"2026-03-19T17:53:08.685755Z","caller":"traceutil/trace.go:171","msg":"trace[295484796] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xthzf; range_end:; response_count:1; response_revision:618; }","duration":"142.783519ms","start":"2026-03-19T17:53:08.542955Z","end":"2026-03-19T17:53:08.685739Z","steps":["trace[295484796] 'agreement among raft nodes before linearized reading' (duration: 142.621635ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:08.689394Z","caller":"traceutil/trace.go:171","msg":"trace[331332042] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"144.755849ms","start":"2026-03-19T17:53:08.544611Z","end":"2026-03-19T17:53:08.689367Z","steps":["trace[331332042] 'process raft request' (duration: 140.604702ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:09.000724Z","caller":"traceutil/trace.go:171","msg":"trace[646820144] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"287.321393ms","start":"2026-03-19T17:53:08.713361Z","end":"2026-03-19T17:53:09.000682Z","steps":["trace[646820144] 'process raft request' (duration: 284.8578ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:09.000773Z","caller":"traceutil/trace.go:171","msg":"trace[1860485624] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"280.538629ms","start":"2026-03-19T17:53:08.720212Z","end":"2026-03-19T17:53:09.000751Z","steps":["trace[1860485624] 'process raft request' (duration: 280.482497ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:09.00083Z","caller":"traceutil/trace.go:171","msg":"trace[1289547817] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"286.037769ms","start":"2026-03-19T17:53:08.71477Z","end":"2026-03-19T17:53:09.000807Z","steps":["trace[1289547817] 'process raft request' (duration: 285.728981ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:09.075257Z","caller":"traceutil/trace.go:171","msg":"trace[357087661] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:643; }","duration":"148.335471ms","start":"2026-03-19T17:53:08.92687Z","end":"2026-03-19T17:53:09.075206Z","steps":["trace[357087661] 'read index received' (duration: 71.297581ms)","trace[357087661] 'applied index is now lower than readState.Index' (duration: 77.03683ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:53:09.07535Z","caller":"traceutil/trace.go:171","msg":"trace[183696055] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"318.001272ms","start":"2026-03-19T17:53:08.757285Z","end":"2026-03-19T17:53:09.075287Z","steps":["trace[183696055] 'process raft request' (duration: 317.780786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:09.075513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.625819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-19T17:53:09.075564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:53:08.75727Z","time spent":"318.197017ms","remote":"127.0.0.1:60448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:53:09.075581Z","caller":"traceutil/trace.go:171","msg":"trace[431811047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"148.756352ms","start":"2026-03-19T17:53:08.926809Z","end":"2026-03-19T17:53:09.075565Z","steps":["trace[431811047] 'agreement among raft nodes before linearized reading' (duration: 148.544987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:09.082376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.944305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-19T17:53:09.082464Z","caller":"traceutil/trace.go:171","msg":"trace[1176738082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"152.085678ms","start":"2026-03-19T17:53:08.930359Z","end":"2026-03-19T17:53:09.082445Z","steps":["trace[1176738082] 'agreement among raft nodes before linearized reading' (duration: 151.919364ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:09.082524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.029838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-19T17:53:09.082604Z","caller":"traceutil/trace.go:171","msg":"trace[1972769631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"126.13715ms","start":"2026-03-19T17:53:08.956447Z","end":"2026-03-19T17:53:09.082585Z","steps":["trace[1972769631] 'agreement among raft nodes before linearized reading' (duration: 125.992537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:09.082829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.239794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T17:53:09.08294Z","caller":"traceutil/trace.go:171","msg":"trace[742144171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"126.401457ms","start":"2026-03-19T17:53:08.95649Z","end":"2026-03-19T17:53:09.082891Z","steps":["trace[742144171] 'agreement among raft nodes before linearized reading' (duration: 125.954336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:29.377268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.174365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:53:29.37755Z","caller":"traceutil/trace.go:171","msg":"trace[531292419] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"169.977618ms","start":"2026-03-19T17:53:29.207523Z","end":"2026-03-19T17:53:29.377501Z","steps":["trace[531292419] 'process raft request' (duration: 60.449593ms)","trace[531292419] 'compare' (duration: 109.032772ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:53:29.377672Z","caller":"traceutil/trace.go:171","msg":"trace[1478299087] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"164.449785ms","start":"2026-03-19T17:53:29.213142Z","end":"2026-03-19T17:53:29.377591Z","steps":["trace[1478299087] 'process raft request' (duration: 164.391124ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:29.377717Z","caller":"traceutil/trace.go:171","msg":"trace[811811100] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"168.218002ms","start":"2026-03-19T17:53:29.209453Z","end":"2026-03-19T17:53:29.377671Z","steps":["trace[811811100] 'process raft request' (duration: 167.955916ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:53:49.506341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.857073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.185\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-19T17:53:49.506645Z","caller":"traceutil/trace.go:171","msg":"trace[1394415016] range","detail":"{range_begin:/registry/masterleases/199.19.213.185; range_end:; response_count:1; response_revision:1063; }","duration":"119.231163ms","start":"2026-03-19T17:53:49.387394Z","end":"2026-03-19T17:53:49.506625Z","steps":["trace[1394415016] 'range keys from in-memory index tree' (duration: 118.648968ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:54.183301Z","caller":"traceutil/trace.go:171","msg":"trace[1680184366] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"124.553301ms","start":"2026-03-19T17:53:54.058721Z","end":"2026-03-19T17:53:54.183274Z","steps":["trace[1680184366] 'process raft request' (duration: 124.364147ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:53:59.598272Z","caller":"traceutil/trace.go:171","msg":"trace[1185672559] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1249; }","duration":"189.391764ms","start":"2026-03-19T17:53:59.408859Z","end":"2026-03-19T17:53:59.59825Z","steps":["trace[1185672559] 'read index received' (duration: 189.25495ms)","trace[1185672559] 'applied index is now lower than readState.Index' (duration: 135.774µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:53:59.598494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.624689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T17:53:59.598534Z","caller":"traceutil/trace.go:171","msg":"trace[1522838971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1214; }","duration":"189.713142ms","start":"2026-03-19T17:53:59.40881Z","end":"2026-03-19T17:53:59.598523Z","steps":["trace[1522838971] 'agreement among raft nodes before linearized reading' (duration: 189.571448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:18.125802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.881317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:54:18.126191Z","caller":"traceutil/trace.go:171","msg":"trace[84253013] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"357.933432ms","start":"2026-03-19T17:54:17.768226Z","end":"2026-03-19T17:54:18.126159Z","steps":["trace[84253013] 'process raft request' (duration: 117.612215ms)","trace[84253013] 'compare' (duration: 239.764174ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:18.126264Z","caller":"traceutil/trace.go:171","msg":"trace[1649544375] linearizableReadLoop","detail":"{readStateIndex:1576; appliedIndex:1574; }","duration":"347.818813ms","start":"2026-03-19T17:54:17.778423Z","end":"2026-03-19T17:54:18.126242Z","steps":["trace[1649544375] 'read index received' (duration: 107.401752ms)","trace[1649544375] 'applied index is now lower than readState.Index' (duration: 240.415601ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:54:18.12635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:17.768208Z","time spent":"358.058176ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:54:18.126511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.083369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:54:18.126565Z","caller":"traceutil/trace.go:171","msg":"trace[1474770058] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1516; }","duration":"348.14599ms","start":"2026-03-19T17:54:17.778393Z","end":"2026-03-19T17:54:18.126539Z","steps":["trace[1474770058] 'agreement among raft nodes before linearized reading' (duration: 348.078249ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:18.126563Z","caller":"traceutil/trace.go:171","msg":"trace[629983237] transaction","detail":"{read_only:false; response_revision:1516; number_of_response:1; }","duration":"356.967927ms","start":"2026-03-19T17:54:17.76957Z","end":"2026-03-19T17:54:18.126538Z","steps":["trace[629983237] 'process raft request' (duration: 356.563657ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:18.126676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.457681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-19T17:54:18.12672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.343004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T17:54:18.126746Z","caller":"traceutil/trace.go:171","msg":"trace[1650849584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1516; }","duration":"312.391054ms","start":"2026-03-19T17:54:17.814348Z","end":"2026-03-19T17:54:18.126739Z","steps":["trace[1650849584] 'agreement among raft nodes before linearized reading' (duration: 312.279771ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:18.126747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.699399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:54:18.12676Z","caller":"traceutil/trace.go:171","msg":"trace[906496689] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1516; }","duration":"304.568194ms","start":"2026-03-19T17:54:17.822176Z","end":"2026-03-19T17:54:18.126744Z","steps":["trace[906496689] 'agreement among raft nodes before linearized reading' (duration: 304.37831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:18.126771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:17.81434Z","time spent":"312.425566ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-19T17:54:18.126811Z","caller":"traceutil/trace.go:171","msg":"trace[1850953397] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:0; response_revision:1516; }","duration":"347.784831ms","start":"2026-03-19T17:54:17.779009Z","end":"2026-03-19T17:54:18.126794Z","steps":["trace[1850953397] 'agreement among raft nodes before linearized reading' (duration: 347.683119ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:18.126816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:17.82216Z","time spent":"304.644166ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T17:54:18.126598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:17.778374Z","time spent":"348.215583ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" limit:10000 "} {"level":"warn","ts":"2026-03-19T17:54:18.126857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:17.779Z","time spent":"347.845433ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-03-19T17:54:18.126815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:17.769556Z","time spent":"357.174512ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:54:18.126881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.322167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-19T17:54:18.126971Z","caller":"traceutil/trace.go:171","msg":"trace[382690374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1516; }","duration":"304.469192ms","start":"2026-03-19T17:54:17.822479Z","end":"2026-03-19T17:54:18.126948Z","steps":["trace[382690374] 'agreement among raft nodes before linearized reading' (duration: 304.173354ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:18.127027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:17.822466Z","time spent":"304.548024ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T17:54:18.12703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.08854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:54:18.12708Z","caller":"traceutil/trace.go:171","msg":"trace[628587412] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:1516; }","duration":"348.657484ms","start":"2026-03-19T17:54:17.778406Z","end":"2026-03-19T17:54:18.127064Z","steps":["trace[628587412] 'agreement among raft nodes before linearized reading' (duration: 348.080719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:18.127121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:17.778396Z","time spent":"348.711965ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-03-19T17:54:18.336826Z","caller":"traceutil/trace.go:171","msg":"trace[1378701186] transaction","detail":"{read_only:false; response_revision:1518; number_of_response:1; }","duration":"204.821318ms","start":"2026-03-19T17:54:18.131982Z","end":"2026-03-19T17:54:18.336803Z","steps":["trace[1378701186] 'process raft request' (duration: 204.749666ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:18.336862Z","caller":"traceutil/trace.go:171","msg":"trace[454896772] transaction","detail":"{read_only:false; response_revision:1517; number_of_response:1; }","duration":"205.425184ms","start":"2026-03-19T17:54:18.131401Z","end":"2026-03-19T17:54:18.336827Z","steps":["trace[454896772] 'process raft request' (duration: 184.467276ms)","trace[454896772] 'compare' (duration: 20.738302ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:18.337445Z","caller":"traceutil/trace.go:171","msg":"trace[973968323] transaction","detail":"{read_only:false; response_revision:1519; number_of_response:1; }","duration":"205.311501ms","start":"2026-03-19T17:54:18.132102Z","end":"2026-03-19T17:54:18.337414Z","steps":["trace[973968323] 'process raft request' (duration: 204.667964ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:18.487825Z","caller":"traceutil/trace.go:171","msg":"trace[363046778] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"118.543788ms","start":"2026-03-19T17:54:18.369256Z","end":"2026-03-19T17:54:18.487799Z","steps":["trace[363046778] 'process raft request' (duration: 100.645069ms)","trace[363046778] 'compare' (duration: 17.781176ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:54:26.550637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.869597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-19T17:54:26.550733Z","caller":"traceutil/trace.go:171","msg":"trace[1275280288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1635; }","duration":"384.022731ms","start":"2026-03-19T17:54:26.16669Z","end":"2026-03-19T17:54:26.550713Z","steps":["trace[1275280288] 'range keys from in-memory index tree' (duration: 383.686322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:26.550848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.131178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T17:54:26.550949Z","caller":"traceutil/trace.go:171","msg":"trace[1826891530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1635; }","duration":"129.265842ms","start":"2026-03-19T17:54:26.421664Z","end":"2026-03-19T17:54:26.550929Z","steps":["trace[1826891530] 'range keys from in-memory index tree' (duration: 128.957814ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:26.550839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:26.166665Z","time spent":"384.161114ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T17:54:26.550929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.872999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T17:54:26.551032Z","caller":"traceutil/trace.go:171","msg":"trace[766347304] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:1635; }","duration":"186.982522ms","start":"2026-03-19T17:54:26.364024Z","end":"2026-03-19T17:54:26.551006Z","steps":["trace[766347304] 'count revisions from in-memory index tree' (duration: 186.774606ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:26.551087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.472551ms","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":"warn","ts":"2026-03-19T17:54:26.551081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.275002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-19T17:54:26.55112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.629116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-19T17:54:26.55116Z","caller":"traceutil/trace.go:171","msg":"trace[650056801] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1635; }","duration":"352.574805ms","start":"2026-03-19T17:54:26.198565Z","end":"2026-03-19T17:54:26.55114Z","steps":["trace[650056801] 'range keys from in-memory index tree' (duration: 352.314587ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:26.551232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.937618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-19T17:54:26.551253Z","caller":"traceutil/trace.go:171","msg":"trace[1940822704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1635; }","duration":"385.442698ms","start":"2026-03-19T17:54:26.165721Z","end":"2026-03-19T17:54:26.551164Z","steps":["trace[1940822704] 'range keys from in-memory index tree' (duration: 385.016296ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:26.551279Z","caller":"traceutil/trace.go:171","msg":"trace[580988298] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1635; }","duration":"135.060721ms","start":"2026-03-19T17:54:26.416206Z","end":"2026-03-19T17:54:26.551267Z","steps":["trace[580988298] 'range keys from in-memory index tree' (duration: 134.815495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:26.551284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:26.198539Z","time spent":"352.729209ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-03-19T17:54:26.551294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:26.165674Z","time spent":"385.610611ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-19T17:54:26.551226Z","caller":"traceutil/trace.go:171","msg":"trace[769543495] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1635; }","duration":"137.74925ms","start":"2026-03-19T17:54:26.413424Z","end":"2026-03-19T17:54:26.551173Z","steps":["trace[769543495] 'range keys from in-memory index tree' (duration: 137.456522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:26.55174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.203658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-44ca1da5-2a3d-4e58-9ba9-8b75033a785d\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-03-19T17:54:26.55183Z","caller":"traceutil/trace.go:171","msg":"trace[1048299480] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-44ca1da5-2a3d-4e58-9ba9-8b75033a785d; range_end:; response_count:1; response_revision:1635; }","duration":"573.350171ms","start":"2026-03-19T17:54:25.978456Z","end":"2026-03-19T17:54:26.551806Z","steps":["trace[1048299480] 'range keys from in-memory index tree' (duration: 572.364666ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:26.551876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:25.978428Z","time spent":"573.438906ms","remote":"127.0.0.1:60434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-44ca1da5-2a3d-4e58-9ba9-8b75033a785d\" "} {"level":"info","ts":"2026-03-19T17:54:26.722615Z","caller":"traceutil/trace.go:171","msg":"trace[396331862] transaction","detail":"{read_only:false; response_revision:1637; number_of_response:1; }","duration":"165.648934ms","start":"2026-03-19T17:54:26.556928Z","end":"2026-03-19T17:54:26.722577Z","steps":["trace[396331862] 'process raft request' (duration: 110.807919ms)","trace[396331862] 'compare' (duration: 54.65002ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:26.722635Z","caller":"traceutil/trace.go:171","msg":"trace[523934760] transaction","detail":"{read_only:false; response_revision:1638; number_of_response:1; }","duration":"165.575352ms","start":"2026-03-19T17:54:26.557035Z","end":"2026-03-19T17:54:26.72261Z","steps":["trace[523934760] 'process raft request' (duration: 165.47796ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:26.722946Z","caller":"traceutil/trace.go:171","msg":"trace[308797153] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"165.81969ms","start":"2026-03-19T17:54:26.557111Z","end":"2026-03-19T17:54:26.72293Z","steps":["trace[308797153] 'process raft request' (duration: 165.442059ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:26.723114Z","caller":"traceutil/trace.go:171","msg":"trace[48981549] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"164.220857ms","start":"2026-03-19T17:54:26.558862Z","end":"2026-03-19T17:54:26.723083Z","steps":["trace[48981549] 'process raft request' (duration: 164.086414ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:26.723203Z","caller":"traceutil/trace.go:171","msg":"trace[2146910357] transaction","detail":"{read_only:false; response_revision:1640; number_of_response:1; }","duration":"165.49347ms","start":"2026-03-19T17:54:26.557662Z","end":"2026-03-19T17:54:26.723155Z","steps":["trace[2146910357] 'process raft request' (duration: 165.135221ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:39.026294Z","caller":"traceutil/trace.go:171","msg":"trace[538374398] transaction","detail":"{read_only:false; response_revision:1736; number_of_response:1; }","duration":"146.773051ms","start":"2026-03-19T17:54:38.879499Z","end":"2026-03-19T17:54:39.026272Z","steps":["trace[538374398] 'process raft request' (duration: 120.454626ms)","trace[538374398] 'compare' (duration: 26.180262ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:54:43.170095Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262225857683726576,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T17:54:43.274949Z","caller":"traceutil/trace.go:171","msg":"trace[1146224877] transaction","detail":"{read_only:false; response_revision:1763; number_of_response:1; }","duration":"795.859243ms","start":"2026-03-19T17:54:42.479067Z","end":"2026-03-19T17:54:43.274926Z","steps":["trace[1146224877] 'process raft request' (duration: 795.71078ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:43.275091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:42.479046Z","time spent":"795.974757ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:54:43.291181Z","caller":"traceutil/trace.go:171","msg":"trace[155747956] linearizableReadLoop","detail":"{readStateIndex:1830; appliedIndex:1828; }","duration":"621.983869ms","start":"2026-03-19T17:54:42.669166Z","end":"2026-03-19T17:54:43.29115Z","steps":["trace[155747956] 'read index received' (duration: 605.64991ms)","trace[155747956] 'applied index is now lower than readState.Index' (duration: 16.332809ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:43.291217Z","caller":"traceutil/trace.go:171","msg":"trace[484390870] transaction","detail":"{read_only:false; response_revision:1764; number_of_response:1; }","duration":"182.862397ms","start":"2026-03-19T17:54:43.108321Z","end":"2026-03-19T17:54:43.291184Z","steps":["trace[484390870] 'process raft request' (duration: 182.667491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:43.291569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.745358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-19T17:54:43.291577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.237268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-19T17:54:43.291613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.640014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-19T17:54:43.291685Z","caller":"traceutil/trace.go:171","msg":"trace[1521517220] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1764; }","duration":"404.341901ms","start":"2026-03-19T17:54:42.887296Z","end":"2026-03-19T17:54:43.291638Z","steps":["trace[1521517220] 'agreement among raft nodes before linearized reading' (duration: 404.154716ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:43.291569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.768718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T17:54:43.291651Z","caller":"traceutil/trace.go:171","msg":"trace[1203376685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1764; }","duration":"535.876721ms","start":"2026-03-19T17:54:42.755753Z","end":"2026-03-19T17:54:43.29163Z","steps":["trace[1203376685] 'agreement among raft nodes before linearized reading' (duration: 535.645866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:43.291725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:42.887287Z","time spent":"404.430693ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-19T17:54:43.29174Z","caller":"traceutil/trace.go:171","msg":"trace[824180351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1764; }","duration":"405.982353ms","start":"2026-03-19T17:54:42.885741Z","end":"2026-03-19T17:54:43.291723Z","steps":["trace[824180351] 'agreement among raft nodes before linearized reading' (duration: 405.726167ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:43.291568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.362071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-19T17:54:43.291777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:42.755732Z","time spent":"536.028315ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T17:54:43.291785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:42.885716Z","time spent":"406.059605ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-19T17:54:43.291815Z","caller":"traceutil/trace.go:171","msg":"trace[876203917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1764; }","duration":"404.649169ms","start":"2026-03-19T17:54:42.887155Z","end":"2026-03-19T17:54:43.291805Z","steps":["trace[876203917] 'agreement among raft nodes before linearized reading' (duration: 404.293099ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:43.291689Z","caller":"traceutil/trace.go:171","msg":"trace[1794358350] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1764; }","duration":"451.740456ms","start":"2026-03-19T17:54:42.839933Z","end":"2026-03-19T17:54:43.291674Z","steps":["trace[1794358350] 'agreement among raft nodes before linearized reading' (duration: 451.578182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:43.291856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:42.887145Z","time spent":"404.697891ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T17:54:43.291651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.483491ms","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-03-19T17:54:43.291925Z","caller":"traceutil/trace.go:171","msg":"trace[90780387] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1764; }","duration":"622.782019ms","start":"2026-03-19T17:54:42.669131Z","end":"2026-03-19T17:54:43.291913Z","steps":["trace[90780387] 'agreement among raft nodes before linearized reading' (duration: 622.172474ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:43.291906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:42.839919Z","time spent":"451.952261ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-19T17:54:43.291953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:42.669118Z","time spent":"622.82773ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-03-19T17:54:43.897416Z","caller":"traceutil/trace.go:171","msg":"trace[740366862] transaction","detail":"{read_only:false; response_revision:1765; number_of_response:1; }","duration":"601.089663ms","start":"2026-03-19T17:54:43.296307Z","end":"2026-03-19T17:54:43.897397Z","steps":["trace[740366862] 'process raft request' (duration: 600.962019ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:43.897553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:43.296278Z","time spent":"601.199026ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:54:43.898869Z","caller":"traceutil/trace.go:171","msg":"trace[1093224125] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"602.262424ms","start":"2026-03-19T17:54:43.296577Z","end":"2026-03-19T17:54:43.898839Z","steps":["trace[1093224125] 'process raft request' (duration: 602.168291ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:43.898869Z","caller":"traceutil/trace.go:171","msg":"trace[1067581352] transaction","detail":"{read_only:false; response_revision:1766; number_of_response:1; }","duration":"602.280564ms","start":"2026-03-19T17:54:43.296555Z","end":"2026-03-19T17:54:43.898836Z","steps":["trace[1067581352] 'process raft request' (duration: 602.081228ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:43.898995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:43.296539Z","time spent":"602.398158ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:54:43.898994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:43.296561Z","time spent":"602.367576ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:54:43.899074Z","caller":"traceutil/trace.go:171","msg":"trace[2093534463] transaction","detail":"{read_only:false; response_revision:1768; number_of_response:1; }","duration":"602.343705ms","start":"2026-03-19T17:54:43.296703Z","end":"2026-03-19T17:54:43.899047Z","steps":["trace[2093534463] 'process raft request' (duration: 602.094729ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:43.89914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:43.296615Z","time spent":"602.49402ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:54:43.899306Z","caller":"traceutil/trace.go:171","msg":"trace[29899321] transaction","detail":"{read_only:false; response_revision:1769; number_of_response:1; }","duration":"602.524839ms","start":"2026-03-19T17:54:43.296769Z","end":"2026-03-19T17:54:43.899294Z","steps":["trace[29899321] 'process raft request' (duration: 602.212722ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:43.899319Z","caller":"traceutil/trace.go:171","msg":"trace[680145504] transaction","detail":"{read_only:false; response_revision:1770; number_of_response:1; }","duration":"207.271301ms","start":"2026-03-19T17:54:43.692032Z","end":"2026-03-19T17:54:43.899304Z","steps":["trace[680145504] 'process raft request' (duration: 207.178849ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:43.899299Z","caller":"traceutil/trace.go:171","msg":"trace[1120105189] linearizableReadLoop","detail":"{readStateIndex:1835; appliedIndex:1830; }","duration":"211.673584ms","start":"2026-03-19T17:54:43.687609Z","end":"2026-03-19T17:54:43.899283Z","steps":["trace[1120105189] 'read index received' (duration: 209.737314ms)","trace[1120105189] 'applied index is now lower than readState.Index' (duration: 1.93518ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:54:43.899382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.771897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:54:43.899427Z","caller":"traceutil/trace.go:171","msg":"trace[1424633330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1770; }","duration":"211.851019ms","start":"2026-03-19T17:54:43.687556Z","end":"2026-03-19T17:54:43.899407Z","steps":["trace[1424633330] 'agreement among raft nodes before linearized reading' (duration: 211.778287ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:43.899423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:54:43.296752Z","time spent":"602.604172ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:54:56.096755Z","caller":"traceutil/trace.go:171","msg":"trace[1141092036] linearizableReadLoop","detail":"{readStateIndex:1900; appliedIndex:1899; }","duration":"110.931193ms","start":"2026-03-19T17:54:55.985782Z","end":"2026-03-19T17:54:56.096713Z","steps":["trace[1141092036] 'read index received' (duration: 43.146336ms)","trace[1141092036] 'applied index is now lower than readState.Index' (duration: 67.783267ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:54:56.096895Z","caller":"traceutil/trace.go:171","msg":"trace[235590459] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"116.719311ms","start":"2026-03-19T17:54:55.980162Z","end":"2026-03-19T17:54:56.096881Z","steps":["trace[235590459] 'process raft request' (duration: 48.74428ms)","trace[235590459] 'compare' (duration: 67.628553ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:54:56.097327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.515248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-19T17:54:56.097424Z","caller":"traceutil/trace.go:171","msg":"trace[1893954324] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1830; }","duration":"111.665542ms","start":"2026-03-19T17:54:55.985736Z","end":"2026-03-19T17:54:56.097402Z","steps":["trace[1893954324] 'agreement among raft nodes before linearized reading' (duration: 111.371364ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:56.102913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.001213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-19T17:54:56.102941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.645408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-19T17:54:56.102947Z","caller":"traceutil/trace.go:171","msg":"trace[1023633619] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"117.011159ms","start":"2026-03-19T17:54:55.985912Z","end":"2026-03-19T17:54:56.102923Z","steps":["trace[1023633619] 'process raft request' (duration: 116.749081ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:56.102976Z","caller":"traceutil/trace.go:171","msg":"trace[93563405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1831; }","duration":"116.107766ms","start":"2026-03-19T17:54:55.986853Z","end":"2026-03-19T17:54:56.102961Z","steps":["trace[93563405] 'agreement among raft nodes before linearized reading' (duration: 115.90945ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:56.102996Z","caller":"traceutil/trace.go:171","msg":"trace[305191745] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1831; }","duration":"107.766971ms","start":"2026-03-19T17:54:55.995219Z","end":"2026-03-19T17:54:56.102986Z","steps":["trace[305191745] 'agreement among raft nodes before linearized reading' (duration: 107.610947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:56.10302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.095505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T17:54:56.103111Z","caller":"traceutil/trace.go:171","msg":"trace[943154603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1831; }","duration":"116.225538ms","start":"2026-03-19T17:54:55.986874Z","end":"2026-03-19T17:54:56.103099Z","steps":["trace[943154603] 'agreement among raft nodes before linearized reading' (duration: 115.983262ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:58.242327Z","caller":"traceutil/trace.go:171","msg":"trace[1677763829] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1908; }","duration":"130.759282ms","start":"2026-03-19T17:54:58.111541Z","end":"2026-03-19T17:54:58.242301Z","steps":["trace[1677763829] 'read index received' (duration: 23.316458ms)","trace[1677763829] 'applied index is now lower than readState.Index' (duration: 107.441434ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:54:58.242589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.690432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T17:54:58.24263Z","caller":"traceutil/trace.go:171","msg":"trace[1177222279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1840; }","duration":"127.799725ms","start":"2026-03-19T17:54:58.11482Z","end":"2026-03-19T17:54:58.242619Z","steps":["trace[1177222279] 'agreement among raft nodes before linearized reading' (duration: 127.632771ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:58.242461Z","caller":"traceutil/trace.go:171","msg":"trace[379528520] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"134.907731ms","start":"2026-03-19T17:54:58.107506Z","end":"2026-03-19T17:54:58.242414Z","steps":["trace[379528520] 'process raft request' (duration: 76.916037ms)","trace[379528520] 'compare' (duration: 57.734277ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:54:58.242753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.77047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-19T17:54:58.24281Z","caller":"traceutil/trace.go:171","msg":"trace[360494047] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1840; }","duration":"123.890483ms","start":"2026-03-19T17:54:58.118906Z","end":"2026-03-19T17:54:58.242796Z","steps":["trace[360494047] 'agreement among raft nodes before linearized reading' (duration: 123.639977ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:54:58.2428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.220073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-19T17:54:58.242836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.927884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T17:54:58.242923Z","caller":"traceutil/trace.go:171","msg":"trace[529545591] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1840; }","duration":"131.404708ms","start":"2026-03-19T17:54:58.111484Z","end":"2026-03-19T17:54:58.242888Z","steps":["trace[529545591] 'agreement among raft nodes before linearized reading' (duration: 130.982247ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:58.242937Z","caller":"traceutil/trace.go:171","msg":"trace[1812900413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1840; }","duration":"124.060457ms","start":"2026-03-19T17:54:58.118847Z","end":"2026-03-19T17:54:58.242908Z","steps":["trace[1812900413] 'agreement among raft nodes before linearized reading' (duration: 123.76799ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:58.46592Z","caller":"traceutil/trace.go:171","msg":"trace[465748190] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"218.097041ms","start":"2026-03-19T17:54:58.247799Z","end":"2026-03-19T17:54:58.465896Z","steps":["trace[465748190] 'process raft request' (duration: 217.986668ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:58.465977Z","caller":"traceutil/trace.go:171","msg":"trace[708414644] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"217.729671ms","start":"2026-03-19T17:54:58.248158Z","end":"2026-03-19T17:54:58.465888Z","steps":["trace[708414644] 'process raft request' (duration: 217.666679ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:58.465919Z","caller":"traceutil/trace.go:171","msg":"trace[194641289] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"218.43458ms","start":"2026-03-19T17:54:58.247453Z","end":"2026-03-19T17:54:58.465888Z","steps":["trace[194641289] 'process raft request' (duration: 218.186854ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:54:58.466155Z","caller":"traceutil/trace.go:171","msg":"trace[1417298447] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"178.654542ms","start":"2026-03-19T17:54:58.287481Z","end":"2026-03-19T17:54:58.466135Z","steps":["trace[1417298447] 'process raft request' (duration: 178.373275ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:55:15.589864Z","caller":"traceutil/trace.go:171","msg":"trace[348919155] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"103.993683ms","start":"2026-03-19T17:55:15.485843Z","end":"2026-03-19T17:55:15.589837Z","steps":["trace[348919155] 'process raft request' (duration: 103.828229ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:55:20.707948Z","caller":"traceutil/trace.go:171","msg":"trace[159631113] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"138.749921ms","start":"2026-03-19T17:55:20.56916Z","end":"2026-03-19T17:55:20.70791Z","steps":["trace[159631113] 'process raft request' (duration: 78.323674ms)","trace[159631113] 'compare' (duration: 60.073967ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:55:20.708312Z","caller":"traceutil/trace.go:171","msg":"trace[1391993417] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"104.091176ms","start":"2026-03-19T17:55:20.604185Z","end":"2026-03-19T17:55:20.708276Z","steps":["trace[1391993417] 'process raft request' (duration: 103.584893ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:55:21.729227Z","caller":"traceutil/trace.go:171","msg":"trace[2039457676] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"120.34573ms","start":"2026-03-19T17:55:21.608856Z","end":"2026-03-19T17:55:21.729202Z","steps":["trace[2039457676] 'process raft request' (duration: 119.95797ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:55:22.281031Z","caller":"traceutil/trace.go:171","msg":"trace[1177311468] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"213.843571ms","start":"2026-03-19T17:55:22.067158Z","end":"2026-03-19T17:55:22.281002Z","steps":["trace[1177311468] 'process raft request' (duration: 213.601215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:23.126698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.551735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:55:23.126863Z","caller":"traceutil/trace.go:171","msg":"trace[2047030023] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"840.841011ms","start":"2026-03-19T17:55:22.286003Z","end":"2026-03-19T17:55:23.126844Z","steps":["trace[2047030023] 'process raft request' (duration: 548.023071ms)","trace[2047030023] 'compare' (duration: 292.448441ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:55:23.126975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:22.285985Z","time spent":"840.913313ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:55:23.127129Z","caller":"traceutil/trace.go:171","msg":"trace[1315106858] linearizableReadLoop","detail":"{readStateIndex:2031; appliedIndex:2029; }","duration":"789.555673ms","start":"2026-03-19T17:55:22.337544Z","end":"2026-03-19T17:55:23.127099Z","steps":["trace[1315106858] 'read index received' (duration: 496.514076ms)","trace[1315106858] 'applied index is now lower than readState.Index' (duration: 293.040047ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:55:23.127381Z","caller":"traceutil/trace.go:171","msg":"trace[143044288] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"798.746213ms","start":"2026-03-19T17:55:22.328621Z","end":"2026-03-19T17:55:23.127368Z","steps":["trace[143044288] 'process raft request' (duration: 798.172448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:23.127499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:22.328592Z","time spent":"798.825546ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:55:23.127532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.674244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T17:55:23.127586Z","caller":"traceutil/trace.go:171","msg":"trace[2031349800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1956; }","duration":"682.765046ms","start":"2026-03-19T17:55:22.444806Z","end":"2026-03-19T17:55:23.127571Z","steps":["trace[2031349800] 'agreement among raft nodes before linearized reading' (duration: 682.621443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:23.127626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:22.444784Z","time spent":"682.833657ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T17:55:23.127713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.169189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T17:55:23.127774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.376103ms","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-03-19T17:55:23.127766Z","caller":"traceutil/trace.go:171","msg":"trace[397789277] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:1956; }","duration":"790.235041ms","start":"2026-03-19T17:55:22.337508Z","end":"2026-03-19T17:55:23.127743Z","steps":["trace[397789277] 'agreement among raft nodes before linearized reading' (duration: 790.179089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:23.127804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.692477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:55:23.127844Z","caller":"traceutil/trace.go:171","msg":"trace[1883707603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1956; }","duration":"438.763799ms","start":"2026-03-19T17:55:22.68907Z","end":"2026-03-19T17:55:23.127834Z","steps":["trace[1883707603] 'agreement among raft nodes before linearized reading' (duration: 438.690497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:23.127624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.26526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-19T17:55:23.127853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:22.337499Z","time spent":"790.340794ms","remote":"127.0.0.1:39100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"warn","ts":"2026-03-19T17:55:23.127882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:22.689049Z","time spent":"438.826651ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T17:55:23.127909Z","caller":"traceutil/trace.go:171","msg":"trace[1366346639] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1956; }","duration":"414.594149ms","start":"2026-03-19T17:55:22.713289Z","end":"2026-03-19T17:55:23.127883Z","steps":["trace[1366346639] 'agreement among raft nodes before linearized reading' (duration: 414.151447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:23.128073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:22.713262Z","time spent":"414.778794ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T17:55:23.127602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.761534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T17:55:23.128261Z","caller":"traceutil/trace.go:171","msg":"trace[1068473355] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1956; }","duration":"292.448692ms","start":"2026-03-19T17:55:22.835801Z","end":"2026-03-19T17:55:23.128249Z","steps":["trace[1068473355] 'agreement among raft nodes before linearized reading' (duration: 291.677812ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:55:23.127865Z","caller":"traceutil/trace.go:171","msg":"trace[1042848068] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1956; }","duration":"391.464305ms","start":"2026-03-19T17:55:22.736347Z","end":"2026-03-19T17:55:23.127812Z","steps":["trace[1042848068] 'agreement among raft nodes before linearized reading' (duration: 391.356733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:23.128379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:22.736327Z","time spent":"391.976158ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-03-19T17:55:23.128621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.37365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T17:55:23.128696Z","caller":"traceutil/trace.go:171","msg":"trace[1878517381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1956; }","duration":"415.474992ms","start":"2026-03-19T17:55:22.713203Z","end":"2026-03-19T17:55:23.128678Z","steps":["trace[1878517381] 'agreement among raft nodes before linearized reading' (duration: 415.231595ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:23.12874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:22.71319Z","time spent":"415.538504ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T17:55:23.128994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.234022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-19T17:55:23.12923Z","caller":"traceutil/trace.go:171","msg":"trace[2120838828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1956; }","duration":"495.510279ms","start":"2026-03-19T17:55:22.633684Z","end":"2026-03-19T17:55:23.129194Z","steps":["trace[2120838828] 'agreement among raft nodes before linearized reading' (duration: 494.694438ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:23.129424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:22.633661Z","time spent":"495.743537ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-19T17:55:23.440696Z","caller":"traceutil/trace.go:171","msg":"trace[1825092353] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"307.490074ms","start":"2026-03-19T17:55:23.133182Z","end":"2026-03-19T17:55:23.440672Z","steps":["trace[1825092353] 'process raft request' (duration: 307.447892ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:55:23.440731Z","caller":"traceutil/trace.go:171","msg":"trace[1163294144] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"307.602186ms","start":"2026-03-19T17:55:23.1331Z","end":"2026-03-19T17:55:23.440702Z","steps":["trace[1163294144] 'process raft request' (duration: 307.311829ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:55:23.44079Z","caller":"traceutil/trace.go:171","msg":"trace[221593695] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"307.614167ms","start":"2026-03-19T17:55:23.133141Z","end":"2026-03-19T17:55:23.440756Z","steps":["trace[221593695] 'process raft request' (duration: 307.442142ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:23.440889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:23.133174Z","time spent":"307.608437ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:55:23.440909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:23.133085Z","time spent":"307.709041ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:55:23.440966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:23.133125Z","time spent":"307.753032ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:55:23.507389Z","caller":"traceutil/trace.go:171","msg":"trace[1874486443] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"373.735753ms","start":"2026-03-19T17:55:23.133607Z","end":"2026-03-19T17:55:23.507342Z","steps":["trace[1874486443] 'process raft request' (duration: 364.649266ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:55:23.507639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:55:23.1336Z","time spent":"373.950398ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:55:31.86401Z","caller":"traceutil/trace.go:171","msg":"trace[652458876] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"103.303286ms","start":"2026-03-19T17:55:31.760682Z","end":"2026-03-19T17:55:31.863985Z","steps":["trace[652458876] 'process raft request' (duration: 103.165893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:56:28.064372Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262225857683728140,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T17:56:28.325508Z","caller":"traceutil/trace.go:171","msg":"trace[1308691256] linearizableReadLoop","detail":"{readStateIndex:2535; appliedIndex:2534; }","duration":"761.793782ms","start":"2026-03-19T17:56:27.563695Z","end":"2026-03-19T17:56:28.325489Z","steps":["trace[1308691256] 'read index received' (duration: 761.7377ms)","trace[1308691256] 'applied index is now lower than readState.Index' (duration: 55.392µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:56:28.325598Z","caller":"traceutil/trace.go:171","msg":"trace[1711729221] transaction","detail":"{read_only:false; response_revision:2439; number_of_response:1; }","duration":"874.989319ms","start":"2026-03-19T17:56:27.450601Z","end":"2026-03-19T17:56:28.325591Z","steps":["trace[1711729221] 'process raft request' (duration: 874.785353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:56:28.325683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:56:27.450565Z","time spent":"875.05325ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:56:28.325754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.050729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-19T17:56:28.325828Z","caller":"traceutil/trace.go:171","msg":"trace[720660833] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2439; }","duration":"762.209392ms","start":"2026-03-19T17:56:27.5636Z","end":"2026-03-19T17:56:28.325809Z","steps":["trace[720660833] 'agreement among raft nodes before linearized reading' (duration: 762.018678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:56:28.325883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:56:27.56357Z","time spent":"762.292665ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-19T17:56:28.395179Z","caller":"traceutil/trace.go:171","msg":"trace[1890390669] transaction","detail":"{read_only:false; response_revision:2440; number_of_response:1; }","duration":"300.578168ms","start":"2026-03-19T17:56:28.094569Z","end":"2026-03-19T17:56:28.395148Z","steps":["trace[1890390669] 'process raft request' (duration: 298.873885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:56:28.395352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.587224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:56:28.395463Z","caller":"traceutil/trace.go:171","msg":"trace[1866512519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2441; }","duration":"706.763348ms","start":"2026-03-19T17:56:27.688676Z","end":"2026-03-19T17:56:28.39544Z","steps":["trace[1866512519] 'agreement among raft nodes before linearized reading' (duration: 706.578174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:56:28.395508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:56:27.688649Z","time spent":"706.850471ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:56:28.395578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.235316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-19T17:56:28.395577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.226986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-19T17:56:28.395606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.512427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-03-19T17:56:28.395608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.140624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T17:56:28.395706Z","caller":"traceutil/trace.go:171","msg":"trace[2102246397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2441; }","duration":"551.343328ms","start":"2026-03-19T17:56:27.844301Z","end":"2026-03-19T17:56:28.395645Z","steps":["trace[2102246397] 'agreement among raft nodes before linearized reading' (duration: 551.096101ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:56:28.395715Z","caller":"traceutil/trace.go:171","msg":"trace[1944901261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2441; }","duration":"426.376089ms","start":"2026-03-19T17:56:27.969261Z","end":"2026-03-19T17:56:28.395637Z","steps":["trace[1944901261] 'agreement among raft nodes before linearized reading' (duration: 426.122522ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:56:28.395735Z","caller":"traceutil/trace.go:171","msg":"trace[1996789751] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2441; }","duration":"601.674941ms","start":"2026-03-19T17:56:27.794033Z","end":"2026-03-19T17:56:28.395708Z","steps":["trace[1996789751] 'agreement among raft nodes before linearized reading' (duration: 601.416743ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:56:28.395753Z","caller":"traceutil/trace.go:171","msg":"trace[1078562106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2441; }","duration":"551.339758ms","start":"2026-03-19T17:56:27.844391Z","end":"2026-03-19T17:56:28.395731Z","steps":["trace[1078562106] 'agreement among raft nodes before linearized reading' (duration: 551.04433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:56:28.39576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:56:27.844289Z","time spent":"551.456632ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T17:56:28.395761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:56:27.96923Z","time spent":"426.520473ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-19T17:56:28.395793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:56:27.794011Z","time spent":"601.768123ms","remote":"127.0.0.1:60434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-03-19T17:56:28.3958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:56:27.844364Z","time spent":"551.423981ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T17:56:28.39586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.447381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-19T17:56:28.396029Z","caller":"traceutil/trace.go:171","msg":"trace[1955302385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2441; }","duration":"551.595064ms","start":"2026-03-19T17:56:27.844344Z","end":"2026-03-19T17:56:28.395939Z","steps":["trace[1955302385] 'agreement among raft nodes before linearized reading' (duration: 550.967468ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:56:28.396117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:56:27.844327Z","time spent":"551.771279ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T17:56:28.39654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:56:28.094546Z","time spent":"300.705432ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:56:34.628613Z","caller":"traceutil/trace.go:171","msg":"trace[1126527900] linearizableReadLoop","detail":"{readStateIndex:2574; appliedIndex:2573; }","duration":"207.810906ms","start":"2026-03-19T17:56:34.420779Z","end":"2026-03-19T17:56:34.62859Z","steps":["trace[1126527900] 'read index received' (duration: 207.717724ms)","trace[1126527900] 'applied index is now lower than readState.Index' (duration: 91.982µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:56:34.628702Z","caller":"traceutil/trace.go:171","msg":"trace[628015058] transaction","detail":"{read_only:false; response_revision:2477; number_of_response:1; }","duration":"213.913773ms","start":"2026-03-19T17:56:34.414757Z","end":"2026-03-19T17:56:34.628671Z","steps":["trace[628015058] 'process raft request' (duration: 213.678457ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:56:34.629055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.261347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-19T17:56:34.629157Z","caller":"traceutil/trace.go:171","msg":"trace[887332044] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2477; }","duration":"208.416231ms","start":"2026-03-19T17:56:34.420725Z","end":"2026-03-19T17:56:34.629141Z","steps":["trace[887332044] 'agreement among raft nodes before linearized reading' (duration: 207.98417ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:56:34.652509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.704775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T17:56:34.652546Z","caller":"traceutil/trace.go:171","msg":"trace[2119874067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2478; }","duration":"223.757666ms","start":"2026-03-19T17:56:34.42878Z","end":"2026-03-19T17:56:34.652537Z","steps":["trace[2119874067] 'agreement among raft nodes before linearized reading' (duration: 223.658194ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:56:34.652628Z","caller":"traceutil/trace.go:171","msg":"trace[1913983767] transaction","detail":"{read_only:false; response_revision:2478; number_of_response:1; }","duration":"106.141866ms","start":"2026-03-19T17:56:34.54646Z","end":"2026-03-19T17:56:34.652602Z","steps":["trace[1913983767] 'process raft request' (duration: 105.794166ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:56:34.652589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.011543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-19T17:56:34.652548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.152819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-19T17:56:34.652695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.151717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T17:56:34.652773Z","caller":"traceutil/trace.go:171","msg":"trace[1425192437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2478; }","duration":"224.248008ms","start":"2026-03-19T17:56:34.428505Z","end":"2026-03-19T17:56:34.652753Z","steps":["trace[1425192437] 'agreement among raft nodes before linearized reading' (duration: 224.079934ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:56:34.652708Z","caller":"traceutil/trace.go:171","msg":"trace[1577174543] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2478; }","duration":"224.150436ms","start":"2026-03-19T17:56:34.428545Z","end":"2026-03-19T17:56:34.652695Z","steps":["trace[1577174543] 'agreement among raft nodes before linearized reading' (duration: 223.91401ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:56:34.6529Z","caller":"traceutil/trace.go:171","msg":"trace[1982224261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2478; }","duration":"228.405775ms","start":"2026-03-19T17:56:34.424334Z","end":"2026-03-19T17:56:34.652739Z","steps":["trace[1982224261] 'agreement among raft nodes before linearized reading' (duration: 228.044416ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:07.008911Z","caller":"traceutil/trace.go:171","msg":"trace[764066230] transaction","detail":"{read_only:false; response_revision:2652; number_of_response:1; }","duration":"201.956456ms","start":"2026-03-19T17:57:06.806925Z","end":"2026-03-19T17:57:07.008881Z","steps":["trace[764066230] 'process raft request' (duration: 190.947803ms)","trace[764066230] 'compare' (duration: 10.755526ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:57:07.00909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.049213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-19T17:57:07.008883Z","caller":"traceutil/trace.go:171","msg":"trace[1644893855] linearizableReadLoop","detail":"{readStateIndex:2756; appliedIndex:2755; }","duration":"193.836658ms","start":"2026-03-19T17:57:06.815017Z","end":"2026-03-19T17:57:07.008853Z","steps":["trace[1644893855] 'read index received' (duration: 182.750163ms)","trace[1644893855] 'applied index is now lower than readState.Index' (duration: 11.085035ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:57:07.009163Z","caller":"traceutil/trace.go:171","msg":"trace[1203837132] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2652; }","duration":"194.162506ms","start":"2026-03-19T17:57:06.814984Z","end":"2026-03-19T17:57:07.009147Z","steps":["trace[1203837132] 'agreement among raft nodes before linearized reading' (duration: 193.951631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:07.009437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.288933ms","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-03-19T17:57:07.009493Z","caller":"traceutil/trace.go:171","msg":"trace[1380137193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2652; }","duration":"193.381716ms","start":"2026-03-19T17:57:06.816091Z","end":"2026-03-19T17:57:07.009473Z","steps":["trace[1380137193] 'agreement among raft nodes before linearized reading' (duration: 193.179711ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:07.009524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.851842ms","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-03-19T17:57:07.009651Z","caller":"traceutil/trace.go:171","msg":"trace[1410952957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2652; }","duration":"192.997817ms","start":"2026-03-19T17:57:06.816627Z","end":"2026-03-19T17:57:07.009625Z","steps":["trace[1410952957] 'agreement among raft nodes before linearized reading' (duration: 192.70322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:57:07.009771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.058308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-19T17:57:07.009845Z","caller":"traceutil/trace.go:171","msg":"trace[339316851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2652; }","duration":"193.152331ms","start":"2026-03-19T17:57:06.816672Z","end":"2026-03-19T17:57:07.009824Z","steps":["trace[339316851] 'agreement among raft nodes before linearized reading' (duration: 192.632128ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:07.177737Z","caller":"traceutil/trace.go:171","msg":"trace[1094166458] transaction","detail":"{read_only:false; response_revision:2654; number_of_response:1; }","duration":"163.524215ms","start":"2026-03-19T17:57:07.014192Z","end":"2026-03-19T17:57:07.177717Z","steps":["trace[1094166458] 'process raft request' (duration: 163.347231ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:07.17817Z","caller":"traceutil/trace.go:171","msg":"trace[191182827] transaction","detail":"{read_only:false; response_revision:2655; number_of_response:1; }","duration":"163.7243ms","start":"2026-03-19T17:57:07.014401Z","end":"2026-03-19T17:57:07.178125Z","steps":["trace[191182827] 'process raft request' (duration: 163.288959ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:57:07.178245Z","caller":"traceutil/trace.go:171","msg":"trace[286319360] transaction","detail":"{read_only:false; response_revision:2656; number_of_response:1; }","duration":"163.550716ms","start":"2026-03-19T17:57:07.014659Z","end":"2026-03-19T17:57:07.17821Z","steps":["trace[286319360] 'process raft request' (duration: 163.376232ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:59:27.163064Z","caller":"traceutil/trace.go:171","msg":"trace[1902777710] transaction","detail":"{read_only:false; response_revision:3519; number_of_response:1; }","duration":"131.253263ms","start":"2026-03-19T17:59:27.031778Z","end":"2026-03-19T17:59:27.163031Z","steps":["trace[1902777710] 'process raft request' (duration: 131.084769ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:59:37.729783Z","caller":"traceutil/trace.go:171","msg":"trace[842187407] transaction","detail":"{read_only:false; response_revision:3568; number_of_response:1; }","duration":"402.972085ms","start":"2026-03-19T17:59:37.326774Z","end":"2026-03-19T17:59:37.729747Z","steps":["trace[842187407] 'process raft request' (duration: 402.725109ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:59:37.730018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:59:37.326744Z","time spent":"403.15378ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:59:38.110667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.187672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:59:38.110768Z","caller":"traceutil/trace.go:171","msg":"trace[1079248642] linearizableReadLoop","detail":"{readStateIndex:3711; appliedIndex:3709; }","duration":"421.358482ms","start":"2026-03-19T17:59:37.689395Z","end":"2026-03-19T17:59:38.110754Z","steps":["trace[1079248642] 'read index received' (duration: 40.16358ms)","trace[1079248642] 'applied index is now lower than readState.Index' (duration: 381.193362ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:59:38.110841Z","caller":"traceutil/trace.go:171","msg":"trace[1832573861] transaction","detail":"{read_only:false; response_revision:3569; number_of_response:1; }","duration":"771.092685ms","start":"2026-03-19T17:59:37.339695Z","end":"2026-03-19T17:59:38.110788Z","steps":["trace[1832573861] 'process raft request' (duration: 604.701318ms)","trace[1832573861] 'compare' (duration: 165.425492ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:59:38.110894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.512855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:59:38.110939Z","caller":"traceutil/trace.go:171","msg":"trace[1913976025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3569; }","duration":"421.597938ms","start":"2026-03-19T17:59:37.689321Z","end":"2026-03-19T17:59:38.110919Z","steps":["trace[1913976025] 'agreement among raft nodes before linearized reading' (duration: 421.532286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:59:38.111012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:59:37.33964Z","time spent":"771.279019ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:59:38.111013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:59:37.689292Z","time spent":"421.70384ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:59:38.11129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.549827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-03-19T17:59:38.111314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.532987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8prdr\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-19T17:59:38.111366Z","caller":"traceutil/trace.go:171","msg":"trace[178758030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3569; }","duration":"184.67877ms","start":"2026-03-19T17:59:37.926668Z","end":"2026-03-19T17:59:38.111347Z","steps":["trace[178758030] 'agreement among raft nodes before linearized reading' (duration: 184.457114ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:59:38.111422Z","caller":"traceutil/trace.go:171","msg":"trace[1627811186] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8prdr; range_end:; response_count:1; response_revision:3569; }","duration":"184.697271ms","start":"2026-03-19T17:59:37.9267Z","end":"2026-03-19T17:59:38.111397Z","steps":["trace[1627811186] 'agreement among raft nodes before linearized reading' (duration: 184.397533ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:59:38.112015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.079023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-19T17:59:38.112067Z","caller":"traceutil/trace.go:171","msg":"trace[1194508343] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3569; }","duration":"341.179846ms","start":"2026-03-19T17:59:37.770875Z","end":"2026-03-19T17:59:38.112054Z","steps":["trace[1194508343] 'agreement among raft nodes before linearized reading' (duration: 340.154499ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:59:38.112109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:59:37.77085Z","time spent":"341.250267ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-19T18:00:54.634389Z","caller":"traceutil/trace.go:171","msg":"trace[1248630283] linearizableReadLoop","detail":"{readStateIndex:4564; appliedIndex:4563; }","duration":"111.057124ms","start":"2026-03-19T18:00:54.523298Z","end":"2026-03-19T18:00:54.634355Z","steps":["trace[1248630283] 'read index received' (duration: 104.425535ms)","trace[1248630283] 'applied index is now lower than readState.Index' (duration: 6.630319ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:00:54.634548Z","caller":"traceutil/trace.go:171","msg":"trace[1262405955] transaction","detail":"{read_only:false; response_revision:4387; number_of_response:1; }","duration":"112.045819ms","start":"2026-03-19T18:00:54.522463Z","end":"2026-03-19T18:00:54.634509Z","steps":["trace[1262405955] 'process raft request' (duration: 105.290116ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:00:54.63513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.598528ms","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-03-19T18:00:54.635202Z","caller":"traceutil/trace.go:171","msg":"trace[454741395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4387; }","duration":"111.908706ms","start":"2026-03-19T18:00:54.523275Z","end":"2026-03-19T18:00:54.635184Z","steps":["trace[454741395] 'agreement among raft nodes before linearized reading' (duration: 111.344962ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:00:54.635388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.735011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62980"} {"level":"info","ts":"2026-03-19T18:00:54.63545Z","caller":"traceutil/trace.go:171","msg":"trace[1562627242] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:4387; }","duration":"109.836773ms","start":"2026-03-19T18:00:54.5256Z","end":"2026-03-19T18:00:54.635436Z","steps":["trace[1562627242] 'agreement among raft nodes before linearized reading' (duration: 109.569617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:00:54.635514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.798297ms","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-03-19T18:00:54.635634Z","caller":"traceutil/trace.go:171","msg":"trace[1236300560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4387; }","duration":"101.962262ms","start":"2026-03-19T18:00:54.533646Z","end":"2026-03-19T18:00:54.635609Z","steps":["trace[1236300560] 'agreement among raft nodes before linearized reading' (duration: 101.642334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:00:55.276134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.74817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T18:00:55.276276Z","caller":"traceutil/trace.go:171","msg":"trace[1255105264] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4394; }","duration":"174.932454ms","start":"2026-03-19T18:00:55.101295Z","end":"2026-03-19T18:00:55.276227Z","steps":["trace[1255105264] 'count revisions from in-memory index tree' (duration: 174.677958ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:00:55.2765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.282982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-03-19T18:00:55.276595Z","caller":"traceutil/trace.go:171","msg":"trace[1149288577] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4394; }","duration":"144.383774ms","start":"2026-03-19T18:00:55.132169Z","end":"2026-03-19T18:00:55.276553Z","steps":["trace[1149288577] 'range keys from in-memory index tree' (duration: 143.987825ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:16.711695Z","caller":"traceutil/trace.go:171","msg":"trace[215988763] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"118.035302ms","start":"2026-03-19T18:01:16.593603Z","end":"2026-03-19T18:01:16.711638Z","steps":["trace[215988763] 'process raft request' (duration: 117.812527ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:17.861381Z","caller":"traceutil/trace.go:171","msg":"trace[574694524] linearizableReadLoop","detail":"{readStateIndex:5090; appliedIndex:5089; }","duration":"158.390843ms","start":"2026-03-19T18:01:17.702951Z","end":"2026-03-19T18:01:17.861342Z","steps":["trace[574694524] 'read index received' (duration: 158.063224ms)","trace[574694524] 'applied index is now lower than readState.Index' (duration: 326.089µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:01:17.861518Z","caller":"traceutil/trace.go:171","msg":"trace[25945916] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"225.416252ms","start":"2026-03-19T18:01:17.636057Z","end":"2026-03-19T18:01:17.861474Z","steps":["trace[25945916] 'process raft request' (duration: 225.054283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:17.861614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.631088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T18:01:17.861886Z","caller":"traceutil/trace.go:171","msg":"trace[333140949] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:4898; }","duration":"158.943086ms","start":"2026-03-19T18:01:17.702928Z","end":"2026-03-19T18:01:17.861871Z","steps":["trace[333140949] 'agreement among raft nodes before linearized reading' (duration: 158.550686ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:23.320978Z","caller":"traceutil/trace.go:171","msg":"trace[1259105918] transaction","detail":"{read_only:false; response_revision:4996; number_of_response:1; }","duration":"202.668873ms","start":"2026-03-19T18:01:23.118281Z","end":"2026-03-19T18:01:23.32095Z","steps":["trace[1259105918] 'process raft request' (duration: 183.38286ms)","trace[1259105918] 'compare' (duration: 19.096848ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:01:23.320978Z","caller":"traceutil/trace.go:171","msg":"trace[2077353512] linearizableReadLoop","detail":"{readStateIndex:5189; appliedIndex:5188; }","duration":"192.228976ms","start":"2026-03-19T18:01:23.128721Z","end":"2026-03-19T18:01:23.32095Z","steps":["trace[2077353512] 'read index received' (duration: 173.122718ms)","trace[2077353512] 'applied index is now lower than readState.Index' (duration: 19.104138ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T18:01:23.321179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.173913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-03-19T18:01:23.32125Z","caller":"traceutil/trace.go:171","msg":"trace[2087994201] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4996; }","duration":"199.262725ms","start":"2026-03-19T18:01:23.121972Z","end":"2026-03-19T18:01:23.321235Z","steps":["trace[2087994201] 'agreement among raft nodes before linearized reading' (duration: 199.011659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:23.713333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.084177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T18:01:23.71354Z","caller":"traceutil/trace.go:171","msg":"trace[1166775794] transaction","detail":"{read_only:false; response_revision:4997; number_of_response:1; }","duration":"578.486624ms","start":"2026-03-19T18:01:23.135034Z","end":"2026-03-19T18:01:23.71352Z","steps":["trace[1166775794] 'process raft request' (duration: 432.126259ms)","trace[1166775794] 'compare' (duration: 145.644076ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T18:01:23.713613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:01:23.135021Z","time spent":"578.560186ms","remote":"127.0.0.1:60422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":904,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:01:23.713802Z","caller":"traceutil/trace.go:171","msg":"trace[173006674] transaction","detail":"{read_only:false; response_revision:4998; number_of_response:1; }","duration":"577.900858ms","start":"2026-03-19T18:01:23.13587Z","end":"2026-03-19T18:01:23.713771Z","steps":["trace[173006674] 'process raft request' (duration: 577.56312ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:23.713874Z","caller":"traceutil/trace.go:171","msg":"trace[1103161171] transaction","detail":"{read_only:false; response_revision:4999; number_of_response:1; }","duration":"577.931339ms","start":"2026-03-19T18:01:23.135921Z","end":"2026-03-19T18:01:23.713852Z","steps":["trace[1103161171] 'process raft request' (duration: 577.730624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:23.713955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:01:23.135916Z","time spent":"577.99918ms","remote":"127.0.0.1:60524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1453,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:01:23.713932Z","caller":"traceutil/trace.go:171","msg":"trace[1066339696] transaction","detail":"{read_only:false; response_revision:5000; number_of_response:1; }","duration":"577.705233ms","start":"2026-03-19T18:01:23.136192Z","end":"2026-03-19T18:01:23.713898Z","steps":["trace[1066339696] 'process raft request' (duration: 577.5828ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:23.713969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:01:23.135862Z","time spent":"578.01117ms","remote":"127.0.0.1:60524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1867,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:01:23.71401Z","caller":"traceutil/trace.go:171","msg":"trace[1378383362] linearizableReadLoop","detail":"{readStateIndex:5194; appliedIndex:5189; }","duration":"392.837605ms","start":"2026-03-19T18:01:23.321151Z","end":"2026-03-19T18:01:23.713989Z","steps":["trace[1378383362] 'read index received' (duration: 245.983288ms)","trace[1378383362] 'applied index is now lower than readState.Index' (duration: 146.851307ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T18:01:23.71412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:01:23.136183Z","time spent":"577.836627ms","remote":"127.0.0.1:60422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:01:23.714103Z","caller":"traceutil/trace.go:171","msg":"trace[882358721] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"373.749118ms","start":"2026-03-19T18:01:23.340316Z","end":"2026-03-19T18:01:23.714065Z","steps":["trace[882358721] 'process raft request' (duration: 373.558963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:23.714173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.250539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T18:01:23.71421Z","caller":"traceutil/trace.go:171","msg":"trace[664023913] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:5002; }","duration":"475.32843ms","start":"2026-03-19T18:01:23.238871Z","end":"2026-03-19T18:01:23.714199Z","steps":["trace[664023913] 'agreement among raft nodes before linearized reading' (duration: 475.254478ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:23.714146Z","caller":"traceutil/trace.go:171","msg":"trace[1163049767] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"496.258614ms","start":"2026-03-19T18:01:23.217806Z","end":"2026-03-19T18:01:23.714064Z","steps":["trace[1163049767] 'process raft request' (duration: 496.020408ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:23.714258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:01:23.238852Z","time spent":"475.396952ms","remote":"127.0.0.1:32824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-19T18:01:23.714283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.044549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T18:01:23.714286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.673135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-375376d3-f0dd-4192-8e6f-e3375524fce2\" ","response":"range_response_count:1 size:1450"} {"level":"warn","ts":"2026-03-19T18:01:23.714314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:01:23.340289Z","time spent":"373.908822ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:01:23.714325Z","caller":"traceutil/trace.go:171","msg":"trace[1328620566] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:5002; }","duration":"581.234233ms","start":"2026-03-19T18:01:23.133078Z","end":"2026-03-19T18:01:23.714312Z","steps":["trace[1328620566] 'agreement among raft nodes before linearized reading' (duration: 581.167641ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:23.714338Z","caller":"traceutil/trace.go:171","msg":"trace[1730694533] range","detail":"{range_begin:/registry/persistentvolumes/pvc-375376d3-f0dd-4192-8e6f-e3375524fce2; range_end:; response_count:1; response_revision:5002; }","duration":"389.752527ms","start":"2026-03-19T18:01:23.324567Z","end":"2026-03-19T18:01:23.71432Z","steps":["trace[1730694533] 'agreement among raft nodes before linearized reading' (duration: 389.601613ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:23.714372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:01:23.13306Z","time spent":"581.305325ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" "} {"level":"warn","ts":"2026-03-19T18:01:23.714378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:01:23.324552Z","time spent":"389.817878ms","remote":"127.0.0.1:60394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1472,"request content":"key:\"/registry/persistentvolumes/pvc-375376d3-f0dd-4192-8e6f-e3375524fce2\" "} {"level":"warn","ts":"2026-03-19T18:01:23.714398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:01:23.217767Z","time spent":"496.523911ms","remote":"127.0.0.1:32808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2229,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T18:01:24.432362Z","caller":"traceutil/trace.go:171","msg":"trace[1232423286] linearizableReadLoop","detail":"{readStateIndex:5200; appliedIndex:5199; }","duration":"149.526045ms","start":"2026-03-19T18:01:24.282811Z","end":"2026-03-19T18:01:24.432337Z","steps":["trace[1232423286] 'read index received' (duration: 149.490084ms)","trace[1232423286] 'applied index is now lower than readState.Index' (duration: 35.081µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:01:24.432477Z","caller":"traceutil/trace.go:171","msg":"trace[349503025] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"223.239657ms","start":"2026-03-19T18:01:24.209216Z","end":"2026-03-19T18:01:24.432456Z","steps":["trace[349503025] 'process raft request' (duration: 222.965459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:24.432655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.025334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-03-19T18:01:24.432741Z","caller":"traceutil/trace.go:171","msg":"trace[998120346] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5007; }","duration":"189.188898ms","start":"2026-03-19T18:01:24.243541Z","end":"2026-03-19T18:01:24.43273Z","steps":["trace[998120346] 'agreement among raft nodes before linearized reading' (duration: 188.947192ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:24.441458Z","caller":"traceutil/trace.go:171","msg":"trace[1281716039] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"152.661846ms","start":"2026-03-19T18:01:24.288781Z","end":"2026-03-19T18:01:24.441443Z","steps":["trace[1281716039] 'process raft request' (duration: 152.572734ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:24.441741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.296963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-03-19T18:01:24.441824Z","caller":"traceutil/trace.go:171","msg":"trace[1941054254] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5008; }","duration":"153.404525ms","start":"2026-03-19T18:01:24.288398Z","end":"2026-03-19T18:01:24.441803Z","steps":["trace[1941054254] 'agreement among raft nodes before linearized reading' (duration: 153.118668ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:24.442011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.634541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-03-19T18:01:24.442111Z","caller":"traceutil/trace.go:171","msg":"trace[345733717] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:5008; }","duration":"115.834426ms","start":"2026-03-19T18:01:24.326255Z","end":"2026-03-19T18:01:24.442089Z","steps":["trace[345733717] 'agreement among raft nodes before linearized reading' (duration: 115.21925ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:39.922699Z","caller":"traceutil/trace.go:171","msg":"trace[1768708359] linearizableReadLoop","detail":"{readStateIndex:5374; appliedIndex:5373; }","duration":"189.10403ms","start":"2026-03-19T18:01:39.733538Z","end":"2026-03-19T18:01:39.922673Z","steps":["trace[1768708359] 'read index received' (duration: 188.920796ms)","trace[1768708359] 'applied index is now lower than readState.Index' (duration: 181.914µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:01:39.922783Z","caller":"traceutil/trace.go:171","msg":"trace[1340998421] transaction","detail":"{read_only:false; response_revision:5176; number_of_response:1; }","duration":"190.040725ms","start":"2026-03-19T18:01:39.732702Z","end":"2026-03-19T18:01:39.922743Z","steps":["trace[1340998421] 'process raft request' (duration: 189.775347ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:39.922906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.650906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T18:01:39.922945Z","caller":"traceutil/trace.go:171","msg":"trace[939406689] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:5176; }","duration":"105.758988ms","start":"2026-03-19T18:01:39.817173Z","end":"2026-03-19T18:01:39.922932Z","steps":["trace[939406689] 'agreement among raft nodes before linearized reading' (duration: 105.655506ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:39.922966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.399369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-03-19T18:01:39.923041Z","caller":"traceutil/trace.go:171","msg":"trace[224776550] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5176; }","duration":"189.502112ms","start":"2026-03-19T18:01:39.73352Z","end":"2026-03-19T18:01:39.923022Z","steps":["trace[224776550] 'agreement among raft nodes before linearized reading' (duration: 189.273856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:51.952636Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262225857683735156,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T18:01:52.223265Z","caller":"traceutil/trace.go:171","msg":"trace[34002054] transaction","detail":"{read_only:false; response_revision:5320; number_of_response:1; }","duration":"837.750332ms","start":"2026-03-19T18:01:51.385473Z","end":"2026-03-19T18:01:52.223223Z","steps":["trace[34002054] 'process raft request' (duration: 837.582328ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:52.22347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:01:51.38543Z","time spent":"837.927217ms","remote":"127.0.0.1:60434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:01:52.26973Z","caller":"traceutil/trace.go:171","msg":"trace[870797860] linearizableReadLoop","detail":"{readStateIndex:5524; appliedIndex:5523; }","duration":"817.218436ms","start":"2026-03-19T18:01:51.452474Z","end":"2026-03-19T18:01:52.269692Z","steps":["trace[870797860] 'read index received' (duration: 770.832213ms)","trace[870797860] 'applied index is now lower than readState.Index' (duration: 46.385183ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:01:52.269844Z","caller":"traceutil/trace.go:171","msg":"trace[362041918] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"288.845195ms","start":"2026-03-19T18:01:51.980935Z","end":"2026-03-19T18:01:52.26978Z","steps":["trace[362041918] 'process raft request' (duration: 288.698561ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:52.269796Z","caller":"traceutil/trace.go:171","msg":"trace[1819435286] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"679.798753ms","start":"2026-03-19T18:01:51.589949Z","end":"2026-03-19T18:01:52.269748Z","steps":["trace[1819435286] 'process raft request' (duration: 679.460614ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:52.2701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.621135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-03-19T18:01:52.270171Z","caller":"traceutil/trace.go:171","msg":"trace[292110283] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5322; }","duration":"817.752649ms","start":"2026-03-19T18:01:51.452401Z","end":"2026-03-19T18:01:52.270154Z","steps":["trace[292110283] 'agreement among raft nodes before linearized reading' (duration: 817.514423ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:01:52.270211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:01:51.452374Z","time spent":"817.8268ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-03-19T18:01:52.270223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:01:51.589924Z","time spent":"680.066179ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T18:01:52.521694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.51843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T18:01:52.521941Z","caller":"traceutil/trace.go:171","msg":"trace[180929937] linearizableReadLoop","detail":"{readStateIndex:5527; appliedIndex:5525; }","duration":"168.143861ms","start":"2026-03-19T18:01:52.353779Z","end":"2026-03-19T18:01:52.521923Z","steps":["trace[180929937] 'read index received' (duration: 64.402125ms)","trace[180929937] 'applied index is now lower than readState.Index' (duration: 103.740856ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T18:01:52.522056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.273484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T18:01:52.522098Z","caller":"traceutil/trace.go:171","msg":"trace[1089213650] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"240.497001ms","start":"2026-03-19T18:01:52.281568Z","end":"2026-03-19T18:01:52.522065Z","steps":["trace[1089213650] 'process raft request' (duration: 136.505719ms)","trace[1089213650] 'compare' (duration: 103.093159ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:01:52.522137Z","caller":"traceutil/trace.go:171","msg":"trace[454082202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5324; }","duration":"168.402717ms","start":"2026-03-19T18:01:52.353724Z","end":"2026-03-19T18:01:52.522127Z","steps":["trace[454082202] 'agreement among raft nodes before linearized reading' (duration: 168.257724ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:52.522204Z","caller":"traceutil/trace.go:171","msg":"trace[41525858] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"169.720842ms","start":"2026-03-19T18:01:52.352442Z","end":"2026-03-19T18:01:52.522163Z","steps":["trace[41525858] 'process raft request' (duration: 169.374284ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:52.674933Z","caller":"traceutil/trace.go:171","msg":"trace[995742965] transaction","detail":"{read_only:false; response_revision:5329; number_of_response:1; }","duration":"143.735684ms","start":"2026-03-19T18:01:52.531171Z","end":"2026-03-19T18:01:52.674907Z","steps":["trace[995742965] 'process raft request' (duration: 143.677103ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:52.675094Z","caller":"traceutil/trace.go:171","msg":"trace[1239597687] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"146.081245ms","start":"2026-03-19T18:01:52.528953Z","end":"2026-03-19T18:01:52.675035Z","steps":["trace[1239597687] 'process raft request' (duration: 145.789367ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:52.675321Z","caller":"traceutil/trace.go:171","msg":"trace[1357066951] transaction","detail":"{read_only:false; response_revision:5328; number_of_response:1; }","duration":"146.278599ms","start":"2026-03-19T18:01:52.529019Z","end":"2026-03-19T18:01:52.675297Z","steps":["trace[1357066951] 'process raft request' (duration: 145.767166ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:52.675432Z","caller":"traceutil/trace.go:171","msg":"trace[234309958] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"146.607778ms","start":"2026-03-19T18:01:52.528782Z","end":"2026-03-19T18:01:52.67539Z","steps":["trace[234309958] 'process raft request' (duration: 129.260642ms)","trace[234309958] 'compare' (duration: 16.585546ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:01:52.838113Z","caller":"traceutil/trace.go:171","msg":"trace[388030622] linearizableReadLoop","detail":"{readStateIndex:5533; appliedIndex:5532; }","duration":"150.452757ms","start":"2026-03-19T18:01:52.687627Z","end":"2026-03-19T18:01:52.83808Z","steps":["trace[388030622] 'read index received' (duration: 133.06253ms)","trace[388030622] 'applied index is now lower than readState.Index' (duration: 17.388687ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T18:01:52.838286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.638372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T18:01:52.838327Z","caller":"traceutil/trace.go:171","msg":"trace[1886761405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5330; }","duration":"150.740545ms","start":"2026-03-19T18:01:52.687573Z","end":"2026-03-19T18:01:52.838314Z","steps":["trace[1886761405] 'agreement among raft nodes before linearized reading' (duration: 150.625032ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:01:52.838362Z","caller":"traceutil/trace.go:171","msg":"trace[1942264671] transaction","detail":"{read_only:false; response_revision:5330; number_of_response:1; }","duration":"152.546871ms","start":"2026-03-19T18:01:52.685758Z","end":"2026-03-19T18:01:52.838305Z","steps":["trace[1942264671] 'process raft request' (duration: 134.894868ms)","trace[1942264671] 'compare' (duration: 17.277433ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:02:26.394256Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2750} {"level":"info","ts":"2026-03-19T18:02:26.453655Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2750,"took":"58.270948ms","hash":532112299,"current-db-size-bytes":40562688,"current-db-size":"41 MB","current-db-size-in-use-bytes":30154752,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-19T18:02:26.453743Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":532112299,"revision":2750,"compact-revision":-1} {"level":"info","ts":"2026-03-19T18:06:07.228398Z","caller":"traceutil/trace.go:171","msg":"trace[23010636] transaction","detail":"{read_only:false; response_revision:7052; number_of_response:1; }","duration":"178.811589ms","start":"2026-03-19T18:06:07.049551Z","end":"2026-03-19T18:06:07.228362Z","steps":["trace[23010636] 'process raft request' (duration: 178.42588ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:06:08.616165Z","caller":"traceutil/trace.go:171","msg":"trace[1958488323] transaction","detail":"{read_only:false; response_revision:7062; number_of_response:1; }","duration":"144.965449ms","start":"2026-03-19T18:06:08.471145Z","end":"2026-03-19T18:06:08.61611Z","steps":["trace[1958488323] 'process raft request' (duration: 144.683981ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:06:52.868924Z","caller":"traceutil/trace.go:171","msg":"trace[703147544] transaction","detail":"{read_only:false; response_revision:7339; number_of_response:1; }","duration":"237.71906ms","start":"2026-03-19T18:06:52.631123Z","end":"2026-03-19T18:06:52.868842Z","steps":["trace[703147544] 'process raft request' (duration: 237.522936ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:06:53.206678Z","caller":"traceutil/trace.go:171","msg":"trace[1868726840] linearizableReadLoop","detail":"{readStateIndex:7614; appliedIndex:7612; }","duration":"530.017034ms","start":"2026-03-19T18:06:52.676628Z","end":"2026-03-19T18:06:53.206645Z","steps":["trace[1868726840] 'read index received' (duration: 192.019789ms)","trace[1868726840] 'applied index is now lower than readState.Index' (duration: 337.995965ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:06:53.206784Z","caller":"traceutil/trace.go:171","msg":"trace[971830265] transaction","detail":"{read_only:false; response_revision:7340; number_of_response:1; }","duration":"571.148378ms","start":"2026-03-19T18:06:52.635589Z","end":"2026-03-19T18:06:53.206738Z","steps":["trace[971830265] 'process raft request' (duration: 570.830879ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:06:53.207001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:52.635578Z","time spent":"571.312342ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T18:06:53.207025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.838193ms","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-03-19T18:06:53.207058Z","caller":"traceutil/trace.go:171","msg":"trace[755476097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7340; }","duration":"528.927675ms","start":"2026-03-19T18:06:52.678122Z","end":"2026-03-19T18:06:53.207049Z","steps":["trace[755476097] 'agreement among raft nodes before linearized reading' (duration: 528.697719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:06:53.207065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.436634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-03-19T18:06:53.207092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:52.678105Z","time spent":"528.980676ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-19T18:06:53.20713Z","caller":"traceutil/trace.go:171","msg":"trace[1790161704] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7340; }","duration":"530.540606ms","start":"2026-03-19T18:06:52.676571Z","end":"2026-03-19T18:06:53.207112Z","steps":["trace[1790161704] 'agreement among raft nodes before linearized reading' (duration: 530.246599ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:06:53.207137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.673441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8prdr\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-03-19T18:06:53.207119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.823062ms","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":"warn","ts":"2026-03-19T18:06:53.207176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:52.676555Z","time spent":"530.612289ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-19T18:06:53.207203Z","caller":"traceutil/trace.go:171","msg":"trace[1355511465] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8prdr; range_end:; response_count:1; response_revision:7340; }","duration":"280.758784ms","start":"2026-03-19T18:06:52.926427Z","end":"2026-03-19T18:06:53.207186Z","steps":["trace[1355511465] 'agreement among raft nodes before linearized reading' (duration: 280.565078ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:06:53.207231Z","caller":"traceutil/trace.go:171","msg":"trace[687912861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7340; }","duration":"289.014518ms","start":"2026-03-19T18:06:52.918193Z","end":"2026-03-19T18:06:53.207208Z","steps":["trace[687912861] 'agreement among raft nodes before linearized reading' (duration: 288.676209ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:06:53.207566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.428846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-19T18:06:53.207718Z","caller":"traceutil/trace.go:171","msg":"trace[762506618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7340; }","duration":"527.60978ms","start":"2026-03-19T18:06:52.680077Z","end":"2026-03-19T18:06:53.207687Z","steps":["trace[762506618] 'agreement among raft nodes before linearized reading' (duration: 526.987354ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:06:53.207791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:52.679942Z","time spent":"527.840608ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T18:06:53.207482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.808534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T18:06:53.208057Z","caller":"traceutil/trace.go:171","msg":"trace[1280747276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7340; }","duration":"519.47413ms","start":"2026-03-19T18:06:52.688508Z","end":"2026-03-19T18:06:53.207982Z","steps":["trace[1280747276] 'agreement among raft nodes before linearized reading' (duration: 518.487754ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:06:53.208182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:06:52.688493Z","time spent":"519.649815ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T18:06:53.351284Z","caller":"traceutil/trace.go:171","msg":"trace[1737483753] transaction","detail":"{read_only:false; response_revision:7341; number_of_response:1; }","duration":"138.476742ms","start":"2026-03-19T18:06:53.212777Z","end":"2026-03-19T18:06:53.351254Z","steps":["trace[1737483753] 'process raft request' (duration: 47.95394ms)","trace[1737483753] 'compare' (duration: 90.249115ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:06:53.35131Z","caller":"traceutil/trace.go:171","msg":"trace[824262858] transaction","detail":"{read_only:false; response_revision:7342; number_of_response:1; }","duration":"138.360209ms","start":"2026-03-19T18:06:53.212909Z","end":"2026-03-19T18:06:53.35127Z","steps":["trace[824262858] 'process raft request' (duration: 138.264977ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:06:53.351978Z","caller":"traceutil/trace.go:171","msg":"trace[721754146] transaction","detail":"{read_only:false; response_revision:7344; number_of_response:1; }","duration":"138.824952ms","start":"2026-03-19T18:06:53.213135Z","end":"2026-03-19T18:06:53.35196Z","steps":["trace[721754146] 'process raft request' (duration: 138.570506ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:06:53.35209Z","caller":"traceutil/trace.go:171","msg":"trace[259603100] transaction","detail":"{read_only:false; response_revision:7343; number_of_response:1; }","duration":"138.938485ms","start":"2026-03-19T18:06:53.213051Z","end":"2026-03-19T18:06:53.351989Z","steps":["trace[259603100] 'process raft request' (duration: 138.174575ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:07:04.385491Z","caller":"traceutil/trace.go:171","msg":"trace[1728163603] transaction","detail":"{read_only:false; response_revision:7427; number_of_response:1; }","duration":"179.567065ms","start":"2026-03-19T18:07:04.205881Z","end":"2026-03-19T18:07:04.385448Z","steps":["trace[1728163603] 'process raft request' (duration: 151.149461ms)","trace[1728163603] 'compare' (duration: 28.25265ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:07:26.400469Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5627} {"level":"info","ts":"2026-03-19T18:07:26.48666Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5627,"took":"83.956402ms","hash":3184864894,"current-db-size-bytes":40562688,"current-db-size":"41 MB","current-db-size-in-use-bytes":21270528,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-03-19T18:07:26.486723Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3184864894,"revision":5627,"compact-revision":2750} {"level":"info","ts":"2026-03-19T18:07:51.050336Z","caller":"traceutil/trace.go:171","msg":"trace[1186606107] transaction","detail":"{read_only:false; response_revision:7713; number_of_response:1; }","duration":"103.841767ms","start":"2026-03-19T18:07:50.946448Z","end":"2026-03-19T18:07:51.050289Z","steps":["trace[1186606107] 'process raft request' (duration: 84.766634ms)","trace[1186606107] 'compare' (duration: 18.741635ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T18:07:59.888401Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262225857683742084,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T18:07:59.943492Z","caller":"traceutil/trace.go:171","msg":"trace[982247294] linearizableReadLoop","detail":"{readStateIndex:8180; appliedIndex:8179; }","duration":"555.467471ms","start":"2026-03-19T18:07:59.38799Z","end":"2026-03-19T18:07:59.943457Z","steps":["trace[982247294] 'read index received' (duration: 555.289517ms)","trace[982247294] 'applied index is now lower than readState.Index' (duration: 176.144µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:07:59.943544Z","caller":"traceutil/trace.go:171","msg":"trace[133407783] transaction","detail":"{read_only:false; response_revision:7888; number_of_response:1; }","duration":"614.527709ms","start":"2026-03-19T18:07:59.328974Z","end":"2026-03-19T18:07:59.943501Z","steps":["trace[133407783] 'process raft request' (duration: 614.291223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:07:59.943752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.732589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T18:07:59.943777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:07:59.328943Z","time spent":"614.696494ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:07:59.943806Z","caller":"traceutil/trace.go:171","msg":"trace[933748645] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:7888; }","duration":"555.858062ms","start":"2026-03-19T18:07:59.387928Z","end":"2026-03-19T18:07:59.943786Z","steps":["trace[933748645] 'agreement among raft nodes before linearized reading' (duration: 555.688997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:07:59.943861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:07:59.387903Z","time spent":"555.943334ms","remote":"127.0.0.1:46878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-03-19T18:07:59.96392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.453752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.185\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-19T18:07:59.964003Z","caller":"traceutil/trace.go:171","msg":"trace[1967418169] range","detail":"{range_begin:/registry/masterleases/199.19.213.185; range_end:; response_count:1; response_revision:7889; }","duration":"526.598496ms","start":"2026-03-19T18:07:59.437386Z","end":"2026-03-19T18:07:59.963984Z","steps":["trace[1967418169] 'agreement among raft nodes before linearized reading' (duration: 526.39267ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:07:59.964002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.032738ms","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":"warn","ts":"2026-03-19T18:07:59.964046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:07:59.43736Z","time spent":"526.673887ms","remote":"127.0.0.1:60264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.185\" "} {"level":"warn","ts":"2026-03-19T18:07:59.964013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.026761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T18:07:59.964093Z","caller":"traceutil/trace.go:171","msg":"trace[275190114] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7889; }","duration":"168.172832ms","start":"2026-03-19T18:07:59.795897Z","end":"2026-03-19T18:07:59.964069Z","steps":["trace[275190114] 'agreement among raft nodes before linearized reading' (duration: 167.904175ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:07:59.964078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.353241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-19T18:07:59.964136Z","caller":"traceutil/trace.go:171","msg":"trace[170677047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7889; }","duration":"274.238826ms","start":"2026-03-19T18:07:59.689864Z","end":"2026-03-19T18:07:59.964103Z","steps":["trace[170677047] 'agreement among raft nodes before linearized reading' (duration: 273.978839ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:07:59.964148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.285759ms","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-03-19T18:07:59.964189Z","caller":"traceutil/trace.go:171","msg":"trace[2067274705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7889; }","duration":"162.513154ms","start":"2026-03-19T18:07:59.801649Z","end":"2026-03-19T18:07:59.964162Z","steps":["trace[2067274705] 'agreement among raft nodes before linearized reading' (duration: 162.151475ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:07:59.964228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.738657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8prdr\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-19T18:07:59.964236Z","caller":"traceutil/trace.go:171","msg":"trace[941930594] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7889; }","duration":"174.420253ms","start":"2026-03-19T18:07:59.789791Z","end":"2026-03-19T18:07:59.964211Z","steps":["trace[941930594] 'agreement among raft nodes before linearized reading' (duration: 174.201977ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:07:59.964266Z","caller":"traceutil/trace.go:171","msg":"trace[192914291] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8prdr; range_end:; response_count:1; response_revision:7889; }","duration":"318.818749ms","start":"2026-03-19T18:07:59.645435Z","end":"2026-03-19T18:07:59.964254Z","steps":["trace[192914291] 'agreement among raft nodes before linearized reading' (duration: 318.720866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:07:59.964028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.124226ms","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-03-19T18:07:59.964919Z","caller":"traceutil/trace.go:171","msg":"trace[1061962025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7889; }","duration":"163.050429ms","start":"2026-03-19T18:07:59.801833Z","end":"2026-03-19T18:07:59.964883Z","steps":["trace[1061962025] 'agreement among raft nodes before linearized reading' (duration: 162.000131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:07:59.964293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:07:59.645408Z","time spent":"318.87759ms","remote":"127.0.0.1:60434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-8prdr\" "} {"level":"warn","ts":"2026-03-19T18:08:10.30287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.858741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-19T18:08:10.302959Z","caller":"traceutil/trace.go:171","msg":"trace[619868358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7938; }","duration":"313.995595ms","start":"2026-03-19T18:08:09.988943Z","end":"2026-03-19T18:08:10.302939Z","steps":["trace[619868358] 'range keys from in-memory index tree' (duration: 313.623395ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:10.302996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:09.988856Z","time spent":"314.131368ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-19T18:08:10.303045Z","caller":"traceutil/trace.go:171","msg":"trace[428143616] linearizableReadLoop","detail":"{readStateIndex:8234; appliedIndex:8233; }","duration":"290.366662ms","start":"2026-03-19T18:08:10.012635Z","end":"2026-03-19T18:08:10.303002Z","steps":["trace[428143616] 'read index received' (duration: 264.097522ms)","trace[428143616] 'applied index is now lower than readState.Index' (duration: 26.26711ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:08:10.303591Z","caller":"traceutil/trace.go:171","msg":"trace[327896310] transaction","detail":"{read_only:false; response_revision:7939; number_of_response:1; }","duration":"310.350039ms","start":"2026-03-19T18:08:09.99318Z","end":"2026-03-19T18:08:10.30353Z","steps":["trace[327896310] 'process raft request' (duration: 283.544326ms)","trace[327896310] 'compare' (duration: 26.130616ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T18:08:10.303822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:09.993149Z","time spent":"310.496184ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T18:08:10.303999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.073949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T18:08:10.304111Z","caller":"traceutil/trace.go:171","msg":"trace[1166058804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7939; }","duration":"265.243353ms","start":"2026-03-19T18:08:10.038837Z","end":"2026-03-19T18:08:10.30408Z","steps":["trace[1166058804] 'agreement among raft nodes before linearized reading' (duration: 264.965496ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:10.304204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.578445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-19T18:08:10.304199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.531503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-19T18:08:10.304268Z","caller":"traceutil/trace.go:171","msg":"trace[2046667068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7939; }","duration":"259.70326ms","start":"2026-03-19T18:08:10.044543Z","end":"2026-03-19T18:08:10.304246Z","steps":["trace[2046667068] 'agreement among raft nodes before linearized reading' (duration: 259.459884ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:10.30425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.038082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-19T18:08:10.304291Z","caller":"traceutil/trace.go:171","msg":"trace[1492972287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7939; }","duration":"291.703276ms","start":"2026-03-19T18:08:10.012567Z","end":"2026-03-19T18:08:10.30427Z","steps":["trace[1492972287] 'agreement among raft nodes before linearized reading' (duration: 291.390469ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:10.30441Z","caller":"traceutil/trace.go:171","msg":"trace[128316259] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7939; }","duration":"259.186067ms","start":"2026-03-19T18:08:10.045145Z","end":"2026-03-19T18:08:10.304331Z","steps":["trace[128316259] 'agreement among raft nodes before linearized reading' (duration: 258.830427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:10.304368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.958603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8prdr\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-19T18:08:10.304816Z","caller":"traceutil/trace.go:171","msg":"trace[1570905388] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8prdr; range_end:; response_count:1; response_revision:7939; }","duration":"256.467475ms","start":"2026-03-19T18:08:10.048325Z","end":"2026-03-19T18:08:10.304793Z","steps":["trace[1570905388] 'agreement among raft nodes before linearized reading' (duration: 255.598533ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:10.717867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.943138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T18:08:10.718005Z","caller":"traceutil/trace.go:171","msg":"trace[867373290] transaction","detail":"{read_only:false; response_revision:7940; number_of_response:1; }","duration":"409.634908ms","start":"2026-03-19T18:08:10.308349Z","end":"2026-03-19T18:08:10.717984Z","steps":["trace[867373290] 'process raft request' (duration: 123.463495ms)","trace[867373290] 'compare' (duration: 285.497115ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T18:08:10.718074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:10.308317Z","time spent":"409.730821ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:08:10.735756Z","caller":"traceutil/trace.go:171","msg":"trace[264540759] transaction","detail":"{read_only:false; response_revision:7941; number_of_response:1; }","duration":"427.028258ms","start":"2026-03-19T18:08:10.308682Z","end":"2026-03-19T18:08:10.73571Z","steps":["trace[264540759] 'process raft request' (duration: 426.692959ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:10.735837Z","caller":"traceutil/trace.go:171","msg":"trace[332971086] transaction","detail":"{read_only:false; response_revision:7942; number_of_response:1; }","duration":"426.746812ms","start":"2026-03-19T18:08:10.309068Z","end":"2026-03-19T18:08:10.735815Z","steps":["trace[332971086] 'process raft request' (duration: 426.474574ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:10.735902Z","caller":"traceutil/trace.go:171","msg":"trace[51559839] transaction","detail":"{read_only:false; response_revision:7944; number_of_response:1; }","duration":"426.378862ms","start":"2026-03-19T18:08:10.309463Z","end":"2026-03-19T18:08:10.735842Z","steps":["trace[51559839] 'process raft request' (duration: 426.135586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:10.735997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:10.309059Z","time spent":"426.830224ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T18:08:10.736007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:10.309442Z","time spent":"426.503595ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T18:08:10.736014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:10.308656Z","time spent":"427.204963ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:08:10.735972Z","caller":"traceutil/trace.go:171","msg":"trace[1777760946] transaction","detail":"{read_only:false; response_revision:7945; number_of_response:1; }","duration":"319.118828ms","start":"2026-03-19T18:08:10.416803Z","end":"2026-03-19T18:08:10.735922Z","steps":["trace[1777760946] 'process raft request' (duration: 318.890582ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:10.735771Z","caller":"traceutil/trace.go:171","msg":"trace[422741168] linearizableReadLoop","detail":"{readStateIndex:8237; appliedIndex:8234; }","duration":"426.496935ms","start":"2026-03-19T18:08:10.309245Z","end":"2026-03-19T18:08:10.735742Z","steps":["trace[422741168] 'read index received' (duration: 122.581832ms)","trace[422741168] 'applied index is now lower than readState.Index' (duration: 303.913543ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T18:08:10.73622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:10.41671Z","time spent":"319.393564ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T18:08:10.736258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.007277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224165"} {"level":"info","ts":"2026-03-19T18:08:10.736304Z","caller":"traceutil/trace.go:171","msg":"trace[1430831987] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:7945; }","duration":"427.094672ms","start":"2026-03-19T18:08:10.309196Z","end":"2026-03-19T18:08:10.736291Z","steps":["trace[1430831987] 'agreement among raft nodes before linearized reading' (duration: 426.634019ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:10.736342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:10.30917Z","time spent":"427.163573ms","remote":"127.0.0.1:60434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":25,"response size":224187,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-03-19T18:08:10.735813Z","caller":"traceutil/trace.go:171","msg":"trace[1774469906] transaction","detail":"{read_only:false; response_revision:7943; number_of_response:1; }","duration":"426.415003ms","start":"2026-03-19T18:08:10.309363Z","end":"2026-03-19T18:08:10.735778Z","steps":["trace[1774469906] 'process raft request' (duration: 426.208727ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:10.736845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:10.309345Z","time spent":"427.403308ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T18:08:10.746943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.765326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T18:08:10.746997Z","caller":"traceutil/trace.go:171","msg":"trace[1372745731] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:7946; }","duration":"326.875288ms","start":"2026-03-19T18:08:10.420111Z","end":"2026-03-19T18:08:10.746986Z","steps":["trace[1372745731] 'agreement among raft nodes before linearized reading' (duration: 326.771015ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:10.747023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:10.420085Z","time spent":"326.931169ms","remote":"127.0.0.1:39048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-03-19T18:08:10.747284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.577115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T18:08:10.747305Z","caller":"traceutil/trace.go:171","msg":"trace[148595314] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:7946; }","duration":"126.661538ms","start":"2026-03-19T18:08:10.620638Z","end":"2026-03-19T18:08:10.747299Z","steps":["trace[148595314] 'agreement among raft nodes before linearized reading' (duration: 126.612476ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:10.74742Z","caller":"traceutil/trace.go:171","msg":"trace[462658922] transaction","detail":"{read_only:false; response_revision:7946; number_of_response:1; }","duration":"100.50986ms","start":"2026-03-19T18:08:10.646874Z","end":"2026-03-19T18:08:10.747384Z","steps":["trace[462658922] 'process raft request' (duration: 99.919715ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:41.130378Z","caller":"traceutil/trace.go:171","msg":"trace[382191537] linearizableReadLoop","detail":"{readStateIndex:8442; appliedIndex:8441; }","duration":"433.146848ms","start":"2026-03-19T18:08:40.697201Z","end":"2026-03-19T18:08:41.130347Z","steps":["trace[382191537] 'read index received' (duration: 432.956313ms)","trace[382191537] 'applied index is now lower than readState.Index' (duration: 188.425µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:08:41.130519Z","caller":"traceutil/trace.go:171","msg":"trace[1646088992] transaction","detail":"{read_only:false; response_revision:8139; number_of_response:1; }","duration":"687.752395ms","start":"2026-03-19T18:08:40.442709Z","end":"2026-03-19T18:08:41.130461Z","steps":["trace[1646088992] 'process raft request' (duration: 687.427746ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:41.130659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.415365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T18:08:41.130763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:40.442683Z","time spent":"687.96417ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:08:41.130827Z","caller":"traceutil/trace.go:171","msg":"trace[1639056409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8139; }","duration":"433.63558ms","start":"2026-03-19T18:08:40.697142Z","end":"2026-03-19T18:08:41.130778Z","steps":["trace[1639056409] 'agreement among raft nodes before linearized reading' (duration: 433.341433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:41.130903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:40.697119Z","time spent":"433.768934ms","remote":"127.0.0.1:60218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T18:08:41.384036Z","caller":"traceutil/trace.go:171","msg":"trace[479505991] linearizableReadLoop","detail":"{readStateIndex:8444; appliedIndex:8442; }","duration":"253.53471ms","start":"2026-03-19T18:08:41.130467Z","end":"2026-03-19T18:08:41.384002Z","steps":["trace[479505991] 'read index received' (duration: 181.810625ms)","trace[479505991] 'applied index is now lower than readState.Index' (duration: 71.722885ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:08:41.38406Z","caller":"traceutil/trace.go:171","msg":"trace[917364153] transaction","detail":"{read_only:false; response_revision:8140; number_of_response:1; }","duration":"459.54027ms","start":"2026-03-19T18:08:40.924464Z","end":"2026-03-19T18:08:41.384004Z","steps":["trace[917364153] 'process raft request' (duration: 387.816905ms)","trace[917364153] 'compare' (duration: 71.191531ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T18:08:41.384259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.23206ms","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":"warn","ts":"2026-03-19T18:08:41.384364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.237795ms","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-03-19T18:08:41.384391Z","caller":"traceutil/trace.go:171","msg":"trace[1755712925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8141; }","duration":"384.376425ms","start":"2026-03-19T18:08:40.999991Z","end":"2026-03-19T18:08:41.384367Z","steps":["trace[1755712925] 'agreement among raft nodes before linearized reading' (duration: 384.033645ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:41.384421Z","caller":"traceutil/trace.go:171","msg":"trace[308712718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8141; }","duration":"388.342979ms","start":"2026-03-19T18:08:40.996063Z","end":"2026-03-19T18:08:41.384406Z","steps":["trace[308712718] 'agreement among raft nodes before linearized reading' (duration: 388.114882ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:41.384447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:40.999946Z","time spent":"384.485748ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T18:08:41.384404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:40.924425Z","time spent":"459.746365ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T18:08:41.384466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:40.996053Z","time spent":"388.40034ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T18:08:41.384675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.523101ms","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-03-19T18:08:41.384706Z","caller":"traceutil/trace.go:171","msg":"trace[1904410859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8141; }","duration":"432.587313ms","start":"2026-03-19T18:08:40.95211Z","end":"2026-03-19T18:08:41.384697Z","steps":["trace[1904410859] 'agreement among raft nodes before linearized reading' (duration: 432.506491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:41.384737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:40.952089Z","time spent":"432.641214ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T18:08:41.384859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.274143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-7fd48d9959-pdrw6.189e505eb5a40b12\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-03-19T18:08:41.384888Z","caller":"traceutil/trace.go:171","msg":"trace[21384713] range","detail":"{range_begin:/registry/events/openstack/barbican-api-7fd48d9959-pdrw6.189e505eb5a40b12; range_end:; response_count:1; response_revision:8141; }","duration":"447.346484ms","start":"2026-03-19T18:08:40.937531Z","end":"2026-03-19T18:08:41.384877Z","steps":["trace[21384713] 'agreement among raft nodes before linearized reading' (duration: 447.277512ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:41.38492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:40.93751Z","time spent":"447.399596ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1157,"request content":"key:\"/registry/events/openstack/barbican-api-7fd48d9959-pdrw6.189e505eb5a40b12\" "} {"level":"info","ts":"2026-03-19T18:08:41.384956Z","caller":"traceutil/trace.go:171","msg":"trace[322026061] transaction","detail":"{read_only:false; response_revision:8141; number_of_response:1; }","duration":"454.411307ms","start":"2026-03-19T18:08:40.930315Z","end":"2026-03-19T18:08:41.384726Z","steps":["trace[322026061] 'process raft request' (duration: 453.526195ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:41.38508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:40.93029Z","time spent":"454.704115ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T18:08:41.385126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.417643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-19T18:08:41.385154Z","caller":"traceutil/trace.go:171","msg":"trace[677549929] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8141; }","duration":"472.495266ms","start":"2026-03-19T18:08:40.912648Z","end":"2026-03-19T18:08:41.385143Z","steps":["trace[677549929] 'agreement among raft nodes before linearized reading' (duration: 472.442754ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:41.385177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:40.912618Z","time spent":"472.552696ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-03-19T18:08:41.385493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.901793ms","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:5"} {"level":"warn","ts":"2026-03-19T18:08:41.385559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.273311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-19T18:08:41.385595Z","caller":"traceutil/trace.go:171","msg":"trace[1359511490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8141; }","duration":"486.359163ms","start":"2026-03-19T18:08:40.899223Z","end":"2026-03-19T18:08:41.385582Z","steps":["trace[1359511490] 'agreement among raft nodes before linearized reading' (duration: 486.265291ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:41.385635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:40.899196Z","time spent":"486.430694ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-19T18:08:41.385736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.418298ms","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-03-19T18:08:41.385758Z","caller":"traceutil/trace.go:171","msg":"trace[1444943658] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8141; }","duration":"585.47609ms","start":"2026-03-19T18:08:40.800277Z","end":"2026-03-19T18:08:41.385753Z","steps":["trace[1444943658] 'agreement among raft nodes before linearized reading' (duration: 585.414237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:41.385782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:40.800257Z","time spent":"585.51416ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-03-19T18:08:41.385567Z","caller":"traceutil/trace.go:171","msg":"trace[2105158396] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:8141; }","duration":"384.000795ms","start":"2026-03-19T18:08:41.00155Z","end":"2026-03-19T18:08:41.385551Z","steps":["trace[2105158396] 'agreement among raft nodes before linearized reading' (duration: 383.670687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:41.386082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:41.001539Z","time spent":"384.484807ms","remote":"127.0.0.1:49658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"warn","ts":"2026-03-19T18:08:41.386333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.116774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T18:08:41.386341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.877757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8prdr\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-19T18:08:41.386366Z","caller":"traceutil/trace.go:171","msg":"trace[702540651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8141; }","duration":"254.186176ms","start":"2026-03-19T18:08:41.13217Z","end":"2026-03-19T18:08:41.386356Z","steps":["trace[702540651] 'agreement among raft nodes before linearized reading' (duration: 254.135865ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:41.386393Z","caller":"traceutil/trace.go:171","msg":"trace[2141399043] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8prdr; range_end:; response_count:1; response_revision:8141; }","duration":"343.953559ms","start":"2026-03-19T18:08:41.042425Z","end":"2026-03-19T18:08:41.386379Z","steps":["trace[2141399043] 'agreement among raft nodes before linearized reading' (duration: 343.794155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:41.386454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:41.042412Z","time spent":"344.032542ms","remote":"127.0.0.1:60434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-8prdr\" "} {"level":"warn","ts":"2026-03-19T18:08:41.386831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.457243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-19T18:08:41.38737Z","caller":"traceutil/trace.go:171","msg":"trace[740969146] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8141; }","duration":"368.788862ms","start":"2026-03-19T18:08:41.01833Z","end":"2026-03-19T18:08:41.387119Z","steps":["trace[740969146] 'agreement among raft nodes before linearized reading' (duration: 367.891558ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:41.387715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:41.018319Z","time spent":"369.369626ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T18:08:41.894315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262225857683742927,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T18:08:41.989432Z","caller":"traceutil/trace.go:171","msg":"trace[395451083] transaction","detail":"{read_only:false; response_revision:8142; number_of_response:1; }","duration":"595.589771ms","start":"2026-03-19T18:08:41.393799Z","end":"2026-03-19T18:08:41.989389Z","steps":["trace[395451083] 'process raft request' (duration: 592.928351ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:41.989624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:41.393768Z","time spent":"595.758885ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T18:08:42.00592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:41.393766Z","time spent":"612.143599ms","remote":"127.0.0.1:60310","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-19T18:08:42.006194Z","caller":"traceutil/trace.go:171","msg":"trace[1527385737] linearizableReadLoop","detail":"{readStateIndex:8451; appliedIndex:8444; }","duration":"612.307094ms","start":"2026-03-19T18:08:41.393848Z","end":"2026-03-19T18:08:42.006155Z","steps":["trace[1527385737] 'read index received' (duration: 592.814459ms)","trace[1527385737] 'applied index is now lower than readState.Index' (duration: 19.491145ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:08:42.006273Z","caller":"traceutil/trace.go:171","msg":"trace[323576394] transaction","detail":"{read_only:false; response_revision:8147; number_of_response:1; }","duration":"612.075777ms","start":"2026-03-19T18:08:41.394157Z","end":"2026-03-19T18:08:42.006233Z","steps":["trace[323576394] 'process raft request' (duration: 611.750848ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:42.005954Z","caller":"traceutil/trace.go:171","msg":"trace[1259152086] transaction","detail":"{read_only:false; response_revision:8146; number_of_response:1; }","duration":"611.872921ms","start":"2026-03-19T18:08:41.39406Z","end":"2026-03-19T18:08:42.005933Z","steps":["trace[1259152086] 'process raft request' (duration: 611.82827ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:42.006466Z","caller":"traceutil/trace.go:171","msg":"trace[412984166] transaction","detail":"{read_only:false; response_revision:8145; number_of_response:1; }","duration":"612.028835ms","start":"2026-03-19T18:08:41.393948Z","end":"2026-03-19T18:08:42.005976Z","steps":["trace[412984166] 'process raft request' (duration: 611.918663ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:42.00644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:41.39405Z","time spent":"612.285273ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T18:08:42.006666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:41.393925Z","time spent":"612.605541ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:08:42.006017Z","caller":"traceutil/trace.go:171","msg":"trace[835296971] transaction","detail":"{read_only:false; response_revision:8143; number_of_response:1; }","duration":"612.133479ms","start":"2026-03-19T18:08:41.393875Z","end":"2026-03-19T18:08:42.006008Z","steps":["trace[835296971] 'process raft request' (duration: 611.873492ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:42.006778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:41.393866Z","time spent":"612.887798ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T18:08:42.007525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.669289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228896"} {"level":"info","ts":"2026-03-19T18:08:42.007591Z","caller":"traceutil/trace.go:171","msg":"trace[1067816258] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8147; }","duration":"613.73819ms","start":"2026-03-19T18:08:41.393837Z","end":"2026-03-19T18:08:42.007575Z","steps":["trace[1067816258] 'agreement among raft nodes before linearized reading' (duration: 612.455307ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:42.007629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:41.393803Z","time spent":"613.816913ms","remote":"127.0.0.1:60434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":26,"response size":228918,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-03-19T18:08:42.008265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:41.394149Z","time spent":"612.326713ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:08:42.011022Z","caller":"traceutil/trace.go:171","msg":"trace[311403449] transaction","detail":"{read_only:false; response_revision:8144; number_of_response:1; }","duration":"617.061327ms","start":"2026-03-19T18:08:41.393935Z","end":"2026-03-19T18:08:42.010996Z","steps":["trace[311403449] 'process raft request' (duration: 611.907853ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:42.011175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:41.393922Z","time spent":"617.172259ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:08:45.691537Z","caller":"traceutil/trace.go:171","msg":"trace[1653943715] transaction","detail":"{read_only:false; response_revision:8158; number_of_response:1; }","duration":"548.255225ms","start":"2026-03-19T18:08:45.143258Z","end":"2026-03-19T18:08:45.691513Z","steps":["trace[1653943715] 'process raft request' (duration: 548.068901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:45.692029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:45.143221Z","time spent":"548.395759ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:08:45.7111Z","caller":"traceutil/trace.go:171","msg":"trace[1203388662] linearizableReadLoop","detail":"{readStateIndex:8465; appliedIndex:8462; }","duration":"192.783099ms","start":"2026-03-19T18:08:45.518291Z","end":"2026-03-19T18:08:45.711074Z","steps":["trace[1203388662] 'read index received' (duration: 173.079889ms)","trace[1203388662] 'applied index is now lower than readState.Index' (duration: 19.70147ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:08:45.711178Z","caller":"traceutil/trace.go:171","msg":"trace[1959699190] transaction","detail":"{read_only:false; response_revision:8159; number_of_response:1; }","duration":"316.11902ms","start":"2026-03-19T18:08:45.395013Z","end":"2026-03-19T18:08:45.711132Z","steps":["trace[1959699190] 'process raft request' (duration: 315.836642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:45.711304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.985954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-03-19T18:08:45.711271Z","caller":"traceutil/trace.go:171","msg":"trace[1484294714] transaction","detail":"{read_only:false; response_revision:8160; number_of_response:1; }","duration":"309.302243ms","start":"2026-03-19T18:08:45.401917Z","end":"2026-03-19T18:08:45.711219Z","steps":["trace[1484294714] 'process raft request' (duration: 309.112459ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:45.711347Z","caller":"traceutil/trace.go:171","msg":"trace[1897234624] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:8160; }","duration":"193.100047ms","start":"2026-03-19T18:08:45.518234Z","end":"2026-03-19T18:08:45.711334Z","steps":["trace[1897234624] 'agreement among raft nodes before linearized reading' (duration: 192.935193ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:45.711408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:45.394981Z","time spent":"316.294644ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T18:08:45.71147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:08:45.401884Z","time spent":"309.472428ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T18:08:56.266964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.288354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T18:08:56.267434Z","caller":"traceutil/trace.go:171","msg":"trace[28066578] transaction","detail":"{read_only:false; response_revision:8257; number_of_response:1; }","duration":"176.192708ms","start":"2026-03-19T18:08:56.091224Z","end":"2026-03-19T18:08:56.267416Z","steps":["trace[28066578] 'process raft request' (duration: 175.958202ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:56.267438Z","caller":"traceutil/trace.go:171","msg":"trace[1963178255] transaction","detail":"{read_only:false; response_revision:8256; number_of_response:1; }","duration":"176.594499ms","start":"2026-03-19T18:08:56.090801Z","end":"2026-03-19T18:08:56.267396Z","steps":["trace[1963178255] 'process raft request' (duration: 176.279102ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:56.267442Z","caller":"traceutil/trace.go:171","msg":"trace[1498310136] transaction","detail":"{read_only:false; response_revision:8255; number_of_response:1; }","duration":"177.80437ms","start":"2026-03-19T18:08:56.089587Z","end":"2026-03-19T18:08:56.267391Z","steps":["trace[1498310136] 'process raft request' (duration: 71.967862ms)","trace[1498310136] 'compare' (duration: 105.013377ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:08:56.267619Z","caller":"traceutil/trace.go:171","msg":"trace[551006038] linearizableReadLoop","detail":"{readStateIndex:8566; appliedIndex:8562; }","duration":"176.090825ms","start":"2026-03-19T18:08:56.091504Z","end":"2026-03-19T18:08:56.267595Z","steps":["trace[551006038] 'read index received' (duration: 11.680702ms)","trace[551006038] 'applied index is now lower than readState.Index' (duration: 164.408043ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:08:56.267713Z","caller":"traceutil/trace.go:171","msg":"trace[1433585420] transaction","detail":"{read_only:false; response_revision:8258; number_of_response:1; }","duration":"166.343694ms","start":"2026-03-19T18:08:56.101285Z","end":"2026-03-19T18:08:56.267629Z","steps":["trace[1433585420] 'process raft request' (duration: 165.949133ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:08:56.267939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.718236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8prdr\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-03-19T18:08:56.268006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.472156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-19T18:08:56.268035Z","caller":"traceutil/trace.go:171","msg":"trace[1852598902] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8prdr; range_end:; response_count:1; response_revision:8258; }","duration":"151.862569ms","start":"2026-03-19T18:08:56.116152Z","end":"2026-03-19T18:08:56.268014Z","steps":["trace[1852598902] 'agreement among raft nodes before linearized reading' (duration: 151.609743ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:56.268096Z","caller":"traceutil/trace.go:171","msg":"trace[256795212] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8258; }","duration":"176.595589ms","start":"2026-03-19T18:08:56.09148Z","end":"2026-03-19T18:08:56.268075Z","steps":["trace[256795212] 'agreement among raft nodes before linearized reading' (duration: 176.257631ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:08:56.867707Z","caller":"traceutil/trace.go:171","msg":"trace[1984853270] transaction","detail":"{read_only:false; response_revision:8261; number_of_response:1; }","duration":"146.540752ms","start":"2026-03-19T18:08:56.721091Z","end":"2026-03-19T18:08:56.867632Z","steps":["trace[1984853270] 'process raft request' (duration: 146.303485ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:09:07.412406Z","caller":"traceutil/trace.go:171","msg":"trace[1094857286] linearizableReadLoop","detail":"{readStateIndex:8655; appliedIndex:8654; }","duration":"215.020605ms","start":"2026-03-19T18:09:07.197357Z","end":"2026-03-19T18:09:07.412378Z","steps":["trace[1094857286] 'read index received' (duration: 214.785239ms)","trace[1094857286] 'applied index is now lower than readState.Index' (duration: 233.836µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:09:07.412451Z","caller":"traceutil/trace.go:171","msg":"trace[111388494] transaction","detail":"{read_only:false; response_revision:8343; number_of_response:1; }","duration":"257.91583ms","start":"2026-03-19T18:09:07.1545Z","end":"2026-03-19T18:09:07.412416Z","steps":["trace[111388494] 'process raft request' (duration: 257.658963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:09:07.412673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.272042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T18:09:07.41278Z","caller":"traceutil/trace.go:171","msg":"trace[149020806] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:8343; }","duration":"215.438516ms","start":"2026-03-19T18:09:07.197314Z","end":"2026-03-19T18:09:07.412752Z","steps":["trace[149020806] 'agreement among raft nodes before linearized reading' (duration: 215.231341ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:09:12.4667Z","caller":"traceutil/trace.go:171","msg":"trace[1251598043] transaction","detail":"{read_only:false; response_revision:8403; number_of_response:1; }","duration":"107.749424ms","start":"2026-03-19T18:09:12.358919Z","end":"2026-03-19T18:09:12.466668Z","steps":["trace[1251598043] 'process raft request' (duration: 77.916426ms)","trace[1251598043] 'compare' (duration: 29.486509ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:09:56.618648Z","caller":"traceutil/trace.go:171","msg":"trace[1500061928] transaction","detail":"{read_only:false; response_revision:8879; number_of_response:1; }","duration":"124.164207ms","start":"2026-03-19T18:09:56.494433Z","end":"2026-03-19T18:09:56.618598Z","steps":["trace[1500061928] 'process raft request' (duration: 123.858549ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:10:09.668619Z","caller":"traceutil/trace.go:171","msg":"trace[467983407] linearizableReadLoop","detail":"{readStateIndex:9278; appliedIndex:9277; }","duration":"139.251595ms","start":"2026-03-19T18:10:09.529334Z","end":"2026-03-19T18:10:09.668586Z","steps":["trace[467983407] 'read index received' (duration: 139.03094ms)","trace[467983407] 'applied index is now lower than readState.Index' (duration: 219.095µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T18:10:09.668824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.45573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T18:10:09.669045Z","caller":"traceutil/trace.go:171","msg":"trace[51268388] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8949; }","duration":"139.721038ms","start":"2026-03-19T18:10:09.529292Z","end":"2026-03-19T18:10:09.669013Z","steps":["trace[51268388] 'agreement among raft nodes before linearized reading' (duration: 139.426969ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:11:57.705329Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"dcbda34f8b503a3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-19T18:11:57.711967Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-19T18:11:57.71204Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-19T18:12:26.408066Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7557} {"level":"info","ts":"2026-03-19T18:12:26.475442Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7557,"took":"64.15162ms","hash":464775608,"current-db-size-bytes":40562688,"current-db-size":"41 MB","current-db-size-in-use-bytes":25051136,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-19T18:12:26.475531Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":464775608,"revision":7557,"compact-revision":5627} {"level":"warn","ts":"2026-03-19T18:14:18.545768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262225857683749240,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-19T18:14:18.636275Z","caller":"traceutil/trace.go:171","msg":"trace[290593088] linearizableReadLoop","detail":"{readStateIndex:10810; appliedIndex:10809; }","duration":"590.936153ms","start":"2026-03-19T18:14:18.045317Z","end":"2026-03-19T18:14:18.636253Z","steps":["trace[290593088] 'read index received' (duration: 590.79948ms)","trace[290593088] 'applied index is now lower than readState.Index' (duration: 135.623µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T18:14:18.636363Z","caller":"traceutil/trace.go:171","msg":"trace[122731099] transaction","detail":"{read_only:false; response_revision:10424; number_of_response:1; }","duration":"597.246467ms","start":"2026-03-19T18:14:18.03907Z","end":"2026-03-19T18:14:18.636316Z","steps":["trace[122731099] 'process raft request' (duration: 597.040202ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:14:18.636543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.665798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T18:14:18.636572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.226511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-19T18:14:18.63661Z","caller":"traceutil/trace.go:171","msg":"trace[420550] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:10424; }","duration":"524.795363ms","start":"2026-03-19T18:14:18.111797Z","end":"2026-03-19T18:14:18.636592Z","steps":["trace[420550] 'agreement among raft nodes before linearized reading' (duration: 524.666148ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:14:18.636603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.750984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-19T18:14:18.636645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:14:18.03905Z","time spent":"597.422461ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T18:14:18.636656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:14:18.111775Z","time spent":"524.871274ms","remote":"127.0.0.1:60386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-03-19T18:14:18.63666Z","caller":"traceutil/trace.go:171","msg":"trace[1574917623] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10424; }","duration":"591.341055ms","start":"2026-03-19T18:14:18.045298Z","end":"2026-03-19T18:14:18.636639Z","steps":["trace[1574917623] 'agreement among raft nodes before linearized reading' (duration: 591.066987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:14:18.636673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.079006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/staffeln-api-5fd8c8945d-whrvq\" ","response":"range_response_count:1 size:7414"} {"level":"warn","ts":"2026-03-19T18:14:18.636707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:14:18.04529Z","time spent":"591.407756ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-19T18:14:18.63674Z","caller":"traceutil/trace.go:171","msg":"trace[1423365104] range","detail":"{range_begin:/registry/pods/openstack/staffeln-api-5fd8c8945d-whrvq; range_end:; response_count:1; response_revision:10424; }","duration":"288.175379ms","start":"2026-03-19T18:14:18.348545Z","end":"2026-03-19T18:14:18.636721Z","steps":["trace[1423365104] 'agreement among raft nodes before linearized reading' (duration: 287.988814ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:14:18.636689Z","caller":"traceutil/trace.go:171","msg":"trace[168821631] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10424; }","duration":"494.890017ms","start":"2026-03-19T18:14:18.141777Z","end":"2026-03-19T18:14:18.636667Z","steps":["trace[168821631] 'agreement among raft nodes before linearized reading' (duration: 494.62567ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:14:18.636865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:14:18.141755Z","time spent":"495.100713ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T18:14:18.636602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.783092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-19T18:14:18.636961Z","caller":"traceutil/trace.go:171","msg":"trace[457547543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10424; }","duration":"588.182322ms","start":"2026-03-19T18:14:18.048764Z","end":"2026-03-19T18:14:18.636946Z","steps":["trace[457547543] 'agreement among raft nodes before linearized reading' (duration: 587.697289ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:14:18.636985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:14:18.048749Z","time spent":"588.229233ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T18:14:18.637217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.48305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-19T18:14:18.637259Z","caller":"traceutil/trace.go:171","msg":"trace[1090598786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10424; }","duration":"588.538642ms","start":"2026-03-19T18:14:18.048709Z","end":"2026-03-19T18:14:18.637248Z","steps":["trace[1090598786] 'agreement among raft nodes before linearized reading' (duration: 587.678999ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:14:18.637307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:14:18.048703Z","time spent":"588.596313ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-19T18:14:18.951152Z","caller":"traceutil/trace.go:171","msg":"trace[1389136837] transaction","detail":"{read_only:false; response_revision:10425; number_of_response:1; }","duration":"309.294624ms","start":"2026-03-19T18:14:18.641831Z","end":"2026-03-19T18:14:18.951125Z","steps":["trace[1389136837] 'process raft request' (duration: 300.698702ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:14:18.951306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:14:18.641803Z","time spent":"309.418927ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:14:18.953054Z","caller":"traceutil/trace.go:171","msg":"trace[424773182] transaction","detail":"{read_only:false; response_revision:10426; number_of_response:1; }","duration":"308.237737ms","start":"2026-03-19T18:14:18.644795Z","end":"2026-03-19T18:14:18.953033Z","steps":["trace[424773182] 'process raft request' (duration: 308.092872ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:14:18.953247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:14:18.644776Z","time spent":"308.33926ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:14:18.953263Z","caller":"traceutil/trace.go:171","msg":"trace[1016000032] transaction","detail":"{read_only:false; response_revision:10427; number_of_response:1; }","duration":"308.422232ms","start":"2026-03-19T18:14:18.644831Z","end":"2026-03-19T18:14:18.953253Z","steps":["trace[1016000032] 'process raft request' (duration: 308.170726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:14:18.953319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:14:18.644812Z","time spent":"308.477743ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:14:18.953508Z","caller":"traceutil/trace.go:171","msg":"trace[2070232974] transaction","detail":"{read_only:false; response_revision:10428; number_of_response:1; }","duration":"305.067554ms","start":"2026-03-19T18:14:18.64843Z","end":"2026-03-19T18:14:18.953498Z","steps":["trace[2070232974] 'process raft request' (duration: 304.757437ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:14:18.953611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:14:18.64841Z","time spent":"305.126455ms","remote":"127.0.0.1:60434","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7634,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:14:18.953705Z","caller":"traceutil/trace.go:171","msg":"trace[351389088] transaction","detail":"{read_only:false; response_revision:10429; number_of_response:1; }","duration":"279.416412ms","start":"2026-03-19T18:14:18.674278Z","end":"2026-03-19T18:14:18.953695Z","steps":["trace[351389088] 'process raft request' (duration: 279.067932ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:14:57.52666Z","caller":"traceutil/trace.go:171","msg":"trace[725237130] transaction","detail":"{read_only:false; response_revision:10667; number_of_response:1; }","duration":"524.872293ms","start":"2026-03-19T18:14:57.001747Z","end":"2026-03-19T18:14:57.526619Z","steps":["trace[725237130] 'process raft request' (duration: 524.672508ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:14:57.526861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:14:57.001718Z","time spent":"525.047199ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T18:14:57.527529Z","caller":"traceutil/trace.go:171","msg":"trace[308215563] linearizableReadLoop","detail":"{readStateIndex:11062; appliedIndex:11062; }","duration":"356.277081ms","start":"2026-03-19T18:14:57.171239Z","end":"2026-03-19T18:14:57.527516Z","steps":["trace[308215563] 'read index received' (duration: 356.272241ms)","trace[308215563] 'applied index is now lower than readState.Index' (duration: 3.95µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T18:14:57.527699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.456495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-19T18:14:57.527739Z","caller":"traceutil/trace.go:171","msg":"trace[273705523] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:10667; }","duration":"356.545907ms","start":"2026-03-19T18:14:57.171181Z","end":"2026-03-19T18:14:57.527727Z","steps":["trace[273705523] 'agreement among raft nodes before linearized reading' (duration: 356.404634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:14:57.527767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:14:57.171152Z","time spent":"356.608539ms","remote":"127.0.0.1:60522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-19T18:14:57.531205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.795658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8prdr\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-19T18:14:57.531297Z","caller":"traceutil/trace.go:171","msg":"trace[1157220163] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8prdr; range_end:; response_count:1; response_revision:10668; }","duration":"304.946432ms","start":"2026-03-19T18:14:57.22633Z","end":"2026-03-19T18:14:57.531276Z","steps":["trace[1157220163] 'agreement among raft nodes before linearized reading' (duration: 304.730767ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T18:14:57.531327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T18:14:57.226301Z","time spent":"305.019944ms","remote":"127.0.0.1:60434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-8prdr\" "} {"level":"info","ts":"2026-03-19T18:14:57.531759Z","caller":"traceutil/trace.go:171","msg":"trace[318127940] transaction","detail":"{read_only:false; response_revision:10668; number_of_response:1; }","duration":"280.945751ms","start":"2026-03-19T18:14:57.250762Z","end":"2026-03-19T18:14:57.531707Z","steps":["trace[318127940] 'process raft request' (duration: 280.13588ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T18:17:26.414137Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9808} {"level":"info","ts":"2026-03-19T18:17:26.496746Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9808,"took":"79.804709ms","hash":2414788526,"current-db-size-bytes":40562688,"current-db-size":"41 MB","current-db-size-in-use-bytes":18419712,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-03-19T18:17:26.496833Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2414788526,"revision":9808,"compact-revision":7557}