{"level":"warn","ts":"2026-02-24T10:44:53.264164Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T10:44:53.264252Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.132: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.204.45.132:2380","--initial-cluster=instance=https://199.204.45.132:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.132:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.132: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-02-24T10:44:53.264319Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-24T10:44:53.264329Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-02-24T10:44:53.264347Z","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-02-24T10:44:53.265044Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"]} {"level":"info","ts":"2026-02-24T10:44:53.265125Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.132: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-02-24T10:44:53.275872Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.347089ms"} {"level":"info","ts":"2026-02-24T10:44:53.293198Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"56c01ea8fdc90390","cluster-id":"c930fe821aab8609"} {"level":"info","ts":"2026-02-24T10:44:53.293283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T10:44:53.293307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 0"} {"level":"info","ts":"2026-02-24T10:44:53.293576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 56c01ea8fdc90390 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T10:44:53.293592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became follower at term 1"} {"level":"info","ts":"2026-02-24T10:44:53.293709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"warn","ts":"2026-02-24T10:44:53.30273Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T10:44:53.306137Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T10:44:53.307613Z","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-02-24T10:44:53.31089Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"56c01ea8fdc90390","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T10:44:53.311223Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"56c01ea8fdc90390","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T10:44:53.311378Z","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-02-24T10:44:53.311907Z","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-02-24T10:44:53.311979Z","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-02-24T10:44:53.313742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 switched to configuration voters=(6251029993951396752)"} {"level":"info","ts":"2026-02-24T10:44:53.31395Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","added-peer-id":"56c01ea8fdc90390","added-peer-peer-urls":["https://199.204.45.132:2380"]} {"level":"info","ts":"2026-02-24T10:44:53.316308Z","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-02-24T10:44:53.316567Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"56c01ea8fdc90390","initial-advertise-peer-urls":["https://199.204.45.132:2380"],"listen-peer-urls":["https://199.204.45.132:2380"],"advertise-client-urls":["https://199.204.45.132:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.132:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T10:44:53.316607Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T10:44:53.316745Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-02-24T10:44:53.316769Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.132:2380"} {"level":"info","ts":"2026-02-24T10:44:54.294961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T10:44:54.295043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T10:44:54.29511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgPreVoteResp from 56c01ea8fdc90390 at term 1"} {"level":"info","ts":"2026-02-24T10:44:54.295136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became candidate at term 2"} {"level":"info","ts":"2026-02-24T10:44:54.295151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 received MsgVoteResp from 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-02-24T10:44:54.295171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"56c01ea8fdc90390 became leader at term 2"} {"level":"info","ts":"2026-02-24T10:44:54.295188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 56c01ea8fdc90390 elected leader 56c01ea8fdc90390 at term 2"} {"level":"info","ts":"2026-02-24T10:44:54.296765Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:44:54.298939Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"56c01ea8fdc90390","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.132:2379]}","request-path":"/0/members/56c01ea8fdc90390/attributes","cluster-id":"c930fe821aab8609","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T10:44:54.298954Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T10:44:54.29912Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T10:44:54.300168Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c930fe821aab8609","local-member-id":"56c01ea8fdc90390","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:44:54.300307Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:44:54.300359Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:44:54.302484Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.132:2379"} {"level":"info","ts":"2026-02-24T10:44:54.302521Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T10:44:54.302627Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T10:44:54.302668Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T10:44:55.811149Z","caller":"traceutil/trace.go:171","msg":"trace[1127545502] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"132.423961ms","start":"2026-02-24T10:44:55.678701Z","end":"2026-02-24T10:44:55.811125Z","steps":["trace[1127545502] 'process raft request' (duration: 132.354262ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:55.81148Z","caller":"traceutil/trace.go:171","msg":"trace[811890124] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"133.771234ms","start":"2026-02-24T10:44:55.677626Z","end":"2026-02-24T10:44:55.811398Z","steps":["trace[811890124] 'process raft request' (duration: 92.04629ms)","trace[811890124] 'compare' (duration: 41.257414ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:55.966966Z","caller":"traceutil/trace.go:171","msg":"trace[895279634] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"101.916368ms","start":"2026-02-24T10:44:55.865023Z","end":"2026-02-24T10:44:55.966939Z","steps":["trace[895279634] 'process raft request' (duration: 101.857801ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:55.967108Z","caller":"traceutil/trace.go:171","msg":"trace[866864934] linearizableReadLoop","detail":"{readStateIndex:70; appliedIndex:69; }","duration":"102.528845ms","start":"2026-02-24T10:44:55.864538Z","end":"2026-02-24T10:44:55.967067Z","steps":["trace[866864934] 'read index received' (duration: 48.798334ms)","trace[866864934] 'applied index is now lower than readState.Index' (duration: 53.728121ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:55.967008Z","caller":"traceutil/trace.go:171","msg":"trace[850506912] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"108.559703ms","start":"2026-02-24T10:44:55.858425Z","end":"2026-02-24T10:44:55.966985Z","steps":["trace[850506912] 'process raft request' (duration: 55.122439ms)","trace[850506912] 'compare' (duration: 53.055996ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:44:55.967413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.986557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/kube-controller-manager\" ","response":"range_response_count:1 size:1128"} {"level":"info","ts":"2026-02-24T10:44:55.967485Z","caller":"traceutil/trace.go:171","msg":"trace[782397555] range","detail":"{range_begin:/registry/flowschemas/kube-controller-manager; range_end:; response_count:1; response_revision:66; }","duration":"102.098311ms","start":"2026-02-24T10:44:55.865368Z","end":"2026-02-24T10:44:55.967466Z","steps":["trace[782397555] 'agreement among raft nodes before linearized reading' (duration: 101.865992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:55.967556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.959971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3181"} {"level":"info","ts":"2026-02-24T10:44:55.967735Z","caller":"traceutil/trace.go:171","msg":"trace[2080469178] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:66; }","duration":"103.207511ms","start":"2026-02-24T10:44:55.864506Z","end":"2026-02-24T10:44:55.967714Z","steps":["trace[2080469178] 'agreement among raft nodes before linearized reading' (duration: 102.688256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:56.19654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.143976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-24T10:44:56.196672Z","caller":"traceutil/trace.go:171","msg":"trace[984978761] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"212.212595ms","start":"2026-02-24T10:44:55.984434Z","end":"2026-02-24T10:44:56.196647Z","steps":["trace[984978761] 'process raft request' (duration: 105.479134ms)","trace[984978761] 'compare' (duration: 105.991796ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:44:56.196728Z","caller":"traceutil/trace.go:171","msg":"trace[67664643] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"132.809491ms","start":"2026-02-24T10:44:56.063902Z","end":"2026-02-24T10:44:56.196712Z","steps":["trace[67664643] 'process raft request' (duration: 132.746413ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:44:56.196826Z","caller":"traceutil/trace.go:171","msg":"trace[966094710] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"134.024794ms","start":"2026-02-24T10:44:56.062793Z","end":"2026-02-24T10:44:56.196817Z","steps":["trace[966094710] 'read index received' (duration: 27.075645ms)","trace[966094710] 'applied index is now lower than readState.Index' (duration: 106.948249ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:44:56.19694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.139029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.189728d959b32487\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-02-24T10:44:56.196976Z","caller":"traceutil/trace.go:171","msg":"trace[686098002] range","detail":"{range_begin:/registry/events/default/instance.189728d959b32487; range_end:; response_count:1; response_revision:72; }","duration":"134.177434ms","start":"2026-02-24T10:44:56.062787Z","end":"2026-02-24T10:44:56.196965Z","steps":["trace[686098002] 'agreement among raft nodes before linearized reading' (duration: 134.06973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:44:56.928185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.907042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:44:56.928293Z","caller":"traceutil/trace.go:171","msg":"trace[1003483224] range","detail":"{range_begin:/registry/clusterroles/system:controller:persistent-volume-binder; range_end:; response_count:0; response_revision:161; }","duration":"111.056071ms","start":"2026-02-24T10:44:56.817213Z","end":"2026-02-24T10:44:56.928269Z","steps":["trace[1003483224] 'range keys from in-memory index tree' (duration: 110.852096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:03.061703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.828814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:45:03.061819Z","caller":"traceutil/trace.go:171","msg":"trace[93568841] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:361; }","duration":"350.450233ms","start":"2026-02-24T10:45:02.711351Z","end":"2026-02-24T10:45:03.061801Z","steps":["trace[93568841] 'read index received' (duration: 410.362µs)","trace[93568841] 'applied index is now lower than readState.Index' (duration: 350.03849ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:03.061907Z","caller":"traceutil/trace.go:171","msg":"trace[1145901412] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"394.148364ms","start":"2026-02-24T10:45:02.667748Z","end":"2026-02-24T10:45:03.061896Z","steps":["trace[1145901412] 'process raft request' (duration: 44.054676ms)","trace[1145901412] 'compare' (duration: 349.63638ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:45:03.061968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:02.667729Z","time spent":"394.214222ms","remote":"127.0.0.1:53856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T10:45:03.062049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.675981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-24T10:45:03.062136Z","caller":"traceutil/trace.go:171","msg":"trace[511028448] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:348; }","duration":"350.799817ms","start":"2026-02-24T10:45:02.71131Z","end":"2026-02-24T10:45:03.06211Z","steps":["trace[511028448] 'agreement among raft nodes before linearized reading' (duration: 350.597341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:03.062147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.609367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-02-24T10:45:03.062208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:02.711291Z","time spent":"350.883298ms","remote":"127.0.0.1:53856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-02-24T10:45:03.062243Z","caller":"traceutil/trace.go:171","msg":"trace[1011183254] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:348; }","duration":"199.745353ms","start":"2026-02-24T10:45:02.862479Z","end":"2026-02-24T10:45:03.062224Z","steps":["trace[1011183254] 'agreement among raft nodes before linearized reading' (duration: 199.620917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:03.062049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.955096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-24T10:45:03.06231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.096537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-24T10:45:03.062354Z","caller":"traceutil/trace.go:171","msg":"trace[835646792] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:348; }","duration":"301.310511ms","start":"2026-02-24T10:45:02.761029Z","end":"2026-02-24T10:45:03.06234Z","steps":["trace[835646792] 'agreement among raft nodes before linearized reading' (duration: 300.905539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:03.062415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:02.761005Z","time spent":"301.390472ms","remote":"127.0.0.1:53856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-02-24T10:45:03.062427Z","caller":"traceutil/trace.go:171","msg":"trace[1586632641] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:348; }","duration":"250.260678ms","start":"2026-02-24T10:45:02.812124Z","end":"2026-02-24T10:45:03.062385Z","steps":["trace[1586632641] 'agreement among raft nodes before linearized reading' (duration: 249.997525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:03.062446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.298624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-24T10:45:03.062503Z","caller":"traceutil/trace.go:171","msg":"trace[999794781] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:348; }","duration":"100.387565ms","start":"2026-02-24T10:45:02.962098Z","end":"2026-02-24T10:45:03.062486Z","steps":["trace[999794781] 'agreement among raft nodes before linearized reading' (duration: 100.238786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:03.062729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.413838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-24T10:45:03.06279Z","caller":"traceutil/trace.go:171","msg":"trace[284845882] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:348; }","duration":"150.581549ms","start":"2026-02-24T10:45:02.912191Z","end":"2026-02-24T10:45:03.062772Z","steps":["trace[284845882] 'agreement among raft nodes before linearized reading' (duration: 149.759825ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:07.689074Z","caller":"traceutil/trace.go:171","msg":"trace[629603316] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"110.816746ms","start":"2026-02-24T10:45:07.57823Z","end":"2026-02-24T10:45:07.689047Z","steps":["trace[629603316] 'process raft request' (duration: 110.661347ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:08.127034Z","caller":"traceutil/trace.go:171","msg":"trace[804705809] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"133.980119ms","start":"2026-02-24T10:45:07.993Z","end":"2026-02-24T10:45:08.12698Z","steps":["trace[804705809] 'process raft request' (duration: 85.860966ms)","trace[804705809] 'compare' (duration: 47.9307ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:08.310524Z","caller":"traceutil/trace.go:171","msg":"trace[2124594167] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"113.763283ms","start":"2026-02-24T10:45:08.196736Z","end":"2026-02-24T10:45:08.310499Z","steps":["trace[2124594167] 'read index received' (duration: 75.29577ms)","trace[2124594167] 'applied index is now lower than readState.Index' (duration: 38.466693ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:08.310639Z","caller":"traceutil/trace.go:171","msg":"trace[1832176935] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"172.020327ms","start":"2026-02-24T10:45:08.138602Z","end":"2026-02-24T10:45:08.310623Z","steps":["trace[1832176935] 'process raft request' (duration: 133.461943ms)","trace[1832176935] 'compare' (duration: 38.308673ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:45:08.310695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.954598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-24T10:45:08.310768Z","caller":"traceutil/trace.go:171","msg":"trace[1925839107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:405; }","duration":"114.090714ms","start":"2026-02-24T10:45:08.196658Z","end":"2026-02-24T10:45:08.310749Z","steps":["trace[1925839107] 'agreement among raft nodes before linearized reading' (duration: 113.935244ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:08.518127Z","caller":"traceutil/trace.go:171","msg":"trace[1752062119] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"195.457102ms","start":"2026-02-24T10:45:08.322642Z","end":"2026-02-24T10:45:08.518099Z","steps":["trace[1752062119] 'process raft request' (duration: 146.011119ms)","trace[1752062119] 'compare' (duration: 49.31551ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:09.321374Z","caller":"traceutil/trace.go:171","msg":"trace[1107292692] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"243.652792ms","start":"2026-02-24T10:45:09.077683Z","end":"2026-02-24T10:45:09.321336Z","steps":["trace[1107292692] 'process raft request' (duration: 243.385607ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:09.435847Z","caller":"traceutil/trace.go:171","msg":"trace[2066661878] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"303.185543ms","start":"2026-02-24T10:45:09.132621Z","end":"2026-02-24T10:45:09.435806Z","steps":["trace[2066661878] 'process raft request' (duration: 297.076686ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:09.435999Z","caller":"traceutil/trace.go:171","msg":"trace[1669402074] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"109.866051ms","start":"2026-02-24T10:45:09.325819Z","end":"2026-02-24T10:45:09.435685Z","steps":["trace[1669402074] 'read index received' (duration: 103.910719ms)","trace[1669402074] 'applied index is now lower than readState.Index' (duration: 5.953862ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:45:09.436122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.283552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5269"} {"level":"info","ts":"2026-02-24T10:45:09.436216Z","caller":"traceutil/trace.go:171","msg":"trace[1674070810] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:410; }","duration":"110.440066ms","start":"2026-02-24T10:45:09.325749Z","end":"2026-02-24T10:45:09.436189Z","steps":["trace[1674070810] 'agreement among raft nodes before linearized reading' (duration: 110.246675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:09.436236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:09.132594Z","time spent":"303.344818ms","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:45:14.098025Z","caller":"traceutil/trace.go:171","msg":"trace[1461339456] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"159.394256ms","start":"2026-02-24T10:45:13.938604Z","end":"2026-02-24T10:45:14.097998Z","steps":["trace[1461339456] 'read index received' (duration: 159.247708ms)","trace[1461339456] 'applied index is now lower than readState.Index' (duration: 145.168µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:45:14.098266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.642906ms","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-02-24T10:45:14.098118Z","caller":"traceutil/trace.go:171","msg":"trace[1807249550] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"171.545693ms","start":"2026-02-24T10:45:13.926525Z","end":"2026-02-24T10:45:14.09807Z","steps":["trace[1807249550] 'process raft request' (duration: 171.278365ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:14.098326Z","caller":"traceutil/trace.go:171","msg":"trace[1933751026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:421; }","duration":"159.766146ms","start":"2026-02-24T10:45:13.938544Z","end":"2026-02-24T10:45:14.09831Z","steps":["trace[1933751026] 'agreement among raft nodes before linearized reading' (duration: 159.600309ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:20.040386Z","caller":"traceutil/trace.go:171","msg":"trace[733742783] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"139.20664ms","start":"2026-02-24T10:45:19.90116Z","end":"2026-02-24T10:45:20.040366Z","steps":["trace[733742783] 'read index received' (duration: 139.025142ms)","trace[733742783] 'applied index is now lower than readState.Index' (duration: 180.758µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:45:20.0405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.324833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:45:20.040525Z","caller":"traceutil/trace.go:171","msg":"trace[2146099298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:465; }","duration":"139.386779ms","start":"2026-02-24T10:45:19.901128Z","end":"2026-02-24T10:45:20.040515Z","steps":["trace[2146099298] 'agreement among raft nodes before linearized reading' (duration: 139.314293ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:20.040512Z","caller":"traceutil/trace.go:171","msg":"trace[1526271220] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"300.2664ms","start":"2026-02-24T10:45:19.740212Z","end":"2026-02-24T10:45:20.040478Z","steps":["trace[1526271220] 'process raft request' (duration: 300.026625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:20.040697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:19.740192Z","time spent":"300.400552ms","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:45:22.501621Z","caller":"traceutil/trace.go:171","msg":"trace[118316946] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"290.231561ms","start":"2026-02-24T10:45:22.211367Z","end":"2026-02-24T10:45:22.501599Z","steps":["trace[118316946] 'read index received' (duration: 290.032613ms)","trace[118316946] 'applied index is now lower than readState.Index' (duration: 198.028µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:22.501741Z","caller":"traceutil/trace.go:171","msg":"trace[1606559430] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"323.319583ms","start":"2026-02-24T10:45:22.178392Z","end":"2026-02-24T10:45:22.501711Z","steps":["trace[1606559430] 'process raft request' (duration: 323.065018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:22.501791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.391981ms","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-02-24T10:45:22.50183Z","caller":"traceutil/trace.go:171","msg":"trace[1718327241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:470; }","duration":"290.501845ms","start":"2026-02-24T10:45:22.211315Z","end":"2026-02-24T10:45:22.501817Z","steps":["trace[1718327241] 'agreement among raft nodes before linearized reading' (duration: 290.40482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:22.50193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:22.178371Z","time spent":"323.466284ms","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:45:22.502131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.699523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-24T10:45:22.502238Z","caller":"traceutil/trace.go:171","msg":"trace[1036161421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:470; }","duration":"290.814826ms","start":"2026-02-24T10:45:22.211402Z","end":"2026-02-24T10:45:22.502217Z","steps":["trace[1036161421] 'agreement among raft nodes before linearized reading' (duration: 290.673744ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:24.649211Z","caller":"traceutil/trace.go:171","msg":"trace[372747165] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"136.342917ms","start":"2026-02-24T10:45:24.512849Z","end":"2026-02-24T10:45:24.649192Z","steps":["trace[372747165] 'read index received' (duration: 136.199285ms)","trace[372747165] 'applied index is now lower than readState.Index' (duration: 142.702µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:45:24.649358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.497498ms","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-02-24T10:45:24.649299Z","caller":"traceutil/trace.go:171","msg":"trace[1690448479] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"140.36345ms","start":"2026-02-24T10:45:24.508886Z","end":"2026-02-24T10:45:24.64925Z","steps":["trace[1690448479] 'process raft request' (duration: 140.164851ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:24.649397Z","caller":"traceutil/trace.go:171","msg":"trace[44090624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:474; }","duration":"136.576325ms","start":"2026-02-24T10:45:24.51281Z","end":"2026-02-24T10:45:24.649386Z","steps":["trace[44090624] 'agreement among raft nodes before linearized reading' (duration: 136.499139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:24.649548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.361897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12673"} {"level":"info","ts":"2026-02-24T10:45:24.649588Z","caller":"traceutil/trace.go:171","msg":"trace[1001579161] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:474; }","duration":"124.417244ms","start":"2026-02-24T10:45:24.52516Z","end":"2026-02-24T10:45:24.649578Z","steps":["trace[1001579161] 'agreement among raft nodes before linearized reading' (duration: 124.334179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:24.649663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.158144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-24T10:45:24.649673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.620799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-24T10:45:24.649833Z","caller":"traceutil/trace.go:171","msg":"trace[1379123477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:474; }","duration":"125.824588ms","start":"2026-02-24T10:45:24.523984Z","end":"2026-02-24T10:45:24.649808Z","steps":["trace[1379123477] 'agreement among raft nodes before linearized reading' (duration: 125.506665ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:24.649763Z","caller":"traceutil/trace.go:171","msg":"trace[778854501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:474; }","duration":"125.303606ms","start":"2026-02-24T10:45:24.524435Z","end":"2026-02-24T10:45:24.649739Z","steps":["trace[778854501] 'agreement among raft nodes before linearized reading' (duration: 125.112566ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:25.713282Z","caller":"traceutil/trace.go:171","msg":"trace[457395106] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"145.817706ms","start":"2026-02-24T10:45:25.567436Z","end":"2026-02-24T10:45:25.713253Z","steps":["trace[457395106] 'process raft request' (duration: 72.40087ms)","trace[457395106] 'compare' (duration: 73.266034ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:26.790949Z","caller":"traceutil/trace.go:171","msg":"trace[905953078] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"122.342404ms","start":"2026-02-24T10:45:26.668581Z","end":"2026-02-24T10:45:26.790923Z","steps":["trace[905953078] 'process raft request' (duration: 122.264678ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:26.791121Z","caller":"traceutil/trace.go:171","msg":"trace[71268429] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"121.38686ms","start":"2026-02-24T10:45:26.669695Z","end":"2026-02-24T10:45:26.791082Z","steps":["trace[71268429] 'process raft request' (duration: 121.19103ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:26.790978Z","caller":"traceutil/trace.go:171","msg":"trace[1324659637] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"122.427189ms","start":"2026-02-24T10:45:26.668494Z","end":"2026-02-24T10:45:26.790921Z","steps":["trace[1324659637] 'process raft request' (duration: 121.747673ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:28.961346Z","caller":"traceutil/trace.go:171","msg":"trace[2056814564] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"163.46035ms","start":"2026-02-24T10:45:28.797861Z","end":"2026-02-24T10:45:28.961322Z","steps":["trace[2056814564] 'read index received' (duration: 163.22179ms)","trace[2056814564] 'applied index is now lower than readState.Index' (duration: 237.43µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:28.96148Z","caller":"traceutil/trace.go:171","msg":"trace[1166925328] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"277.874074ms","start":"2026-02-24T10:45:28.683567Z","end":"2026-02-24T10:45:28.961441Z","steps":["trace[1166925328] 'process raft request' (duration: 277.603106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:28.961539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.652011ms","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-02-24T10:45:28.961575Z","caller":"traceutil/trace.go:171","msg":"trace[40336579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:519; }","duration":"163.763456ms","start":"2026-02-24T10:45:28.797802Z","end":"2026-02-24T10:45:28.961565Z","steps":["trace[40336579] 'agreement among raft nodes before linearized reading' (duration: 163.67711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:28.961628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.44193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-24T10:45:28.96173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.898364ms","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-02-24T10:45:28.961724Z","caller":"traceutil/trace.go:171","msg":"trace[615824271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:519; }","duration":"163.596714ms","start":"2026-02-24T10:45:28.798102Z","end":"2026-02-24T10:45:28.961699Z","steps":["trace[615824271] 'agreement among raft nodes before linearized reading' (duration: 163.403402ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:28.96177Z","caller":"traceutil/trace.go:171","msg":"trace[1437325614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:519; }","duration":"162.956572ms","start":"2026-02-24T10:45:28.798803Z","end":"2026-02-24T10:45:28.96176Z","steps":["trace[1437325614] 'agreement among raft nodes before linearized reading' (duration: 162.895765ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:29.871688Z","caller":"traceutil/trace.go:171","msg":"trace[1203977429] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"905.613431ms","start":"2026-02-24T10:45:28.966051Z","end":"2026-02-24T10:45:29.871664Z","steps":["trace[1203977429] 'process raft request' (duration: 905.445668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:29.871852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:28.966023Z","time spent":"905.751314ms","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:45:29.878716Z","caller":"traceutil/trace.go:171","msg":"trace[1759627373] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"910.957346ms","start":"2026-02-24T10:45:28.967729Z","end":"2026-02-24T10:45:29.878687Z","steps":["trace[1759627373] 'process raft request' (duration: 910.902508ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:29.878792Z","caller":"traceutil/trace.go:171","msg":"trace[1524662583] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"911.781289ms","start":"2026-02-24T10:45:28.966967Z","end":"2026-02-24T10:45:29.878748Z","steps":["trace[1524662583] 'process raft request' (duration: 911.5319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:29.878883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:28.967722Z","time spent":"911.073861ms","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:45:29.878935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:28.966948Z","time spent":"911.910063ms","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:45:36.681934Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256877317836091849,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:45:37.183001Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256877317836091849,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:45:37.523475Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.558168489s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T10:45:37.523841Z","caller":"traceutil/trace.go:171","msg":"trace[279819910] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.58718448s","start":"2026-02-24T10:45:35.936639Z","end":"2026-02-24T10:45:37.523824Z","steps":["trace[279819910] 'process raft request' (duration: 1.587087293s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:37.523929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:35.93661Z","time spent":"1.587258768s","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:45:37.625363Z","caller":"traceutil/trace.go:171","msg":"trace[1268659921] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.68831534s","start":"2026-02-24T10:45:35.937026Z","end":"2026-02-24T10:45:37.625341Z","steps":["trace[1268659921] 'process raft request' (duration: 1.625063601s)","trace[1268659921] 'compare' (duration: 63.097983ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:37.625512Z","caller":"traceutil/trace.go:171","msg":"trace[660344257] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:579; }","duration":"1.443984802s","start":"2026-02-24T10:45:36.181515Z","end":"2026-02-24T10:45:37.6255Z","steps":["trace[660344257] 'read index received' (duration: 1.342223309s)","trace[660344257] 'applied index is now lower than readState.Index' (duration: 101.760703ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:37.625579Z","caller":"traceutil/trace.go:171","msg":"trace[1963775008] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.68800232s","start":"2026-02-24T10:45:35.937569Z","end":"2026-02-24T10:45:37.625571Z","steps":["trace[1963775008] 'process raft request' (duration: 1.687736417s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:37.625632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:35.937563Z","time spent":"1.68803372s","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:45:37.625691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.444158467s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dp7mj\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-24T10:45:37.625732Z","caller":"traceutil/trace.go:171","msg":"trace[399734325] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dp7mj; range_end:; response_count:1; response_revision:561; }","duration":"1.444256985s","start":"2026-02-24T10:45:36.181463Z","end":"2026-02-24T10:45:37.62572Z","steps":["trace[399734325] 'agreement among raft nodes before linearized reading' (duration: 1.444169317s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:37.625729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.191996ms","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-02-24T10:45:37.625772Z","caller":"traceutil/trace.go:171","msg":"trace[1290035214] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"878.89973ms","start":"2026-02-24T10:45:36.746837Z","end":"2026-02-24T10:45:37.625737Z","steps":["trace[1290035214] 'process raft request' (duration: 878.617767ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:37.625834Z","caller":"traceutil/trace.go:171","msg":"trace[2125789613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:561; }","duration":"601.297433ms","start":"2026-02-24T10:45:37.024484Z","end":"2026-02-24T10:45:37.625781Z","steps":["trace[2125789613] 'agreement among raft nodes before linearized reading' (duration: 601.186736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:37.625886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:37.024464Z","time spent":"601.407871ms","remote":"127.0.0.1:53946","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-02-24T10:45:37.625782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.63482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.132\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T10:45:37.62595Z","caller":"traceutil/trace.go:171","msg":"trace[1302011348] range","detail":"{range_begin:/registry/masterleases/199.204.45.132; range_end:; response_count:1; response_revision:561; }","duration":"223.859234ms","start":"2026-02-24T10:45:37.402075Z","end":"2026-02-24T10:45:37.625935Z","steps":["trace[1302011348] 'agreement among raft nodes before linearized reading' (duration: 223.63595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:37.625923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:36.181444Z","time spent":"1.444304654s","remote":"127.0.0.1:53832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-dp7mj\" "} {"level":"warn","ts":"2026-02-24T10:45:37.62596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:36.746815Z","time spent":"879.045296ms","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:45:37.626341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:35.93701Z","time spent":"1.688438218s","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:45:38.394754Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256877317836091855,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T10:45:38.713813Z","caller":"traceutil/trace.go:171","msg":"trace[1366424643] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.0833716s","start":"2026-02-24T10:45:37.630419Z","end":"2026-02-24T10:45:38.71379Z","steps":["trace[1366424643] 'process raft request' (duration: 1.083147835s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:38.713935Z","caller":"traceutil/trace.go:171","msg":"trace[1629661548] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.075378325s","start":"2026-02-24T10:45:37.638534Z","end":"2026-02-24T10:45:38.713913Z","steps":["trace[1629661548] 'process raft request' (duration: 1.075192889s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:38.714052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:37.638525Z","time spent":"1.075460003s","remote":"127.0.0.1:53832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:45:38.714484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:37.630402Z","time spent":"1.083481977s","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:45:38.990012Z","caller":"traceutil/trace.go:171","msg":"trace[1103394244] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:584; }","duration":"1.096195154s","start":"2026-02-24T10:45:37.893797Z","end":"2026-02-24T10:45:38.989992Z","steps":["trace[1103394244] 'read index received' (duration: 819.841011ms)","trace[1103394244] 'applied index is now lower than readState.Index' (duration: 276.353393ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:38.990074Z","caller":"traceutil/trace.go:171","msg":"trace[1643737642] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.096834508s","start":"2026-02-24T10:45:37.893198Z","end":"2026-02-24T10:45:38.990033Z","steps":["trace[1643737642] 'process raft request' (duration: 1.096688872s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:38.990208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09637352s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:45:38.990273Z","caller":"traceutil/trace.go:171","msg":"trace[745858501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"1.096482067s","start":"2026-02-24T10:45:37.893772Z","end":"2026-02-24T10:45:38.990254Z","steps":["trace[745858501] 'agreement among raft nodes before linearized reading' (duration: 1.096321311s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:38.990268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:37.893131Z","time spent":"1.097021334s","remote":"127.0.0.1:53670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:45:38.990233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087574496s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:45:38.990306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:37.893763Z","time spent":"1.096535936s","remote":"127.0.0.1:53622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T10:45:38.990372Z","caller":"traceutil/trace.go:171","msg":"trace[942837610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"1.087735621s","start":"2026-02-24T10:45:37.902581Z","end":"2026-02-24T10:45:38.990316Z","steps":["trace[942837610] 'agreement among raft nodes before linearized reading' (duration: 1.087533286s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:38.990543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:37.902574Z","time spent":"1.087958286s","remote":"127.0.0.1:53636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:45:41.221512Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256877317836091870,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:45:41.72243Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256877317836091870,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:45:42.223315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":256877317836091870,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:45:42.266034Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.036468848s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T10:45:42.326868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.696934351s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-24T10:45:42.326909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.797714862s","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-02-24T10:45:42.326933Z","caller":"traceutil/trace.go:171","msg":"trace[658654685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:564; }","duration":"2.797740962s","start":"2026-02-24T10:45:39.529185Z","end":"2026-02-24T10:45:42.326926Z","steps":["trace[658654685] 'range keys from in-memory index tree' (duration: 2.797617844s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:42.326935Z","caller":"traceutil/trace.go:171","msg":"trace[51011343] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"2.697034889s","start":"2026-02-24T10:45:39.629885Z","end":"2026-02-24T10:45:42.32692Z","steps":["trace[51011343] 'range keys from in-memory index tree' (duration: 2.696861762s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:42.326954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:39.529124Z","time spent":"2.79782635s","remote":"127.0.0.1:53946","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-02-24T10:45:42.326927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.695817965s","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-02-24T10:45:42.32697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:39.62987Z","time spent":"2.697091498s","remote":"127.0.0.1:53946","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-02-24T10:45:42.327002Z","caller":"traceutil/trace.go:171","msg":"trace[1754246964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"2.695936943s","start":"2026-02-24T10:45:39.631042Z","end":"2026-02-24T10:45:42.326979Z","steps":["trace[1754246964] 'range keys from in-memory index tree' (duration: 2.695704167s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:42.327043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:39.631013Z","time spent":"2.696021261s","remote":"127.0.0.1:53946","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-02-24T10:45:42.327015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.828221833s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:45:42.3271Z","caller":"traceutil/trace.go:171","msg":"trace[1831711881] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:564; }","duration":"1.828309201s","start":"2026-02-24T10:45:40.498778Z","end":"2026-02-24T10:45:42.327087Z","steps":["trace[1831711881] 'range keys from in-memory index tree' (duration: 1.828211873s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:42.328141Z","caller":"traceutil/trace.go:171","msg":"trace[1900369795] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"1.606731986s","start":"2026-02-24T10:45:40.7214Z","end":"2026-02-24T10:45:42.328132Z","steps":["trace[1900369795] 'read index received' (duration: 1.544755592s)","trace[1900369795] 'applied index is now lower than readState.Index' (duration: 61.975564ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:45:42.328261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426144615s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:45:42.328281Z","caller":"traceutil/trace.go:171","msg":"trace[460594818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"1.426200794s","start":"2026-02-24T10:45:40.902075Z","end":"2026-02-24T10:45:42.328276Z","steps":["trace[460594818] 'agreement among raft nodes before linearized reading' (duration: 1.426167404s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:42.328294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:40.902055Z","time spent":"1.426236093s","remote":"127.0.0.1:53622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T10:45:42.328296Z","caller":"traceutil/trace.go:171","msg":"trace[971814397] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"2.098787896s","start":"2026-02-24T10:45:40.229474Z","end":"2026-02-24T10:45:42.328262Z","steps":["trace[971814397] 'process raft request' (duration: 2.036853431s)","trace[971814397] 'compare' (duration: 60.168493ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:45:42.32836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.606965411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-24T10:45:42.328412Z","caller":"traceutil/trace.go:171","msg":"trace[1302239689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:565; }","duration":"1.60708968s","start":"2026-02-24T10:45:40.72131Z","end":"2026-02-24T10:45:42.3284Z","steps":["trace[1302239689] 'agreement among raft nodes before linearized reading' (duration: 1.606921072s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:42.328451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:40.721289Z","time spent":"1.607154518s","remote":"127.0.0.1:53946","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-02-24T10:45:42.328472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:40.229453Z","time spent":"2.098919104s","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:45:43.218105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.663367ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T10:45:45.164798Z","caller":"traceutil/trace.go:171","msg":"trace[767405790] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"807.246629ms","start":"2026-02-24T10:45:44.357505Z","end":"2026-02-24T10:45:45.164751Z","steps":["trace[767405790] 'process raft request' (duration: 790.015824ms)","trace[767405790] 'compare' (duration: 16.78557ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:45.164816Z","caller":"traceutil/trace.go:171","msg":"trace[1255082562] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"806.850035ms","start":"2026-02-24T10:45:44.357949Z","end":"2026-02-24T10:45:45.164799Z","steps":["trace[1255082562] 'process raft request' (duration: 806.590658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:45.164972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:44.357938Z","time spent":"806.951884ms","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:45:45.164973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:44.357491Z","time spent":"807.392338ms","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:45:45.165082Z","caller":"traceutil/trace.go:171","msg":"trace[370972524] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"807.053953ms","start":"2026-02-24T10:45:44.358019Z","end":"2026-02-24T10:45:45.165073Z","steps":["trace[370972524] 'process raft request' (duration: 806.577438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:45.165122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:44.357998Z","time spent":"807.104542ms","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:45:45.165174Z","caller":"traceutil/trace.go:171","msg":"trace[307251726] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"807.113232ms","start":"2026-02-24T10:45:44.358056Z","end":"2026-02-24T10:45:45.165169Z","steps":["trace[307251726] 'process raft request' (duration: 806.562008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:45.165202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:45:44.358048Z","time spent":"807.137631ms","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:45:49.257951Z","caller":"traceutil/trace.go:171","msg":"trace[1775133337] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"107.850344ms","start":"2026-02-24T10:45:49.150082Z","end":"2026-02-24T10:45:49.257932Z","steps":["trace[1775133337] 'process raft request' (duration: 106.36717ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:49.258042Z","caller":"traceutil/trace.go:171","msg":"trace[439444691] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"106.47301ms","start":"2026-02-24T10:45:49.151545Z","end":"2026-02-24T10:45:49.258018Z","steps":["trace[439444691] 'process raft request' (duration: 106.288ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:52.471362Z","caller":"traceutil/trace.go:171","msg":"trace[1994835678] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"191.515773ms","start":"2026-02-24T10:45:52.279826Z","end":"2026-02-24T10:45:52.471342Z","steps":["trace[1994835678] 'process raft request' (duration: 191.320073ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:52.52411Z","caller":"traceutil/trace.go:171","msg":"trace[29734126] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"231.682428ms","start":"2026-02-24T10:45:52.29241Z","end":"2026-02-24T10:45:52.524093Z","steps":["trace[29734126] 'process raft request' (duration: 231.585718ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:52.583232Z","caller":"traceutil/trace.go:171","msg":"trace[1141429928] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"104.325889ms","start":"2026-02-24T10:45:52.478887Z","end":"2026-02-24T10:45:52.583213Z","steps":["trace[1141429928] 'process raft request' (duration: 100.945317ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:53.353302Z","caller":"traceutil/trace.go:171","msg":"trace[1405749475] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"156.278526ms","start":"2026-02-24T10:45:53.196991Z","end":"2026-02-24T10:45:53.353269Z","steps":["trace[1405749475] 'process raft request' (duration: 156.130676ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:55.142633Z","caller":"traceutil/trace.go:171","msg":"trace[1440929578] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"113.172615ms","start":"2026-02-24T10:45:55.029439Z","end":"2026-02-24T10:45:55.142612Z","steps":["trace[1440929578] 'process raft request' (duration: 113.076545ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:55.571476Z","caller":"traceutil/trace.go:171","msg":"trace[727561996] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:727; }","duration":"158.08886ms","start":"2026-02-24T10:45:55.413367Z","end":"2026-02-24T10:45:55.571456Z","steps":["trace[727561996] 'read index received' (duration: 54.782928ms)","trace[727561996] 'applied index is now lower than readState.Index' (duration: 103.304922ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:45:55.571525Z","caller":"traceutil/trace.go:171","msg":"trace[1852354057] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"166.523506ms","start":"2026-02-24T10:45:55.404967Z","end":"2026-02-24T10:45:55.571491Z","steps":["trace[1852354057] 'process raft request' (duration: 166.357625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:55.571701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.327701ms","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-02-24T10:45:55.571736Z","caller":"traceutil/trace.go:171","msg":"trace[34007316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:701; }","duration":"158.389381ms","start":"2026-02-24T10:45:55.413337Z","end":"2026-02-24T10:45:55.571727Z","steps":["trace[34007316] 'agreement among raft nodes before linearized reading' (duration: 158.249331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:55.571775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.328011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-24T10:45:55.57181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.851109ms","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-02-24T10:45:55.571874Z","caller":"traceutil/trace.go:171","msg":"trace[1467096412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:701; }","duration":"157.939519ms","start":"2026-02-24T10:45:55.413921Z","end":"2026-02-24T10:45:55.57186Z","steps":["trace[1467096412] 'agreement among raft nodes before linearized reading' (duration: 157.729918ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:55.571834Z","caller":"traceutil/trace.go:171","msg":"trace[223768433] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:701; }","duration":"158.427012ms","start":"2026-02-24T10:45:55.413392Z","end":"2026-02-24T10:45:55.571819Z","steps":["trace[223768433] 'agreement among raft nodes before linearized reading' (duration: 158.256671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:45:55.571796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.389832ms","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-02-24T10:45:55.572066Z","caller":"traceutil/trace.go:171","msg":"trace[1251951085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:701; }","duration":"158.665623ms","start":"2026-02-24T10:45:55.413377Z","end":"2026-02-24T10:45:55.572042Z","steps":["trace[1251951085] 'agreement among raft nodes before linearized reading' (duration: 158.253241ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:56.541865Z","caller":"traceutil/trace.go:171","msg":"trace[798272425] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"106.339287ms","start":"2026-02-24T10:45:56.435496Z","end":"2026-02-24T10:45:56.541836Z","steps":["trace[798272425] 'process raft request' (duration: 98.759494ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:56.549527Z","caller":"traceutil/trace.go:171","msg":"trace[608693818] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"111.781364ms","start":"2026-02-24T10:45:56.437723Z","end":"2026-02-24T10:45:56.549505Z","steps":["trace[608693818] 'process raft request' (duration: 111.614213ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:56.549532Z","caller":"traceutil/trace.go:171","msg":"trace[478737330] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"111.191179ms","start":"2026-02-24T10:45:56.438305Z","end":"2026-02-24T10:45:56.549496Z","steps":["trace[478737330] 'process raft request' (duration: 111.148368ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:45:56.54954Z","caller":"traceutil/trace.go:171","msg":"trace[1380498231] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"111.672723ms","start":"2026-02-24T10:45:56.437853Z","end":"2026-02-24T10:45:56.549526Z","steps":["trace[1380498231] 'process raft request' (duration: 111.569622ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:03.642679Z","caller":"traceutil/trace.go:171","msg":"trace[557732171] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"135.632789ms","start":"2026-02-24T10:46:03.507014Z","end":"2026-02-24T10:46:03.642647Z","steps":["trace[557732171] 'process raft request' (duration: 135.444916ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:59.347469Z","caller":"traceutil/trace.go:171","msg":"trace[250870415] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"165.899028ms","start":"2026-02-24T10:46:59.18154Z","end":"2026-02-24T10:46:59.347439Z","steps":["trace[250870415] 'process raft request' (duration: 165.701706ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:59.348851Z","caller":"traceutil/trace.go:171","msg":"trace[352404180] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"159.867682ms","start":"2026-02-24T10:46:59.188968Z","end":"2026-02-24T10:46:59.348835Z","steps":["trace[352404180] 'process raft request' (duration: 159.794107ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:59.601327Z","caller":"traceutil/trace.go:171","msg":"trace[1935101935] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"142.640892ms","start":"2026-02-24T10:46:59.458661Z","end":"2026-02-24T10:46:59.601302Z","steps":["trace[1935101935] 'process raft request' (duration: 142.505734ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:46:59.628153Z","caller":"traceutil/trace.go:171","msg":"trace[1500752921] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"128.842236ms","start":"2026-02-24T10:46:59.499283Z","end":"2026-02-24T10:46:59.628126Z","steps":["trace[1500752921] 'process raft request' (duration: 128.563198ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:06.179648Z","caller":"traceutil/trace.go:171","msg":"trace[73375291] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"152.245411ms","start":"2026-02-24T10:47:06.027375Z","end":"2026-02-24T10:47:06.17962Z","steps":["trace[73375291] 'process raft request' (duration: 123.882517ms)","trace[73375291] 'compare' (duration: 28.202673ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:47:06.180079Z","caller":"traceutil/trace.go:171","msg":"trace[1767653877] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"152.616167ms","start":"2026-02-24T10:47:06.027447Z","end":"2026-02-24T10:47:06.180063Z","steps":["trace[1767653877] 'process raft request' (duration: 152.131293ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:06.180153Z","caller":"traceutil/trace.go:171","msg":"trace[1982331083] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"152.625178ms","start":"2026-02-24T10:47:06.027514Z","end":"2026-02-24T10:47:06.180139Z","steps":["trace[1982331083] 'process raft request' (duration: 152.325357ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:10.16446Z","caller":"traceutil/trace.go:171","msg":"trace[1650441979] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"123.232575ms","start":"2026-02-24T10:47:10.041203Z","end":"2026-02-24T10:47:10.164436Z","steps":["trace[1650441979] 'process raft request' (duration: 71.43175ms)","trace[1650441979] 'compare' (duration: 51.709129ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:47:10.3491Z","caller":"traceutil/trace.go:171","msg":"trace[1783113837] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"130.819955ms","start":"2026-02-24T10:47:10.218241Z","end":"2026-02-24T10:47:10.349061Z","steps":["trace[1783113837] 'process raft request' (duration: 99.540801ms)","trace[1783113837] 'compare' (duration: 30.908538ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:47:11.221497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.328522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:47:11.22158Z","caller":"traceutil/trace.go:171","msg":"trace[820763801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:997; }","duration":"319.44534ms","start":"2026-02-24T10:47:10.902115Z","end":"2026-02-24T10:47:11.221561Z","steps":["trace[820763801] 'range keys from in-memory index tree' (duration: 319.254116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:11.221614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:10.902098Z","time spent":"319.507824ms","remote":"127.0.0.1:53622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:47:11.221673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.415113ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:47:11.221786Z","caller":"traceutil/trace.go:171","msg":"trace[1770698755] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:997; }","duration":"723.535702ms","start":"2026-02-24T10:47:10.498233Z","end":"2026-02-24T10:47:11.221769Z","steps":["trace[1770698755] 'range keys from in-memory index tree' (duration: 723.396913ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:14.359995Z","caller":"traceutil/trace.go:171","msg":"trace[1335331215] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"116.930514ms","start":"2026-02-24T10:47:14.243036Z","end":"2026-02-24T10:47:14.359967Z","steps":["trace[1335331215] 'process raft request' (duration: 107.453804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:24.414393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.033429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:service-account-issuer-discovery\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-02-24T10:47:24.414521Z","caller":"traceutil/trace.go:171","msg":"trace[760631811] range","detail":"{range_begin:/registry/clusterroles/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:1035; }","duration":"168.212633ms","start":"2026-02-24T10:47:24.24628Z","end":"2026-02-24T10:47:24.414493Z","steps":["trace[760631811] 'range keys from in-memory index tree' (duration: 167.876747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:24.414517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.408708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-24T10:47:24.414427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.486322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pv-protection-controller\" ","response":"range_response_count:1 size:771"} {"level":"warn","ts":"2026-02-24T10:47:24.414584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.039109ms","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-02-24T10:47:24.414595Z","caller":"traceutil/trace.go:171","msg":"trace[717251310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1035; }","duration":"142.506705ms","start":"2026-02-24T10:47:24.272067Z","end":"2026-02-24T10:47:24.414574Z","steps":["trace[717251310] 'range keys from in-memory index tree' (duration: 142.274817ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:24.414635Z","caller":"traceutil/trace.go:171","msg":"trace[115215737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1035; }","duration":"149.107754ms","start":"2026-02-24T10:47:24.265509Z","end":"2026-02-24T10:47:24.414617Z","steps":["trace[115215737] 'range keys from in-memory index tree' (duration: 148.893897ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:24.414648Z","caller":"traceutil/trace.go:171","msg":"trace[574990796] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pv-protection-controller; range_end:; response_count:1; response_revision:1035; }","duration":"146.84807ms","start":"2026-02-24T10:47:24.26775Z","end":"2026-02-24T10:47:24.414598Z","steps":["trace[574990796] 'range keys from in-memory index tree' (duration: 146.341681ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:24.706701Z","caller":"traceutil/trace.go:171","msg":"trace[1595376978] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1089; }","duration":"172.598901ms","start":"2026-02-24T10:47:24.534071Z","end":"2026-02-24T10:47:24.70667Z","steps":["trace[1595376978] 'read index received' (duration: 169.106372ms)","trace[1595376978] 'applied index is now lower than readState.Index' (duration: 3.491209ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:47:24.706804Z","caller":"traceutil/trace.go:171","msg":"trace[204779788] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"233.432989ms","start":"2026-02-24T10:47:24.473339Z","end":"2026-02-24T10:47:24.706772Z","steps":["trace[204779788] 'process raft request' (duration: 229.920078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:24.706902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.803187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pvc-protection-controller\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-02-24T10:47:24.706947Z","caller":"traceutil/trace.go:171","msg":"trace[1015053715] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pvc-protection-controller; range_end:; response_count:1; response_revision:1039; }","duration":"172.911795ms","start":"2026-02-24T10:47:24.534021Z","end":"2026-02-24T10:47:24.706933Z","steps":["trace[1015053715] 'agreement among raft nodes before linearized reading' (duration: 172.803657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:24.707109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.756341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:volume-scheduler\" ","response":"range_response_count:1 size:725"} {"level":"info","ts":"2026-02-24T10:47:24.707285Z","caller":"traceutil/trace.go:171","msg":"trace[1013247972] range","detail":"{range_begin:/registry/clusterroles/system:volume-scheduler; range_end:; response_count:1; response_revision:1039; }","duration":"157.982208ms","start":"2026-02-24T10:47:24.549261Z","end":"2026-02-24T10:47:24.707244Z","steps":["trace[1013247972] 'agreement among raft nodes before linearized reading' (duration: 157.650622ms)"],"step_count":1}