{"level":"warn","ts":"2026-02-20T21:31:53.257202Z","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-20T21:31:53.257319Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.74: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://162.253.55.74:2380","--initial-cluster=instance=https://162.253.55.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.74: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-20T21:31:53.257412Z","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-20T21:31:53.257421Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-02-20T21:31:53.257444Z","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-20T21:31:53.258263Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"]} {"level":"info","ts":"2026-02-20T21:31:53.258366Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.74: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-20T21:31:53.26349Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.832321ms"} {"level":"info","ts":"2026-02-20T21:31:53.276469Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30aa8136fb61a722","cluster-id":"e7ea3889c4872560"} {"level":"info","ts":"2026-02-20T21:31:53.276545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=()"} {"level":"info","ts":"2026-02-20T21:31:53.27657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 0"} {"level":"info","ts":"2026-02-20T21:31:53.27659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30aa8136fb61a722 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-20T21:31:53.276608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 1"} {"level":"info","ts":"2026-02-20T21:31:53.276655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"warn","ts":"2026-02-20T21:31:53.28584Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-20T21:31:53.287409Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-20T21:31:53.289247Z","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-20T21:31:53.291868Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30aa8136fb61a722","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-20T21:31:53.293973Z","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-20T21:31:53.294102Z","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-20T21:31:53.294138Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30aa8136fb61a722","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-20T21:31:53.294383Z","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-20T21:31:53.294889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"info","ts":"2026-02-20T21:31:53.295116Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","added-peer-id":"30aa8136fb61a722","added-peer-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-02-20T21:31:53.299833Z","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-20T21:31:53.299948Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-20T21:31:53.300057Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-20T21:31:53.300184Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30aa8136fb61a722","initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-20T21:31:53.300221Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-20T21:31:53.577558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 is starting a new election at term 1"} {"level":"info","ts":"2026-02-20T21:31:53.577632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-20T21:31:53.577711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgPreVoteResp from 30aa8136fb61a722 at term 1"} {"level":"info","ts":"2026-02-20T21:31:53.577765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became candidate at term 2"} {"level":"info","ts":"2026-02-20T21:31:53.577786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgVoteResp from 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-20T21:31:53.577832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became leader at term 2"} {"level":"info","ts":"2026-02-20T21:31:53.577853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30aa8136fb61a722 elected leader 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-20T21:31:53.586431Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T21:31:53.589103Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30aa8136fb61a722","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.74:2379]}","request-path":"/0/members/30aa8136fb61a722/attributes","cluster-id":"e7ea3889c4872560","publish-timeout":"7s"} {"level":"info","ts":"2026-02-20T21:31:53.5892Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T21:31:53.589422Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T21:31:53.590072Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-20T21:31:53.590146Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-20T21:31:53.59213Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T21:31:53.592306Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T21:31:53.592387Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T21:31:53.593286Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.74:2379"} {"level":"info","ts":"2026-02-20T21:31:53.593317Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-20T21:31:56.82937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.448236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-20T21:31:56.829496Z","caller":"traceutil/trace.go:171","msg":"trace[164534918] transaction","detail":"{read_only:false; response_revision:123; number_of_response:1; }","duration":"223.076213ms","start":"2026-02-20T21:31:56.606399Z","end":"2026-02-20T21:31:56.829475Z","steps":["trace[164534918] 'process raft request' (duration: 114.028065ms)","trace[164534918] 'compare' (duration: 108.322302ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:31:57.039406Z","caller":"traceutil/trace.go:171","msg":"trace[1453615742] transaction","detail":"{read_only:false; response_revision:127; number_of_response:1; }","duration":"133.574643ms","start":"2026-02-20T21:31:56.905803Z","end":"2026-02-20T21:31:57.039378Z","steps":["trace[1453615742] 'process raft request' (duration: 120.892141ms)","trace[1453615742] 'compare' (duration: 12.491955ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:31:57.204855Z","caller":"traceutil/trace.go:171","msg":"trace[457707770] transaction","detail":"{read_only:false; response_revision:129; number_of_response:1; }","duration":"146.395815ms","start":"2026-02-20T21:31:57.05844Z","end":"2026-02-20T21:31:57.204836Z","steps":["trace[457707770] 'process raft request' (duration: 106.343106ms)","trace[457707770] 'compare' (duration: 39.933804ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:32:04.057453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360414082416366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T21:32:04.55863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360414082416366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T21:32:04.571525Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.091996748s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T21:32:04.571848Z","caller":"traceutil/trace.go:171","msg":"trace[678288856] linearizableReadLoop","detail":"{readStateIndex:343; appliedIndex:342; }","duration":"1.01546431s","start":"2026-02-20T21:32:03.556363Z","end":"2026-02-20T21:32:04.571827Z","steps":["trace[678288856] 'read index received' (duration: 1.015308845s)","trace[678288856] 'applied index is now lower than readState.Index' (duration: 154.595µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:32:04.571888Z","caller":"traceutil/trace.go:171","msg":"trace[749234559] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"1.092473416s","start":"2026-02-20T21:32:03.47939Z","end":"2026-02-20T21:32:04.571864Z","steps":["trace[749234559] 'process raft request' (duration: 1.09231735s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:04.57205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015621486s","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-20T21:32:04.572098Z","caller":"traceutil/trace.go:171","msg":"trace[1972325450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:332; }","duration":"1.015786001s","start":"2026-02-20T21:32:03.556299Z","end":"2026-02-20T21:32:04.572085Z","steps":["trace[1972325450] 'agreement among raft nodes before linearized reading' (duration: 1.015614455s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:04.57205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:03.47935Z","time spent":"1.092596179s","remote":"127.0.0.1:51288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":186,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T21:32:04.572147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:03.556271Z","time spent":"1.015866714s","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T21:32:04.57222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.206448ms","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-20T21:32:04.57229Z","caller":"traceutil/trace.go:171","msg":"trace[1165871419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:332; }","duration":"984.292812ms","start":"2026-02-20T21:32:03.58797Z","end":"2026-02-20T21:32:04.572262Z","steps":["trace[1165871419] 'agreement among raft nodes before linearized reading' (duration: 984.131836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:04.572271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.718446ms","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-20T21:32:04.572334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:03.587962Z","time spent":"984.363934ms","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-20T21:32:04.572355Z","caller":"traceutil/trace.go:171","msg":"trace[778382079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:332; }","duration":"994.85284ms","start":"2026-02-20T21:32:03.577482Z","end":"2026-02-20T21:32:04.572335Z","steps":["trace[778382079] 'agreement among raft nodes before linearized reading' (duration: 994.615602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:04.572423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:03.577466Z","time spent":"994.947584ms","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-20T21:32:10.896286Z","caller":"traceutil/trace.go:171","msg":"trace[1623722955] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"222.052455ms","start":"2026-02-20T21:32:10.674208Z","end":"2026-02-20T21:32:10.89626Z","steps":["trace[1623722955] 'read index received' (duration: 221.826517ms)","trace[1623722955] 'applied index is now lower than readState.Index' (duration: 224.988µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:32:10.896652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.404349ms","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-20T21:32:10.896713Z","caller":"traceutil/trace.go:171","msg":"trace[2003357500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:412; }","duration":"222.532083ms","start":"2026-02-20T21:32:10.674169Z","end":"2026-02-20T21:32:10.896701Z","steps":["trace[2003357500] 'agreement among raft nodes before linearized reading' (duration: 222.321415ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:32:10.896635Z","caller":"traceutil/trace.go:171","msg":"trace[1348889833] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"875.473199ms","start":"2026-02-20T21:32:10.021137Z","end":"2026-02-20T21:32:10.89661Z","steps":["trace[1348889833] 'process raft request' (duration: 874.95154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:10.896762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.992541ms","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-20T21:32:10.896828Z","caller":"traceutil/trace.go:171","msg":"trace[646310337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:412; }","duration":"220.090994ms","start":"2026-02-20T21:32:10.676721Z","end":"2026-02-20T21:32:10.896812Z","steps":["trace[646310337] 'agreement among raft nodes before linearized reading' (duration: 219.899527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:10.89697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.589945ms","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-20T21:32:10.897077Z","caller":"traceutil/trace.go:171","msg":"trace[1787512387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:412; }","duration":"222.701159ms","start":"2026-02-20T21:32:10.67434Z","end":"2026-02-20T21:32:10.897041Z","steps":["trace[1787512387] 'agreement among raft nodes before linearized reading' (duration: 222.199371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:10.897315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:10.021111Z","time spent":"875.680186ms","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:32:12.280969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115046851s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T21:32:12.281314Z","caller":"traceutil/trace.go:171","msg":"trace[118457612] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"1.380048747s","start":"2026-02-20T21:32:10.901231Z","end":"2026-02-20T21:32:12.28128Z","steps":["trace[118457612] 'process raft request' (duration: 264.335855ms)","trace[118457612] 'compare' (duration: 1.11481278s)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:32:12.28134Z","caller":"traceutil/trace.go:171","msg":"trace[1466481796] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"1.380039127s","start":"2026-02-20T21:32:10.90125Z","end":"2026-02-20T21:32:12.281289Z","steps":["trace[1466481796] 'process raft request' (duration: 1.379822456s)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:32:12.281408Z","caller":"traceutil/trace.go:171","msg":"trace[1450088257] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"1.380050534s","start":"2026-02-20T21:32:10.901328Z","end":"2026-02-20T21:32:12.281378Z","steps":["trace[1450088257] 'process raft request' (duration: 1.379806923s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:12.281506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:10.901213Z","time spent":"1.380190608s","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:32:12.281536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:10.901308Z","time spent":"1.380170685s","remote":"127.0.0.1:51352","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-20T21:32:12.281567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:10.901224Z","time spent":"1.380214158s","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T21:32:12.28238Z","caller":"traceutil/trace.go:171","msg":"trace[1216702206] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:425; }","duration":"1.005892149s","start":"2026-02-20T21:32:11.276417Z","end":"2026-02-20T21:32:12.282309Z","steps":["trace[1216702206] 'read index received' (duration: 61.582µs)","trace[1216702206] 'applied index is now lower than readState.Index' (duration: 1.005829457s)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:32:12.282394Z","caller":"traceutil/trace.go:171","msg":"trace[918295448] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"1.381012575s","start":"2026-02-20T21:32:10.901332Z","end":"2026-02-20T21:32:12.282345Z","steps":["trace[918295448] 'process raft request' (duration: 1.379995554s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:12.282612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:10.90131Z","time spent":"1.381254956s","remote":"127.0.0.1:51352","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-20T21:32:12.282495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:32:12.282901Z","caller":"traceutil/trace.go:171","msg":"trace[251590704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"1.006534824s","start":"2026-02-20T21:32:11.276344Z","end":"2026-02-20T21:32:12.282879Z","steps":["trace[251590704] 'agreement among raft nodes before linearized reading' (duration: 1.006107002s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:12.282962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:11.276314Z","time spent":"1.006633775s","remote":"127.0.0.1:51074","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-20T21:32:21.440775Z","caller":"traceutil/trace.go:171","msg":"trace[2087191532] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"314.41423ms","start":"2026-02-20T21:32:21.126339Z","end":"2026-02-20T21:32:21.440754Z","steps":["trace[2087191532] 'process raft request' (duration: 314.280749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:21.440936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:21.126306Z","time spent":"314.53179ms","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:32:22.834917Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360414082416656,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T21:32:22.903693Z","caller":"traceutil/trace.go:171","msg":"trace[1485831793] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"574.42392ms","start":"2026-02-20T21:32:22.329225Z","end":"2026-02-20T21:32:22.903649Z","steps":["trace[1485831793] 'process raft request' (duration: 574.27557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:22.90387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:22.329209Z","time spent":"574.576661ms","remote":"127.0.0.1:51352","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-20T21:32:22.910244Z","caller":"traceutil/trace.go:171","msg":"trace[1127807832] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:483; }","duration":"576.329412ms","start":"2026-02-20T21:32:22.333886Z","end":"2026-02-20T21:32:22.910215Z","steps":["trace[1127807832] 'read index received' (duration: 569.72402ms)","trace[1127807832] 'applied index is now lower than readState.Index' (duration: 6.604232ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:32:22.910367Z","caller":"traceutil/trace.go:171","msg":"trace[2090817656] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"577.346698ms","start":"2026-02-20T21:32:22.332988Z","end":"2026-02-20T21:32:22.910335Z","steps":["trace[2090817656] 'process raft request' (duration: 577.097927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:22.910418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.248326ms","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-20T21:32:22.910461Z","caller":"traceutil/trace.go:171","msg":"trace[64591632] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:467; }","duration":"575.350886ms","start":"2026-02-20T21:32:22.335096Z","end":"2026-02-20T21:32:22.910447Z","steps":["trace[64591632] 'agreement among raft nodes before linearized reading' (duration: 575.257715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:22.910445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.576713ms","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-20T21:32:22.910499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:22.335078Z","time spent":"575.412546ms","remote":"127.0.0.1:51352","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-20T21:32:22.910508Z","caller":"traceutil/trace.go:171","msg":"trace[1684748236] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:467; }","duration":"576.689923ms","start":"2026-02-20T21:32:22.333803Z","end":"2026-02-20T21:32:22.910493Z","steps":["trace[1684748236] 'agreement among raft nodes before linearized reading' (duration: 576.563123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:22.910533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:22.332961Z","time spent":"577.47479ms","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:32:22.910545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:22.33377Z","time spent":"576.766715ms","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-20T21:32:29.614924Z","caller":"traceutil/trace.go:171","msg":"trace[448401039] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"436.320839ms","start":"2026-02-20T21:32:29.178571Z","end":"2026-02-20T21:32:29.614892Z","steps":["trace[448401039] 'process raft request' (duration: 436.165327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:29.615087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:29.178468Z","time spent":"436.539301ms","remote":"127.0.0.1:51254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:32:31.45315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360414082416771,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T21:32:31.788896Z","caller":"traceutil/trace.go:171","msg":"trace[1983629090] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"842.667216ms","start":"2026-02-20T21:32:30.946204Z","end":"2026-02-20T21:32:31.788871Z","steps":["trace[1983629090] 'process raft request' (duration: 842.533365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:31.789065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:30.946187Z","time spent":"842.788327ms","remote":"127.0.0.1:51352","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-20T21:32:31.793746Z","caller":"traceutil/trace.go:171","msg":"trace[1368512461] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"844.983309ms","start":"2026-02-20T21:32:30.948748Z","end":"2026-02-20T21:32:31.793731Z","steps":["trace[1368512461] 'process raft request' (duration: 844.870978ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:32:31.793997Z","caller":"traceutil/trace.go:171","msg":"trace[968581217] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"184.60922ms","start":"2026-02-20T21:32:31.60937Z","end":"2026-02-20T21:32:31.793979Z","steps":["trace[968581217] 'process raft request' (duration: 184.326756ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:32:31.79399Z","caller":"traceutil/trace.go:171","msg":"trace[318061132] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"841.876551ms","start":"2026-02-20T21:32:30.952094Z","end":"2026-02-20T21:32:31.79397Z","steps":["trace[318061132] 'read index received' (duration: 836.891701ms)","trace[318061132] 'applied index is now lower than readState.Index' (duration: 4.98345ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:32:31.794219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.675761ms","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-20T21:32:31.794238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.394752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:32:31.794314Z","caller":"traceutil/trace.go:171","msg":"trace[764840707] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:522; }","duration":"837.793962ms","start":"2026-02-20T21:32:30.956497Z","end":"2026-02-20T21:32:31.794291Z","steps":["trace[764840707] 'agreement among raft nodes before linearized reading' (duration: 837.62165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:31.794214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:30.948731Z","time spent":"845.109561ms","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:32:31.794453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.360445ms","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-20T21:32:31.794496Z","caller":"traceutil/trace.go:171","msg":"trace[35747723] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:522; }","duration":"842.420357ms","start":"2026-02-20T21:32:30.952063Z","end":"2026-02-20T21:32:31.794483Z","steps":["trace[35747723] 'agreement among raft nodes before linearized reading' (duration: 842.354236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:31.794525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:30.952054Z","time spent":"842.465307ms","remote":"127.0.0.1:51352","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-20T21:32:31.794366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:30.95649Z","time spent":"837.867492ms","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-20T21:32:31.794377Z","caller":"traceutil/trace.go:171","msg":"trace[2121211041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"514.571283ms","start":"2026-02-20T21:32:31.279779Z","end":"2026-02-20T21:32:31.79435Z","steps":["trace[2121211041] 'agreement among raft nodes before linearized reading' (duration: 514.363581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:31.794687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:31.279765Z","time spent":"514.913316ms","remote":"127.0.0.1:51074","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-20T21:32:37.979658Z","caller":"traceutil/trace.go:171","msg":"trace[1628414672] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"161.56932ms","start":"2026-02-20T21:32:37.818063Z","end":"2026-02-20T21:32:37.979632Z","steps":["trace[1628414672] 'process raft request' (duration: 161.373078ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:32:38.23965Z","caller":"traceutil/trace.go:171","msg":"trace[1791461059] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:579; }","duration":"403.288003ms","start":"2026-02-20T21:32:37.83634Z","end":"2026-02-20T21:32:38.239628Z","steps":["trace[1791461059] 'read index received' (duration: 143.122849ms)","trace[1791461059] 'applied index is now lower than readState.Index' (duration: 260.164164ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:32:38.239728Z","caller":"traceutil/trace.go:171","msg":"trace[1695637951] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"420.169906ms","start":"2026-02-20T21:32:37.819522Z","end":"2026-02-20T21:32:38.239692Z","steps":["trace[1695637951] 'process raft request' (duration: 419.987923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:38.239818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.457086ms","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-20T21:32:38.23988Z","caller":"traceutil/trace.go:171","msg":"trace[1959571169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"403.539507ms","start":"2026-02-20T21:32:37.83631Z","end":"2026-02-20T21:32:38.239849Z","steps":["trace[1959571169] 'agreement among raft nodes before linearized reading' (duration: 403.452126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:38.239916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:37.836295Z","time spent":"403.613337ms","remote":"127.0.0.1:51352","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-20T21:32:38.239935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:37.819498Z","time spent":"420.321077ms","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:32:38.240012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.210199ms","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-20T21:32:38.240095Z","caller":"traceutil/trace.go:171","msg":"trace[1998654931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"401.335891ms","start":"2026-02-20T21:32:37.838736Z","end":"2026-02-20T21:32:38.240072Z","steps":["trace[1998654931] 'agreement among raft nodes before linearized reading' (duration: 401.20742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:38.240136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:37.838717Z","time spent":"401.410852ms","remote":"127.0.0.1:51352","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-20T21:32:38.240314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.447617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-f9hhm\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-20T21:32:38.240382Z","caller":"traceutil/trace.go:171","msg":"trace[383890873] range","detail":"{range_begin:/registry/pods/kube-system/cilium-f9hhm; range_end:; response_count:1; response_revision:560; }","duration":"190.551199ms","start":"2026-02-20T21:32:38.049807Z","end":"2026-02-20T21:32:38.240358Z","steps":["trace[383890873] 'agreement among raft nodes before linearized reading' (duration: 190.333426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:38.822003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360414082416851,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T21:32:39.163156Z","caller":"traceutil/trace.go:171","msg":"trace[1776144085] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:582; }","duration":"841.565229ms","start":"2026-02-20T21:32:38.321573Z","end":"2026-02-20T21:32:39.163139Z","steps":["trace[1776144085] 'read index received' (duration: 841.379227ms)","trace[1776144085] 'applied index is now lower than readState.Index' (duration: 185.492µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:32:39.163292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.681301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-20T21:32:39.163325Z","caller":"traceutil/trace.go:171","msg":"trace[1679199950] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:563; }","duration":"841.775831ms","start":"2026-02-20T21:32:38.321533Z","end":"2026-02-20T21:32:39.163309Z","steps":["trace[1679199950] 'agreement among raft nodes before linearized reading' (duration: 841.67844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:39.163347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:38.321515Z","time spent":"841.826082ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.74\" "} {"level":"info","ts":"2026-02-20T21:32:39.163421Z","caller":"traceutil/trace.go:171","msg":"trace[156822436] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"919.316513ms","start":"2026-02-20T21:32:38.244069Z","end":"2026-02-20T21:32:39.163386Z","steps":["trace[156822436] 'process raft request' (duration: 918.926409ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:32:39.163485Z","caller":"traceutil/trace.go:171","msg":"trace[708882119] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"909.809646ms","start":"2026-02-20T21:32:38.253645Z","end":"2026-02-20T21:32:39.163455Z","steps":["trace[708882119] 'process raft request' (duration: 909.441182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:39.163784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:38.253619Z","time spent":"910.07019ms","remote":"127.0.0.1:51264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:32:39.163607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:38.244052Z","time spent":"919.460205ms","remote":"127.0.0.1:51352","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-20T21:32:39.52461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.290619ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-20T21:32:39.524729Z","caller":"traceutil/trace.go:171","msg":"trace[670534616] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"264.794625ms","start":"2026-02-20T21:32:39.259918Z","end":"2026-02-20T21:32:39.524712Z","steps":["trace[670534616] 'read index received' (duration: 377.834µs)","trace[670534616] 'applied index is now lower than readState.Index' (duration: 264.415291ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:32:39.524751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:39.164622Z","time spent":"360.119845ms","remote":"127.0.0.1:51098","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-20T21:32:39.524816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.872285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:32:39.52485Z","caller":"traceutil/trace.go:171","msg":"trace[1948063823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"264.967177ms","start":"2026-02-20T21:32:39.259874Z","end":"2026-02-20T21:32:39.524842Z","steps":["trace[1948063823] 'agreement among raft nodes before linearized reading' (duration: 264.886246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:41.525979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999885738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-20T21:32:41.526127Z","caller":"traceutil/trace.go:171","msg":"trace[1127942769] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00007343s","start":"2026-02-20T21:32:39.526025Z","end":"2026-02-20T21:32:41.526098Z","steps":["trace[1127942769] 'agreement among raft nodes before linearized reading' (duration: 1.999874197s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:41.526199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:39.525938Z","time spent":"2.000245722s","remote":"127.0.0.1:51090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/20 21:32:41 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T21:32:41.54183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.908215873s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T21:32:41.541968Z","caller":"traceutil/trace.go:171","msg":"trace[131722278] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"2.015907299s","start":"2026-02-20T21:32:39.526036Z","end":"2026-02-20T21:32:41.541943Z","steps":["trace[131722278] 'read index received' (duration: 107.350381ms)","trace[131722278] 'applied index is now lower than readState.Index' (duration: 1.908554358s)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:32:41.542071Z","caller":"traceutil/trace.go:171","msg":"trace[926252350] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"2.016089631s","start":"2026-02-20T21:32:39.525967Z","end":"2026-02-20T21:32:41.542056Z","steps":["trace[926252350] 'process raft request' (duration: 107.482773ms)","trace[926252350] 'compare' (duration: 1.907420073s)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:32:41.542597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:39.525943Z","time spent":"2.016599739s","remote":"127.0.0.1:51098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:32:42.042257Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360414082416861,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T21:32:42.542788Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360414082416861,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T21:32:42.933227Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.402797003s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-20T21:32:43.133199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.499519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T21:32:43.133286Z","caller":"traceutil/trace.go:171","msg":"trace[1655738875] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.60293983s","start":"2026-02-20T21:32:41.530331Z","end":"2026-02-20T21:32:43.13327Z","steps":["trace[1655738875] 'process raft request' (duration: 1.403304909s)","trace[1655738875] 'compare' (duration: 199.058404ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:32:43.133325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:41.530304Z","time spent":"1.603007252s","remote":"127.0.0.1:51120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T21:32:43.144345Z","caller":"traceutil/trace.go:171","msg":"trace[2086747861] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:586; }","duration":"1.6023193s","start":"2026-02-20T21:32:41.542004Z","end":"2026-02-20T21:32:43.144323Z","steps":["trace[2086747861] 'read index received' (duration: 1.391645205s)","trace[2086747861] 'applied index is now lower than readState.Index' (duration: 210.673255ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:32:43.14441Z","caller":"traceutil/trace.go:171","msg":"trace[874815873] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.20012041s","start":"2026-02-20T21:32:41.944263Z","end":"2026-02-20T21:32:43.144383Z","steps":["trace[874815873] 'process raft request' (duration: 1.199959187s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:43.144542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:41.944241Z","time spent":"1.200230731s","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:32:43.144564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.881950273s","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-20T21:32:43.144594Z","caller":"traceutil/trace.go:171","msg":"trace[1108603307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:566; }","duration":"2.882027344s","start":"2026-02-20T21:32:40.262557Z","end":"2026-02-20T21:32:43.144584Z","steps":["trace[1108603307] 'agreement among raft nodes before linearized reading' (duration: 2.881863182s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:43.144619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:40.262539Z","time spent":"2.882074155s","remote":"127.0.0.1:51352","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-20T21:32:43.144664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.610988104s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:32:43.144691Z","caller":"traceutil/trace.go:171","msg":"trace[1610956039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"1.611012475s","start":"2026-02-20T21:32:41.53367Z","end":"2026-02-20T21:32:43.144682Z","steps":["trace[1610956039] 'agreement among raft nodes before linearized reading' (duration: 1.610967144s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:43.144711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:41.533621Z","time spent":"1.611086076s","remote":"127.0.0.1:51074","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-20T21:32:43.144717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.901424875s","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-20T21:32:43.144736Z","caller":"traceutil/trace.go:171","msg":"trace[787124676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:566; }","duration":"2.901443155s","start":"2026-02-20T21:32:40.243287Z","end":"2026-02-20T21:32:43.14473Z","steps":["trace[787124676] 'agreement among raft nodes before linearized reading' (duration: 2.901405555s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:43.144756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:40.24321Z","time spent":"2.901541647s","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T21:32:43.144815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.161803092s","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-20T21:32:43.14483Z","caller":"traceutil/trace.go:171","msg":"trace[1775851912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:566; }","duration":"3.161844453s","start":"2026-02-20T21:32:39.98298Z","end":"2026-02-20T21:32:43.144825Z","steps":["trace[1775851912] 'agreement among raft nodes before linearized reading' (duration: 3.161817252s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:43.144845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:39.982965Z","time spent":"3.161877083s","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T21:32:43.14491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.600537278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-20T21:32:43.144924Z","caller":"traceutil/trace.go:171","msg":"trace[1120157142] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:566; }","duration":"1.600594498s","start":"2026-02-20T21:32:41.544325Z","end":"2026-02-20T21:32:43.144919Z","steps":["trace[1120157142] 'agreement among raft nodes before linearized reading' (duration: 1.600564897s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:43.144939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:41.544303Z","time spent":"1.600633079s","remote":"127.0.0.1:51240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-20T21:32:43.14497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.795294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:32:43.144999Z","caller":"traceutil/trace.go:171","msg":"trace[1145983875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"359.869235ms","start":"2026-02-20T21:32:42.785122Z","end":"2026-02-20T21:32:43.144991Z","steps":["trace[1145983875] 'agreement among raft nodes before linearized reading' (duration: 359.827794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:43.145017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:42.785102Z","time spent":"359.910606ms","remote":"127.0.0.1:51074","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-20T21:32:43.145314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.976739464s","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-20T21:32:43.145362Z","caller":"traceutil/trace.go:171","msg":"trace[268938225] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:566; }","duration":"1.976828825s","start":"2026-02-20T21:32:41.168525Z","end":"2026-02-20T21:32:43.145354Z","steps":["trace[268938225] 'agreement among raft nodes before linearized reading' (duration: 1.976648602s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:43.145393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:41.168494Z","time spent":"1.976892045s","remote":"127.0.0.1:51352","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-20T21:32:46.967522Z","caller":"traceutil/trace.go:171","msg":"trace[1141051806] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"129.536038ms","start":"2026-02-20T21:32:46.837965Z","end":"2026-02-20T21:32:46.967501Z","steps":["trace[1141051806] 'read index received' (duration: 129.403656ms)","trace[1141051806] 'applied index is now lower than readState.Index' (duration: 131.332µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:32:46.967601Z","caller":"traceutil/trace.go:171","msg":"trace[228184933] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"490.994009ms","start":"2026-02-20T21:32:46.476579Z","end":"2026-02-20T21:32:46.967573Z","steps":["trace[228184933] 'process raft request' (duration: 490.809246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:46.967666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.68583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-20T21:32:46.96775Z","caller":"traceutil/trace.go:171","msg":"trace[1302612613] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:590; }","duration":"129.756601ms","start":"2026-02-20T21:32:46.837953Z","end":"2026-02-20T21:32:46.967709Z","steps":["trace[1302612613] 'agreement among raft nodes before linearized reading' (duration: 129.649869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:32:46.967782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:32:46.476553Z","time spent":"491.135931ms","remote":"127.0.0.1:51352","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-20T21:32:48.544605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.36156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T21:32:48.544673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.971097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-20T21:32:48.544882Z","caller":"traceutil/trace.go:171","msg":"trace[1293835995] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:627; }","duration":"135.519851ms","start":"2026-02-20T21:32:48.409158Z","end":"2026-02-20T21:32:48.544678Z","steps":["trace[1293835995] 'range keys from in-memory index tree' (duration: 135.289798ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:32:48.544933Z","caller":"traceutil/trace.go:171","msg":"trace[417354180] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:627; }","duration":"232.294172ms","start":"2026-02-20T21:32:48.312619Z","end":"2026-02-20T21:32:48.544913Z","steps":["trace[417354180] 'range keys from in-memory index tree' (duration: 231.792174ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:33:03.582957Z","caller":"traceutil/trace.go:171","msg":"trace[852833454] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:791; }","duration":"275.116759ms","start":"2026-02-20T21:33:03.307809Z","end":"2026-02-20T21:33:03.582926Z","steps":["trace[852833454] 'read index received' (duration: 274.947746ms)","trace[852833454] 'applied index is now lower than readState.Index' (duration: 167.783µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:33:03.583056Z","caller":"traceutil/trace.go:171","msg":"trace[349471598] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"295.256113ms","start":"2026-02-20T21:33:03.287766Z","end":"2026-02-20T21:33:03.583022Z","steps":["trace[349471598] 'process raft request' (duration: 295.017639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:33:03.583214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.393025ms","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-20T21:33:03.583306Z","caller":"traceutil/trace.go:171","msg":"trace[844413160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:763; }","duration":"275.525887ms","start":"2026-02-20T21:33:03.307768Z","end":"2026-02-20T21:33:03.583293Z","steps":["trace[844413160] 'agreement among raft nodes before linearized reading' (duration: 275.306243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:33:03.589015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.817965ms","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-20T21:33:03.589069Z","caller":"traceutil/trace.go:171","msg":"trace[32144276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"274.902666ms","start":"2026-02-20T21:33:03.314153Z","end":"2026-02-20T21:33:03.589055Z","steps":["trace[32144276] 'agreement among raft nodes before linearized reading' (duration: 274.743273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:33:03.58911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.315294ms","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-20T21:33:03.589197Z","caller":"traceutil/trace.go:171","msg":"trace[1782946398] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:763; }","duration":"264.438568ms","start":"2026-02-20T21:33:03.324738Z","end":"2026-02-20T21:33:03.589177Z","steps":["trace[1782946398] 'agreement among raft nodes before linearized reading' (duration: 264.202113ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:33:48.13994Z","caller":"traceutil/trace.go:171","msg":"trace[294311491] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"175.843655ms","start":"2026-02-20T21:33:47.964075Z","end":"2026-02-20T21:33:48.139919Z","steps":["trace[294311491] 'read index received' (duration: 175.66118ms)","trace[294311491] 'applied index is now lower than readState.Index' (duration: 181.845µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:33:48.140022Z","caller":"traceutil/trace.go:171","msg":"trace[2110638802] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"312.003265ms","start":"2026-02-20T21:33:47.827996Z","end":"2026-02-20T21:33:48.14Z","steps":["trace[2110638802] 'process raft request' (duration: 311.787719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:33:48.140146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.062701ms","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-20T21:33:48.140176Z","caller":"traceutil/trace.go:171","msg":"trace[1969482593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:891; }","duration":"176.151113ms","start":"2026-02-20T21:33:47.964017Z","end":"2026-02-20T21:33:48.140168Z","steps":["trace[1969482593] 'agreement among raft nodes before linearized reading' (duration: 176.01233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:33:48.140192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:33:47.827963Z","time spent":"312.139388ms","remote":"127.0.0.1:51352","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-20T21:33:48.140337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.001468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"warn","ts":"2026-02-20T21:33:48.140404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.464177ms","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-20T21:33:48.140462Z","caller":"traceutil/trace.go:171","msg":"trace[1322262371] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:891; }","duration":"134.179072ms","start":"2026-02-20T21:33:48.006242Z","end":"2026-02-20T21:33:48.140421Z","steps":["trace[1322262371] 'agreement among raft nodes before linearized reading' (duration: 133.922785ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:33:48.140485Z","caller":"traceutil/trace.go:171","msg":"trace[1875552333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:891; }","duration":"157.605271ms","start":"2026-02-20T21:33:47.982862Z","end":"2026-02-20T21:33:48.140467Z","steps":["trace[1875552333] 'agreement among raft nodes before linearized reading' (duration: 157.346344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:33:48.140437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.650468ms","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-20T21:33:48.140575Z","caller":"traceutil/trace.go:171","msg":"trace[2129939416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:891; }","duration":"158.858254ms","start":"2026-02-20T21:33:47.981685Z","end":"2026-02-20T21:33:48.140543Z","steps":["trace[2129939416] 'agreement among raft nodes before linearized reading' (duration: 158.454703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:33:48.588265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.204499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T21:33:48.58839Z","caller":"traceutil/trace.go:171","msg":"trace[1814810888] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"237.978772ms","start":"2026-02-20T21:33:48.350394Z","end":"2026-02-20T21:33:48.588372Z","steps":["trace[1814810888] 'process raft request' (duration: 30.583348ms)","trace[1814810888] 'compare' (duration: 207.075856ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:33:49.665556Z","caller":"traceutil/trace.go:171","msg":"trace[1406504889] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"259.320197ms","start":"2026-02-20T21:33:49.406211Z","end":"2026-02-20T21:33:49.665531Z","steps":["trace[1406504889] 'process raft request' (duration: 259.117872ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:34:02.087681Z","caller":"traceutil/trace.go:171","msg":"trace[16320880] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"370.713907ms","start":"2026-02-20T21:34:01.716913Z","end":"2026-02-20T21:34:02.087627Z","steps":["trace[16320880] 'process raft request' (duration: 370.47653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:02.087927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:34:01.716886Z","time spent":"370.913343ms","remote":"127.0.0.1:51352","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-20T21:34:10.735535Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360414082417896,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T21:34:11.13906Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.01433623s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T21:34:11.139427Z","caller":"traceutil/trace.go:171","msg":"trace[1617271199] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"904.650877ms","start":"2026-02-20T21:34:10.234756Z","end":"2026-02-20T21:34:11.139406Z","steps":["trace[1617271199] 'read index received' (duration: 904.443551ms)","trace[1617271199] 'applied index is now lower than readState.Index' (duration: 206.466µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:34:11.139512Z","caller":"traceutil/trace.go:171","msg":"trace[1606830945] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"1.014882876s","start":"2026-02-20T21:34:10.12462Z","end":"2026-02-20T21:34:11.139502Z","steps":["trace[1606830945] 'process raft request' (duration: 1.014627139s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:11.139638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:34:10.1246Z","time spent":"1.014943567s","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:34:11.139728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.843153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:34:11.13979Z","caller":"traceutil/trace.go:171","msg":"trace[921164906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"877.936556ms","start":"2026-02-20T21:34:10.261837Z","end":"2026-02-20T21:34:11.139774Z","steps":["trace[921164906] 'agreement among raft nodes before linearized reading' (duration: 877.839884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:11.139824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:34:10.261826Z","time spent":"877.990337ms","remote":"127.0.0.1:51074","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-20T21:34:11.139962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.554278ms","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-20T21:34:11.140008Z","caller":"traceutil/trace.go:171","msg":"trace[1465035345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:991; }","duration":"827.62364ms","start":"2026-02-20T21:34:10.312372Z","end":"2026-02-20T21:34:11.139996Z","steps":["trace[1465035345] 'agreement among raft nodes before linearized reading' (duration: 827.491367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:11.140045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:34:10.312358Z","time spent":"827.678652ms","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T21:34:11.140063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.706821ms","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-20T21:34:11.140086Z","caller":"traceutil/trace.go:171","msg":"trace[2028235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:991; }","duration":"828.757383ms","start":"2026-02-20T21:34:10.311322Z","end":"2026-02-20T21:34:11.14008Z","steps":["trace[2028235] 'agreement among raft nodes before linearized reading' (duration: 828.65329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:11.140111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:34:10.311307Z","time spent":"828.798803ms","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T21:34:11.14022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.478319ms","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-20T21:34:11.140256Z","caller":"traceutil/trace.go:171","msg":"trace[392914374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:991; }","duration":"905.568992ms","start":"2026-02-20T21:34:10.234679Z","end":"2026-02-20T21:34:11.140248Z","steps":["trace[392914374] 'agreement among raft nodes before linearized reading' (duration: 905.48221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:11.140264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.677334ms","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-20T21:34:11.14029Z","caller":"traceutil/trace.go:171","msg":"trace[1964704556] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:991; }","duration":"853.736725ms","start":"2026-02-20T21:34:10.286547Z","end":"2026-02-20T21:34:11.140283Z","steps":["trace[1964704556] 'agreement among raft nodes before linearized reading' (duration: 853.671314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:11.140316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:34:10.286526Z","time spent":"853.784626ms","remote":"127.0.0.1:51352","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-20T21:34:11.140289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:34:10.234641Z","time spent":"905.642164ms","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-20T21:34:11.952969Z","caller":"traceutil/trace.go:171","msg":"trace[15668835] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"809.108522ms","start":"2026-02-20T21:34:11.143843Z","end":"2026-02-20T21:34:11.952951Z","steps":["trace[15668835] 'process raft request' (duration: 809.00353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:11.953081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:34:11.143823Z","time spent":"809.195554ms","remote":"127.0.0.1:51352","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-20T21:34:12.029561Z","caller":"traceutil/trace.go:171","msg":"trace[904996232] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"885.25342ms","start":"2026-02-20T21:34:11.144284Z","end":"2026-02-20T21:34:12.029537Z","steps":["trace[904996232] 'process raft request' (duration: 885.057295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:12.029798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:34:11.144276Z","time spent":"885.434257ms","remote":"127.0.0.1:51352","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-20T21:34:12.029917Z","caller":"traceutil/trace.go:171","msg":"trace[1593887393] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"884.547371ms","start":"2026-02-20T21:34:11.145335Z","end":"2026-02-20T21:34:12.029883Z","steps":["trace[1593887393] 'process raft request' (duration: 884.432308ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:34:12.029922Z","caller":"traceutil/trace.go:171","msg":"trace[1063052638] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"885.482797ms","start":"2026-02-20T21:34:11.144396Z","end":"2026-02-20T21:34:12.029879Z","steps":["trace[1063052638] 'process raft request' (duration: 885.094505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:12.030088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:34:11.145323Z","time spent":"884.678694ms","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:34:12.030111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:34:11.144391Z","time spent":"885.630431ms","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T21:34:18.178344Z","caller":"traceutil/trace.go:171","msg":"trace[766396099] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"116.78828ms","start":"2026-02-20T21:34:18.061528Z","end":"2026-02-20T21:34:18.178317Z","steps":["trace[766396099] 'read index received' (duration: 18.828929ms)","trace[766396099] 'applied index is now lower than readState.Index' (duration: 97.958191ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:34:18.178497Z","caller":"traceutil/trace.go:171","msg":"trace[507656089] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"119.466987ms","start":"2026-02-20T21:34:18.059015Z","end":"2026-02-20T21:34:18.178482Z","steps":["trace[507656089] 'process raft request' (duration: 21.412263ms)","trace[507656089] 'compare' (duration: 97.759285ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:34:18.178723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.180373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-20T21:34:18.178732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.934828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-20T21:34:18.178782Z","caller":"traceutil/trace.go:171","msg":"trace[1705002393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1013; }","duration":"117.262045ms","start":"2026-02-20T21:34:18.061504Z","end":"2026-02-20T21:34:18.178766Z","steps":["trace[1705002393] 'agreement among raft nodes before linearized reading' (duration: 117.082739ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:34:18.178806Z","caller":"traceutil/trace.go:171","msg":"trace[2040659361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1013; }","duration":"115.03683ms","start":"2026-02-20T21:34:18.063751Z","end":"2026-02-20T21:34:18.178788Z","steps":["trace[2040659361] 'agreement among raft nodes before linearized reading' (duration: 114.783512ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:34:18.365784Z","caller":"traceutil/trace.go:171","msg":"trace[645821036] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1061; }","duration":"115.38842ms","start":"2026-02-20T21:34:18.250377Z","end":"2026-02-20T21:34:18.365766Z","steps":["trace[645821036] 'read index received' (duration: 30.184976ms)","trace[645821036] 'applied index is now lower than readState.Index' (duration: 85.202904ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:34:18.365828Z","caller":"traceutil/trace.go:171","msg":"trace[2065952289] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"183.053121ms","start":"2026-02-20T21:34:18.182746Z","end":"2026-02-20T21:34:18.365799Z","steps":["trace[2065952289] 'process raft request' (duration: 182.99486ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:34:18.365838Z","caller":"traceutil/trace.go:171","msg":"trace[751689407] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"183.159635ms","start":"2026-02-20T21:34:18.182637Z","end":"2026-02-20T21:34:18.365797Z","steps":["trace[751689407] 'process raft request' (duration: 97.93882ms)","trace[751689407] 'compare' (duration: 85.057881ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:34:18.365938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.717853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-02-20T21:34:18.365965Z","caller":"traceutil/trace.go:171","msg":"trace[507208295] range","detail":"{range_begin:/registry/clusterroles/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:1015; }","duration":"112.798046ms","start":"2026-02-20T21:34:18.253157Z","end":"2026-02-20T21:34:18.365955Z","steps":["trace[507208295] 'agreement among raft nodes before linearized reading' (duration: 112.714003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:18.36596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.569445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:certificate-controller\" ","response":"range_response_count:1 size:1142"} {"level":"info","ts":"2026-02-20T21:34:18.366015Z","caller":"traceutil/trace.go:171","msg":"trace[1152104835] range","detail":"{range_begin:/registry/clusterroles/system:controller:certificate-controller; range_end:; response_count:1; response_revision:1015; }","duration":"115.661518ms","start":"2026-02-20T21:34:18.25034Z","end":"2026-02-20T21:34:18.366001Z","steps":["trace[1152104835] 'agreement among raft nodes before linearized reading' (duration: 115.512964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:18.366086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.190375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:34:18.36612Z","caller":"traceutil/trace.go:171","msg":"trace[1481624859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"107.267597ms","start":"2026-02-20T21:34:18.258841Z","end":"2026-02-20T21:34:18.366109Z","steps":["trace[1481624859] 'agreement among raft nodes before linearized reading' (duration: 107.191175ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:34:18.991172Z","caller":"traceutil/trace.go:171","msg":"trace[686115422] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"169.618657ms","start":"2026-02-20T21:34:18.821526Z","end":"2026-02-20T21:34:18.991144Z","steps":["trace[686115422] 'read index received' (duration: 169.319478ms)","trace[686115422] 'applied index is now lower than readState.Index' (duration: 297.999µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:34:18.991332Z","caller":"traceutil/trace.go:171","msg":"trace[958220669] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"191.009681ms","start":"2026-02-20T21:34:18.80031Z","end":"2026-02-20T21:34:18.991319Z","steps":["trace[958220669] 'process raft request' (duration: 190.63212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:18.991518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.021095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cilium-operator\" ","response":"range_response_count:1 size:785"} {"level":"warn","ts":"2026-02-20T21:34:18.991518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.983927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:disruption-controller\" ","response":"range_response_count:1 size:974"} {"level":"info","ts":"2026-02-20T21:34:18.99159Z","caller":"traceutil/trace.go:171","msg":"trace[379128103] range","detail":"{range_begin:/registry/clusterrolebindings/cilium-operator; range_end:; response_count:1; response_revision:1017; }","duration":"127.148569ms","start":"2026-02-20T21:34:18.864424Z","end":"2026-02-20T21:34:18.991572Z","steps":["trace[379128103] 'agreement among raft nodes before linearized reading' (duration: 127.015095ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:34:18.991606Z","caller":"traceutil/trace.go:171","msg":"trace[1665328753] range","detail":"{range_begin:/registry/clusterroles/system:controller:disruption-controller; range_end:; response_count:1; response_revision:1017; }","duration":"170.108961ms","start":"2026-02-20T21:34:18.821472Z","end":"2026-02-20T21:34:18.991581Z","steps":["trace[1665328753] 'agreement among raft nodes before linearized reading' (duration: 169.971217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:19.553858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.181884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-20T21:34:19.553931Z","caller":"traceutil/trace.go:171","msg":"trace[1770545336] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1017; }","duration":"380.297196ms","start":"2026-02-20T21:34:19.173616Z","end":"2026-02-20T21:34:19.553913Z","steps":["trace[1770545336] 'range keys from in-memory index tree' (duration: 380.03429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:19.553941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.270277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cluster-admin\" ","response":"range_response_count:1 size:679"} {"level":"warn","ts":"2026-02-20T21:34:19.553968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:34:19.173605Z","time spent":"380.354419ms","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T21:34:19.553957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.753622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T21:34:19.554007Z","caller":"traceutil/trace.go:171","msg":"trace[1356810711] range","detail":"{range_begin:/registry/clusterrolebindings/cluster-admin; range_end:; response_count:1; response_revision:1017; }","duration":"412.387141ms","start":"2026-02-20T21:34:19.141602Z","end":"2026-02-20T21:34:19.553989Z","steps":["trace[1356810711] 'range keys from in-memory index tree' (duration: 412.180704ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:34:19.554017Z","caller":"traceutil/trace.go:171","msg":"trace[466157071] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1017; }","duration":"355.847915ms","start":"2026-02-20T21:34:19.198153Z","end":"2026-02-20T21:34:19.554001Z","steps":["trace[466157071] 'count revisions from in-memory index tree' (duration: 355.67718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:19.554054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:34:19.141586Z","time spent":"412.456112ms","remote":"127.0.0.1:51462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":702,"request content":"key:\"/registry/clusterrolebindings/cluster-admin\" "} {"level":"warn","ts":"2026-02-20T21:34:19.554063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:34:19.19814Z","time spent":"355.910526ms","remote":"127.0.0.1:51216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-20T21:34:19.55418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.460063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:34:19.554267Z","caller":"traceutil/trace.go:171","msg":"trace[2076565576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"292.530194ms","start":"2026-02-20T21:34:19.261683Z","end":"2026-02-20T21:34:19.554213Z","steps":["trace[2076565576] 'range keys from in-memory index tree' (duration: 292.4114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:19.554004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.762851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:disruption-controller\" ","response":"range_response_count:1 size:974"} {"level":"info","ts":"2026-02-20T21:34:19.554454Z","caller":"traceutil/trace.go:171","msg":"trace[334608503] range","detail":"{range_begin:/registry/clusterroles/system:controller:disruption-controller; range_end:; response_count:1; response_revision:1017; }","duration":"478.239295ms","start":"2026-02-20T21:34:19.076195Z","end":"2026-02-20T21:34:19.554434Z","steps":["trace[334608503] 'range keys from in-memory index tree' (duration: 477.680298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:19.554509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:34:19.076175Z","time spent":"478.317257ms","remote":"127.0.0.1:51452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":997,"request content":"key:\"/registry/clusterroles/system:controller:disruption-controller\" "} {"level":"info","ts":"2026-02-20T21:34:19.693275Z","caller":"traceutil/trace.go:171","msg":"trace[711412022] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"133.439948ms","start":"2026-02-20T21:34:19.559798Z","end":"2026-02-20T21:34:19.693238Z","steps":["trace[711412022] 'process raft request' (duration: 133.102888ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:34:20.782656Z","caller":"traceutil/trace.go:171","msg":"trace[369816684] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"342.41315ms","start":"2026-02-20T21:34:20.440217Z","end":"2026-02-20T21:34:20.78263Z","steps":["trace[369816684] 'read index received' (duration: 331.228927ms)","trace[369816684] 'applied index is now lower than readState.Index' (duration: 11.182953ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T21:34:20.782708Z","caller":"traceutil/trace.go:171","msg":"trace[826195239] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"408.220497ms","start":"2026-02-20T21:34:20.374459Z","end":"2026-02-20T21:34:20.78268Z","steps":["trace[826195239] 'process raft request' (duration: 396.939962ms)","trace[826195239] 'compare' (duration: 11.068299ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:34:20.782851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.614696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation\" ","response":"range_response_count:1 size:541"} {"level":"warn","ts":"2026-02-20T21:34:20.782856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:34:20.374446Z","time spent":"408.33631ms","remote":"127.0.0.1:51352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T21:34:20.782867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.02451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:ephemeral-volume-controller\" ","response":"range_response_count:1 size:761"} {"level":"info","ts":"2026-02-20T21:34:20.782889Z","caller":"traceutil/trace.go:171","msg":"trace[418569877] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation; range_end:; response_count:1; response_revision:1022; }","duration":"342.699378ms","start":"2026-02-20T21:34:20.440177Z","end":"2026-02-20T21:34:20.782876Z","steps":["trace[418569877] 'agreement among raft nodes before linearized reading' (duration: 342.599505ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:34:20.782918Z","caller":"traceutil/trace.go:171","msg":"trace[219354843] range","detail":"{range_begin:/registry/clusterroles/system:controller:ephemeral-volume-controller; range_end:; response_count:1; response_revision:1022; }","duration":"340.087333ms","start":"2026-02-20T21:34:20.442815Z","end":"2026-02-20T21:34:20.782902Z","steps":["trace[219354843] 'agreement among raft nodes before linearized reading' (duration: 340.00162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:20.782937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:34:20.440156Z","time spent":"342.77196ms","remote":"127.0.0.1:51462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":564,"request content":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation\" "} {"level":"warn","ts":"2026-02-20T21:34:20.782952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T21:34:20.442809Z","time spent":"340.136134ms","remote":"127.0.0.1:51452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":784,"request content":"key:\"/registry/clusterroles/system:controller:ephemeral-volume-controller\" "} {"level":"warn","ts":"2026-02-20T21:34:20.783143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.712021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T21:34:20.783241Z","caller":"traceutil/trace.go:171","msg":"trace[1796899704] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1022; }","duration":"289.827144ms","start":"2026-02-20T21:34:20.493386Z","end":"2026-02-20T21:34:20.783213Z","steps":["trace[1796899704] 'agreement among raft nodes before linearized reading' (duration: 289.71819ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:34:23.960971Z","caller":"traceutil/trace.go:171","msg":"trace[245438109] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1077; }","duration":"113.268447ms","start":"2026-02-20T21:34:23.84768Z","end":"2026-02-20T21:34:23.960948Z","steps":["trace[245438109] 'read index received' (duration: 113.131243ms)","trace[245438109] 'applied index is now lower than readState.Index' (duration: 136.174µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T21:34:23.961196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.051673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:route-controller\" ","response":"range_response_count:1 size:677"} {"level":"info","ts":"2026-02-20T21:34:23.961238Z","caller":"traceutil/trace.go:171","msg":"trace[1063126621] range","detail":"{range_begin:/registry/clusterroles/system:controller:route-controller; range_end:; response_count:1; response_revision:1028; }","duration":"111.136525ms","start":"2026-02-20T21:34:23.85009Z","end":"2026-02-20T21:34:23.961226Z","steps":["trace[1063126621] 'agreement among raft nodes before linearized reading' (duration: 111.000221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T21:34:23.961322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.577037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:771"} {"level":"info","ts":"2026-02-20T21:34:23.96139Z","caller":"traceutil/trace.go:171","msg":"trace[1975753796] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:1028; }","duration":"113.748381ms","start":"2026-02-20T21:34:23.847626Z","end":"2026-02-20T21:34:23.961374Z","steps":["trace[1975753796] 'agreement among raft nodes before linearized reading' (duration: 113.410722ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:34:23.961606Z","caller":"traceutil/trace.go:171","msg":"trace[1140657679] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"205.549355ms","start":"2026-02-20T21:34:23.756037Z","end":"2026-02-20T21:34:23.961586Z","steps":["trace[1140657679] 'process raft request' (duration: 204.758563ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T21:34:44.466589Z","caller":"traceutil/trace.go:171","msg":"trace[429103148] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"123.478811ms","start":"2026-02-20T21:34:44.343078Z","end":"2026-02-20T21:34:44.466557Z","steps":["trace[429103148] 'process raft request' (duration: 123.232374ms)"],"step_count":1}