{"level":"warn","ts":"2026-02-24T10:47:19.898245Z","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:47:19.89834Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38: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.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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:47:19.898412Z","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:47:19.89843Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-02-24T10:47:19.898465Z","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:47:19.899107Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-02-24T10:47:19.899204Z","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.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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:47:19.903278Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.833428ms"} {"level":"info","ts":"2026-02-24T10:47:19.913203Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-02-24T10:47:19.913272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T10:47:19.913293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-02-24T10:47:19.913312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T10:47:19.913323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-02-24T10:47:19.913367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-02-24T10:47:19.920746Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T10:47:19.922191Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T10:47:19.923216Z","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:47:19.926065Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T10:47:19.926461Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T10:47:19.926645Z","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:47:19.926741Z","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:47:19.926756Z","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:47:19.928545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-02-24T10:47:19.928899Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-02-24T10:47:19.930775Z","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:47:19.930958Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T10:47:19.930981Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T10:47:19.931048Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-02-24T10:47:19.931055Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-02-24T10:47:20.714246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T10:47:20.71481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T10:47:20.71483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-02-24T10:47:20.71484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-02-24T10:47:20.714852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-02-24T10:47:20.71486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-02-24T10:47:20.714868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-02-24T10:47:20.715747Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:47:20.716483Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T10:47:20.7165Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T10:47:20.716717Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T10:47:20.71678Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T10:47:20.71685Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T10:47:20.71882Z","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:47:20.719238Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-02-24T10:47:20.720275Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:47:20.720384Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T10:47:20.720406Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-24T10:47:23.861259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.917387ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-02-24T10:47:24.546261Z","caller":"traceutil/trace.go:171","msg":"trace[1851613286] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"141.786834ms","start":"2026-02-24T10:47:24.404452Z","end":"2026-02-24T10:47:24.546239Z","steps":["trace[1851613286] 'process raft request' (duration: 141.677351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:24.971382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.482558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:47:24.971886Z","caller":"traceutil/trace.go:171","msg":"trace[483265312] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"416.09796ms","start":"2026-02-24T10:47:24.555537Z","end":"2026-02-24T10:47:24.971635Z","steps":["trace[483265312] 'process raft request' (duration: 305.836242ms)","trace[483265312] 'compare' (duration: 109.354075ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:47:24.972194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:24.555517Z","time spent":"416.50686ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3384,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:47:25.154181Z","caller":"traceutil/trace.go:171","msg":"trace[1946710945] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"153.293066ms","start":"2026-02-24T10:47:25.000864Z","end":"2026-02-24T10:47:25.154157Z","steps":["trace[1946710945] 'process raft request' (duration: 153.260135ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:25.154378Z","caller":"traceutil/trace.go:171","msg":"trace[943638316] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"156.839065ms","start":"2026-02-24T10:47:24.997506Z","end":"2026-02-24T10:47:25.154345Z","steps":["trace[943638316] 'process raft request' (duration: 89.514362ms)","trace[943638316] 'compare' (duration: 66.987074ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:47:25.154348Z","caller":"traceutil/trace.go:171","msg":"trace[1159643823] linearizableReadLoop","detail":"{readStateIndex:212; appliedIndex:211; }","duration":"156.011134ms","start":"2026-02-24T10:47:24.998296Z","end":"2026-02-24T10:47:25.154307Z","steps":["trace[1159643823] 'read index received' (duration: 88.704012ms)","trace[1159643823] 'applied index is now lower than readState.Index' (duration: 67.300461ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:47:25.154644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.342782ms","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:47:25.154803Z","caller":"traceutil/trace.go:171","msg":"trace[480759368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:208; }","duration":"156.593969ms","start":"2026-02-24T10:47:24.998198Z","end":"2026-02-24T10:47:25.154792Z","steps":["trace[480759368] 'agreement among raft nodes before linearized reading' (duration: 156.376553ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:47:46.218711Z","caller":"traceutil/trace.go:171","msg":"trace[1494508913] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"961.967271ms","start":"2026-02-24T10:47:45.256701Z","end":"2026-02-24T10:47:46.218668Z","steps":["trace[1494508913] 'process raft request' (duration: 961.738125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:46.218953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:45.256686Z","time spent":"962.143116ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:47:46.25723Z","caller":"traceutil/trace.go:171","msg":"trace[19733342] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:445; }","duration":"510.071653ms","start":"2026-02-24T10:47:45.747116Z","end":"2026-02-24T10:47:46.257187Z","steps":["trace[19733342] 'read index received' (duration: 471.269252ms)","trace[19733342] 'applied index is now lower than readState.Index' (duration: 38.800481ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:47:46.257747Z","caller":"traceutil/trace.go:171","msg":"trace[1161245469] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"998.75728ms","start":"2026-02-24T10:47:45.25895Z","end":"2026-02-24T10:47:46.257707Z","steps":["trace[1161245469] 'process raft request' (duration: 998.031201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:46.257855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.015824ms","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:47:46.257921Z","caller":"traceutil/trace.go:171","msg":"trace[342270073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:427; }","duration":"505.152828ms","start":"2026-02-24T10:47:45.752749Z","end":"2026-02-24T10:47:46.257902Z","steps":["trace[342270073] 'agreement among raft nodes before linearized reading' (duration: 504.967903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:46.257953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:45.752738Z","time spent":"505.207719ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T10:47:46.25795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:45.258934Z","time spent":"998.909483ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:47:46.258177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.059979ms","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:46.258213Z","caller":"traceutil/trace.go:171","msg":"trace[943830176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"511.12717ms","start":"2026-02-24T10:47:45.747072Z","end":"2026-02-24T10:47:46.258199Z","steps":["trace[943830176] 'agreement among raft nodes before linearized reading' (duration: 511.056338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:46.258241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:45.747053Z","time spent":"511.182272ms","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T10:47:46.392257Z","caller":"traceutil/trace.go:171","msg":"trace[1733686110] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"129.666581ms","start":"2026-02-24T10:47:46.262506Z","end":"2026-02-24T10:47:46.392172Z","steps":["trace[1733686110] 'process raft request' (duration: 57.985441ms)","trace[1733686110] 'compare' (duration: 70.802228ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:47:52.74119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018653270633767,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:47:53.241902Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018653270633767,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:47:53.571584Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.798071288s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T10:47:53.572125Z","caller":"traceutil/trace.go:171","msg":"trace[47930211] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"1.798690714s","start":"2026-02-24T10:47:51.773398Z","end":"2026-02-24T10:47:53.572088Z","steps":["trace[47930211] 'process raft request' (duration: 1.798402257s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:53.572318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:51.773376Z","time spent":"1.798837808s","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4257,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:47:53.662314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.421978666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-24T10:47:53.662513Z","caller":"traceutil/trace.go:171","msg":"trace[1655717084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:474; }","duration":"1.422219852s","start":"2026-02-24T10:47:52.240258Z","end":"2026-02-24T10:47:53.662478Z","steps":["trace[1655717084] 'agreement among raft nodes before linearized reading' (duration: 1.421958396s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:53.662556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:52.240229Z","time spent":"1.422318015s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T10:47:53.662531Z","caller":"traceutil/trace.go:171","msg":"trace[896338512] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:493; }","duration":"1.421069522s","start":"2026-02-24T10:47:52.240326Z","end":"2026-02-24T10:47:53.661396Z","steps":["trace[896338512] 'read index received' (duration: 1.331430893s)","trace[896338512] 'applied index is now lower than readState.Index' (duration: 89.636959ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:47:53.662092Z","caller":"traceutil/trace.go:171","msg":"trace[1765280412] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"820.53241ms","start":"2026-02-24T10:47:52.84153Z","end":"2026-02-24T10:47:53.662063Z","steps":["trace[1765280412] 'process raft request' (duration: 812.963346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:53.662775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.376503135s","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:47:53.662822Z","caller":"traceutil/trace.go:171","msg":"trace[685485925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:474; }","duration":"1.376613108s","start":"2026-02-24T10:47:52.286194Z","end":"2026-02-24T10:47:53.662807Z","steps":["trace[685485925] 'agreement among raft nodes before linearized reading' (duration: 1.376509125s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:53.662862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.486589ms","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:53.662899Z","caller":"traceutil/trace.go:171","msg":"trace[378082236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:474; }","duration":"916.574162ms","start":"2026-02-24T10:47:52.746314Z","end":"2026-02-24T10:47:53.662888Z","steps":["trace[378082236] 'agreement among raft nodes before linearized reading' (duration: 916.50632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:53.662949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:52.746294Z","time spent":"916.648353ms","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:47:53.662976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192274752s","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:47:53.663012Z","caller":"traceutil/trace.go:171","msg":"trace[1411325520] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:474; }","duration":"1.192341882s","start":"2026-02-24T10:47:52.470661Z","end":"2026-02-24T10:47:53.663003Z","steps":["trace[1411325520] 'agreement among raft nodes before linearized reading' (duration: 1.192224319s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:53.663039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:52.470642Z","time spent":"1.192390714s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T10:47:53.662997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:52.841499Z","time spent":"821.267478ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:47:53.662865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:52.286168Z","time spent":"1.37668887s","remote":"127.0.0.1:57372","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:47:53.663275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.241871067s","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:47:53.663303Z","caller":"traceutil/trace.go:171","msg":"trace[395192652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:474; }","duration":"1.241915448s","start":"2026-02-24T10:47:52.421379Z","end":"2026-02-24T10:47:53.663295Z","steps":["trace[395192652] 'agreement among raft nodes before linearized reading' (duration: 1.241816155s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:47:53.663329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:47:52.421322Z","time spent":"1.242000919s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T10:48:01.24746Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018653270633821,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:48:01.655445Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.363093962s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T10:48:01.655872Z","caller":"traceutil/trace.go:171","msg":"trace[599555915] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"908.660809ms","start":"2026-02-24T10:48:00.747185Z","end":"2026-02-24T10:48:01.655846Z","steps":["trace[599555915] 'read index received' (duration: 908.411933ms)","trace[599555915] 'applied index is now lower than readState.Index' (duration: 247.746µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:48:01.656083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.792572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:48:01.656143Z","caller":"traceutil/trace.go:171","msg":"trace[1937509603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"908.980487ms","start":"2026-02-24T10:48:00.747148Z","end":"2026-02-24T10:48:01.656129Z","steps":["trace[1937509603] 'agreement among raft nodes before linearized reading' (duration: 908.791353ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:01.656144Z","caller":"traceutil/trace.go:171","msg":"trace[102323276] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.363946754s","start":"2026-02-24T10:48:00.292176Z","end":"2026-02-24T10:48:01.656123Z","steps":["trace[102323276] 'process raft request' (duration: 1.363480372s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:01.656193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:00.747131Z","time spent":"909.048419ms","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:48:01.656371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:00.292136Z","time spent":"1.364092358s","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:48:02.332148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.933694ms","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:48:02.332234Z","caller":"traceutil/trace.go:171","msg":"trace[426190236] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:496; }","duration":"585.060777ms","start":"2026-02-24T10:48:01.747153Z","end":"2026-02-24T10:48:02.332213Z","steps":["trace[426190236] 'range keys from in-memory index tree' (duration: 584.759139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:02.332292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:01.747144Z","time spent":"585.125348ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T10:48:02.332557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.624242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:48:02.332599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.479958ms","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:48:02.33263Z","caller":"traceutil/trace.go:171","msg":"trace[320098246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:496; }","duration":"585.52597ms","start":"2026-02-24T10:48:01.747094Z","end":"2026-02-24T10:48:02.33262Z","steps":["trace[320098246] 'range keys from in-memory index tree' (duration: 585.407727ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:02.332636Z","caller":"traceutil/trace.go:171","msg":"trace[791078511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"585.731955ms","start":"2026-02-24T10:48:01.746886Z","end":"2026-02-24T10:48:02.332618Z","steps":["trace[791078511] 'range keys from in-memory index tree' (duration: 585.56422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:02.332661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:01.747088Z","time spent":"585.566041ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T10:48:02.332678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:01.746875Z","time spent":"585.792766ms","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:48:02.332781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.085583ms","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:48:02.33283Z","caller":"traceutil/trace.go:171","msg":"trace[872120366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"593.170415ms","start":"2026-02-24T10:48:01.739646Z","end":"2026-02-24T10:48:02.332816Z","steps":["trace[872120366] 'range keys from in-memory index tree' (duration: 592.96676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:02.332871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:01.73963Z","time spent":"593.233416ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T10:48:02.333125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.260948ms","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:48:02.333216Z","caller":"traceutil/trace.go:171","msg":"trace[1610545630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"586.383992ms","start":"2026-02-24T10:48:01.746811Z","end":"2026-02-24T10:48:02.333195Z","steps":["trace[1610545630] 'range keys from in-memory index tree' (duration: 586.088013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:02.333271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:01.746794Z","time spent":"586.463734ms","remote":"127.0.0.1:57372","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:48:02.79361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.65164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:48:02.794073Z","caller":"traceutil/trace.go:171","msg":"trace[1639518150] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"447.886015ms","start":"2026-02-24T10:48:02.346155Z","end":"2026-02-24T10:48:02.794041Z","steps":["trace[1639518150] 'process raft request' (duration: 447.607098ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:02.794083Z","caller":"traceutil/trace.go:171","msg":"trace[721646689] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"448.000779ms","start":"2026-02-24T10:48:02.34603Z","end":"2026-02-24T10:48:02.794031Z","steps":["trace[721646689] 'process raft request' (duration: 346.829586ms)","trace[721646689] 'compare' (duration: 100.443914ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:48:02.794166Z","caller":"traceutil/trace.go:171","msg":"trace[1732829081] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"448.018819ms","start":"2026-02-24T10:48:02.346112Z","end":"2026-02-24T10:48:02.79413Z","steps":["trace[1732829081] 'process raft request' (duration: 447.595589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:02.794284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:02.346106Z","time spent":"448.128392ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:48:02.79428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:02.34615Z","time spent":"447.997919ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:48:02.794297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:02.345999Z","time spent":"448.182183ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:48:04.570737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353873017s","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:48:04.570854Z","caller":"traceutil/trace.go:171","msg":"trace[180419374] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:500; }","duration":"1.354002641s","start":"2026-02-24T10:48:03.216835Z","end":"2026-02-24T10:48:04.570838Z","steps":["trace[180419374] 'range keys from in-memory index tree' (duration: 1.353858057s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:04.571028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.348400098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:48:04.571052Z","caller":"traceutil/trace.go:171","msg":"trace[642641984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"1.34846207s","start":"2026-02-24T10:48:03.222582Z","end":"2026-02-24T10:48:04.571045Z","steps":["trace[642641984] 'range keys from in-memory index tree' (duration: 1.348333746s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:04.571078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:03.222559Z","time spent":"1.348512301s","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:48:04.571524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.172443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:48:04.571514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.958881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T10:48:04.571555Z","caller":"traceutil/trace.go:171","msg":"trace[1773118413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"824.205203ms","start":"2026-02-24T10:48:03.747342Z","end":"2026-02-24T10:48:04.571547Z","steps":["trace[1773118413] 'range keys from in-memory index tree' (duration: 824.109641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:04.571578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:03.747273Z","time spent":"824.300416ms","remote":"127.0.0.1:57108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T10:48:04.57159Z","caller":"traceutil/trace.go:171","msg":"trace[813765607] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:500; }","duration":"953.084064ms","start":"2026-02-24T10:48:03.618485Z","end":"2026-02-24T10:48:04.571569Z","steps":["trace[813765607] 'range keys from in-memory index tree' (duration: 952.788406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:04.57163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:03.618465Z","time spent":"953.155895ms","remote":"127.0.0.1:57142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"info","ts":"2026-02-24T10:48:04.571695Z","caller":"traceutil/trace.go:171","msg":"trace[1724437703] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"149.493737ms","start":"2026-02-24T10:48:04.422179Z","end":"2026-02-24T10:48:04.571673Z","steps":["trace[1724437703] 'read index received' (duration: 149.308232ms)","trace[1724437703] 'applied index is now lower than readState.Index' (duration: 184.485µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:48:04.571828Z","caller":"traceutil/trace.go:171","msg":"trace[371117492] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"674.03995ms","start":"2026-02-24T10:48:03.897767Z","end":"2026-02-24T10:48:04.571807Z","steps":["trace[371117492] 'process raft request' (duration: 673.780404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:04.571886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.702731ms","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:48:04.57194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:03.897741Z","time spent":"674.132703ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:48:04.571948Z","caller":"traceutil/trace.go:171","msg":"trace[2073955364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:501; }","duration":"149.777163ms","start":"2026-02-24T10:48:04.422157Z","end":"2026-02-24T10:48:04.571934Z","steps":["trace[2073955364] 'agreement among raft nodes before linearized reading' (duration: 149.604819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:04.828691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.345998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T10:48:04.8288Z","caller":"traceutil/trace.go:171","msg":"trace[107945954] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:503; }","duration":"157.513652ms","start":"2026-02-24T10:48:04.671268Z","end":"2026-02-24T10:48:04.828782Z","steps":["trace[107945954] 'range keys from in-memory index tree' (duration: 157.245026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:06.247294Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018653270633851,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:48:06.385724Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.552597751s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T10:48:06.386126Z","caller":"traceutil/trace.go:171","msg":"trace[1886034005] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.553089484s","start":"2026-02-24T10:48:04.833018Z","end":"2026-02-24T10:48:06.386108Z","steps":["trace[1886034005] 'process raft request' (duration: 1.55295901s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:06.386251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:04.832999Z","time spent":"1.553187697s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:48:06.43623Z","caller":"traceutil/trace.go:171","msg":"trace[1618726838] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:527; }","duration":"688.98579ms","start":"2026-02-24T10:48:05.74722Z","end":"2026-02-24T10:48:06.436205Z","steps":["trace[1618726838] 'read index received' (duration: 638.796409ms)","trace[1618726838] 'applied index is now lower than readState.Index' (duration: 50.187921ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:48:06.436337Z","caller":"traceutil/trace.go:171","msg":"trace[769589868] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.603263215s","start":"2026-02-24T10:48:04.833052Z","end":"2026-02-24T10:48:06.436315Z","steps":["trace[769589868] 'process raft request' (duration: 1.602984387s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:06.436371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.131155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:48:06.436429Z","caller":"traceutil/trace.go:171","msg":"trace[1626358104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"689.252109ms","start":"2026-02-24T10:48:05.747166Z","end":"2026-02-24T10:48:06.436418Z","steps":["trace[1626358104] 'agreement among raft nodes before linearized reading' (duration: 689.113345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:06.436443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:04.833034Z","time spent":"1.603351028s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:48:06.436465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:05.747146Z","time spent":"689.31109ms","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T10:48:06.436529Z","caller":"traceutil/trace.go:171","msg":"trace[1933583934] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"1.602430133s","start":"2026-02-24T10:48:04.833967Z","end":"2026-02-24T10:48:06.436397Z","steps":["trace[1933583934] 'process raft request' (duration: 1.602199396s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:06.436864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:04.833953Z","time spent":"1.602768082s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:48:07.151068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.266743ms","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:48:07.151146Z","caller":"traceutil/trace.go:171","msg":"trace[92842199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:506; }","duration":"518.400366ms","start":"2026-02-24T10:48:06.632728Z","end":"2026-02-24T10:48:07.151128Z","steps":["trace[92842199] 'range keys from in-memory index tree' (duration: 518.049047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:07.151235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:06.632705Z","time spent":"518.489277ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T10:48:07.166107Z","caller":"traceutil/trace.go:171","msg":"trace[70933611] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"107.915196ms","start":"2026-02-24T10:48:07.058173Z","end":"2026-02-24T10:48:07.166088Z","steps":["trace[70933611] 'process raft request' (duration: 107.790282ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:08.876797Z","caller":"traceutil/trace.go:171","msg":"trace[1963577199] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:541; }","duration":"129.728453ms","start":"2026-02-24T10:48:08.747039Z","end":"2026-02-24T10:48:08.876768Z","steps":["trace[1963577199] 'read index received' (duration: 115.559021ms)","trace[1963577199] 'applied index is now lower than readState.Index' (duration: 14.168522ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:48:08.876793Z","caller":"traceutil/trace.go:171","msg":"trace[1033196342] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"431.335653ms","start":"2026-02-24T10:48:08.445408Z","end":"2026-02-24T10:48:08.876744Z","steps":["trace[1033196342] 'process raft request' (duration: 417.179832ms)","trace[1033196342] 'compare' (duration: 13.799502ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:48:08.876911Z","caller":"traceutil/trace.go:171","msg":"trace[1451694424] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"429.782974ms","start":"2026-02-24T10:48:08.447113Z","end":"2026-02-24T10:48:08.876896Z","steps":["trace[1451694424] 'process raft request' (duration: 429.549178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:08.876971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.860227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:48:08.877019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:08.447077Z","time spent":"429.867786ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:48:08.87704Z","caller":"traceutil/trace.go:171","msg":"trace[619770712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"130.04128ms","start":"2026-02-24T10:48:08.746987Z","end":"2026-02-24T10:48:08.877029Z","steps":["trace[619770712] 'agreement among raft nodes before linearized reading' (duration: 129.876646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:08.877009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:08.445391Z","time spent":"431.497447ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:48:11.592828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.688636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-24T10:48:11.592931Z","caller":"traceutil/trace.go:171","msg":"trace[1883797967] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:574; }","duration":"119.854941ms","start":"2026-02-24T10:48:11.47305Z","end":"2026-02-24T10:48:11.592905Z","steps":["trace[1883797967] 'range keys from in-memory index tree' (duration: 119.409979ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:13.788008Z","caller":"traceutil/trace.go:171","msg":"trace[1780774667] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"136.856053ms","start":"2026-02-24T10:48:13.651126Z","end":"2026-02-24T10:48:13.787982Z","steps":["trace[1780774667] 'process raft request' (duration: 107.249847ms)","trace[1780774667] 'compare' (duration: 28.763645ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:48:17.034346Z","caller":"traceutil/trace.go:171","msg":"trace[138671521] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"116.693279ms","start":"2026-02-24T10:48:16.917628Z","end":"2026-02-24T10:48:17.034321Z","steps":["trace[138671521] 'process raft request' (duration: 114.425891ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:17.684768Z","caller":"traceutil/trace.go:171","msg":"trace[1052106390] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"410.203884ms","start":"2026-02-24T10:48:17.274548Z","end":"2026-02-24T10:48:17.684752Z","steps":["trace[1052106390] 'read index received' (duration: 410.133222ms)","trace[1052106390] 'applied index is now lower than readState.Index' (duration: 69.842µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:48:17.684856Z","caller":"traceutil/trace.go:171","msg":"trace[1092186698] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"660.477284ms","start":"2026-02-24T10:48:17.024355Z","end":"2026-02-24T10:48:17.684832Z","steps":["trace[1092186698] 'process raft request' (duration: 660.291089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:17.684957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.394308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-02-24T10:48:17.684973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:17.024328Z","time spent":"660.592976ms","remote":"127.0.0.1:51934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1236,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T10:48:17.684984Z","caller":"traceutil/trace.go:171","msg":"trace[658678688] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:624; }","duration":"410.47726ms","start":"2026-02-24T10:48:17.274498Z","end":"2026-02-24T10:48:17.684975Z","steps":["trace[658678688] 'agreement among raft nodes before linearized reading' (duration: 410.339037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:17.685009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:17.274478Z","time spent":"410.525502ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T10:48:17.693562Z","caller":"traceutil/trace.go:171","msg":"trace[1114586415] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"185.796944ms","start":"2026-02-24T10:48:17.507753Z","end":"2026-02-24T10:48:17.69355Z","steps":["trace[1114586415] 'process raft request' (duration: 185.363163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:17.694213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.005174ms","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:48:17.695013Z","caller":"traceutil/trace.go:171","msg":"trace[823977349] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"411.456725ms","start":"2026-02-24T10:48:17.28303Z","end":"2026-02-24T10:48:17.694487Z","steps":["trace[823977349] 'agreement among raft nodes before linearized reading' (duration: 410.326347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:17.695153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:17.283002Z","time spent":"412.128623ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T10:48:17.836297Z","caller":"traceutil/trace.go:171","msg":"trace[176914449] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"142.377395ms","start":"2026-02-24T10:48:17.693884Z","end":"2026-02-24T10:48:17.836261Z","steps":["trace[176914449] 'process raft request' (duration: 142.043747ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:17.836309Z","caller":"traceutil/trace.go:171","msg":"trace[189915267] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"141.261888ms","start":"2026-02-24T10:48:17.695013Z","end":"2026-02-24T10:48:17.836275Z","steps":["trace[189915267] 'process raft request' (duration: 141.149795ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:17.844301Z","caller":"traceutil/trace.go:171","msg":"trace[1825019365] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"143.473603ms","start":"2026-02-24T10:48:17.700808Z","end":"2026-02-24T10:48:17.844281Z","steps":["trace[1825019365] 'process raft request' (duration: 143.427562ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:17.844324Z","caller":"traceutil/trace.go:171","msg":"trace[2036637823] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"145.935776ms","start":"2026-02-24T10:48:17.698372Z","end":"2026-02-24T10:48:17.844308Z","steps":["trace[2036637823] 'process raft request' (duration: 145.783912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:24.151408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.750775ms","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:48:24.151533Z","caller":"traceutil/trace.go:171","msg":"trace[136002529] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:691; }","duration":"934.905829ms","start":"2026-02-24T10:48:23.216608Z","end":"2026-02-24T10:48:24.151514Z","steps":["trace[136002529] 'range keys from in-memory index tree' (duration: 934.732495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:24.151763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.693806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:48:24.151856Z","caller":"traceutil/trace.go:171","msg":"trace[1778805103] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:720; }","duration":"532.6734ms","start":"2026-02-24T10:48:23.619169Z","end":"2026-02-24T10:48:24.151843Z","steps":["trace[1778805103] 'read index received' (duration: 421.82879ms)","trace[1778805103] 'applied index is now lower than readState.Index' (duration: 110.84352ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:48:24.151896Z","caller":"traceutil/trace.go:171","msg":"trace[578689] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"1.090137753s","start":"2026-02-24T10:48:23.061745Z","end":"2026-02-24T10:48:24.151882Z","steps":["trace[578689] 'process raft request' (duration: 979.238582ms)","trace[578689] 'compare' (duration: 110.283075ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:48:24.151986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.821473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T10:48:24.152015Z","caller":"traceutil/trace.go:171","msg":"trace[12159970] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:692; }","duration":"532.926786ms","start":"2026-02-24T10:48:23.61908Z","end":"2026-02-24T10:48:24.152007Z","steps":["trace[12159970] 'agreement among raft nodes before linearized reading' (duration: 532.817003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:24.152005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:23.06172Z","time spent":"1.090224486s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:48:24.152039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:23.619031Z","time spent":"533.002919ms","remote":"127.0.0.1:57142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"warn","ts":"2026-02-24T10:48:24.652767Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018653270634198,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:48:25.153377Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018653270634198,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:48:25.176876Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.135914843s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T10:48:25.177174Z","caller":"traceutil/trace.go:171","msg":"trace[1226641590] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"1.285079821s","start":"2026-02-24T10:48:23.892076Z","end":"2026-02-24T10:48:25.177156Z","steps":["trace[1226641590] 'process raft request' (duration: 1.284958818s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:25.17727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:23.892057Z","time spent":"1.285150152s","remote":"127.0.0.1:57192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T10:48:25.212029Z","caller":"traceutil/trace.go:171","msg":"trace[1574384971] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:721; }","duration":"1.060136958s","start":"2026-02-24T10:48:24.151876Z","end":"2026-02-24T10:48:25.212013Z","steps":["trace[1574384971] 'read index received' (duration: 1.025177915s)","trace[1574384971] 'applied index is now lower than readState.Index' (duration: 34.956173ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:48:25.212091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:24.15315Z","time spent":"1.058937007s","remote":"127.0.0.1:57142","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-24T10:48:25.212119Z","caller":"traceutil/trace.go:171","msg":"trace[1020837131] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"224.314137ms","start":"2026-02-24T10:48:24.987778Z","end":"2026-02-24T10:48:25.212092Z","steps":["trace[1020837131] 'process raft request' (duration: 224.166273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:25.212216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347580327s","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:48:25.212252Z","caller":"traceutil/trace.go:171","msg":"trace[1814737460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:694; }","duration":"1.347663219s","start":"2026-02-24T10:48:23.864578Z","end":"2026-02-24T10:48:25.212241Z","steps":["trace[1814737460] 'agreement among raft nodes before linearized reading' (duration: 1.347528335s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:25.212282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:23.864553Z","time spent":"1.34772285s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T10:48:25.212307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.141759ms","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:48:25.212347Z","caller":"traceutil/trace.go:171","msg":"trace[2066535642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"189.222531ms","start":"2026-02-24T10:48:25.023113Z","end":"2026-02-24T10:48:25.212335Z","steps":["trace[2066535642] 'agreement among raft nodes before linearized reading' (duration: 189.113028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:25.212377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.057808ms","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:48:25.212409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.573977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:48:25.212444Z","caller":"traceutil/trace.go:171","msg":"trace[1249836257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"690.177371ms","start":"2026-02-24T10:48:24.522247Z","end":"2026-02-24T10:48:25.212425Z","steps":["trace[1249836257] 'agreement among raft nodes before linearized reading' (duration: 689.964766ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:25.212451Z","caller":"traceutil/trace.go:171","msg":"trace[665826264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"465.642749ms","start":"2026-02-24T10:48:24.746795Z","end":"2026-02-24T10:48:25.212438Z","steps":["trace[665826264] 'agreement among raft nodes before linearized reading' (duration: 465.565537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:25.212471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49147718s","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:48:25.212485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:24.746777Z","time spent":"465.70174ms","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:48:25.212402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.184533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:48:25.21249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:24.52223Z","time spent":"690.250983ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T10:48:25.212496Z","caller":"traceutil/trace.go:171","msg":"trace[1543817675] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:694; }","duration":"1.491545002s","start":"2026-02-24T10:48:23.720944Z","end":"2026-02-24T10:48:25.212489Z","steps":["trace[1543817675] 'agreement among raft nodes before linearized reading' (duration: 1.49147248s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:25.212517Z","caller":"traceutil/trace.go:171","msg":"trace[378967135] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:694; }","duration":"345.342907ms","start":"2026-02-24T10:48:24.867166Z","end":"2026-02-24T10:48:25.212509Z","steps":["trace[378967135] 'agreement among raft nodes before linearized reading' (duration: 345.190923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:25.212537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:24.867153Z","time spent":"345.377988ms","remote":"127.0.0.1:57208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-02-24T10:48:25.212551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:23.720925Z","time spent":"1.491594353s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T10:48:27.728617Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018653270634219,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T10:48:27.733537Z","caller":"traceutil/trace.go:171","msg":"trace[789396530] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"507.858926ms","start":"2026-02-24T10:48:27.22566Z","end":"2026-02-24T10:48:27.733519Z","steps":["trace[789396530] 'process raft request' (duration: 507.757363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:27.733647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:27.225647Z","time spent":"507.927558ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:48:28.502745Z","caller":"traceutil/trace.go:171","msg":"trace[1050063016] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"1.275082616s","start":"2026-02-24T10:48:27.227634Z","end":"2026-02-24T10:48:28.502716Z","steps":["trace[1050063016] 'process raft request' (duration: 1.274892901s)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:28.502755Z","caller":"traceutil/trace.go:171","msg":"trace[707885071] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"1.274836289s","start":"2026-02-24T10:48:27.227896Z","end":"2026-02-24T10:48:28.502733Z","steps":["trace[707885071] 'process raft request' (duration: 1.274750477s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:28.502898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:27.227607Z","time spent":"1.2752103s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:48:28.502916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:27.227885Z","time spent":"1.274942513s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:48:28.502923Z","caller":"traceutil/trace.go:171","msg":"trace[1216121152] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:730; }","duration":"1.275394895s","start":"2026-02-24T10:48:27.227508Z","end":"2026-02-24T10:48:28.502903Z","steps":["trace[1216121152] 'read index received' (duration: 505.980189ms)","trace[1216121152] 'applied index is now lower than readState.Index' (duration: 769.413156ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:48:28.503097Z","caller":"traceutil/trace.go:171","msg":"trace[981094224] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"882.180864ms","start":"2026-02-24T10:48:27.620907Z","end":"2026-02-24T10:48:28.503087Z","steps":["trace[981094224] 'process raft request' (duration: 881.781473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:28.503142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:27.620887Z","time spent":"882.233216ms","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4727,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:48:28.503149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.27560769s","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:48:28.503192Z","caller":"traceutil/trace.go:171","msg":"trace[1632903758] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:704; }","duration":"1.275688022s","start":"2026-02-24T10:48:27.227493Z","end":"2026-02-24T10:48:28.503181Z","steps":["trace[1632903758] 'agreement among raft nodes before linearized reading' (duration: 1.275463046s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:28.503326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:27.227486Z","time spent":"1.275830445s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T10:48:28.578408Z","caller":"traceutil/trace.go:171","msg":"trace[990071797] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"816.070387ms","start":"2026-02-24T10:48:27.762306Z","end":"2026-02-24T10:48:28.578376Z","steps":["trace[990071797] 'process raft request' (duration: 806.538494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:28.578547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174536149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T10:48:28.578582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:27.762288Z","time spent":"816.199811ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:48:28.57863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.136956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-24T10:48:28.578686Z","caller":"traceutil/trace.go:171","msg":"trace[884628690] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:706; }","duration":"416.230848ms","start":"2026-02-24T10:48:28.162441Z","end":"2026-02-24T10:48:28.578672Z","steps":["trace[884628690] 'agreement among raft nodes before linearized reading' (duration: 416.077955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:28.578714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:28.162422Z","time spent":"416.286919ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T10:48:28.578713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.062452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:48:28.578751Z","caller":"traceutil/trace.go:171","msg":"trace[1206459113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:706; }","duration":"835.125714ms","start":"2026-02-24T10:48:27.743614Z","end":"2026-02-24T10:48:28.578739Z","steps":["trace[1206459113] 'agreement among raft nodes before linearized reading' (duration: 835.056632ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:28.57864Z","caller":"traceutil/trace.go:171","msg":"trace[972231378] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:706; }","duration":"1.174683963s","start":"2026-02-24T10:48:27.403934Z","end":"2026-02-24T10:48:28.578618Z","steps":["trace[972231378] 'agreement among raft nodes before linearized reading' (duration: 1.174513869s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:28.578762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.8913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T10:48:28.578796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:27.403916Z","time spent":"1.174871107s","remote":"127.0.0.1:57516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-02-24T10:48:28.578791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:27.743595Z","time spent":"835.187116ms","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T10:48:28.578806Z","caller":"traceutil/trace.go:171","msg":"trace[1556013337] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:706; }","duration":"126.986293ms","start":"2026-02-24T10:48:28.451812Z","end":"2026-02-24T10:48:28.578798Z","steps":["trace[1556013337] 'agreement among raft nodes before linearized reading' (duration: 126.86853ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:48.815835Z","caller":"traceutil/trace.go:171","msg":"trace[1618307825] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"191.898016ms","start":"2026-02-24T10:48:48.623903Z","end":"2026-02-24T10:48:48.815801Z","steps":["trace[1618307825] 'process raft request' (duration: 191.709402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:49.280846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.740716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:48:49.28094Z","caller":"traceutil/trace.go:171","msg":"trace[1994676173] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:798; }","duration":"600.22649ms","start":"2026-02-24T10:48:48.680697Z","end":"2026-02-24T10:48:49.280924Z","steps":["trace[1994676173] 'read index received' (duration: 134.949726ms)","trace[1994676173] 'applied index is now lower than readState.Index' (duration: 465.275724ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:48:49.281223Z","caller":"traceutil/trace.go:171","msg":"trace[638177331] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"655.010717ms","start":"2026-02-24T10:48:48.626171Z","end":"2026-02-24T10:48:49.281182Z","steps":["trace[638177331] 'process raft request' (duration: 254.85316ms)","trace[638177331] 'compare' (duration: 399.649334ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:48:49.281324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.6032ms","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:48:49.281418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:48.626152Z","time spent":"655.1585ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:48:49.281442Z","caller":"traceutil/trace.go:171","msg":"trace[104094425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:765; }","duration":"600.756784ms","start":"2026-02-24T10:48:48.680657Z","end":"2026-02-24T10:48:49.281414Z","steps":["trace[104094425] 'agreement among raft nodes before linearized reading' (duration: 600.416545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:49.2815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:48.680643Z","time spent":"600.844796ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T10:48:49.282824Z","caller":"traceutil/trace.go:171","msg":"trace[2104987894] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"402.607398ms","start":"2026-02-24T10:48:48.880202Z","end":"2026-02-24T10:48:49.28281Z","steps":["trace[2104987894] 'process raft request' (duration: 402.482595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:49.28292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:48.880176Z","time spent":"402.7047ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:48:50.214382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.832827ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-24T10:48:50.214701Z","caller":"traceutil/trace.go:171","msg":"trace[1169167706] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"927.584116ms","start":"2026-02-24T10:48:49.287054Z","end":"2026-02-24T10:48:50.214638Z","steps":["trace[1169167706] 'process raft request' (duration: 927.451132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:50.214817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:49.287025Z","time spent":"927.723699ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:48:50.214627Z","caller":"traceutil/trace.go:171","msg":"trace[1346516411] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"933.631219ms","start":"2026-02-24T10:48:49.280964Z","end":"2026-02-24T10:48:50.214595Z","steps":["trace[1346516411] 'read index received' (duration: 1.940509ms)","trace[1346516411] 'applied index is now lower than readState.Index' (duration: 931.688089ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:48:50.214971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.467768686s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:48:50.215009Z","caller":"traceutil/trace.go:171","msg":"trace[954398745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"1.467825347s","start":"2026-02-24T10:48:48.747171Z","end":"2026-02-24T10:48:50.214996Z","steps":["trace[954398745] 'agreement among raft nodes before linearized reading' (duration: 1.467771246s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:50.215036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:48.747159Z","time spent":"1.467871818s","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:48:50.21542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.49281623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-24T10:48:50.215449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.031226ms","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:48:50.215481Z","caller":"traceutil/trace.go:171","msg":"trace[1502840014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:767; }","duration":"1.492924822s","start":"2026-02-24T10:48:48.722542Z","end":"2026-02-24T10:48:50.215467Z","steps":["trace[1502840014] 'agreement among raft nodes before linearized reading' (duration: 1.492702787s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:50.21557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:48.722528Z","time spent":"1.493029715s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T10:48:50.215489Z","caller":"traceutil/trace.go:171","msg":"trace[1772676296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:767; }","duration":"357.112597ms","start":"2026-02-24T10:48:49.858363Z","end":"2026-02-24T10:48:50.215476Z","steps":["trace[1772676296] 'agreement among raft nodes before linearized reading' (duration: 356.965224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:50.215772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:49.858344Z","time spent":"357.373014ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T10:48:50.484626Z","caller":"traceutil/trace.go:171","msg":"trace[1705501179] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"263.15514ms","start":"2026-02-24T10:48:50.221446Z","end":"2026-02-24T10:48:50.484601Z","steps":["trace[1705501179] 'process raft request' (duration: 262.300769ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:50.515445Z","caller":"traceutil/trace.go:171","msg":"trace[841960702] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:803; }","duration":"289.457045ms","start":"2026-02-24T10:48:50.225963Z","end":"2026-02-24T10:48:50.51542Z","steps":["trace[841960702] 'read index received' (duration: 257.799104ms)","trace[841960702] 'applied index is now lower than readState.Index' (duration: 31.656931ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:48:50.515595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.599578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:48:50.515646Z","caller":"traceutil/trace.go:171","msg":"trace[1693302656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"289.67866ms","start":"2026-02-24T10:48:50.225944Z","end":"2026-02-24T10:48:50.515623Z","steps":["trace[1693302656] 'agreement among raft nodes before linearized reading' (duration: 289.588158ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:50.515739Z","caller":"traceutil/trace.go:171","msg":"trace[1852266970] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"293.305602ms","start":"2026-02-24T10:48:50.222397Z","end":"2026-02-24T10:48:50.515703Z","steps":["trace[1852266970] 'process raft request' (duration: 292.875821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:50.975423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.048238ms","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:48:50.975494Z","caller":"traceutil/trace.go:171","msg":"trace[2046755219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:769; }","duration":"154.180993ms","start":"2026-02-24T10:48:50.821297Z","end":"2026-02-24T10:48:50.975478Z","steps":["trace[2046755219] 'range keys from in-memory index tree' (duration: 153.849272ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:51.440077Z","caller":"traceutil/trace.go:171","msg":"trace[1532851715] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"135.091259ms","start":"2026-02-24T10:48:51.304958Z","end":"2026-02-24T10:48:51.44005Z","steps":["trace[1532851715] 'process raft request' (duration: 134.896394ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:52.392935Z","caller":"traceutil/trace.go:171","msg":"trace[1779630453] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"167.016597ms","start":"2026-02-24T10:48:52.225898Z","end":"2026-02-24T10:48:52.392915Z","steps":["trace[1779630453] 'process raft request' (duration: 166.879903ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:52.763806Z","caller":"traceutil/trace.go:171","msg":"trace[1372820158] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"175.45295ms","start":"2026-02-24T10:48:52.588298Z","end":"2026-02-24T10:48:52.763751Z","steps":["trace[1372820158] 'process raft request' (duration: 175.173853ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:53.413377Z","caller":"traceutil/trace.go:171","msg":"trace[247589792] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:808; }","duration":"665.239376ms","start":"2026-02-24T10:48:52.748113Z","end":"2026-02-24T10:48:53.413353Z","steps":["trace[247589792] 'read index received' (duration: 15.322039ms)","trace[247589792] 'applied index is now lower than readState.Index' (duration: 649.916407ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:48:53.413454Z","caller":"traceutil/trace.go:171","msg":"trace[1527432537] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"824.836305ms","start":"2026-02-24T10:48:52.588566Z","end":"2026-02-24T10:48:53.413402Z","steps":["trace[1527432537] 'process raft request' (duration: 824.592529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:53.413664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.536783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:48:53.413739Z","caller":"traceutil/trace.go:171","msg":"trace[380599082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:774; }","duration":"665.695128ms","start":"2026-02-24T10:48:52.748031Z","end":"2026-02-24T10:48:53.413726Z","steps":["trace[380599082] 'agreement among raft nodes before linearized reading' (duration: 665.572014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:53.413767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:52.588547Z","time spent":"825.02484ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:48:53.413781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.910186ms","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:48:53.413889Z","caller":"traceutil/trace.go:171","msg":"trace[821665620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:774; }","duration":"418.07071ms","start":"2026-02-24T10:48:52.995797Z","end":"2026-02-24T10:48:53.413868Z","steps":["trace[821665620] 'agreement among raft nodes before linearized reading' (duration: 417.836674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:53.413938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:52.99577Z","time spent":"418.158233ms","remote":"127.0.0.1:57372","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:48:53.413945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:52.747999Z","time spent":"665.880772ms","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:48:53.903363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.336154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:48:53.903757Z","caller":"traceutil/trace.go:171","msg":"trace[2057820299] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"459.415157ms","start":"2026-02-24T10:48:53.444323Z","end":"2026-02-24T10:48:53.903738Z","steps":["trace[2057820299] 'read index received' (duration: 33.629101ms)","trace[2057820299] 'applied index is now lower than readState.Index' (duration: 425.783816ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:48:53.904013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.662913ms","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":"info","ts":"2026-02-24T10:48:53.904104Z","caller":"traceutil/trace.go:171","msg":"trace[1321147006] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"484.528992ms","start":"2026-02-24T10:48:53.419214Z","end":"2026-02-24T10:48:53.903743Z","steps":["trace[1321147006] 'process raft request' (duration: 58.720756ms)","trace[1321147006] 'compare' (duration: 425.231452ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:48:53.904118Z","caller":"traceutil/trace.go:171","msg":"trace[360017249] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:775; }","duration":"459.818787ms","start":"2026-02-24T10:48:53.444275Z","end":"2026-02-24T10:48:53.904094Z","steps":["trace[360017249] 'agreement among raft nodes before linearized reading' (duration: 459.54619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:53.904172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:53.444255Z","time spent":"459.907ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T10:48:53.9042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:53.419197Z","time spent":"484.959914ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:48:53.904059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.428028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:48:53.904281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.322703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T10:48:53.904677Z","caller":"traceutil/trace.go:171","msg":"trace[108419131] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:775; }","duration":"270.701052ms","start":"2026-02-24T10:48:53.633831Z","end":"2026-02-24T10:48:53.904532Z","steps":["trace[108419131] 'agreement among raft nodes before linearized reading' (duration: 270.140038ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:53.904444Z","caller":"traceutil/trace.go:171","msg":"trace[838186373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"154.861249ms","start":"2026-02-24T10:48:53.749559Z","end":"2026-02-24T10:48:53.904421Z","steps":["trace[838186373] 'agreement among raft nodes before linearized reading' (duration: 154.460419ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:48:54.104617Z","caller":"traceutil/trace.go:171","msg":"trace[1581210598] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"195.808924ms","start":"2026-02-24T10:48:53.908782Z","end":"2026-02-24T10:48:54.104591Z","steps":["trace[1581210598] 'process raft request' (duration: 195.694892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:54.709349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.697164ms","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:48:54.709512Z","caller":"traceutil/trace.go:171","msg":"trace[1529505788] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:777; }","duration":"310.922749ms","start":"2026-02-24T10:48:54.398568Z","end":"2026-02-24T10:48:54.709491Z","steps":["trace[1529505788] 'range keys from in-memory index tree' (duration: 310.227652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:48:54.709589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:48:54.398538Z","time spent":"311.036952ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T10:49:06.02131Z","caller":"traceutil/trace.go:171","msg":"trace[314154187] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"132.147314ms","start":"2026-02-24T10:49:05.889123Z","end":"2026-02-24T10:49:06.02127Z","steps":["trace[314154187] 'process raft request' (duration: 131.95422ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:49:06.65459Z","caller":"traceutil/trace.go:171","msg":"trace[444613136] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"482.298425ms","start":"2026-02-24T10:49:06.172268Z","end":"2026-02-24T10:49:06.654566Z","steps":["trace[444613136] 'process raft request' (duration: 478.838458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:06.654787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:06.172215Z","time spent":"482.47424ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:49:08.613259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018653270634645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:49:09.11429Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018653270634645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:49:09.592805Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.040831828s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T10:49:09.593165Z","caller":"traceutil/trace.go:171","msg":"trace[301869281] linearizableReadLoop","detail":"{readStateIndex:906; appliedIndex:905; }","duration":"1.480145988s","start":"2026-02-24T10:49:08.112992Z","end":"2026-02-24T10:49:09.593138Z","steps":["trace[301869281] 'read index received' (duration: 1.480011145s)","trace[301869281] 'applied index is now lower than readState.Index' (duration: 133.693µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:49:09.593212Z","caller":"traceutil/trace.go:171","msg":"trace[1206912992] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"2.0413161s","start":"2026-02-24T10:49:07.551854Z","end":"2026-02-24T10:49:09.59317Z","steps":["trace[1206912992] 'process raft request' (duration: 2.041142636s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:09.593335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:07.551834Z","time spent":"2.041435303s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:49:09.59338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.480365003s","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:49:09.593418Z","caller":"traceutil/trace.go:171","msg":"trace[1399321888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:865; }","duration":"1.480456476s","start":"2026-02-24T10:49:08.11295Z","end":"2026-02-24T10:49:09.593406Z","steps":["trace[1399321888] 'agreement among raft nodes before linearized reading' (duration: 1.480307382s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:09.593459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:08.11293Z","time spent":"1.480520779s","remote":"127.0.0.1:57372","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:49:09.598387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.104003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T10:49:09.598419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.396113ms","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:49:09.598455Z","caller":"traceutil/trace.go:171","msg":"trace[2140197234] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:865; }","duration":"358.211585ms","start":"2026-02-24T10:49:09.240222Z","end":"2026-02-24T10:49:09.598434Z","steps":["trace[2140197234] 'agreement among raft nodes before linearized reading' (duration: 358.072032ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:49:09.598461Z","caller":"traceutil/trace.go:171","msg":"trace[1017261071] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:865; }","duration":"792.470065ms","start":"2026-02-24T10:49:08.805978Z","end":"2026-02-24T10:49:09.598448Z","steps":["trace[1017261071] 'agreement among raft nodes before linearized reading' (duration: 792.333282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:09.598505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:08.80596Z","time spent":"792.538937ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T10:49:09.598509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.381247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:49:09.598559Z","caller":"traceutil/trace.go:171","msg":"trace[639447619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:865; }","duration":"851.441688ms","start":"2026-02-24T10:49:08.747098Z","end":"2026-02-24T10:49:09.59854Z","steps":["trace[639447619] 'agreement among raft nodes before linearized reading' (duration: 851.385986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:09.598613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:08.747082Z","time spent":"851.520439ms","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:49:09.598616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.683704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T10:49:09.59865Z","caller":"traceutil/trace.go:171","msg":"trace[346050886] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:865; }","duration":"120.745665ms","start":"2026-02-24T10:49:09.477896Z","end":"2026-02-24T10:49:09.598641Z","steps":["trace[346050886] 'agreement among raft nodes before linearized reading' (duration: 120.652413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:09.598659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.52243ms","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:49:09.598756Z","caller":"traceutil/trace.go:171","msg":"trace[1634318810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:865; }","duration":"689.644483ms","start":"2026-02-24T10:49:08.909104Z","end":"2026-02-24T10:49:09.598748Z","steps":["trace[1634318810] 'agreement among raft nodes before linearized reading' (duration: 689.51033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:09.598778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:08.909084Z","time spent":"689.688063ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T10:49:09.59851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:09.240204Z","time spent":"358.295518ms","remote":"127.0.0.1:57624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-24T10:49:09.598436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.186907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-24T10:49:09.598872Z","caller":"traceutil/trace.go:171","msg":"trace[878474761] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:865; }","duration":"937.667879ms","start":"2026-02-24T10:49:08.661192Z","end":"2026-02-24T10:49:09.59886Z","steps":["trace[878474761] 'agreement among raft nodes before linearized reading' (duration: 937.081414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:09.598915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:08.661175Z","time spent":"937.729911ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T10:49:21.892439Z","caller":"traceutil/trace.go:171","msg":"trace[290256349] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"115.182695ms","start":"2026-02-24T10:49:21.777235Z","end":"2026-02-24T10:49:21.892418Z","steps":["trace[290256349] 'process raft request' (duration: 114.98247ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:49:21.899588Z","caller":"traceutil/trace.go:171","msg":"trace[348566555] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"119.877343ms","start":"2026-02-24T10:49:21.779682Z","end":"2026-02-24T10:49:21.89956Z","steps":["trace[348566555] 'process raft request' (duration: 119.837312ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:49:21.899604Z","caller":"traceutil/trace.go:171","msg":"trace[1834482100] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"121.30789ms","start":"2026-02-24T10:49:21.77827Z","end":"2026-02-24T10:49:21.899578Z","steps":["trace[1834482100] 'process raft request' (duration: 121.212178ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:49:21.899608Z","caller":"traceutil/trace.go:171","msg":"trace[501794843] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"121.397872ms","start":"2026-02-24T10:49:21.778167Z","end":"2026-02-24T10:49:21.899565Z","steps":["trace[501794843] 'process raft request' (duration: 121.154716ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:49:26.014035Z","caller":"traceutil/trace.go:171","msg":"trace[945638491] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"100.303169ms","start":"2026-02-24T10:49:25.91371Z","end":"2026-02-24T10:49:26.014013Z","steps":["trace[945638491] 'read index received' (duration: 100.196056ms)","trace[945638491] 'applied index is now lower than readState.Index' (duration: 106.323µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:49:26.014083Z","caller":"traceutil/trace.go:171","msg":"trace[2023761573] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"106.540136ms","start":"2026-02-24T10:49:25.907513Z","end":"2026-02-24T10:49:26.014054Z","steps":["trace[2023761573] 'process raft request' (duration: 106.360442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:26.014244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.512384ms","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:49:26.014275Z","caller":"traceutil/trace.go:171","msg":"trace[647631841] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:920; }","duration":"100.610106ms","start":"2026-02-24T10:49:25.913655Z","end":"2026-02-24T10:49:26.014266Z","steps":["trace[647631841] 'agreement among raft nodes before linearized reading' (duration: 100.472283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:26.014448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.091192ms","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":"info","ts":"2026-02-24T10:49:26.014493Z","caller":"traceutil/trace.go:171","msg":"trace[2102666602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:920; }","duration":"100.186925ms","start":"2026-02-24T10:49:25.914293Z","end":"2026-02-24T10:49:26.01448Z","steps":["trace[2102666602] 'agreement among raft nodes before linearized reading' (duration: 99.968519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:26.014511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.584156ms","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:49:26.014657Z","caller":"traceutil/trace.go:171","msg":"trace[352920291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:920; }","duration":"100.768251ms","start":"2026-02-24T10:49:25.913862Z","end":"2026-02-24T10:49:26.01463Z","steps":["trace[352920291] 'agreement among raft nodes before linearized reading' (duration: 100.300209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:26.870365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018653270634785,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:49:27.097941Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.007493968s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T10:49:27.814185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.771455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:49:27.814539Z","caller":"traceutil/trace.go:171","msg":"trace[1818127251] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:966; }","duration":"1.444962529s","start":"2026-02-24T10:49:26.369549Z","end":"2026-02-24T10:49:27.814512Z","steps":["trace[1818127251] 'read index received' (duration: 728.719802ms)","trace[1818127251] 'applied index is now lower than readState.Index' (duration: 716.241607ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:49:27.814667Z","caller":"traceutil/trace.go:171","msg":"trace[1738111604] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"1.795485999s","start":"2026-02-24T10:49:26.019167Z","end":"2026-02-24T10:49:27.814653Z","steps":["trace[1738111604] 'process raft request' (duration: 1.079104719s)","trace[1738111604] 'compare' (duration: 715.261791ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:49:27.814736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:26.01914Z","time spent":"1.795558311s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:49:27.815104Z","caller":"traceutil/trace.go:171","msg":"trace[1810622301] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"1.795485019s","start":"2026-02-24T10:49:26.019607Z","end":"2026-02-24T10:49:27.815092Z","steps":["trace[1810622301] 'process raft request' (duration: 1.79472172s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:27.815165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:26.019597Z","time spent":"1.795533071s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:49:27.815299Z","caller":"traceutil/trace.go:171","msg":"trace[164686456] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"1.483428211s","start":"2026-02-24T10:49:26.33186Z","end":"2026-02-24T10:49:27.815288Z","steps":["trace[164686456] 'process raft request' (duration: 1.482608061s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:27.815736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068711016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:49:27.81601Z","caller":"traceutil/trace.go:171","msg":"trace[12674682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"1.069062494s","start":"2026-02-24T10:49:26.746927Z","end":"2026-02-24T10:49:27.81599Z","steps":["trace[12674682] 'agreement among raft nodes before linearized reading' (duration: 1.068718026s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:27.816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.221802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:49:27.816091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:26.746884Z","time spent":"1.069192198s","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T10:49:27.816098Z","caller":"traceutil/trace.go:171","msg":"trace[1790697790] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:924; }","duration":"378.578391ms","start":"2026-02-24T10:49:27.437496Z","end":"2026-02-24T10:49:27.816075Z","steps":["trace[1790697790] 'agreement among raft nodes before linearized reading' (duration: 378.213231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:27.815736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446152248s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:49:27.816143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:27.437467Z","time spent":"378.665423ms","remote":"127.0.0.1:57506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-02-24T10:49:27.816167Z","caller":"traceutil/trace.go:171","msg":"trace[293508414] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:924; }","duration":"1.4466512s","start":"2026-02-24T10:49:26.369497Z","end":"2026-02-24T10:49:27.816149Z","steps":["trace[293508414] 'agreement among raft nodes before linearized reading' (duration: 1.446119177s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:27.816136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:26.331831Z","time spent":"1.483490013s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:49:27.81623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:26.369477Z","time spent":"1.446728653s","remote":"127.0.0.1:57258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-02-24T10:49:27.98442Z","caller":"traceutil/trace.go:171","msg":"trace[193193113] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"112.060796ms","start":"2026-02-24T10:49:27.872329Z","end":"2026-02-24T10:49:27.98439Z","steps":["trace[193193113] 'process raft request' (duration: 48.305773ms)","trace[193193113] 'compare' (duration: 63.62496ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:49:28.198295Z","caller":"traceutil/trace.go:171","msg":"trace[714994183] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"101.93981ms","start":"2026-02-24T10:49:28.096328Z","end":"2026-02-24T10:49:28.198268Z","steps":["trace[714994183] 'read index received' (duration: 101.643233ms)","trace[714994183] 'applied index is now lower than readState.Index' (duration: 295.597µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:49:28.198394Z","caller":"traceutil/trace.go:171","msg":"trace[253315269] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"173.525292ms","start":"2026-02-24T10:49:28.024839Z","end":"2026-02-24T10:49:28.198365Z","steps":["trace[253315269] 'process raft request' (duration: 173.135682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:28.198519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.166766ms","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:49:28.198563Z","caller":"traceutil/trace.go:171","msg":"trace[58981476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:926; }","duration":"102.272148ms","start":"2026-02-24T10:49:28.096279Z","end":"2026-02-24T10:49:28.198551Z","steps":["trace[58981476] 'agreement among raft nodes before linearized reading' (duration: 102.088214ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:49:28.372483Z","caller":"traceutil/trace.go:171","msg":"trace[531771191] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"169.546341ms","start":"2026-02-24T10:49:28.202892Z","end":"2026-02-24T10:49:28.372438Z","steps":["trace[531771191] 'process raft request' (duration: 100.823991ms)","trace[531771191] 'compare' (duration: 68.503544ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:49:29.740605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.821917ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-24T10:49:29.740715Z","caller":"traceutil/trace.go:171","msg":"trace[468095055] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:972; }","duration":"262.465212ms","start":"2026-02-24T10:49:29.47823Z","end":"2026-02-24T10:49:29.740695Z","steps":["trace[468095055] 'read index received' (duration: 52.111µs)","trace[468095055] 'applied index is now lower than readState.Index' (duration: 262.411441ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:49:29.740796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.563364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T10:49:29.740828Z","caller":"traceutil/trace.go:171","msg":"trace[1599247656] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:927; }","duration":"262.646296ms","start":"2026-02-24T10:49:29.478173Z","end":"2026-02-24T10:49:29.740819Z","steps":["trace[1599247656] 'agreement among raft nodes before linearized reading' (duration: 262.570174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:30.255287Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018653270634803,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:49:30.755721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018653270634803,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T10:49:30.987623Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.372539766s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T10:49:30.98808Z","caller":"traceutil/trace.go:171","msg":"trace[1640155600] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"1.37311262s","start":"2026-02-24T10:49:29.614942Z","end":"2026-02-24T10:49:30.988055Z","steps":["trace[1640155600] 'process raft request' (duration: 1.372928565s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:30.988251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:29.614892Z","time spent":"1.373266504s","remote":"127.0.0.1:57284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5043,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:49:31.027026Z","caller":"traceutil/trace.go:171","msg":"trace[443071194] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:974; }","duration":"1.27234504s","start":"2026-02-24T10:49:29.754646Z","end":"2026-02-24T10:49:31.026991Z","steps":["trace[443071194] 'read index received' (duration: 1.237644942s)","trace[443071194] 'applied index is now lower than readState.Index' (duration: 34.698968ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:49:31.027407Z","caller":"traceutil/trace.go:171","msg":"trace[590881464] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"792.737712ms","start":"2026-02-24T10:49:30.234656Z","end":"2026-02-24T10:49:31.027394Z","steps":["trace[590881464] 'process raft request' (duration: 792.085485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:31.027548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:30.234633Z","time spent":"792.814654ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:49:31.027783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.27313284s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:49:31.027825Z","caller":"traceutil/trace.go:171","msg":"trace[472881859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"1.273205832s","start":"2026-02-24T10:49:29.754609Z","end":"2026-02-24T10:49:31.027814Z","steps":["trace[472881859] 'agreement among raft nodes before linearized reading' (duration: 1.27314285s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:31.02786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:29.754586Z","time spent":"1.273268214s","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:49:31.028047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233848074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T10:49:31.028085Z","caller":"traceutil/trace.go:171","msg":"trace[133090220] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:929; }","duration":"1.233910887s","start":"2026-02-24T10:49:29.794163Z","end":"2026-02-24T10:49:31.028074Z","steps":["trace[133090220] 'agreement among raft nodes before linearized reading' (duration: 1.233840276s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:31.028113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:29.794148Z","time spent":"1.233957708s","remote":"127.0.0.1:57220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-24T10:49:31.028445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.136058ms","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:49:31.028482Z","caller":"traceutil/trace.go:171","msg":"trace[1673169456] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:929; }","duration":"651.173219ms","start":"2026-02-24T10:49:30.3773Z","end":"2026-02-24T10:49:31.028473Z","steps":["trace[1673169456] 'agreement among raft nodes before linearized reading' (duration: 651.035466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:31.028508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:30.377223Z","time spent":"651.279702ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T10:49:31.02872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.998174ms","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:49:31.028757Z","caller":"traceutil/trace.go:171","msg":"trace[831381229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:929; }","duration":"826.062825ms","start":"2026-02-24T10:49:30.202686Z","end":"2026-02-24T10:49:31.028748Z","steps":["trace[831381229] 'agreement among raft nodes before linearized reading' (duration: 825.965193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:31.02879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:30.202667Z","time spent":"826.117517ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T10:49:31.02898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038595844s","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:49:31.029015Z","caller":"traceutil/trace.go:171","msg":"trace[1298285481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:929; }","duration":"1.038660515s","start":"2026-02-24T10:49:29.990345Z","end":"2026-02-24T10:49:31.029005Z","steps":["trace[1298285481] 'agreement among raft nodes before linearized reading' (duration: 1.038568302s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:31.029048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:29.990325Z","time spent":"1.038717637s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T10:49:31.02923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207330124s","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":"info","ts":"2026-02-24T10:49:31.029264Z","caller":"traceutil/trace.go:171","msg":"trace[2060889925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:929; }","duration":"1.207373926s","start":"2026-02-24T10:49:29.821881Z","end":"2026-02-24T10:49:31.029255Z","steps":["trace[2060889925] 'agreement among raft nodes before linearized reading' (duration: 1.207281864s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:31.029298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:29.821878Z","time spent":"1.207414037s","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T10:49:31.02948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207619131s","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:49:31.029514Z","caller":"traceutil/trace.go:171","msg":"trace[1650411673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:929; }","duration":"1.207672453s","start":"2026-02-24T10:49:29.821832Z","end":"2026-02-24T10:49:31.029505Z","steps":["trace[1650411673] 'agreement among raft nodes before linearized reading' (duration: 1.207583701s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:31.029545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:29.821792Z","time spent":"1.207746465s","remote":"127.0.0.1:57372","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:49:31.322966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.060244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:49:31.323098Z","caller":"traceutil/trace.go:171","msg":"trace[322656163] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"288.43553ms","start":"2026-02-24T10:49:31.034642Z","end":"2026-02-24T10:49:31.323077Z","steps":["trace[322656163] 'process raft request' (duration: 133.174261ms)","trace[322656163] 'compare' (duration: 154.92076ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:49:32.008055Z","caller":"traceutil/trace.go:171","msg":"trace[1683299566] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"973.005084ms","start":"2026-02-24T10:49:31.035021Z","end":"2026-02-24T10:49:32.008026Z","steps":["trace[1683299566] 'process raft request' (duration: 972.788489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:32.008228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:31.03501Z","time spent":"973.124818ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:49:32.008722Z","caller":"traceutil/trace.go:171","msg":"trace[2098053330] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"973.439284ms","start":"2026-02-24T10:49:31.035264Z","end":"2026-02-24T10:49:32.008704Z","steps":["trace[2098053330] 'process raft request' (duration: 972.709286ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:49:32.008978Z","caller":"traceutil/trace.go:171","msg":"trace[70926017] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"972.295786ms","start":"2026-02-24T10:49:31.03667Z","end":"2026-02-24T10:49:32.008965Z","steps":["trace[70926017] 'process raft request' (duration: 971.718932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:32.009054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:31.036647Z","time spent":"972.368588ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T10:49:32.009172Z","caller":"traceutil/trace.go:171","msg":"trace[1394502671] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:975; }","duration":"971.300672ms","start":"2026-02-24T10:49:31.037861Z","end":"2026-02-24T10:49:32.009162Z","steps":["trace[1394502671] 'read index received' (duration: 129.97331ms)","trace[1394502671] 'applied index is now lower than readState.Index' (duration: 841.326772ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:49:32.009237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.438824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:49:32.009265Z","caller":"traceutil/trace.go:171","msg":"trace[793139985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"971.486746ms","start":"2026-02-24T10:49:31.03777Z","end":"2026-02-24T10:49:32.009257Z","steps":["trace[793139985] 'agreement among raft nodes before linearized reading' (duration: 971.430745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:32.009288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:31.037762Z","time spent":"971.520337ms","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T10:49:32.009361Z","caller":"traceutil/trace.go:171","msg":"trace[507437592] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"971.144206ms","start":"2026-02-24T10:49:31.038195Z","end":"2026-02-24T10:49:32.009339Z","steps":["trace[507437592] 'process raft request' (duration: 970.722806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:32.00949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:31.038181Z","time spent":"971.238949ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:49:32.009879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:31.035255Z","time spent":"973.528907ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:49:32.826993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.214532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:49:32.827093Z","caller":"traceutil/trace.go:171","msg":"trace[1467417378] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"633.020921ms","start":"2026-02-24T10:49:32.194058Z","end":"2026-02-24T10:49:32.827079Z","steps":["trace[1467417378] 'read index received' (duration: 24.591893ms)","trace[1467417378] 'applied index is now lower than readState.Index' (duration: 608.427268ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:49:32.827165Z","caller":"traceutil/trace.go:171","msg":"trace[869290116] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"813.655992ms","start":"2026-02-24T10:49:32.013501Z","end":"2026-02-24T10:49:32.827157Z","steps":["trace[869290116] 'process raft request' (duration: 205.201873ms)","trace[869290116] 'compare' (duration: 607.797782ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:49:32.827207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:32.01348Z","time spent":"813.707882ms","remote":"127.0.0.1:57192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T10:49:32.827351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.268306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:49:32.827432Z","caller":"traceutil/trace.go:171","msg":"trace[2131115390] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:935; }","duration":"633.38753ms","start":"2026-02-24T10:49:32.194026Z","end":"2026-02-24T10:49:32.827414Z","steps":["trace[2131115390] 'agreement among raft nodes before linearized reading' (duration: 633.239456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:32.827476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:32.194006Z","time spent":"633.460671ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-24T10:49:33.265911Z","caller":"traceutil/trace.go:171","msg":"trace[69230690] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"380.679034ms","start":"2026-02-24T10:49:32.885208Z","end":"2026-02-24T10:49:33.265887Z","steps":["trace[69230690] 'process raft request' (duration: 380.51332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:33.26608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:32.885189Z","time spent":"380.831998ms","remote":"127.0.0.1:57192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":769,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T10:49:33.4689Z","caller":"traceutil/trace.go:171","msg":"trace[2085081329] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:983; }","duration":"140.259229ms","start":"2026-02-24T10:49:33.32862Z","end":"2026-02-24T10:49:33.468879Z","steps":["trace[2085081329] 'read index received' (duration: 95.348683ms)","trace[2085081329] 'applied index is now lower than readState.Index' (duration: 44.909716ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:49:33.469093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.458524ms","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:49:33.469121Z","caller":"traceutil/trace.go:171","msg":"trace[1072687526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:939; }","duration":"140.534216ms","start":"2026-02-24T10:49:33.328579Z","end":"2026-02-24T10:49:33.469113Z","steps":["trace[1072687526] 'agreement among raft nodes before linearized reading' (duration: 140.379592ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:49:33.469155Z","caller":"traceutil/trace.go:171","msg":"trace[1423114442] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"581.039784ms","start":"2026-02-24T10:49:32.888076Z","end":"2026-02-24T10:49:33.469116Z","steps":["trace[1423114442] 'process raft request' (duration: 535.975934ms)","trace[1423114442] 'compare' (duration: 44.6549ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:49:33.469294Z","caller":"traceutil/trace.go:171","msg":"trace[1200327722] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"518.463651ms","start":"2026-02-24T10:49:32.950802Z","end":"2026-02-24T10:49:33.469266Z","steps":["trace[1200327722] 'process raft request' (duration: 518.02969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:33.469321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:32.888057Z","time spent":"581.175397ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:49:33.469394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:32.950781Z","time spent":"518.563973ms","remote":"127.0.0.1:57192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T10:49:34.431018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.702927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T10:49:34.431125Z","caller":"traceutil/trace.go:171","msg":"trace[203203076] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:941; }","duration":"793.865301ms","start":"2026-02-24T10:49:33.637234Z","end":"2026-02-24T10:49:34.4311Z","steps":["trace[203203076] 'range keys from in-memory index tree' (duration: 793.477372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:34.431181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:33.63721Z","time spent":"793.954554ms","remote":"127.0.0.1:57142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"warn","ts":"2026-02-24T10:49:34.431233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.49893ms","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:49:34.431304Z","caller":"traceutil/trace.go:171","msg":"trace[117135595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:941; }","duration":"416.622524ms","start":"2026-02-24T10:49:34.014664Z","end":"2026-02-24T10:49:34.431287Z","steps":["trace[117135595] 'range keys from in-memory index tree' (duration: 416.255774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:34.431342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:34.014653Z","time spent":"416.681595ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T10:49:34.431451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.189983ms","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:49:34.431486Z","caller":"traceutil/trace.go:171","msg":"trace[1885286327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:941; }","duration":"416.244605ms","start":"2026-02-24T10:49:34.015231Z","end":"2026-02-24T10:49:34.431475Z","steps":["trace[1885286327] 'range keys from in-memory index tree' (duration: 416.07885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:34.431476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.304638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T10:49:34.431516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:34.015217Z","time spent":"416.291375ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T10:49:34.431552Z","caller":"traceutil/trace.go:171","msg":"trace[1679654766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"684.421681ms","start":"2026-02-24T10:49:33.74711Z","end":"2026-02-24T10:49:34.431532Z","steps":["trace[1679654766] 'range keys from in-memory index tree' (duration: 684.227415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:34.431602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:33.747088Z","time spent":"684.498992ms","remote":"127.0.0.1:57092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T10:49:34.431616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.010842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:49:34.431646Z","caller":"traceutil/trace.go:171","msg":"trace[380288820] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:941; }","duration":"313.045873ms","start":"2026-02-24T10:49:34.11859Z","end":"2026-02-24T10:49:34.431636Z","steps":["trace[380288820] 'count revisions from in-memory index tree' (duration: 312.92236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:34.431659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.417864ms","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":"info","ts":"2026-02-24T10:49:34.431684Z","caller":"traceutil/trace.go:171","msg":"trace[30947563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:941; }","duration":"417.482376ms","start":"2026-02-24T10:49:34.014195Z","end":"2026-02-24T10:49:34.431677Z","steps":["trace[30947563] 'range keys from in-memory index tree' (duration: 417.317681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:34.431702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:34.014171Z","time spent":"417.527446ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T10:49:34.431676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:34.118501Z","time spent":"313.166565ms","remote":"127.0.0.1:43952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-24T10:49:34.431815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.167018ms","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:49:34.431837Z","caller":"traceutil/trace.go:171","msg":"trace[466072165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:941; }","duration":"417.215328ms","start":"2026-02-24T10:49:34.014616Z","end":"2026-02-24T10:49:34.431832Z","steps":["trace[466072165] 'range keys from in-memory index tree' (duration: 417.092475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:34.431853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:34.014604Z","time spent":"417.24562ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T10:49:39.740065Z","caller":"traceutil/trace.go:171","msg":"trace[507297331] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"176.938938ms","start":"2026-02-24T10:49:39.563093Z","end":"2026-02-24T10:49:39.740032Z","steps":["trace[507297331] 'process raft request' (duration: 176.718153ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:49:55.264308Z","caller":"traceutil/trace.go:171","msg":"trace[1722380275] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"253.634634ms","start":"2026-02-24T10:49:55.010646Z","end":"2026-02-24T10:49:55.26428Z","steps":["trace[1722380275] 'process raft request' (duration: 253.0885ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:49:55.604351Z","caller":"traceutil/trace.go:171","msg":"trace[920346060] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"332.512175ms","start":"2026-02-24T10:49:55.271808Z","end":"2026-02-24T10:49:55.60432Z","steps":["trace[920346060] 'process raft request' (duration: 238.198362ms)","trace[920346060] 'compare' (duration: 94.164489ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:49:55.604547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:49:55.271771Z","time spent":"332.672779ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:49:55.934705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.76353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T10:49:55.934913Z","caller":"traceutil/trace.go:171","msg":"trace[1916509282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1057; }","duration":"169.032928ms","start":"2026-02-24T10:49:55.765854Z","end":"2026-02-24T10:49:55.934887Z","steps":["trace[1916509282] 'range keys from in-memory index tree' (duration: 168.615897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:49:55.934912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.05827ms","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:49:55.935043Z","caller":"traceutil/trace.go:171","msg":"trace[1792265570] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1057; }","duration":"106.257056ms","start":"2026-02-24T10:49:55.828751Z","end":"2026-02-24T10:49:55.935008Z","steps":["trace[1792265570] 'range keys from in-memory index tree' (duration: 105.783094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:59.14966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.634329ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-24T10:50:59.149937Z","caller":"traceutil/trace.go:171","msg":"trace[1564944322] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1376; }","duration":"297.857695ms","start":"2026-02-24T10:50:58.852034Z","end":"2026-02-24T10:50:59.149892Z","steps":["trace[1564944322] 'read index received' (duration: 126.878925ms)","trace[1564944322] 'applied index is now lower than readState.Index' (duration: 170.97658ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:50:59.150178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.119631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-24T10:50:59.150235Z","caller":"traceutil/trace.go:171","msg":"trace[959047984] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1313; }","duration":"298.242175ms","start":"2026-02-24T10:50:58.851973Z","end":"2026-02-24T10:50:59.150215Z","steps":["trace[959047984] 'agreement among raft nodes before linearized reading' (duration: 298.026119ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:50:59.396099Z","caller":"traceutil/trace.go:171","msg":"trace[726064433] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"239.662024ms","start":"2026-02-24T10:50:59.156404Z","end":"2026-02-24T10:50:59.396066Z","steps":["trace[726064433] 'process raft request' (duration: 239.374096ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:50:59.396365Z","caller":"traceutil/trace.go:171","msg":"trace[1769736239] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1377; }","duration":"181.626397ms","start":"2026-02-24T10:50:59.214339Z","end":"2026-02-24T10:50:59.395965Z","steps":["trace[1769736239] 'read index received' (duration: 14.744242ms)","trace[1769736239] 'applied index is now lower than readState.Index' (duration: 166.881135ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:50:59.396683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.292524ms","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:50:59.396798Z","caller":"traceutil/trace.go:171","msg":"trace[1845355258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1315; }","duration":"182.490269ms","start":"2026-02-24T10:50:59.214281Z","end":"2026-02-24T10:50:59.396772Z","steps":["trace[1845355258] 'agreement among raft nodes before linearized reading' (duration: 182.031307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:50:59.396826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.534475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-02-24T10:50:59.396901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.375391ms","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:50:59.39693Z","caller":"traceutil/trace.go:171","msg":"trace[1907407041] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1315; }","duration":"175.76513ms","start":"2026-02-24T10:50:59.221149Z","end":"2026-02-24T10:50:59.396915Z","steps":["trace[1907407041] 'agreement among raft nodes before linearized reading' (duration: 175.458802ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:50:59.396984Z","caller":"traceutil/trace.go:171","msg":"trace[1554237103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1315; }","duration":"162.600837ms","start":"2026-02-24T10:50:59.234363Z","end":"2026-02-24T10:50:59.396964Z","steps":["trace[1554237103] 'agreement among raft nodes before linearized reading' (duration: 162.3311ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:00.544283Z","caller":"traceutil/trace.go:171","msg":"trace[184348456] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"106.743326ms","start":"2026-02-24T10:51:00.437505Z","end":"2026-02-24T10:51:00.544248Z","steps":["trace[184348456] 'process raft request' (duration: 106.455639ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:19.506855Z","caller":"traceutil/trace.go:171","msg":"trace[753964268] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"160.143246ms","start":"2026-02-24T10:51:19.346653Z","end":"2026-02-24T10:51:19.506797Z","steps":["trace[753964268] 'process raft request' (duration: 159.831268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:51:19.754455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.76993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:51:19.754572Z","caller":"traceutil/trace.go:171","msg":"trace[752835185] linearizableReadLoop","detail":"{readStateIndex:1467; appliedIndex:1466; }","duration":"164.477746ms","start":"2026-02-24T10:51:19.590076Z","end":"2026-02-24T10:51:19.754554Z","steps":["trace[752835185] 'read index received' (duration: 30.46061ms)","trace[752835185] 'applied index is now lower than readState.Index' (duration: 134.015966ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:51:19.754674Z","caller":"traceutil/trace.go:171","msg":"trace[2043685807] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"194.501754ms","start":"2026-02-24T10:51:19.560161Z","end":"2026-02-24T10:51:19.754663Z","steps":["trace[2043685807] 'process raft request' (duration: 60.425966ms)","trace[2043685807] 'compare' (duration: 133.581655ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:51:19.75488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.801824ms","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:51:19.754919Z","caller":"traceutil/trace.go:171","msg":"trace[744877503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1399; }","duration":"164.884706ms","start":"2026-02-24T10:51:19.590022Z","end":"2026-02-24T10:51:19.754907Z","steps":["trace[744877503] 'agreement among raft nodes before linearized reading' (duration: 164.762803ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:20.569563Z","caller":"traceutil/trace.go:171","msg":"trace[1857616150] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"169.243096ms","start":"2026-02-24T10:51:20.400271Z","end":"2026-02-24T10:51:20.569514Z","steps":["trace[1857616150] 'process raft request' (duration: 168.961019ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:51:23.684409Z","caller":"traceutil/trace.go:171","msg":"trace[65682212] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"136.728854ms","start":"2026-02-24T10:51:23.547655Z","end":"2026-02-24T10:51:23.684384Z","steps":["trace[65682212] 'process raft request' (duration: 136.399865ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:02.507768Z","caller":"traceutil/trace.go:171","msg":"trace[1507809290] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"149.123322ms","start":"2026-02-24T10:52:02.358618Z","end":"2026-02-24T10:52:02.507742Z","steps":["trace[1507809290] 'process raft request' (duration: 148.952967ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:05.379586Z","caller":"traceutil/trace.go:171","msg":"trace[2011132946] transaction","detail":"{read_only:false; response_revision:1585; number_of_response:1; }","duration":"310.07845ms","start":"2026-02-24T10:52:05.06947Z","end":"2026-02-24T10:52:05.379549Z","steps":["trace[2011132946] 'process raft request' (duration: 309.857925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:05.379876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:52:05.069432Z","time spent":"310.259095ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:52:10.330817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.230296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:52:10.331067Z","caller":"traceutil/trace.go:171","msg":"trace[298745355] linearizableReadLoop","detail":"{readStateIndex:1682; appliedIndex:1680; }","duration":"135.734714ms","start":"2026-02-24T10:52:10.195312Z","end":"2026-02-24T10:52:10.331047Z","steps":["trace[298745355] 'read index received' (duration: 28.180181ms)","trace[298745355] 'applied index is now lower than readState.Index' (duration: 107.553463ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:52:10.331267Z","caller":"traceutil/trace.go:171","msg":"trace[370533900] transaction","detail":"{read_only:false; response_revision:1604; number_of_response:1; }","duration":"224.744048ms","start":"2026-02-24T10:52:10.10646Z","end":"2026-02-24T10:52:10.331204Z","steps":["trace[370533900] 'process raft request' (duration: 224.521543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:10.331323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.014641ms","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:52:10.331327Z","caller":"traceutil/trace.go:171","msg":"trace[1689171951] transaction","detail":"{read_only:false; response_revision:1603; number_of_response:1; }","duration":"227.773424ms","start":"2026-02-24T10:52:10.103516Z","end":"2026-02-24T10:52:10.331289Z","steps":["trace[1689171951] 'process raft request' (duration: 119.959544ms)","trace[1689171951] 'compare' (duration: 107.040231ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:52:10.331368Z","caller":"traceutil/trace.go:171","msg":"trace[896566163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1604; }","duration":"136.086443ms","start":"2026-02-24T10:52:10.195267Z","end":"2026-02-24T10:52:10.331353Z","steps":["trace[896566163] 'agreement among raft nodes before linearized reading' (duration: 135.841867ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:11.490736Z","caller":"traceutil/trace.go:171","msg":"trace[621725638] transaction","detail":"{read_only:false; response_revision:1608; number_of_response:1; }","duration":"120.622592ms","start":"2026-02-24T10:52:11.37009Z","end":"2026-02-24T10:52:11.490713Z","steps":["trace[621725638] 'process raft request' (duration: 120.002377ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:21.287328Z","caller":"traceutil/trace.go:171","msg":"trace[778248139] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"114.284103ms","start":"2026-02-24T10:52:21.173008Z","end":"2026-02-24T10:52:21.287292Z","steps":["trace[778248139] 'process raft request' (duration: 114.044977ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:26.597074Z","caller":"traceutil/trace.go:171","msg":"trace[1758630645] transaction","detail":"{read_only:false; response_revision:1673; number_of_response:1; }","duration":"131.584208ms","start":"2026-02-24T10:52:26.465455Z","end":"2026-02-24T10:52:26.59704Z","steps":["trace[1758630645] 'process raft request' (duration: 131.481916ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:26.597061Z","caller":"traceutil/trace.go:171","msg":"trace[1966388499] transaction","detail":"{read_only:false; response_revision:1672; number_of_response:1; }","duration":"131.575439ms","start":"2026-02-24T10:52:26.465429Z","end":"2026-02-24T10:52:26.597004Z","steps":["trace[1966388499] 'process raft request' (duration: 116.468248ms)","trace[1966388499] 'compare' (duration: 14.805713ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:30.718275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.12166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-24T10:52:30.718376Z","caller":"traceutil/trace.go:171","msg":"trace[1133666140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1686; }","duration":"101.282424ms","start":"2026-02-24T10:52:30.61707Z","end":"2026-02-24T10:52:30.718353Z","steps":["trace[1133666140] 'range keys from in-memory index tree' (duration: 100.904855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:30.718384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.626947ms","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":"info","ts":"2026-02-24T10:52:30.718582Z","caller":"traceutil/trace.go:171","msg":"trace[1620779766] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1686; }","duration":"100.885716ms","start":"2026-02-24T10:52:30.617667Z","end":"2026-02-24T10:52:30.718553Z","steps":["trace[1620779766] 'range keys from in-memory index tree' (duration: 100.403482ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:31.576702Z","caller":"traceutil/trace.go:171","msg":"trace[1276692157] transaction","detail":"{read_only:false; response_revision:1690; number_of_response:1; }","duration":"250.204371ms","start":"2026-02-24T10:52:31.326459Z","end":"2026-02-24T10:52:31.576664Z","steps":["trace[1276692157] 'process raft request' (duration: 249.974695ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:38.374398Z","caller":"traceutil/trace.go:171","msg":"trace[1617333878] transaction","detail":"{read_only:false; response_revision:1718; number_of_response:1; }","duration":"113.795ms","start":"2026-02-24T10:52:38.260566Z","end":"2026-02-24T10:52:38.374361Z","steps":["trace[1617333878] 'process raft request' (duration: 113.590235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:39.079318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.874658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T10:52:39.079717Z","caller":"traceutil/trace.go:171","msg":"trace[1428790554] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"189.453878ms","start":"2026-02-24T10:52:38.89022Z","end":"2026-02-24T10:52:39.079673Z","steps":["trace[1428790554] 'process raft request' (duration: 72.104358ms)","trace[1428790554] 'compare' (duration: 116.713714ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:52:39.158362Z","caller":"traceutil/trace.go:171","msg":"trace[293480866] linearizableReadLoop","detail":"{readStateIndex:1805; appliedIndex:1803; }","duration":"128.966783ms","start":"2026-02-24T10:52:39.029232Z","end":"2026-02-24T10:52:39.158199Z","steps":["trace[293480866] 'read index received' (duration: 94.292µs)","trace[293480866] 'applied index is now lower than readState.Index' (duration: 128.871111ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T10:52:39.158591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.343563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-24T10:52:39.158699Z","caller":"traceutil/trace.go:171","msg":"trace[68246965] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1721; }","duration":"129.524326ms","start":"2026-02-24T10:52:39.029146Z","end":"2026-02-24T10:52:39.15867Z","steps":["trace[68246965] 'agreement among raft nodes before linearized reading' (duration: 129.234029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:52:57.29246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.088613ms","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:52:57.292684Z","caller":"traceutil/trace.go:171","msg":"trace[518118447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1820; }","duration":"121.337689ms","start":"2026-02-24T10:52:57.17128Z","end":"2026-02-24T10:52:57.292618Z","steps":["trace[518118447] 'range keys from in-memory index tree' (duration: 120.743533ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:52:58.097662Z","caller":"traceutil/trace.go:171","msg":"trace[393917296] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"157.228483ms","start":"2026-02-24T10:52:57.940403Z","end":"2026-02-24T10:52:58.097632Z","steps":["trace[393917296] 'process raft request' (duration: 157.064869ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:53:06.693452Z","caller":"traceutil/trace.go:171","msg":"trace[1388431531] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"146.654976ms","start":"2026-02-24T10:53:06.546755Z","end":"2026-02-24T10:53:06.69341Z","steps":["trace[1388431531] 'process raft request' (duration: 146.38805ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T10:53:07.684117Z","caller":"traceutil/trace.go:171","msg":"trace[749959973] linearizableReadLoop","detail":"{readStateIndex:1957; appliedIndex:1956; }","duration":"371.079174ms","start":"2026-02-24T10:53:07.313002Z","end":"2026-02-24T10:53:07.684081Z","steps":["trace[749959973] 'read index received' (duration: 370.817237ms)","trace[749959973] 'applied index is now lower than readState.Index' (duration: 260.696µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T10:53:07.684512Z","caller":"traceutil/trace.go:171","msg":"trace[1669472507] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"509.026801ms","start":"2026-02-24T10:53:07.175471Z","end":"2026-02-24T10:53:07.684498Z","steps":["trace[1669472507] 'process raft request' (duration: 508.440946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:53:07.684658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:53:07.175456Z","time spent":"509.097903ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T10:53:07.685005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.001936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T10:53:07.685052Z","caller":"traceutil/trace.go:171","msg":"trace[337850431] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1867; }","duration":"372.093058ms","start":"2026-02-24T10:53:07.312948Z","end":"2026-02-24T10:53:07.685041Z","steps":["trace[337850431] 'agreement among raft nodes before linearized reading' (duration: 372.001336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:53:07.685085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:53:07.312924Z","time spent":"372.152951ms","remote":"127.0.0.1:57546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-24T10:53:07.6854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.615959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-24T10:53:07.685443Z","caller":"traceutil/trace.go:171","msg":"trace[302389321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1867; }","duration":"333.709461ms","start":"2026-02-24T10:53:07.351724Z","end":"2026-02-24T10:53:07.685434Z","steps":["trace[302389321] 'agreement among raft nodes before linearized reading' (duration: 333.553267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:53:07.685472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:53:07.351697Z","time spent":"333.768893ms","remote":"127.0.0.1:57372","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:53:07.685729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.280344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-24T10:53:07.685768Z","caller":"traceutil/trace.go:171","msg":"trace[1452807055] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1867; }","duration":"324.339305ms","start":"2026-02-24T10:53:07.361419Z","end":"2026-02-24T10:53:07.685758Z","steps":["trace[1452807055] 'agreement among raft nodes before linearized reading' (duration: 324.234743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T10:53:07.685887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T10:53:07.361408Z","time spent":"324.381297ms","remote":"127.0.0.1:57372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "}