{"level":"warn","ts":"2026-02-19T02:50:08.342279Z","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-19T02:50:08.342367Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.3:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.3:2380","--initial-cluster=instance=https://199.19.213.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.3: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-19T02:50:08.342437Z","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-19T02:50:08.342448Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.3:2380"]} {"level":"info","ts":"2026-02-19T02:50:08.342469Z","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-19T02:50:08.343278Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.3:2379"]} {"level":"info","ts":"2026-02-19T02:50:08.343386Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.3:2380"],"listen-peer-urls":["https://199.19.213.3:2380"],"advertise-client-urls":["https://199.19.213.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.3: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-19T02:50:08.347562Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.322062ms"} {"level":"info","ts":"2026-02-19T02:50:08.354962Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cf904ec39d3e333","cluster-id":"7b1ae51097bedd0e"} {"level":"info","ts":"2026-02-19T02:50:08.355069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 switched to configuration voters=()"} {"level":"info","ts":"2026-02-19T02:50:08.355101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became follower at term 0"} {"level":"info","ts":"2026-02-19T02:50:08.355126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cf904ec39d3e333 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-19T02:50:08.355155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became follower at term 1"} {"level":"info","ts":"2026-02-19T02:50:08.355222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 switched to configuration voters=(4393548324105610035)"} {"level":"warn","ts":"2026-02-19T02:50:08.36375Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-19T02:50:08.365068Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-19T02:50:08.366199Z","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-19T02:50:08.368149Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cf904ec39d3e333","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-19T02:50:08.368492Z","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-19T02:50:08.368538Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cf904ec39d3e333","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-19T02:50:08.368625Z","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-19T02:50:08.368639Z","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-19T02:50:08.369213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 switched to configuration voters=(4393548324105610035)"} {"level":"info","ts":"2026-02-19T02:50:08.370144Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7b1ae51097bedd0e","local-member-id":"3cf904ec39d3e333","added-peer-id":"3cf904ec39d3e333","added-peer-peer-urls":["https://199.19.213.3:2380"]} {"level":"info","ts":"2026-02-19T02:50:08.372449Z","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-19T02:50:08.372496Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.3:2380"} {"level":"info","ts":"2026-02-19T02:50:08.372532Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.3:2380"} {"level":"info","ts":"2026-02-19T02:50:08.373662Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cf904ec39d3e333","initial-advertise-peer-urls":["https://199.19.213.3:2380"],"listen-peer-urls":["https://199.19.213.3:2380"],"advertise-client-urls":["https://199.19.213.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-19T02:50:08.373695Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-19T02:50:08.755471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 is starting a new election at term 1"} {"level":"info","ts":"2026-02-19T02:50:08.755547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-19T02:50:08.755573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 received MsgPreVoteResp from 3cf904ec39d3e333 at term 1"} {"level":"info","ts":"2026-02-19T02:50:08.755593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became candidate at term 2"} {"level":"info","ts":"2026-02-19T02:50:08.75561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 received MsgVoteResp from 3cf904ec39d3e333 at term 2"} {"level":"info","ts":"2026-02-19T02:50:08.755737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became leader at term 2"} {"level":"info","ts":"2026-02-19T02:50:08.755763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cf904ec39d3e333 elected leader 3cf904ec39d3e333 at term 2"} {"level":"info","ts":"2026-02-19T02:50:08.756779Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:50:08.757703Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T02:50:08.757696Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cf904ec39d3e333","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.3:2379]}","request-path":"/0/members/3cf904ec39d3e333/attributes","cluster-id":"7b1ae51097bedd0e","publish-timeout":"7s"} {"level":"info","ts":"2026-02-19T02:50:08.757782Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T02:50:08.758229Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7b1ae51097bedd0e","local-member-id":"3cf904ec39d3e333","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:50:08.758427Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:50:08.758534Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:50:08.760321Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-19T02:50:08.760431Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-19T02:50:08.761612Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.3:2379"} {"level":"info","ts":"2026-02-19T02:50:08.762451Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-19T02:50:31.290184Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16371601091613714091,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T02:50:31.346321Z","caller":"traceutil/trace.go:171","msg":"trace[2016100302] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"556.95138ms","start":"2026-02-19T02:50:30.78932Z","end":"2026-02-19T02:50:31.346271Z","steps":["trace[2016100302] 'read index received' (duration: 556.788659ms)","trace[2016100302] 'applied index is now lower than readState.Index' (duration: 161.521µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:50:31.346638Z","caller":"traceutil/trace.go:171","msg":"trace[212515393] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"581.005544ms","start":"2026-02-19T02:50:30.765616Z","end":"2026-02-19T02:50:31.346621Z","steps":["trace[212515393] 'process raft request' (duration: 580.52369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:31.347176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:30.76559Z","time spent":"581.086755ms","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:50:31.347492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.16452ms","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-19T02:50:31.347544Z","caller":"traceutil/trace.go:171","msg":"trace[178725971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:419; }","duration":"558.240471ms","start":"2026-02-19T02:50:30.789292Z","end":"2026-02-19T02:50:31.347533Z","steps":["trace[178725971] 'agreement among raft nodes before linearized reading' (duration: 558.069499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:31.347587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:30.789278Z","time spent":"558.295611ms","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-19T02:50:31.347773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.159111ms","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-19T02:50:31.347812Z","caller":"traceutil/trace.go:171","msg":"trace[599128928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:419; }","duration":"557.221083ms","start":"2026-02-19T02:50:30.790581Z","end":"2026-02-19T02:50:31.347803Z","steps":["trace[599128928] 'agreement among raft nodes before linearized reading' (duration: 557.161142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:31.347846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:30.790572Z","time spent":"557.266333ms","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T02:50:31.348067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.162708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-19T02:50:31.348106Z","caller":"traceutil/trace.go:171","msg":"trace[1873868554] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:419; }","duration":"227.235809ms","start":"2026-02-19T02:50:31.120861Z","end":"2026-02-19T02:50:31.348097Z","steps":["trace[1873868554] 'agreement among raft nodes before linearized reading' (duration: 227.175779ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:50:31.816658Z","caller":"traceutil/trace.go:171","msg":"trace[4423603] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"465.055563ms","start":"2026-02-19T02:50:31.351574Z","end":"2026-02-19T02:50:31.816629Z","steps":["trace[4423603] 'process raft request' (duration: 435.287105ms)","trace[4423603] 'compare' (duration: 29.568906ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:50:31.816915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:31.351546Z","time spent":"465.212774ms","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:50:31.821318Z","caller":"traceutil/trace.go:171","msg":"trace[482542376] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:434; }","duration":"162.641462ms","start":"2026-02-19T02:50:31.658639Z","end":"2026-02-19T02:50:31.821281Z","steps":["trace[482542376] 'read index received' (duration: 128.310327ms)","trace[482542376] 'applied index is now lower than readState.Index' (duration: 34.330165ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:50:31.82139Z","caller":"traceutil/trace.go:171","msg":"trace[1353682602] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"468.785012ms","start":"2026-02-19T02:50:31.35255Z","end":"2026-02-19T02:50:31.821335Z","steps":["trace[1353682602] 'process raft request' (duration: 468.694352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:31.821482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.809303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:50:31.821496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:31.352538Z","time spent":"468.909603ms","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:50:31.82152Z","caller":"traceutil/trace.go:171","msg":"trace[1406301815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"162.912834ms","start":"2026-02-19T02:50:31.658599Z","end":"2026-02-19T02:50:31.821511Z","steps":["trace[1406301815] 'agreement among raft nodes before linearized reading' (duration: 162.821263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:31.821591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.897741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-02-19T02:50:31.821674Z","caller":"traceutil/trace.go:171","msg":"trace[1542214301] range","detail":"{range_begin:/registry/masterleases/199.19.213.3; range_end:; response_count:1; response_revision:422; }","duration":"109.025293ms","start":"2026-02-19T02:50:31.712629Z","end":"2026-02-19T02:50:31.821654Z","steps":["trace[1542214301] 'agreement among raft nodes before linearized reading' (duration: 108.843921ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:50:31.821682Z","caller":"traceutil/trace.go:171","msg":"trace[1832200356] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"469.320907ms","start":"2026-02-19T02:50:31.352341Z","end":"2026-02-19T02:50:31.821661Z","steps":["trace[1832200356] 'process raft request' (duration: 468.763773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:31.821779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:31.352317Z","time spent":"469.422648ms","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:50:38.341896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16371601091613714193,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T02:50:38.410784Z","caller":"traceutil/trace.go:171","msg":"trace[65386899] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"569.193772ms","start":"2026-02-19T02:50:37.841564Z","end":"2026-02-19T02:50:38.410757Z","steps":["trace[65386899] 'read index received' (duration: 569.012161ms)","trace[65386899] 'applied index is now lower than readState.Index' (duration: 180.331µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:50:38.410815Z","caller":"traceutil/trace.go:171","msg":"trace[1363907481] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"726.032741ms","start":"2026-02-19T02:50:37.684749Z","end":"2026-02-19T02:50:38.410782Z","steps":["trace[1363907481] 'process raft request' (duration: 725.85411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:38.411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:37.684734Z","time spent":"726.160192ms","remote":"127.0.0.1:52128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:50:38.411108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.177454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-19T02:50:38.411114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.523764ms","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-19T02:50:38.411158Z","caller":"traceutil/trace.go:171","msg":"trace[2075466093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:469; }","duration":"542.270795ms","start":"2026-02-19T02:50:37.868871Z","end":"2026-02-19T02:50:38.411142Z","steps":["trace[2075466093] 'agreement among raft nodes before linearized reading' (duration: 542.098054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:38.411205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:37.868856Z","time spent":"542.339875ms","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-19T02:50:38.411209Z","caller":"traceutil/trace.go:171","msg":"trace[1044390778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:469; }","duration":"569.654206ms","start":"2026-02-19T02:50:37.841523Z","end":"2026-02-19T02:50:38.411178Z","steps":["trace[1044390778] 'agreement among raft nodes before linearized reading' (duration: 569.366534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:38.411257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.80443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-19T02:50:38.411351Z","caller":"traceutil/trace.go:171","msg":"trace[1727723241] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:469; }","duration":"247.975421ms","start":"2026-02-19T02:50:38.163351Z","end":"2026-02-19T02:50:38.411326Z","steps":["trace[1727723241] 'agreement among raft nodes before linearized reading' (duration: 247.586028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:38.411286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:37.841504Z","time spent":"569.743407ms","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T02:50:38.411561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.239919ms","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-19T02:50:38.411596Z","caller":"traceutil/trace.go:171","msg":"trace[464918227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:469; }","duration":"566.292409ms","start":"2026-02-19T02:50:37.845292Z","end":"2026-02-19T02:50:38.411585Z","steps":["trace[464918227] 'agreement among raft nodes before linearized reading' (duration: 565.609834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:38.411624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:37.845284Z","time spent":"566.333691ms","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-19T02:50:41.930255Z","caller":"traceutil/trace.go:171","msg":"trace[1441671903] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"210.323267ms","start":"2026-02-19T02:50:41.719901Z","end":"2026-02-19T02:50:41.930224Z","steps":["trace[1441671903] 'process raft request' (duration: 210.272517ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:50:41.930504Z","caller":"traceutil/trace.go:171","msg":"trace[450019486] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"389.205566ms","start":"2026-02-19T02:50:41.541288Z","end":"2026-02-19T02:50:41.930494Z","steps":["trace[450019486] 'process raft request' (duration: 387.997707ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:50:41.930548Z","caller":"traceutil/trace.go:171","msg":"trace[1528048918] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:507; }","duration":"321.920528ms","start":"2026-02-19T02:50:41.608602Z","end":"2026-02-19T02:50:41.930522Z","steps":["trace[1528048918] 'read index received' (duration: 320.648529ms)","trace[1528048918] 'applied index is now lower than readState.Index' (duration: 1.269779ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:50:41.93059Z","caller":"traceutil/trace.go:171","msg":"trace[526900850] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"388.221429ms","start":"2026-02-19T02:50:41.542358Z","end":"2026-02-19T02:50:41.93058Z","steps":["trace[526900850] 'process raft request' (duration: 387.729915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:41.930612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:41.541279Z","time spent":"389.250226ms","remote":"127.0.0.1:52422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3328,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:50:41.930642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:41.542349Z","time spent":"388.269929ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2550,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T02:50:41.930705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.080653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:50:41.93074Z","caller":"traceutil/trace.go:171","msg":"trace[814615145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"272.165404ms","start":"2026-02-19T02:50:41.658563Z","end":"2026-02-19T02:50:41.930728Z","steps":["trace[814615145] 'agreement among raft nodes before linearized reading' (duration: 272.091543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:41.930748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.241599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-02-19T02:50:41.930797Z","caller":"traceutil/trace.go:171","msg":"trace[1477721845] range","detail":"{range_begin:/registry/masterleases/199.19.213.3; range_end:; response_count:1; response_revision:493; }","duration":"217.316739ms","start":"2026-02-19T02:50:41.713467Z","end":"2026-02-19T02:50:41.930783Z","steps":["trace[1477721845] 'agreement among raft nodes before linearized reading' (duration: 217.228639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:41.930826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.225231ms","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":"info","ts":"2026-02-19T02:50:41.930897Z","caller":"traceutil/trace.go:171","msg":"trace[159554064] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:493; }","duration":"322.354572ms","start":"2026-02-19T02:50:41.608523Z","end":"2026-02-19T02:50:41.930878Z","steps":["trace[159554064] 'agreement among raft nodes before linearized reading' (duration: 322.10281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:41.930938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:41.608467Z","time spent":"322.459592ms","remote":"127.0.0.1:52064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12749,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-19T02:50:45.777676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.568955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:50:45.777361Z","caller":"traceutil/trace.go:171","msg":"trace[1110849151] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"255.821842ms","start":"2026-02-19T02:50:45.521493Z","end":"2026-02-19T02:50:45.777315Z","steps":["trace[1110849151] 'process raft request' (duration: 255.588281ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:50:45.777832Z","caller":"traceutil/trace.go:171","msg":"trace[1758679882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"119.757576ms","start":"2026-02-19T02:50:45.658057Z","end":"2026-02-19T02:50:45.777815Z","steps":["trace[1758679882] 'agreement among raft nodes before linearized reading' (duration: 119.555424ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:50:45.777284Z","caller":"traceutil/trace.go:171","msg":"trace[18619871] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"119.171152ms","start":"2026-02-19T02:50:45.658091Z","end":"2026-02-19T02:50:45.777263Z","steps":["trace[18619871] 'read index received' (duration: 119.021911ms)","trace[18619871] 'applied index is now lower than readState.Index' (duration: 148.551µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:50:46.806258Z","caller":"traceutil/trace.go:171","msg":"trace[465934169] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"289.708483ms","start":"2026-02-19T02:50:46.516523Z","end":"2026-02-19T02:50:46.806231Z","steps":["trace[465934169] 'process raft request' (duration: 289.558002ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:50:46.829191Z","caller":"traceutil/trace.go:171","msg":"trace[605380307] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:533; }","duration":"170.135903ms","start":"2026-02-19T02:50:46.659035Z","end":"2026-02-19T02:50:46.829171Z","steps":["trace[605380307] 'read index received' (duration: 147.141824ms)","trace[605380307] 'applied index is now lower than readState.Index' (duration: 22.993319ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:50:46.829241Z","caller":"traceutil/trace.go:171","msg":"trace[635565189] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"312.678322ms","start":"2026-02-19T02:50:46.516539Z","end":"2026-02-19T02:50:46.829217Z","steps":["trace[635565189] 'process raft request' (duration: 312.506701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:46.829292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.245444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:50:46.829318Z","caller":"traceutil/trace.go:171","msg":"trace[1436033287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"170.290814ms","start":"2026-02-19T02:50:46.659019Z","end":"2026-02-19T02:50:46.82931Z","steps":["trace[1436033287] 'agreement among raft nodes before linearized reading' (duration: 170.230144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:46.829373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:46.516525Z","time spent":"312.768683ms","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:50:46.829549Z","caller":"traceutil/trace.go:171","msg":"trace[460271263] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"312.673542ms","start":"2026-02-19T02:50:46.516829Z","end":"2026-02-19T02:50:46.829502Z","steps":["trace[460271263] 'process raft request' (duration: 312.31894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:46.829614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:46.516815Z","time spent":"312.765053ms","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:50:48.159224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16371601091613714297,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:50:48.457427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.291637ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:50:48.457514Z","caller":"traceutil/trace.go:171","msg":"trace[1610162726] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:518; }","duration":"276.72526ms","start":"2026-02-19T02:50:48.180777Z","end":"2026-02-19T02:50:48.457502Z","steps":["trace[1610162726] 'range keys from in-memory index tree' (duration: 276.275027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:48.659689Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16371601091613714297,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:50:49.053971Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.140004676s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-19T02:50:49.659964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000792205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-19T02:50:49.660048Z","caller":"traceutil/trace.go:171","msg":"trace[1349492599] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000943846s","start":"2026-02-19T02:50:47.659084Z","end":"2026-02-19T02:50:49.660028Z","steps":["trace[1349492599] 'agreement among raft nodes before linearized reading' (duration: 2.000832425s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:49.660108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:47.659059Z","time spent":"2.001038697s","remote":"127.0.0.1:52006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/19 02:50:49 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-19T02:50:50.279605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.225139358s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-19T02:50:50.279684Z","caller":"traceutil/trace.go:171","msg":"trace[1437992130] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"2.620527552s","start":"2026-02-19T02:50:47.659144Z","end":"2026-02-19T02:50:50.279671Z","steps":["trace[1437992130] 'read index received' (duration: 1.395116502s)","trace[1437992130] 'applied index is now lower than readState.Index' (duration: 1.2254097s)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:50:50.279856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.472835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:50:50.279888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.774460705s","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-19T02:50:50.279892Z","caller":"traceutil/trace.go:171","msg":"trace[703565340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"619.552245ms","start":"2026-02-19T02:50:49.660329Z","end":"2026-02-19T02:50:50.279881Z","steps":["trace[703565340] 'agreement among raft nodes before linearized reading' (duration: 619.497595ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:50:50.279909Z","caller":"traceutil/trace.go:171","msg":"trace[753193599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:518; }","duration":"1.774519567s","start":"2026-02-19T02:50:48.505383Z","end":"2026-02-19T02:50:50.279903Z","steps":["trace[753193599] 'agreement among raft nodes before linearized reading' (duration: 1.774421166s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:50.279937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:48.505365Z","time spent":"1.774567107s","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T02:50:50.279951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446588932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-19T02:50:50.279982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445970317s","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-19T02:50:50.280002Z","caller":"traceutil/trace.go:171","msg":"trace[724878627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"1.446006998s","start":"2026-02-19T02:50:48.833989Z","end":"2026-02-19T02:50:50.279996Z","steps":["trace[724878627] 'agreement among raft nodes before linearized reading' (duration: 1.445949848s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:50.280018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:48.833983Z","time spent":"1.446030658s","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-19T02:50:50.280013Z","caller":"traceutil/trace.go:171","msg":"trace[925629879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:518; }","duration":"1.446682202s","start":"2026-02-19T02:50:48.833312Z","end":"2026-02-19T02:50:50.279994Z","steps":["trace[925629879] 'agreement among raft nodes before linearized reading' (duration: 1.446521261s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:50.279918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:49.660299Z","time spent":"619.612336ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:50:50.280054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:48.833293Z","time spent":"1.446752034s","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T02:50:50.280142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.467828557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-19T02:50:50.280212Z","caller":"traceutil/trace.go:171","msg":"trace[473851213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"1.467967339s","start":"2026-02-19T02:50:48.812226Z","end":"2026-02-19T02:50:50.280193Z","steps":["trace[473851213] 'agreement among raft nodes before linearized reading' (duration: 1.467729057s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:50.280254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:48.8122Z","time spent":"1.468042739s","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T02:50:52.256048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.426681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-02-19T02:50:52.256124Z","caller":"traceutil/trace.go:171","msg":"trace[865893339] range","detail":"{range_begin:/registry/masterleases/199.19.213.3; range_end:; response_count:1; response_revision:531; }","duration":"542.543095ms","start":"2026-02-19T02:50:51.713563Z","end":"2026-02-19T02:50:52.256106Z","steps":["trace[865893339] 'range keys from in-memory index tree' (duration: 542.246676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:50:52.256182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:50:51.713549Z","time spent":"542.622489ms","remote":"127.0.0.1:52016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":153,"request content":"key:\"/registry/masterleases/199.19.213.3\" "} {"level":"info","ts":"2026-02-19T02:50:52.259411Z","caller":"traceutil/trace.go:171","msg":"trace[1817263842] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"140.639367ms","start":"2026-02-19T02:50:52.118759Z","end":"2026-02-19T02:50:52.259398Z","steps":["trace[1817263842] 'process raft request' (duration: 140.540223ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:50:52.87334Z","caller":"traceutil/trace.go:171","msg":"trace[577484501] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"179.096527ms","start":"2026-02-19T02:50:52.694223Z","end":"2026-02-19T02:50:52.87332Z","steps":["trace[577484501] 'process raft request' (duration: 178.991502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:17.023451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.059432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:52:17.02504Z","caller":"traceutil/trace.go:171","msg":"trace[1191453250] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"167.195099ms","start":"2026-02-19T02:52:16.857811Z","end":"2026-02-19T02:52:17.025006Z","steps":["trace[1191453250] 'process raft request' (duration: 55.423235ms)","trace[1191453250] 'compare' (duration: 109.879589ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:17.025078Z","caller":"traceutil/trace.go:171","msg":"trace[2014005233] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"166.439665ms","start":"2026-02-19T02:52:16.85861Z","end":"2026-02-19T02:52:17.02505Z","steps":["trace[2014005233] 'process raft request' (duration: 166.297812ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:17.025184Z","caller":"traceutil/trace.go:171","msg":"trace[835352087] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"166.001107ms","start":"2026-02-19T02:52:16.859153Z","end":"2026-02-19T02:52:17.025154Z","steps":["trace[835352087] 'process raft request' (duration: 165.818324ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:17.110539Z","caller":"traceutil/trace.go:171","msg":"trace[1025137786] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:989; }","duration":"188.586938ms","start":"2026-02-19T02:52:16.921923Z","end":"2026-02-19T02:52:17.11051Z","steps":["trace[1025137786] 'read index received' (duration: 101.221587ms)","trace[1025137786] 'applied index is now lower than readState.Index' (duration: 87.36394ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:52:17.110767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.837403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-19T02:52:17.110801Z","caller":"traceutil/trace.go:171","msg":"trace[1835590372] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:953; }","duration":"189.601517ms","start":"2026-02-19T02:52:16.92119Z","end":"2026-02-19T02:52:17.110792Z","steps":["trace[1835590372] 'agreement among raft nodes before linearized reading' (duration: 189.438504ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:17.887183Z","caller":"traceutil/trace.go:171","msg":"trace[1772290110] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"104.859045ms","start":"2026-02-19T02:52:17.782302Z","end":"2026-02-19T02:52:17.887161Z","steps":["trace[1772290110] 'process raft request' (duration: 104.735883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:19.010307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.950097ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:52:19.010491Z","caller":"traceutil/trace.go:171","msg":"trace[681941829] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:955; }","duration":"830.1447ms","start":"2026-02-19T02:52:18.180326Z","end":"2026-02-19T02:52:19.010471Z","steps":["trace[681941829] 'range keys from in-memory index tree' (duration: 829.932087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:19.010542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.204686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:52:19.010673Z","caller":"traceutil/trace.go:171","msg":"trace[1837232851] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"1.083776371s","start":"2026-02-19T02:52:17.926845Z","end":"2026-02-19T02:52:19.010621Z","steps":["trace[1837232851] 'read index received' (duration: 294.441363ms)","trace[1837232851] 'applied index is now lower than readState.Index' (duration: 789.333448ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:19.010762Z","caller":"traceutil/trace.go:171","msg":"trace[1167562310] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"1.11659516s","start":"2026-02-19T02:52:17.894155Z","end":"2026-02-19T02:52:19.01075Z","steps":["trace[1167562310] 'process raft request' (duration: 327.098258ms)","trace[1167562310] 'compare' (duration: 788.918661ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:52:19.010818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:17.894137Z","time spent":"1.116654521s","remote":"127.0.0.1:52048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T02:52:19.011047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.058354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:52:19.011107Z","caller":"traceutil/trace.go:171","msg":"trace[777606918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"352.151906ms","start":"2026-02-19T02:52:18.658941Z","end":"2026-02-19T02:52:19.011092Z","steps":["trace[777606918] 'agreement among raft nodes before linearized reading' (duration: 351.919082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:19.011143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:18.658917Z","time spent":"352.217157ms","remote":"127.0.0.1:52006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:52:19.011279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084424673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:52:19.01135Z","caller":"traceutil/trace.go:171","msg":"trace[815668351] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:956; }","duration":"1.084526515s","start":"2026-02-19T02:52:17.926806Z","end":"2026-02-19T02:52:19.011333Z","steps":["trace[815668351] 'agreement among raft nodes before linearized reading' (duration: 1.084416413s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:19.011349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.337499ms","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-19T02:52:19.011398Z","caller":"traceutil/trace.go:171","msg":"trace[1368438427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:956; }","duration":"175.42067ms","start":"2026-02-19T02:52:18.835965Z","end":"2026-02-19T02:52:19.011386Z","steps":["trace[1368438427] 'agreement among raft nodes before linearized reading' (duration: 175.253757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:19.011394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:17.926788Z","time spent":"1.084596006s","remote":"127.0.0.1:41608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-19T02:52:19.341538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.387681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:52:19.341814Z","caller":"traceutil/trace.go:171","msg":"trace[1679009179] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"299.737983ms","start":"2026-02-19T02:52:19.042022Z","end":"2026-02-19T02:52:19.34176Z","steps":["trace[1679009179] 'process raft request' (duration: 90.012106ms)","trace[1679009179] 'compare' (duration: 209.259979ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:19.341902Z","caller":"traceutil/trace.go:171","msg":"trace[1264069276] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"298.722096ms","start":"2026-02-19T02:52:19.043155Z","end":"2026-02-19T02:52:19.341877Z","steps":["trace[1264069276] 'process raft request' (duration: 298.512462ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:19.342202Z","caller":"traceutil/trace.go:171","msg":"trace[1831705992] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"298.616153ms","start":"2026-02-19T02:52:19.043564Z","end":"2026-02-19T02:52:19.34218Z","steps":["trace[1831705992] 'process raft request' (duration: 298.265597ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:19.342304Z","caller":"traceutil/trace.go:171","msg":"trace[1935706320] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:997; }","duration":"207.909524ms","start":"2026-02-19T02:52:19.134193Z","end":"2026-02-19T02:52:19.342103Z","steps":["trace[1935706320] 'read index received' (duration: 180.426051ms)","trace[1935706320] 'applied index is now lower than readState.Index' (duration: 27.481293ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:52:19.3428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.580766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-19T02:52:19.34289Z","caller":"traceutil/trace.go:171","msg":"trace[251133277] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:960; }","duration":"208.727739ms","start":"2026-02-19T02:52:19.134142Z","end":"2026-02-19T02:52:19.34287Z","steps":["trace[251133277] 'agreement among raft nodes before linearized reading' (duration: 208.490484ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:21.534947Z","caller":"traceutil/trace.go:171","msg":"trace[1818155480] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"489.386749ms","start":"2026-02-19T02:52:21.045527Z","end":"2026-02-19T02:52:21.534913Z","steps":["trace[1818155480] 'process raft request' (duration: 489.194456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:21.535126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:52:21.045503Z","time spent":"489.529172ms","remote":"127.0.0.1:52236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:52:21.535211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.304368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-19T02:52:21.535314Z","caller":"traceutil/trace.go:171","msg":"trace[1444821923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:962; }","duration":"188.497681ms","start":"2026-02-19T02:52:21.346797Z","end":"2026-02-19T02:52:21.535295Z","steps":["trace[1444821923] 'agreement among raft nodes before linearized reading' (duration: 188.214826ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:21.534953Z","caller":"traceutil/trace.go:171","msg":"trace[1945609190] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"188.022233ms","start":"2026-02-19T02:52:21.346852Z","end":"2026-02-19T02:52:21.534874Z","steps":["trace[1945609190] 'read index received' (duration: 187.767418ms)","trace[1945609190] 'applied index is now lower than readState.Index' (duration: 253.335µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:52:21.535767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.836907ms","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-19T02:52:21.535799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.864108ms","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-19T02:52:21.535817Z","caller":"traceutil/trace.go:171","msg":"trace[1555410584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:962; }","duration":"188.926639ms","start":"2026-02-19T02:52:21.346876Z","end":"2026-02-19T02:52:21.535802Z","steps":["trace[1555410584] 'agreement among raft nodes before linearized reading' (duration: 188.749906ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:21.535835Z","caller":"traceutil/trace.go:171","msg":"trace[950281402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:962; }","duration":"188.929259ms","start":"2026-02-19T02:52:21.346895Z","end":"2026-02-19T02:52:21.535824Z","steps":["trace[950281402] 'agreement among raft nodes before linearized reading' (duration: 188.833567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:21.535814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.939772ms","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-19T02:52:21.535904Z","caller":"traceutil/trace.go:171","msg":"trace[1044992147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:962; }","duration":"167.094225ms","start":"2026-02-19T02:52:21.368788Z","end":"2026-02-19T02:52:21.535882Z","steps":["trace[1044992147] 'agreement among raft nodes before linearized reading' (duration: 166.85901ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:52:45.967334Z","caller":"traceutil/trace.go:171","msg":"trace[1610304794] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"127.220363ms","start":"2026-02-19T02:52:45.840089Z","end":"2026-02-19T02:52:45.967309Z","steps":["trace[1610304794] 'read index received' (duration: 127.02976ms)","trace[1610304794] 'applied index is now lower than readState.Index' (duration: 189.693µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:52:45.967414Z","caller":"traceutil/trace.go:171","msg":"trace[1302888098] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"136.061161ms","start":"2026-02-19T02:52:45.831315Z","end":"2026-02-19T02:52:45.967377Z","steps":["trace[1302888098] 'process raft request' (duration: 135.774017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:52:45.967538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.416406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-19T02:52:45.967697Z","caller":"traceutil/trace.go:171","msg":"trace[963797475] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:1034; }","duration":"127.60674ms","start":"2026-02-19T02:52:45.840072Z","end":"2026-02-19T02:52:45.967679Z","steps":["trace[963797475] 'agreement among raft nodes before linearized reading' (duration: 127.381576ms)"],"step_count":1}