{"level":"warn","ts":"2026-02-16T23:00:05.259383Z","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-16T23:00:05.259498Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242: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.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242: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-16T23:00:05.259588Z","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-16T23:00:05.259604Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-02-16T23:00:05.259619Z","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-16T23:00:05.260422Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-02-16T23:00:05.260495Z","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":2,"max-cpu-available":2,"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.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242: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-16T23:00:05.266623Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.936845ms"} {"level":"info","ts":"2026-02-16T23:00:05.287026Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-02-16T23:00:05.2871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-02-16T23:00:05.28712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-02-16T23:00:05.287129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-16T23:00:05.287174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-02-16T23:00:05.287204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-02-16T23:00:05.300132Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-16T23:00:05.307003Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-16T23:00:05.31317Z","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-16T23:00:05.319316Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-16T23:00:05.31977Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-16T23:00:05.3199Z","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-16T23:00:05.321331Z","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-16T23:00:05.321367Z","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-16T23:00:05.32199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-02-16T23:00:05.322091Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-02-16T23:00:05.336232Z","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-16T23:00:05.336576Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-16T23:00:05.336864Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-16T23:00:05.336419Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-02-16T23:00:05.339771Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-02-16T23:00:05.587791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-02-16T23:00:05.587884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-16T23:00:05.587917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-02-16T23:00:05.587946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-02-16T23:00:05.587968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-02-16T23:00:05.587987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-02-16T23:00:05.588008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-02-16T23:00:05.590005Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-16T23:00:05.593295Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-16T23:00:05.59341Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-16T23:00:05.593672Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-16T23:00:05.593799Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-16T23:00:05.593829Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-16T23:00:05.595288Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-02-16T23:00:05.595357Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-16T23:00:05.595386Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-16T23:00:05.598783Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-16T23:00:05.600249Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"warn","ts":"2026-02-16T23:00:10.113198Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.011229416s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-16T23:00:10.507151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.017817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-16T23:00:10.507821Z","caller":"traceutil/trace.go:171","msg":"trace[376259402] linearizableReadLoop","detail":"{readStateIndex:111; appliedIndex:108; }","duration":"870.236427ms","start":"2026-02-16T23:00:09.637567Z","end":"2026-02-16T23:00:10.507803Z","steps":["trace[376259402] 'read index received' (duration: 477.112314ms)","trace[376259402] 'applied index is now lower than readState.Index' (duration: 393.123243ms)"],"step_count":2} {"level":"info","ts":"2026-02-16T23:00:10.508264Z","caller":"traceutil/trace.go:171","msg":"trace[2061968403] transaction","detail":"{read_only:false; response_revision:105; number_of_response:1; }","duration":"1.40634553s","start":"2026-02-16T23:00:09.101903Z","end":"2026-02-16T23:00:10.508248Z","steps":["trace[2061968403] 'process raft request' (duration: 1.012731145s)","trace[2061968403] 'compare' (duration: 391.899716ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:00:10.50906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:09.10189Z","time spent":"1.407136415s","remote":"127.0.0.1:39984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":685,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-16T23:00:10.508634Z","caller":"traceutil/trace.go:171","msg":"trace[139670793] transaction","detail":"{read_only:false; response_revision:106; number_of_response:1; }","duration":"1.33322179s","start":"2026-02-16T23:00:09.175333Z","end":"2026-02-16T23:00:10.508555Z","steps":["trace[139670793] 'process raft request' (duration: 1.332377376s)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:10.509825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:09.175307Z","time spent":"1.334485627s","remote":"127.0.0.1:39822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5476,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-16T23:00:10.508687Z","caller":"traceutil/trace.go:171","msg":"trace[1532964924] transaction","detail":"{read_only:false; response_revision:107; number_of_response:1; }","duration":"1.294559049s","start":"2026-02-16T23:00:09.21412Z","end":"2026-02-16T23:00:10.508679Z","steps":["trace[1532964924] 'process raft request' (duration: 1.293650944s)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:10.510068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:09.214089Z","time spent":"1.295948296s","remote":"127.0.0.1:39822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5860,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-16T23:00:10.508772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.194342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-16T23:00:10.51029Z","caller":"traceutil/trace.go:171","msg":"trace[1849962232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:107; }","duration":"872.779731ms","start":"2026-02-16T23:00:09.637504Z","end":"2026-02-16T23:00:10.510283Z","steps":["trace[1849962232] 'agreement among raft nodes before linearized reading' (duration: 871.209982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:10.510392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:09.637484Z","time spent":"872.902341ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} 2026/02/16 23:00:13 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-16T23:00:18.165924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.583943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-16T23:00:18.166075Z","caller":"traceutil/trace.go:171","msg":"trace[966039450] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"303.270232ms","start":"2026-02-16T23:00:17.862789Z","end":"2026-02-16T23:00:18.166059Z","steps":["trace[966039450] 'process raft request' (duration: 303.219062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:18.16613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:17.862779Z","time spent":"303.327623ms","remote":"127.0.0.1:39756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-16T23:00:18.166177Z","caller":"traceutil/trace.go:171","msg":"trace[1870666383] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"306.65164ms","start":"2026-02-16T23:00:17.859492Z","end":"2026-02-16T23:00:18.166144Z","steps":["trace[1870666383] 'process raft request' (duration: 87.641454ms)","trace[1870666383] 'compare' (duration: 218.493913ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:00:18.166315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:17.859484Z","time spent":"306.75635ms","remote":"127.0.0.1:39984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-16T23:00:18.166827Z","caller":"traceutil/trace.go:171","msg":"trace[1061765596] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:319; }","duration":"302.468499ms","start":"2026-02-16T23:00:17.864347Z","end":"2026-02-16T23:00:18.166815Z","steps":["trace[1061765596] 'read index received' (duration: 82.79724ms)","trace[1061765596] 'applied index is now lower than readState.Index' (duration: 219.670429ms)"],"step_count":2} {"level":"info","ts":"2026-02-16T23:00:18.166903Z","caller":"traceutil/trace.go:171","msg":"trace[745258830] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"300.80971ms","start":"2026-02-16T23:00:17.866083Z","end":"2026-02-16T23:00:18.166893Z","steps":["trace[745258830] 'process raft request' (duration: 300.650279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:18.166976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:17.866078Z","time spent":"300.84467ms","remote":"127.0.0.1:39840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-16T23:00:18.16715Z","caller":"traceutil/trace.go:171","msg":"trace[976805458] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"303.738074ms","start":"2026-02-16T23:00:17.863396Z","end":"2026-02-16T23:00:18.167134Z","steps":["trace[976805458] 'process raft request' (duration: 303.203922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:18.16722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:17.863389Z","time spent":"303.793786ms","remote":"127.0.0.1:39822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5083,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-16T23:00:18.16728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.926921ms","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-16T23:00:18.167328Z","caller":"traceutil/trace.go:171","msg":"trace[2005788747] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:313; }","duration":"302.993051ms","start":"2026-02-16T23:00:17.864325Z","end":"2026-02-16T23:00:18.167318Z","steps":["trace[2005788747] 'agreement among raft nodes before linearized reading' (duration: 302.919741ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:18.167209Z","caller":"traceutil/trace.go:171","msg":"trace[1394484643] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"300.879411ms","start":"2026-02-16T23:00:17.866319Z","end":"2026-02-16T23:00:18.167198Z","steps":["trace[1394484643] 'process raft request' (duration: 300.456049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:18.16737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:17.864306Z","time spent":"303.045981ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-16T23:00:18.167404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:17.866312Z","time spent":"301.064952ms","remote":"127.0.0.1:39886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-16T23:00:18.174099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.912756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-16T23:00:18.174208Z","caller":"traceutil/trace.go:171","msg":"trace[1184959098] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:314; }","duration":"284.051427ms","start":"2026-02-16T23:00:17.890147Z","end":"2026-02-16T23:00:18.174199Z","steps":["trace[1184959098] 'agreement among raft nodes before linearized reading' (duration: 283.905286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:18.174367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.380733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-16T23:00:18.174403Z","caller":"traceutil/trace.go:171","msg":"trace[824668212] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:314; }","duration":"133.441413ms","start":"2026-02-16T23:00:18.040953Z","end":"2026-02-16T23:00:18.174394Z","steps":["trace[824668212] 'agreement among raft nodes before linearized reading' (duration: 133.257342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:18.174494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.589885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-16T23:00:18.17456Z","caller":"traceutil/trace.go:171","msg":"trace[1192399229] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:314; }","duration":"180.668016ms","start":"2026-02-16T23:00:17.993886Z","end":"2026-02-16T23:00:18.174554Z","steps":["trace[1192399229] 'agreement among raft nodes before linearized reading' (duration: 180.491925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:18.17472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.956583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-16T23:00:18.174761Z","caller":"traceutil/trace.go:171","msg":"trace[964168151] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:314; }","duration":"235.016624ms","start":"2026-02-16T23:00:17.93974Z","end":"2026-02-16T23:00:18.174756Z","steps":["trace[964168151] 'agreement among raft nodes before linearized reading' (duration: 234.954973ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:18.175046Z","caller":"traceutil/trace.go:171","msg":"trace[991813745] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"202.558873ms","start":"2026-02-16T23:00:17.972482Z","end":"2026-02-16T23:00:18.175041Z","steps":["trace[991813745] 'process raft request' (duration: 201.524538ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:18.391096Z","caller":"traceutil/trace.go:171","msg":"trace[1238454700] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"186.989885ms","start":"2026-02-16T23:00:18.204064Z","end":"2026-02-16T23:00:18.391053Z","steps":["trace[1238454700] 'process raft request' (duration: 186.850875ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:18.391268Z","caller":"traceutil/trace.go:171","msg":"trace[1487290284] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"193.760278ms","start":"2026-02-16T23:00:18.197499Z","end":"2026-02-16T23:00:18.39126Z","steps":["trace[1487290284] 'process raft request' (duration: 103.813228ms)","trace[1487290284] 'compare' (duration: 89.354347ms)"],"step_count":2} {"level":"info","ts":"2026-02-16T23:00:18.391442Z","caller":"traceutil/trace.go:171","msg":"trace[831228920] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"191.252686ms","start":"2026-02-16T23:00:18.200183Z","end":"2026-02-16T23:00:18.391435Z","steps":["trace[831228920] 'process raft request' (duration: 190.658053ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:18.391794Z","caller":"traceutil/trace.go:171","msg":"trace[2146276188] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"183.582619ms","start":"2026-02-16T23:00:18.208205Z","end":"2026-02-16T23:00:18.391787Z","steps":["trace[2146276188] 'process raft request' (duration: 182.743905ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:18.391929Z","caller":"traceutil/trace.go:171","msg":"trace[661975030] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"183.68752ms","start":"2026-02-16T23:00:18.208236Z","end":"2026-02-16T23:00:18.391923Z","steps":["trace[661975030] 'process raft request' (duration: 182.740665ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:18.39219Z","caller":"traceutil/trace.go:171","msg":"trace[1170357486] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"183.86926ms","start":"2026-02-16T23:00:18.208313Z","end":"2026-02-16T23:00:18.392182Z","steps":["trace[1170357486] 'process raft request' (duration: 182.688574ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:18.392725Z","caller":"traceutil/trace.go:171","msg":"trace[1634771830] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"183.169306ms","start":"2026-02-16T23:00:18.209533Z","end":"2026-02-16T23:00:18.392702Z","steps":["trace[1634771830] 'process raft request' (duration: 183.020646ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:18.393459Z","caller":"traceutil/trace.go:171","msg":"trace[1831723791] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"128.399039ms","start":"2026-02-16T23:00:18.265043Z","end":"2026-02-16T23:00:18.393442Z","steps":["trace[1831723791] 'process raft request' (duration: 128.328069ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:18.39365Z","caller":"traceutil/trace.go:171","msg":"trace[570931561] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"177.457228ms","start":"2026-02-16T23:00:18.216182Z","end":"2026-02-16T23:00:18.393639Z","steps":["trace[570931561] 'process raft request' (duration: 176.467323ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:18.393702Z","caller":"traceutil/trace.go:171","msg":"trace[375597051] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:326; }","duration":"196.046389ms","start":"2026-02-16T23:00:18.197646Z","end":"2026-02-16T23:00:18.393693Z","steps":["trace[375597051] 'read index received' (duration: 65.023748ms)","trace[375597051] 'applied index is now lower than readState.Index' (duration: 131.021781ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:00:18.393766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.103609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:3548"} {"level":"info","ts":"2026-02-16T23:00:18.396885Z","caller":"traceutil/trace.go:171","msg":"trace[1959387812] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:324; }","duration":"199.249086ms","start":"2026-02-16T23:00:18.197623Z","end":"2026-02-16T23:00:18.396872Z","steps":["trace[1959387812] 'agreement among raft nodes before linearized reading' (duration: 196.09717ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:26.675832Z","caller":"traceutil/trace.go:171","msg":"trace[951727006] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"148.210537ms","start":"2026-02-16T23:00:26.527594Z","end":"2026-02-16T23:00:26.675805Z","steps":["trace[951727006] 'process raft request' (duration: 93.418031ms)","trace[951727006] 'compare' (duration: 54.612375ms)"],"step_count":2} {"level":"info","ts":"2026-02-16T23:00:26.677025Z","caller":"traceutil/trace.go:171","msg":"trace[139681206] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"128.779549ms","start":"2026-02-16T23:00:26.548237Z","end":"2026-02-16T23:00:26.677017Z","steps":["trace[139681206] 'process raft request' (duration: 128.714209ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:36.896711Z","caller":"traceutil/trace.go:171","msg":"trace[222427455] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"386.889088ms","start":"2026-02-16T23:00:36.509786Z","end":"2026-02-16T23:00:36.896675Z","steps":["trace[222427455] 'process raft request' (duration: 386.440826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:36.896836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:36.509769Z","time spent":"387.001478ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-16T23:00:39.907801Z","caller":"traceutil/trace.go:171","msg":"trace[1011024135] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"130.057906ms","start":"2026-02-16T23:00:39.777522Z","end":"2026-02-16T23:00:39.907579Z","steps":["trace[1011024135] 'process raft request' (duration: 129.936295ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:39.911101Z","caller":"traceutil/trace.go:171","msg":"trace[1956458082] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"130.059396ms","start":"2026-02-16T23:00:39.781029Z","end":"2026-02-16T23:00:39.911088Z","steps":["trace[1956458082] 'process raft request' (duration: 129.963486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:41.45083Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500112426094701331,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-16T23:00:41.663942Z","caller":"traceutil/trace.go:171","msg":"trace[345915801] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"713.385577ms","start":"2026-02-16T23:00:40.950537Z","end":"2026-02-16T23:00:41.663922Z","steps":["trace[345915801] 'read index received' (duration: 713.222166ms)","trace[345915801] 'applied index is now lower than readState.Index' (duration: 162.431µs)"],"step_count":2} {"level":"info","ts":"2026-02-16T23:00:41.664279Z","caller":"traceutil/trace.go:171","msg":"trace[1492981694] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"740.146984ms","start":"2026-02-16T23:00:40.924121Z","end":"2026-02-16T23:00:41.664268Z","steps":["trace[1492981694] 'process raft request' (duration: 739.693762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:41.664371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:40.924111Z","time spent":"740.196704ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-16T23:00:41.664601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.064659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-16T23:00:41.664637Z","caller":"traceutil/trace.go:171","msg":"trace[164174430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:471; }","duration":"714.129739ms","start":"2026-02-16T23:00:40.950496Z","end":"2026-02-16T23:00:41.664626Z","steps":["trace[164174430] 'agreement among raft nodes before linearized reading' (duration: 714.053069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:41.664667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:40.950485Z","time spent":"714.17307ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-16T23:00:41.664781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.943561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:00:41.664817Z","caller":"traceutil/trace.go:171","msg":"trace[409860012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"216.004092ms","start":"2026-02-16T23:00:41.448801Z","end":"2026-02-16T23:00:41.664806Z","steps":["trace[409860012] 'agreement among raft nodes before linearized reading' (duration: 215.950912ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:49.413219Z","caller":"traceutil/trace.go:171","msg":"trace[1990028039] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"883.924488ms","start":"2026-02-16T23:00:48.529271Z","end":"2026-02-16T23:00:49.413196Z","steps":["trace[1990028039] 'process raft request' (duration: 883.726337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:49.413385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:48.529249Z","time spent":"884.030538ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-16T23:00:49.413613Z","caller":"traceutil/trace.go:171","msg":"trace[1255151104] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:533; }","duration":"477.268925ms","start":"2026-02-16T23:00:48.936327Z","end":"2026-02-16T23:00:49.413596Z","steps":["trace[1255151104] 'read index received' (duration: 477.264565ms)","trace[1255151104] 'applied index is now lower than readState.Index' (duration: 3.17µs)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:00:49.413777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.443405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-29rst\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-16T23:00:49.413807Z","caller":"traceutil/trace.go:171","msg":"trace[80483226] range","detail":"{range_begin:/registry/pods/kube-system/cilium-29rst; range_end:; response_count:1; response_revision:514; }","duration":"477.498635ms","start":"2026-02-16T23:00:48.9363Z","end":"2026-02-16T23:00:49.413799Z","steps":["trace[80483226] 'agreement among raft nodes before linearized reading' (duration: 477.337965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:49.413836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:48.936286Z","time spent":"477.543375ms","remote":"127.0.0.1:39822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-29rst\" "} {"level":"warn","ts":"2026-02-16T23:00:49.9466Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500112426094701424,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-16T23:00:50.447273Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500112426094701424,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-16T23:00:50.470769Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.041825139s","expected-duration":"1s"} {"level":"info","ts":"2026-02-16T23:00:50.470998Z","caller":"traceutil/trace.go:171","msg":"trace[2117324304] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"1.025221065s","start":"2026-02-16T23:00:49.445761Z","end":"2026-02-16T23:00:50.470982Z","steps":["trace[2117324304] 'read index received' (duration: 1.025080595s)","trace[2117324304] 'applied index is now lower than readState.Index' (duration: 138.47µs)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:00:50.471142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025363446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:00:50.471202Z","caller":"traceutil/trace.go:171","msg":"trace[812645514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"1.025460276s","start":"2026-02-16T23:00:49.445724Z","end":"2026-02-16T23:00:50.471184Z","steps":["trace[812645514] 'agreement among raft nodes before linearized reading' (duration: 1.025342466s)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:50.471228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:49.445708Z","time spent":"1.025515926s","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-16T23:00:50.471329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.213524ms","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-16T23:00:50.471357Z","caller":"traceutil/trace.go:171","msg":"trace[311888409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:515; }","duration":"748.277044ms","start":"2026-02-16T23:00:49.723072Z","end":"2026-02-16T23:00:50.471349Z","steps":["trace[311888409] 'agreement among raft nodes before linearized reading' (duration: 748.138113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:50.471379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:49.723058Z","time spent":"748.314264ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-16T23:00:50.471407Z","caller":"traceutil/trace.go:171","msg":"trace[23562409] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.042553361s","start":"2026-02-16T23:00:49.428846Z","end":"2026-02-16T23:00:50.471399Z","steps":["trace[23562409] 'process raft request' (duration: 1.042038249s)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:50.471459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:49.42883Z","time spent":"1.042591241s","remote":"127.0.0.1:39822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-16T23:00:50.47164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.409724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-16T23:00:50.471649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.823501ms","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-16T23:00:50.471665Z","caller":"traceutil/trace.go:171","msg":"trace[1275853747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"428.868431ms","start":"2026-02-16T23:00:50.042792Z","end":"2026-02-16T23:00:50.47166Z","steps":["trace[1275853747] 'agreement among raft nodes before linearized reading' (duration: 428.797841ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:50.471664Z","caller":"traceutil/trace.go:171","msg":"trace[458109394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"403.492714ms","start":"2026-02-16T23:00:50.068165Z","end":"2026-02-16T23:00:50.471657Z","steps":["trace[458109394] 'agreement among raft nodes before linearized reading' (duration: 403.399004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:50.471678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:50.042777Z","time spent":"428.898501ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-16T23:00:50.471682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:50.068149Z","time spent":"403.529315ms","remote":"127.0.0.1:39900","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-16T23:00:50.471785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.874438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-16T23:00:50.471799Z","caller":"traceutil/trace.go:171","msg":"trace[1845539643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:515; }","duration":"704.907388ms","start":"2026-02-16T23:00:49.766887Z","end":"2026-02-16T23:00:50.471795Z","steps":["trace[1845539643] 'agreement among raft nodes before linearized reading' (duration: 704.859568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:50.471811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:49.766874Z","time spent":"704.934249ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-16T23:00:51.019192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:50.595191Z","time spent":"423.997682ms","remote":"127.0.0.1:42484","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-16T23:00:51.019539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.47837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-16T23:00:51.019653Z","caller":"traceutil/trace.go:171","msg":"trace[372004910] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:534; }","duration":"534.876572ms","start":"2026-02-16T23:00:50.484767Z","end":"2026-02-16T23:00:51.019644Z","steps":["trace[372004910] 'read index received' (duration: 254.257511ms)","trace[372004910] 'applied index is now lower than readState.Index' (duration: 280.618371ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:00:51.019692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.924382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:00:51.019715Z","caller":"traceutil/trace.go:171","msg":"trace[1834920007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"534.954912ms","start":"2026-02-16T23:00:50.484745Z","end":"2026-02-16T23:00:51.0197Z","steps":["trace[1834920007] 'agreement among raft nodes before linearized reading' (duration: 534.923462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:51.019735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:50.484738Z","time spent":"534.994612ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-16T23:00:51.019872Z","caller":"traceutil/trace.go:171","msg":"trace[1376605143] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"541.932158ms","start":"2026-02-16T23:00:50.477934Z","end":"2026-02-16T23:00:51.019866Z","steps":["trace[1376605143] 'process raft request' (duration: 261.082316ms)","trace[1376605143] 'compare' (duration: 280.113779ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:00:51.019905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:50.477923Z","time spent":"541.965908ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-16T23:00:51.019965Z","caller":"traceutil/trace.go:171","msg":"trace[2107029705] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"539.83911ms","start":"2026-02-16T23:00:50.480122Z","end":"2026-02-16T23:00:51.019961Z","steps":["trace[2107029705] 'process raft request' (duration: 539.453409ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:51.020665Z","caller":"traceutil/trace.go:171","msg":"trace[400840917] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"540.372952ms","start":"2026-02-16T23:00:50.48028Z","end":"2026-02-16T23:00:51.020653Z","steps":["trace[400840917] 'process raft request' (duration: 539.332798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:51.020712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:50.480274Z","time spent":"540.414182ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-16T23:00:51.021012Z","caller":"traceutil/trace.go:171","msg":"trace[1058292663] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"540.314582ms","start":"2026-02-16T23:00:50.480688Z","end":"2026-02-16T23:00:51.021002Z","steps":["trace[1058292663] 'process raft request' (duration: 538.939017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:51.02105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:50.48068Z","time spent":"540.348932ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-16T23:00:51.024225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:50.480103Z","time spent":"544.096646ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-16T23:00:52.948379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500112426094701434,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-16T23:00:53.090001Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.24838148s","expected-duration":"1s"} {"level":"info","ts":"2026-02-16T23:00:53.090304Z","caller":"traceutil/trace.go:171","msg":"trace[912088451] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"642.506071ms","start":"2026-02-16T23:00:52.447778Z","end":"2026-02-16T23:00:53.090284Z","steps":["trace[912088451] 'read index received' (duration: 642.327431ms)","trace[912088451] 'applied index is now lower than readState.Index' (duration: 177.52µs)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:00:53.090383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:51.841521Z","time spent":"1.248857513s","remote":"127.0.0.1:39670","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-16T23:00:53.090634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.841403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:00:53.090708Z","caller":"traceutil/trace.go:171","msg":"trace[135104252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"642.950303ms","start":"2026-02-16T23:00:52.447745Z","end":"2026-02-16T23:00:53.090695Z","steps":["trace[135104252] 'agreement among raft nodes before linearized reading' (duration: 642.848402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:53.090746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:52.447729Z","time spent":"643.008693ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-16T23:00:53.593283Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500112426094701438,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-16T23:00:53.794957Z","caller":"traceutil/trace.go:171","msg":"trace[1811960029] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"702.718235ms","start":"2026-02-16T23:00:53.092201Z","end":"2026-02-16T23:00:53.79492Z","steps":["trace[1811960029] 'process raft request' (duration: 646.306335ms)","trace[1811960029] 'compare' (duration: 56.22993ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:00:53.795043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:53.092186Z","time spent":"702.811855ms","remote":"127.0.0.1:39670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-16T23:00:54.140823Z","caller":"traceutil/trace.go:171","msg":"trace[1301796250] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:539; }","duration":"1.047681826s","start":"2026-02-16T23:00:53.093125Z","end":"2026-02-16T23:00:54.140807Z","steps":["trace[1301796250] 'read index received' (duration: 645.389091ms)","trace[1301796250] 'applied index is now lower than readState.Index' (duration: 402.292155ms)"],"step_count":2} {"level":"info","ts":"2026-02-16T23:00:54.14105Z","caller":"traceutil/trace.go:171","msg":"trace[373997767] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.044810446s","start":"2026-02-16T23:00:53.096233Z","end":"2026-02-16T23:00:54.141044Z","steps":["trace[373997767] 'process raft request' (duration: 1.003105852s)","trace[373997767] 'compare' (duration: 41.323173ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:00:54.141122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:53.096224Z","time spent":"1.044843026s","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-16T23:00:54.141235Z","caller":"traceutil/trace.go:171","msg":"trace[1191175369] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.044945817s","start":"2026-02-16T23:00:53.09628Z","end":"2026-02-16T23:00:54.141225Z","steps":["trace[1191175369] 'process raft request' (duration: 1.044472615s)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:54.141273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:53.096275Z","time spent":"1.044975127s","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-16T23:00:54.141367Z","caller":"traceutil/trace.go:171","msg":"trace[151659107] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.042706678s","start":"2026-02-16T23:00:53.098655Z","end":"2026-02-16T23:00:54.141362Z","steps":["trace[151659107] 'process raft request' (duration: 1.042118386s)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:54.141395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:53.098646Z","time spent":"1.042733528s","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-16T23:00:54.141447Z","caller":"traceutil/trace.go:171","msg":"trace[120158297] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.042742198s","start":"2026-02-16T23:00:53.098701Z","end":"2026-02-16T23:00:54.141443Z","steps":["trace[120158297] 'process raft request' (duration: 1.042087836s)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:54.141468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:53.098697Z","time spent":"1.042759579s","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-16T23:00:54.141529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048402389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:00:54.141544Z","caller":"traceutil/trace.go:171","msg":"trace[1093237306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"1.048436959s","start":"2026-02-16T23:00:53.093102Z","end":"2026-02-16T23:00:54.141539Z","steps":["trace[1093237306] 'agreement among raft nodes before linearized reading' (duration: 1.048410979s)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:54.141558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:53.093093Z","time spent":"1.048460889s","remote":"127.0.0.1:39648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-16T23:00:54.141638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.522133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-16T23:00:54.14165Z","caller":"traceutil/trace.go:171","msg":"trace[494388312] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:524; }","duration":"345.548283ms","start":"2026-02-16T23:00:53.796098Z","end":"2026-02-16T23:00:54.141647Z","steps":["trace[494388312] 'agreement among raft nodes before linearized reading' (duration: 345.519973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:54.141661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:53.796089Z","time spent":"345.569873ms","remote":"127.0.0.1:39812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-02-16T23:00:55.070939Z","caller":"traceutil/trace.go:171","msg":"trace[1842404092] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"100.50068ms","start":"2026-02-16T23:00:54.97041Z","end":"2026-02-16T23:00:55.07091Z","steps":["trace[1842404092] 'process raft request' (duration: 100.36941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:56.212852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.973361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-29rst\" ","response":"range_response_count:1 size:16916"} {"level":"info","ts":"2026-02-16T23:00:56.212908Z","caller":"traceutil/trace.go:171","msg":"trace[1738187933] range","detail":"{range_begin:/registry/pods/kube-system/cilium-29rst; range_end:; response_count:1; response_revision:529; }","duration":"259.062131ms","start":"2026-02-16T23:00:55.953834Z","end":"2026-02-16T23:00:56.212896Z","steps":["trace[1738187933] 'range keys from in-memory index tree' (duration: 258.896211ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:56.335927Z","caller":"traceutil/trace.go:171","msg":"trace[486216350] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"113.846318ms","start":"2026-02-16T23:00:56.222044Z","end":"2026-02-16T23:00:56.335891Z","steps":["trace[486216350] 'process raft request' (duration: 112.392422ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:56.336765Z","caller":"traceutil/trace.go:171","msg":"trace[1973665423] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"112.472223ms","start":"2026-02-16T23:00:56.224234Z","end":"2026-02-16T23:00:56.336706Z","steps":["trace[1973665423] 'process raft request' (duration: 112.415402ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:56.337093Z","caller":"traceutil/trace.go:171","msg":"trace[342040318] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"114.855052ms","start":"2026-02-16T23:00:56.222225Z","end":"2026-02-16T23:00:56.33708Z","steps":["trace[342040318] 'process raft request' (duration: 114.38601ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:56.337362Z","caller":"traceutil/trace.go:171","msg":"trace[1991396420] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"115.223833ms","start":"2026-02-16T23:00:56.222128Z","end":"2026-02-16T23:00:56.337352Z","steps":["trace[1991396420] 'process raft request' (duration: 112.902934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:57.835809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.178931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-16T23:00:57.835886Z","caller":"traceutil/trace.go:171","msg":"trace[1017181198] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:558; }","duration":"386.839172ms","start":"2026-02-16T23:00:57.449036Z","end":"2026-02-16T23:00:57.835875Z","steps":["trace[1017181198] 'read index received' (duration: 39.71µs)","trace[1017181198] 'applied index is now lower than readState.Index' (duration: 386.798622ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:00:57.835938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.904662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:00:57.835951Z","caller":"traceutil/trace.go:171","msg":"trace[569080525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"386.946713ms","start":"2026-02-16T23:00:57.448999Z","end":"2026-02-16T23:00:57.835945Z","steps":["trace[569080525] 'agreement among raft nodes before linearized reading' (duration: 386.901822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:57.835976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:57.448981Z","time spent":"386.990923ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-16T23:00:57.83611Z","caller":"traceutil/trace.go:171","msg":"trace[442535553] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"666.686925ms","start":"2026-02-16T23:00:57.169417Z","end":"2026-02-16T23:00:57.836104Z","steps":["trace[442535553] 'process raft request' (duration: 110.176413ms)","trace[442535553] 'compare' (duration: 555.9899ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:00:57.836139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:00:57.169401Z","time spent":"666.727815ms","remote":"127.0.0.1:39718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-16T23:00:58.116647Z","caller":"traceutil/trace.go:171","msg":"trace[1193061184] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"103.825458ms","start":"2026-02-16T23:00:58.012798Z","end":"2026-02-16T23:00:58.116623Z","steps":["trace[1193061184] 'process raft request' (duration: 103.693617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:58.790239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.829448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:00:58.790312Z","caller":"traceutil/trace.go:171","msg":"trace[432482684] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"125.954538ms","start":"2026-02-16T23:00:58.66434Z","end":"2026-02-16T23:00:58.790294Z","steps":["trace[432482684] 'agreement among raft nodes before linearized reading' (duration: 125.787257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:58.79099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.799746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:00:58.791041Z","caller":"traceutil/trace.go:171","msg":"trace[354077479] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"122.858927ms","start":"2026-02-16T23:00:58.668171Z","end":"2026-02-16T23:00:58.791029Z","steps":["trace[354077479] 'agreement among raft nodes before linearized reading' (duration: 122.795946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:58.791178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.979624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:00:58.791217Z","caller":"traceutil/trace.go:171","msg":"trace[759127510] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"125.028934ms","start":"2026-02-16T23:00:58.66618Z","end":"2026-02-16T23:00:58.791209Z","steps":["trace[759127510] 'agreement among raft nodes before linearized reading' (duration: 124.977134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:58.791344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.169665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:00:58.791371Z","caller":"traceutil/trace.go:171","msg":"trace[459537734] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"125.209195ms","start":"2026-02-16T23:00:58.666154Z","end":"2026-02-16T23:00:58.791363Z","steps":["trace[459537734] 'agreement among raft nodes before linearized reading' (duration: 125.170825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:00:58.791495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.338729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:00:58.791524Z","caller":"traceutil/trace.go:171","msg":"trace[1363199751] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"126.379859ms","start":"2026-02-16T23:00:58.665136Z","end":"2026-02-16T23:00:58.791516Z","steps":["trace[1363199751] 'agreement among raft nodes before linearized reading' (duration: 126.340989ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:59.560249Z","caller":"traceutil/trace.go:171","msg":"trace[1682577008] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"110.743201ms","start":"2026-02-16T23:00:59.449482Z","end":"2026-02-16T23:00:59.560225Z","steps":["trace[1682577008] 'read index received' (duration: 71.92487ms)","trace[1682577008] 'applied index is now lower than readState.Index' (duration: 38.817121ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:00:59.560383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.874552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:00:59.560412Z","caller":"traceutil/trace.go:171","msg":"trace[690015572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"110.986542ms","start":"2026-02-16T23:00:59.449417Z","end":"2026-02-16T23:00:59.560403Z","steps":["trace[690015572] 'agreement among raft nodes before linearized reading' (duration: 110.895941ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:59.560521Z","caller":"traceutil/trace.go:171","msg":"trace[474411917] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"149.627742ms","start":"2026-02-16T23:00:59.41087Z","end":"2026-02-16T23:00:59.560497Z","steps":["trace[474411917] 'process raft request' (duration: 110.52956ms)","trace[474411917] 'compare' (duration: 38.62513ms)"],"step_count":2} {"level":"info","ts":"2026-02-16T23:00:59.76415Z","caller":"traceutil/trace.go:171","msg":"trace[1222087198] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"185.324591ms","start":"2026-02-16T23:00:59.5788Z","end":"2026-02-16T23:00:59.764124Z","steps":["trace[1222087198] 'process raft request' (duration: 100.188403ms)","trace[1222087198] 'compare' (duration: 84.643477ms)"],"step_count":2} {"level":"info","ts":"2026-02-16T23:00:59.768611Z","caller":"traceutil/trace.go:171","msg":"trace[1830737012] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"128.039913ms","start":"2026-02-16T23:00:59.640558Z","end":"2026-02-16T23:00:59.768598Z","steps":["trace[1830737012] 'process raft request' (duration: 128.000163ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:00:59.768773Z","caller":"traceutil/trace.go:171","msg":"trace[134577933] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"159.976629ms","start":"2026-02-16T23:00:59.608792Z","end":"2026-02-16T23:00:59.768768Z","steps":["trace[134577933] 'process raft request' (duration: 159.710018ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:01:00.387676Z","caller":"traceutil/trace.go:171","msg":"trace[516638816] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"175.933595ms","start":"2026-02-16T23:01:00.211726Z","end":"2026-02-16T23:01:00.387659Z","steps":["trace[516638816] 'process raft request' (duration: 175.685844ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:01:15.618984Z","caller":"traceutil/trace.go:171","msg":"trace[1788795784] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"413.937281ms","start":"2026-02-16T23:01:15.205031Z","end":"2026-02-16T23:01:15.618969Z","steps":["trace[1788795784] 'process raft request' (duration: 413.80653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:01:15.619096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:01:15.205011Z","time spent":"414.033152ms","remote":"127.0.0.1:39820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4898,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-16T23:01:15.622167Z","caller":"traceutil/trace.go:171","msg":"trace[2064183641] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:752; }","duration":"172.830974ms","start":"2026-02-16T23:01:15.449327Z","end":"2026-02-16T23:01:15.622158Z","steps":["trace[2064183641] 'read index received' (duration: 169.579072ms)","trace[2064183641] 'applied index is now lower than readState.Index' (duration: 3.249992ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:01:15.622337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.006454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:01:15.622363Z","caller":"traceutil/trace.go:171","msg":"trace[1528025962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"173.060654ms","start":"2026-02-16T23:01:15.449294Z","end":"2026-02-16T23:01:15.622355Z","steps":["trace[1528025962] 'agreement among raft nodes before linearized reading' (duration: 172.941584ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:01:15.622549Z","caller":"traceutil/trace.go:171","msg":"trace[1127643433] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"329.630862ms","start":"2026-02-16T23:01:15.292912Z","end":"2026-02-16T23:01:15.622543Z","steps":["trace[1127643433] 'process raft request' (duration: 329.17103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:01:15.622591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:01:15.292896Z","time spent":"329.670202ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-16T23:01:26.775255Z","caller":"traceutil/trace.go:171","msg":"trace[120691172] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"109.464117ms","start":"2026-02-16T23:01:26.665762Z","end":"2026-02-16T23:01:26.775226Z","steps":["trace[120691172] 'process raft request' (duration: 16.447975ms)","trace[120691172] 'compare' (duration: 92.666001ms)"],"step_count":2} {"level":"info","ts":"2026-02-16T23:01:26.775464Z","caller":"traceutil/trace.go:171","msg":"trace[1170057917] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"106.212476ms","start":"2026-02-16T23:01:26.669235Z","end":"2026-02-16T23:01:26.775447Z","steps":["trace[1170057917] 'process raft request' (duration: 105.784264ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:01:26.775709Z","caller":"traceutil/trace.go:171","msg":"trace[937780335] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"105.420622ms","start":"2026-02-16T23:01:26.670276Z","end":"2026-02-16T23:01:26.775697Z","steps":["trace[937780335] 'process raft request' (duration: 105.111151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:03.244541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.941685ms","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-16T23:02:03.244611Z","caller":"traceutil/trace.go:171","msg":"trace[273431357] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:864; }","duration":"151.049205ms","start":"2026-02-16T23:02:03.093547Z","end":"2026-02-16T23:02:03.244596Z","steps":["trace[273431357] 'range keys from in-memory index tree' (duration: 150.827524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:03.244834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.967506ms","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-16T23:02:03.244877Z","caller":"traceutil/trace.go:171","msg":"trace[1746036285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:864; }","duration":"195.053176ms","start":"2026-02-16T23:02:03.049814Z","end":"2026-02-16T23:02:03.244867Z","steps":["trace[1746036285] 'range keys from in-memory index tree' (duration: 194.888545ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:02:06.723948Z","caller":"traceutil/trace.go:171","msg":"trace[515369004] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"212.667561ms","start":"2026-02-16T23:02:06.511261Z","end":"2026-02-16T23:02:06.723929Z","steps":["trace[515369004] 'process raft request' (duration: 211.384637ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:02:07.154318Z","caller":"traceutil/trace.go:171","msg":"trace[967447489] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"262.397771ms","start":"2026-02-16T23:02:06.891894Z","end":"2026-02-16T23:02:07.154292Z","steps":["trace[967447489] 'read index received' (duration: 255.425929ms)","trace[967447489] 'applied index is now lower than readState.Index' (duration: 6.970432ms)"],"step_count":2} {"level":"info","ts":"2026-02-16T23:02:07.154456Z","caller":"traceutil/trace.go:171","msg":"trace[703735985] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"422.148372ms","start":"2026-02-16T23:02:06.732268Z","end":"2026-02-16T23:02:07.154416Z","steps":["trace[703735985] 'process raft request' (duration: 415.03794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:07.155895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.912205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-16T23:02:07.156965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:06.732245Z","time spent":"422.244372ms","remote":"127.0.0.1:37630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2231,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-16T23:02:07.157198Z","caller":"traceutil/trace.go:171","msg":"trace[888272927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:885; }","duration":"265.150139ms","start":"2026-02-16T23:02:06.891856Z","end":"2026-02-16T23:02:07.157006Z","steps":["trace[888272927] 'agreement among raft nodes before linearized reading' (duration: 262.790502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:07.550016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.107267ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-16T23:02:07.550192Z","caller":"traceutil/trace.go:171","msg":"trace[1820630136] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:922; }","duration":"395.776327ms","start":"2026-02-16T23:02:07.154387Z","end":"2026-02-16T23:02:07.550163Z","steps":["trace[1820630136] 'read index received' (duration: 40.790421ms)","trace[1820630136] 'applied index is now lower than readState.Index' (duration: 354.984996ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:02:07.550488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.946741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:02:07.550521Z","caller":"traceutil/trace.go:171","msg":"trace[1553340396] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:885; }","duration":"499.990171ms","start":"2026-02-16T23:02:07.050521Z","end":"2026-02-16T23:02:07.550511Z","steps":["trace[1553340396] 'agreement among raft nodes before linearized reading' (duration: 499.926901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:07.550547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:07.050474Z","time spent":"500.065831ms","remote":"127.0.0.1:40124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-16T23:02:07.550688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.207151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:02:07.550754Z","caller":"traceutil/trace.go:171","msg":"trace[1790609381] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:885; }","duration":"584.292571ms","start":"2026-02-16T23:02:06.966444Z","end":"2026-02-16T23:02:07.550736Z","steps":["trace[1790609381] 'agreement among raft nodes before linearized reading' (duration: 584.170881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:07.550795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:06.966392Z","time spent":"584.393221ms","remote":"127.0.0.1:39844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-02-16T23:02:07.551295Z","caller":"traceutil/trace.go:171","msg":"trace[977312502] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"389.214896ms","start":"2026-02-16T23:02:07.162068Z","end":"2026-02-16T23:02:07.551283Z","steps":["trace[977312502] 'process raft request' (duration: 388.026832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:07.551662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:07.162052Z","time spent":"389.544838ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-16T23:02:07.898906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.079275ms","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-16T23:02:07.899059Z","caller":"traceutil/trace.go:171","msg":"trace[18717323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:886; }","duration":"570.272666ms","start":"2026-02-16T23:02:07.328765Z","end":"2026-02-16T23:02:07.899038Z","steps":["trace[18717323] 'agreement among raft nodes before linearized reading' (duration: 223.005384ms)","trace[18717323] 'range keys from in-memory index tree' (duration: 346.996631ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:02:07.899166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:07.328759Z","time spent":"570.386096ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-16T23:02:07.899568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.387932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-16T23:02:07.899951Z","caller":"traceutil/trace.go:171","msg":"trace[1185220744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:886; }","duration":"578.899964ms","start":"2026-02-16T23:02:07.321036Z","end":"2026-02-16T23:02:07.899936Z","steps":["trace[1185220744] 'agreement among raft nodes before linearized reading' (duration: 230.232728ms)","trace[1185220744] 'range keys from in-memory index tree' (duration: 348.233324ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:02:07.900072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:07.320973Z","time spent":"579.050974ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-16T23:02:07.900463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.014348ms","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-16T23:02:07.90056Z","caller":"traceutil/trace.go:171","msg":"trace[1436064719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:886; }","duration":"949.145379ms","start":"2026-02-16T23:02:06.951402Z","end":"2026-02-16T23:02:07.900547Z","steps":["trace[1436064719] 'agreement among raft nodes before linearized reading' (duration: 599.57565ms)","trace[1436064719] 'range keys from in-memory index tree' (duration: 349.417368ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:02:07.900686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:06.951384Z","time spent":"949.290999ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-16T23:02:07.901361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.117073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-43f01a6b-9bd7-441d-a0fa-735803b4f1a0\" ","response":"range_response_count:1 size:3535"} {"level":"info","ts":"2026-02-16T23:02:07.901909Z","caller":"traceutil/trace.go:171","msg":"trace[1172759431] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-43f01a6b-9bd7-441d-a0fa-735803b4f1a0; range_end:; response_count:1; response_revision:886; }","duration":"535.679645ms","start":"2026-02-16T23:02:07.366212Z","end":"2026-02-16T23:02:07.901892Z","steps":["trace[1172759431] 'agreement among raft nodes before linearized reading' (duration: 185.540024ms)","trace[1172759431] 'range keys from in-memory index tree' (duration: 349.543879ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:02:07.902011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:07.366202Z","time spent":"535.794615ms","remote":"127.0.0.1:39822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3558,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-43f01a6b-9bd7-441d-a0fa-735803b4f1a0\" "} {"level":"warn","ts":"2026-02-16T23:02:07.902306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.061363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:02:07.902387Z","caller":"traceutil/trace.go:171","msg":"trace[537262655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"454.174464ms","start":"2026-02-16T23:02:07.448201Z","end":"2026-02-16T23:02:07.902375Z","steps":["trace[537262655] 'agreement among raft nodes before linearized reading' (duration: 103.562651ms)","trace[537262655] 'range keys from in-memory index tree' (duration: 350.523842ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:02:07.902534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:07.448181Z","time spent":"454.310324ms","remote":"127.0.0.1:39634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-16T23:02:07.899585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.434246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-16T23:02:07.899811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.320088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-16T23:02:07.903409Z","caller":"traceutil/trace.go:171","msg":"trace[1841103414] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:886; }","duration":"299.94094ms","start":"2026-02-16T23:02:07.603422Z","end":"2026-02-16T23:02:07.903363Z","steps":["trace[1841103414] 'count revisions from in-memory index tree' (duration: 296.258688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:07.903503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:07.603407Z","time spent":"300.07729ms","remote":"127.0.0.1:37662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-02-16T23:02:07.902672Z","caller":"traceutil/trace.go:171","msg":"trace[77211783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:886; }","duration":"577.112958ms","start":"2026-02-16T23:02:07.325536Z","end":"2026-02-16T23:02:07.902649Z","steps":["trace[77211783] 'agreement among raft nodes before linearized reading' (duration: 226.240314ms)","trace[77211783] 'range keys from in-memory index tree' (duration: 347.128822ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:02:07.906536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:07.325527Z","time spent":"580.99667ms","remote":"127.0.0.1:39900","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-16T23:02:09.174519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:08.350973Z","time spent":"823.542674ms","remote":"127.0.0.1:50426","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-16T23:02:09.17527Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.244752831s","expected-duration":"1s"} {"level":"info","ts":"2026-02-16T23:02:09.175589Z","caller":"traceutil/trace.go:171","msg":"trace[518530728] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:925; }","duration":"261.243115ms","start":"2026-02-16T23:02:08.914329Z","end":"2026-02-16T23:02:09.175572Z","steps":["trace[518530728] 'read index received' (duration: 261.013895ms)","trace[518530728] 'applied index is now lower than readState.Index' (duration: 228.46µs)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:02:09.175764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.442956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-43f01a6b-9bd7-441d-a0fa-735803b4f1a0\" ","response":"range_response_count:1 size:3535"} {"level":"info","ts":"2026-02-16T23:02:09.175804Z","caller":"traceutil/trace.go:171","msg":"trace[215931368] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-43f01a6b-9bd7-441d-a0fa-735803b4f1a0; range_end:; response_count:1; response_revision:890; }","duration":"261.526146ms","start":"2026-02-16T23:02:08.914268Z","end":"2026-02-16T23:02:09.175794Z","steps":["trace[215931368] 'agreement among raft nodes before linearized reading' (duration: 261.369496ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:02:09.176461Z","caller":"traceutil/trace.go:171","msg":"trace[613007600] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"1.266642602s","start":"2026-02-16T23:02:07.909804Z","end":"2026-02-16T23:02:09.176447Z","steps":["trace[613007600] 'process raft request' (duration: 1.265592509s)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:09.176634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:07.909788Z","time spent":"1.266769223s","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-16T23:02:09.176845Z","caller":"traceutil/trace.go:171","msg":"trace[352394494] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"1.264487335s","start":"2026-02-16T23:02:07.912346Z","end":"2026-02-16T23:02:09.176833Z","steps":["trace[352394494] 'process raft request' (duration: 1.263161011s)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:09.176914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:07.912337Z","time spent":"1.264541866s","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-16T23:02:09.177046Z","caller":"traceutil/trace.go:171","msg":"trace[999779989] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"1.264403555s","start":"2026-02-16T23:02:07.912633Z","end":"2026-02-16T23:02:09.177036Z","steps":["trace[999779989] 'process raft request' (duration: 1.26290663s)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:09.177105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:07.912625Z","time spent":"1.264449035s","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-16T23:02:11.343297Z","caller":"traceutil/trace.go:171","msg":"trace[1731191885] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"150.804341ms","start":"2026-02-16T23:02:11.192419Z","end":"2026-02-16T23:02:11.343223Z","steps":["trace[1731191885] 'process raft request' (duration: 150.689101ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:02:11.34886Z","caller":"traceutil/trace.go:171","msg":"trace[1962809074] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:936; }","duration":"154.386073ms","start":"2026-02-16T23:02:11.194454Z","end":"2026-02-16T23:02:11.34884Z","steps":["trace[1962809074] 'read index received' (duration: 148.827626ms)","trace[1962809074] 'applied index is now lower than readState.Index' (duration: 5.557447ms)"],"step_count":2} {"level":"info","ts":"2026-02-16T23:02:11.349101Z","caller":"traceutil/trace.go:171","msg":"trace[1239082108] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"156.49934ms","start":"2026-02-16T23:02:11.192523Z","end":"2026-02-16T23:02:11.349022Z","steps":["trace[1239082108] 'process raft request' (duration: 156.103599ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:02:11.349404Z","caller":"traceutil/trace.go:171","msg":"trace[220915201] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"156.673741ms","start":"2026-02-16T23:02:11.192718Z","end":"2026-02-16T23:02:11.349392Z","steps":["trace[220915201] 'process raft request' (duration: 156.017929ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:02:11.349694Z","caller":"traceutil/trace.go:171","msg":"trace[1794522642] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"103.679081ms","start":"2026-02-16T23:02:11.245999Z","end":"2026-02-16T23:02:11.349678Z","steps":["trace[1794522642] 'process raft request' (duration: 102.777718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:11.350051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.569157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-43f01a6b-9bd7-441d-a0fa-735803b4f1a0\" ","response":"range_response_count:1 size:3639"} {"level":"info","ts":"2026-02-16T23:02:11.350525Z","caller":"traceutil/trace.go:171","msg":"trace[1565067153] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-43f01a6b-9bd7-441d-a0fa-735803b4f1a0; range_end:; response_count:1; response_revision:901; }","duration":"156.063919ms","start":"2026-02-16T23:02:11.194438Z","end":"2026-02-16T23:02:11.350502Z","steps":["trace[1565067153] 'agreement among raft nodes before linearized reading' (duration: 155.472207ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:02:16.751687Z","caller":"traceutil/trace.go:171","msg":"trace[737562638] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"259.381287ms","start":"2026-02-16T23:02:16.492224Z","end":"2026-02-16T23:02:16.751605Z","steps":["trace[737562638] 'process raft request' (duration: 259.226977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:21.59821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.125134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:02:21.598302Z","caller":"traceutil/trace.go:171","msg":"trace[575990155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"149.262115ms","start":"2026-02-16T23:02:21.449023Z","end":"2026-02-16T23:02:21.598285Z","steps":["trace[575990155] 'range keys from in-memory index tree' (duration: 149.057874ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:02:22.01022Z","caller":"traceutil/trace.go:171","msg":"trace[1138647535] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"163.956811ms","start":"2026-02-16T23:02:21.846243Z","end":"2026-02-16T23:02:22.0102Z","steps":["trace[1138647535] 'read index received' (duration: 109.459018ms)","trace[1138647535] 'applied index is now lower than readState.Index' (duration: 54.496793ms)"],"step_count":2} {"level":"info","ts":"2026-02-16T23:02:22.010823Z","caller":"traceutil/trace.go:171","msg":"trace[1143977418] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"320.813831ms","start":"2026-02-16T23:02:21.689994Z","end":"2026-02-16T23:02:22.010808Z","steps":["trace[1143977418] 'process raft request' (duration: 265.708486ms)","trace[1143977418] 'compare' (duration: 54.181802ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:02:22.011639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:21.689968Z","time spent":"320.894711ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-16T23:02:22.011962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.713117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-16T23:02:22.012002Z","caller":"traceutil/trace.go:171","msg":"trace[68048675] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:953; }","duration":"165.777407ms","start":"2026-02-16T23:02:21.846214Z","end":"2026-02-16T23:02:22.011991Z","steps":["trace[68048675] 'agreement among raft nodes before linearized reading' (duration: 165.656807ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:02:39.757822Z","caller":"traceutil/trace.go:171","msg":"trace[1851315501] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1049; }","duration":"162.916886ms","start":"2026-02-16T23:02:39.594881Z","end":"2026-02-16T23:02:39.757798Z","steps":["trace[1851315501] 'read index received' (duration: 162.723885ms)","trace[1851315501] 'applied index is now lower than readState.Index' (duration: 192.061µs)"],"step_count":2} {"level":"info","ts":"2026-02-16T23:02:39.758242Z","caller":"traceutil/trace.go:171","msg":"trace[1561834259] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"196.610712ms","start":"2026-02-16T23:02:39.561616Z","end":"2026-02-16T23:02:39.758227Z","steps":["trace[1561834259] 'process raft request' (duration: 196.04093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:39.758835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.935359ms","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-16T23:02:39.758893Z","caller":"traceutil/trace.go:171","msg":"trace[1890696449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1004; }","duration":"164.029279ms","start":"2026-02-16T23:02:39.594853Z","end":"2026-02-16T23:02:39.758882Z","steps":["trace[1890696449] 'agreement among raft nodes before linearized reading' (duration: 163.872739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:39.759939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.82529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-02-16T23:02:39.760128Z","caller":"traceutil/trace.go:171","msg":"trace[315542764] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/kube-root-ca.crt; range_end:; response_count:1; response_revision:1004; }","duration":"101.02462ms","start":"2026-02-16T23:02:39.659047Z","end":"2026-02-16T23:02:39.760072Z","steps":["trace[315542764] 'agreement among raft nodes before linearized reading' (duration: 100.341948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:40.347294Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500112426094703021,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-16T23:02:40.524464Z","caller":"traceutil/trace.go:171","msg":"trace[1583505655] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1051; }","duration":"678.203227ms","start":"2026-02-16T23:02:39.846233Z","end":"2026-02-16T23:02:40.524436Z","steps":["trace[1583505655] 'read index received' (duration: 677.960326ms)","trace[1583505655] 'applied index is now lower than readState.Index' (duration: 241.731µs)"],"step_count":2} {"level":"info","ts":"2026-02-16T23:02:40.524814Z","caller":"traceutil/trace.go:171","msg":"trace[2018065532] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"753.725696ms","start":"2026-02-16T23:02:39.771022Z","end":"2026-02-16T23:02:40.524747Z","steps":["trace[2018065532] 'process raft request' (duration: 753.358325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:40.52504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:39.771014Z","time spent":"753.901917ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-16T23:02:40.525361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.12159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-02-16T23:02:40.525733Z","caller":"traceutil/trace.go:171","msg":"trace[736953438] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/kube-root-ca.crt; range_end:; response_count:1; response_revision:1007; }","duration":"679.460471ms","start":"2026-02-16T23:02:39.8462Z","end":"2026-02-16T23:02:40.52566Z","steps":["trace[736953438] 'agreement among raft nodes before linearized reading' (duration: 679.08536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:40.52599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:39.846185Z","time spent":"679.785252ms","remote":"127.0.0.1:39756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1777,"request content":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" "} {"level":"info","ts":"2026-02-16T23:02:40.524479Z","caller":"traceutil/trace.go:171","msg":"trace[1240332857] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"754.722289ms","start":"2026-02-16T23:02:39.769727Z","end":"2026-02-16T23:02:40.52445Z","steps":["trace[1240332857] 'process raft request' (duration: 754.488529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:40.526334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.058398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/\" range_end:\"/registry/services/endpoints/default0\" limit:500 ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-16T23:02:40.526996Z","caller":"traceutil/trace.go:171","msg":"trace[1908084646] range","detail":"{range_begin:/registry/services/endpoints/default/; range_end:/registry/services/endpoints/default0; response_count:1; response_revision:1007; }","duration":"631.76353ms","start":"2026-02-16T23:02:39.895218Z","end":"2026-02-16T23:02:40.526981Z","steps":["trace[1908084646] 'agreement among raft nodes before linearized reading' (duration: 631.000378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:40.52725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:39.895202Z","time spent":"631.983251ms","remote":"127.0.0.1:39812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/\" range_end:\"/registry/services/endpoints/default0\" limit:500 "} {"level":"warn","ts":"2026-02-16T23:02:40.52776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:39.769723Z","time spent":"757.96139ms","remote":"127.0.0.1:39900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-16T23:02:40.526691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.518715ms","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-16T23:02:40.528266Z","caller":"traceutil/trace.go:171","msg":"trace[668255894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1007; }","duration":"316.12744ms","start":"2026-02-16T23:02:40.212125Z","end":"2026-02-16T23:02:40.528252Z","steps":["trace[668255894] 'agreement among raft nodes before linearized reading' (duration: 314.475335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:40.528765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:40.212106Z","time spent":"316.643272ms","remote":"127.0.0.1:39900","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-16T23:02:40.526737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.300063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/default/\" range_end:\"/registry/ingress/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:02:40.529022Z","caller":"traceutil/trace.go:171","msg":"trace[1960377042] range","detail":"{range_begin:/registry/ingress/default/; range_end:/registry/ingress/default0; response_count:0; response_revision:1007; }","duration":"631.59879ms","start":"2026-02-16T23:02:39.897415Z","end":"2026-02-16T23:02:40.529014Z","steps":["trace[1960377042] 'agreement among raft nodes before linearized reading' (duration: 629.301013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:40.529294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-16T23:02:39.897409Z","time spent":"631.874441ms","remote":"127.0.0.1:39936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/default/\" range_end:\"/registry/ingress/default0\" limit:500 "} {"level":"info","ts":"2026-02-16T23:02:40.66973Z","caller":"traceutil/trace.go:171","msg":"trace[1030697671] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"127.459363ms","start":"2026-02-16T23:02:40.542252Z","end":"2026-02-16T23:02:40.669712Z","steps":["trace[1030697671] 'read index received' (duration: 109.293776ms)","trace[1030697671] 'applied index is now lower than readState.Index' (duration: 18.164947ms)"],"step_count":2} {"level":"info","ts":"2026-02-16T23:02:40.669821Z","caller":"traceutil/trace.go:171","msg":"trace[1794375762] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"133.131241ms","start":"2026-02-16T23:02:40.536679Z","end":"2026-02-16T23:02:40.669811Z","steps":["trace[1794375762] 'process raft request' (duration: 114.909034ms)","trace[1794375762] 'compare' (duration: 18.055817ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:02:40.670022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.752914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-node-lease/\" range_end:\"/registry/events/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:02:40.670072Z","caller":"traceutil/trace.go:171","msg":"trace[178015185] range","detail":"{range_begin:/registry/events/kube-node-lease/; range_end:/registry/events/kube-node-lease0; response_count:0; response_revision:1008; }","duration":"127.842484ms","start":"2026-02-16T23:02:40.542216Z","end":"2026-02-16T23:02:40.670059Z","steps":["trace[178015185] 'agreement among raft nodes before linearized reading' (duration: 127.772574ms)"],"step_count":1} {"level":"info","ts":"2026-02-16T23:02:42.908195Z","caller":"traceutil/trace.go:171","msg":"trace[323279735] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"137.548535ms","start":"2026-02-16T23:02:42.770624Z","end":"2026-02-16T23:02:42.908172Z","steps":["trace[323279735] 'read index received' (duration: 81.504507ms)","trace[323279735] 'applied index is now lower than readState.Index' (duration: 56.043078ms)"],"step_count":2} {"level":"info","ts":"2026-02-16T23:02:42.908567Z","caller":"traceutil/trace.go:171","msg":"trace[237938498] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"183.978042ms","start":"2026-02-16T23:02:42.72457Z","end":"2026-02-16T23:02:42.908548Z","steps":["trace[237938498] 'process raft request' (duration: 127.547173ms)","trace[237938498] 'compare' (duration: 55.904107ms)"],"step_count":2} {"level":"warn","ts":"2026-02-16T23:02:42.908867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.271411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-16T23:02:42.909048Z","caller":"traceutil/trace.go:171","msg":"trace[161540931] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-public/; range_end:/registry/persistentvolumeclaims/kube-public0; response_count:0; response_revision:1015; }","duration":"139.488091ms","start":"2026-02-16T23:02:42.769542Z","end":"2026-02-16T23:02:42.90903Z","steps":["trace[161540931] 'agreement among raft nodes before linearized reading' (duration: 139.271551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-16T23:02:42.909479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.96842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-node-lease/default\" ","response":"range_response_count:1 size:187"} {"level":"info","ts":"2026-02-16T23:02:42.909995Z","caller":"traceutil/trace.go:171","msg":"trace[947245542] range","detail":"{range_begin:/registry/serviceaccounts/kube-node-lease/default; range_end:; response_count:1; response_revision:1015; }","duration":"136.503551ms","start":"2026-02-16T23:02:42.773474Z","end":"2026-02-16T23:02:42.909978Z","steps":["trace[947245542] 'agreement among raft nodes before linearized reading' (duration: 135.95637ms)"],"step_count":1}