{"level":"warn","ts":"2026-05-11T10:49:31.787525Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T10:49:31.787608Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.61: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.61:2380","--initial-cluster=instance=https://199.19.213.61:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.61:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.61: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-05-11T10:49:31.787667Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-11T10:49:31.787674Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.61:2380"]} {"level":"info","ts":"2026-05-11T10:49:31.787694Z","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-05-11T10:49:31.788498Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.61:2379"]} {"level":"info","ts":"2026-05-11T10:49:31.788725Z","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.61:2380"],"listen-peer-urls":["https://199.19.213.61:2380"],"advertise-client-urls":["https://199.19.213.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.61: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-05-11T10:49:31.792566Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.640682ms"} {"level":"info","ts":"2026-05-11T10:49:31.802252Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d1484a2210d3c2b8","cluster-id":"27fc2dec3fac595c"} {"level":"info","ts":"2026-05-11T10:49:31.802329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 switched to configuration voters=()"} {"level":"info","ts":"2026-05-11T10:49:31.802353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became follower at term 0"} {"level":"info","ts":"2026-05-11T10:49:31.802363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d1484a2210d3c2b8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-11T10:49:31.802372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became follower at term 1"} {"level":"info","ts":"2026-05-11T10:49:31.802408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 switched to configuration voters=(15080384862421762744)"} {"level":"warn","ts":"2026-05-11T10:49:31.811679Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-11T10:49:31.814551Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-11T10:49:31.818546Z","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-05-11T10:49:31.820757Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d1484a2210d3c2b8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-11T10:49:31.820893Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d1484a2210d3c2b8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-11T10:49:31.82099Z","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-05-11T10:49:31.821035Z","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-05-11T10:49:31.821053Z","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-05-11T10:49:31.826266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 switched to configuration voters=(15080384862421762744)"} {"level":"info","ts":"2026-05-11T10:49:31.826354Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"27fc2dec3fac595c","local-member-id":"d1484a2210d3c2b8","added-peer-id":"d1484a2210d3c2b8","added-peer-peer-urls":["https://199.19.213.61:2380"]} {"level":"info","ts":"2026-05-11T10:49:31.827726Z","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-05-11T10:49:31.827859Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.61:2380"} {"level":"info","ts":"2026-05-11T10:49:31.827909Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.61:2380"} {"level":"info","ts":"2026-05-11T10:49:31.828152Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d1484a2210d3c2b8","initial-advertise-peer-urls":["https://199.19.213.61:2380"],"listen-peer-urls":["https://199.19.213.61:2380"],"advertise-client-urls":["https://199.19.213.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-11T10:49:31.828197Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-11T10:49:32.002717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 is starting a new election at term 1"} {"level":"info","ts":"2026-05-11T10:49:32.002785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-11T10:49:32.002825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 received MsgPreVoteResp from d1484a2210d3c2b8 at term 1"} {"level":"info","ts":"2026-05-11T10:49:32.002852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became candidate at term 2"} {"level":"info","ts":"2026-05-11T10:49:32.002869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 received MsgVoteResp from d1484a2210d3c2b8 at term 2"} {"level":"info","ts":"2026-05-11T10:49:32.002891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d1484a2210d3c2b8 became leader at term 2"} {"level":"info","ts":"2026-05-11T10:49:32.002911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d1484a2210d3c2b8 elected leader d1484a2210d3c2b8 at term 2"} {"level":"info","ts":"2026-05-11T10:49:32.003656Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T10:49:32.004316Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d1484a2210d3c2b8","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.61:2379]}","request-path":"/0/members/d1484a2210d3c2b8/attributes","cluster-id":"27fc2dec3fac595c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-11T10:49:32.004484Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T10:49:32.004546Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-11T10:49:32.007499Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-11T10:49:32.007832Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-11T10:49:32.007875Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-11T10:49:32.0122Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"27fc2dec3fac595c","local-member-id":"d1484a2210d3c2b8","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T10:49:32.012292Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T10:49:32.012322Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-11T10:49:32.01237Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.61:2379"} {"level":"info","ts":"2026-05-11T10:49:37.159839Z","caller":"traceutil/trace.go:171","msg":"trace[2107798357] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"301.762494ms","start":"2026-05-11T10:49:36.857982Z","end":"2026-05-11T10:49:37.159745Z","steps":["trace[2107798357] 'process raft request' (duration: 213.802066ms)","trace[2107798357] 'compare' (duration: 87.791304ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:49:37.160458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:49:36.857971Z","time spent":"302.000639ms","remote":"127.0.0.1:60054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T10:49:37.552221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.365727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T10:49:37.552581Z","caller":"traceutil/trace.go:171","msg":"trace[376673931] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"694.296244ms","start":"2026-05-11T10:49:36.858242Z","end":"2026-05-11T10:49:37.552538Z","steps":["trace[376673931] 'process raft request' (duration: 501.383433ms)","trace[376673931] 'compare' (duration: 192.202524ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:49:37.552732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:49:36.858234Z","time spent":"694.437727ms","remote":"127.0.0.1:60170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1196,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T10:49:37.55356Z","caller":"traceutil/trace.go:171","msg":"trace[1066801641] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"671.816991ms","start":"2026-05-11T10:49:36.881465Z","end":"2026-05-11T10:49:37.553282Z","steps":["trace[1066801641] 'process raft request' (duration: 671.161014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:49:37.55364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:49:36.881445Z","time spent":"672.158189ms","remote":"127.0.0.1:60186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T10:49:37.553817Z","caller":"traceutil/trace.go:171","msg":"trace[316743902] transaction","detail":"{read_only:false; number_of_response:0; response_revision:285; }","duration":"587.281599ms","start":"2026-05-11T10:49:36.966522Z","end":"2026-05-11T10:49:37.553804Z","steps":["trace[316743902] 'process raft request' (duration: 586.434278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:49:37.553869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:49:36.966487Z","time spent":"587.358941ms","remote":"127.0.0.1:60162","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T10:49:37.554028Z","caller":"traceutil/trace.go:171","msg":"trace[675055720] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"388.559902ms","start":"2026-05-11T10:49:37.165445Z","end":"2026-05-11T10:49:37.554005Z","steps":["trace[675055720] 'process raft request' (duration: 388.509511ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:49:37.554044Z","caller":"traceutil/trace.go:171","msg":"trace[1817900342] transaction","detail":"{read_only:false; number_of_response:0; response_revision:287; }","duration":"572.778735ms","start":"2026-05-11T10:49:36.981231Z","end":"2026-05-11T10:49:37.55401Z","steps":["trace[1817900342] 'process raft request' (duration: 572.626291ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:49:37.554113Z","caller":"traceutil/trace.go:171","msg":"trace[817218035] transaction","detail":"{read_only:false; number_of_response:0; response_revision:287; }","duration":"572.721523ms","start":"2026-05-11T10:49:36.981386Z","end":"2026-05-11T10:49:37.554108Z","steps":["trace[817218035] 'process raft request' (duration: 572.513347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:49:37.554162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:49:37.165342Z","time spent":"388.693316ms","remote":"127.0.0.1:60054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T10:49:37.554176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:49:36.981219Z","time spent":"572.897259ms","remote":"127.0.0.1:60164","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T10:49:37.554206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:49:36.98138Z","time spent":"572.772285ms","remote":"127.0.0.1:60164","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T10:49:37.554223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.142097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-11T10:49:37.554258Z","caller":"traceutil/trace.go:171","msg":"trace[235894905] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:288; }","duration":"693.222388ms","start":"2026-05-11T10:49:36.861025Z","end":"2026-05-11T10:49:37.554248Z","steps":["trace[235894905] 'agreement among raft nodes before linearized reading' (duration: 693.112215ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:49:37.55411Z","caller":"traceutil/trace.go:171","msg":"trace[939259268] linearizableReadLoop","detail":"{readStateIndex:290; appliedIndex:288; }","duration":"692.836409ms","start":"2026-05-11T10:49:36.861262Z","end":"2026-05-11T10:49:37.554099Z","steps":["trace[939259268] 'read index received' (duration: 210.536655ms)","trace[939259268] 'applied index is now lower than readState.Index' (duration: 482.298154ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:49:37.554296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:49:36.861006Z","time spent":"693.28336ms","remote":"127.0.0.1:60406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-11T10:49:37.554358Z","caller":"traceutil/trace.go:171","msg":"trace[2013713866] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"574.227051ms","start":"2026-05-11T10:49:36.98012Z","end":"2026-05-11T10:49:37.554347Z","steps":["trace[2013713866] 'process raft request' (duration: 573.584135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:49:37.554384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.483104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:49:37.554408Z","caller":"traceutil/trace.go:171","msg":"trace[176322129] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:288; }","duration":"689.528415ms","start":"2026-05-11T10:49:36.864871Z","end":"2026-05-11T10:49:37.5544Z","steps":["trace[176322129] 'agreement among raft nodes before linearized reading' (duration: 689.475764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:49:37.554428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:49:36.980101Z","time spent":"574.291733ms","remote":"127.0.0.1:60164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T10:49:37.554454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:49:36.864863Z","time spent":"689.586866ms","remote":"127.0.0.1:60260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-05-11T10:49:37.55406Z","caller":"traceutil/trace.go:171","msg":"trace[1676009860] transaction","detail":"{read_only:false; number_of_response:0; response_revision:287; }","duration":"572.137559ms","start":"2026-05-11T10:49:36.981896Z","end":"2026-05-11T10:49:37.554034Z","steps":["trace[1676009860] 'process raft request' (duration: 572.028836ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:49:37.554516Z","caller":"traceutil/trace.go:171","msg":"trace[305080478] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"574.207821ms","start":"2026-05-11T10:49:36.980299Z","end":"2026-05-11T10:49:37.554507Z","steps":["trace[305080478] 'process raft request' (duration: 573.499353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:49:37.554524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:49:36.981889Z","time spent":"572.609482ms","remote":"127.0.0.1:60164","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-11T10:49:37.554558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.182915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-11T10:49:37.554561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:49:36.980271Z","time spent":"574.271603ms","remote":"127.0.0.1:60164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-11T10:49:37.554582Z","caller":"traceutil/trace.go:171","msg":"trace[609529325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:288; }","duration":"628.245896ms","start":"2026-05-11T10:49:36.926331Z","end":"2026-05-11T10:49:37.554576Z","steps":["trace[609529325] 'agreement among raft nodes before linearized reading' (duration: 628.212806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:49:37.554604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:49:36.926311Z","time spent":"628.288839ms","remote":"127.0.0.1:60260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-11T10:50:07.580483Z","caller":"traceutil/trace.go:171","msg":"trace[1586255732] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"134.636133ms","start":"2026-05-11T10:50:07.445818Z","end":"2026-05-11T10:50:07.580454Z","steps":["trace[1586255732] 'process raft request' (duration: 134.145741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:50:07.837227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.650733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T10:50:07.837338Z","caller":"traceutil/trace.go:171","msg":"trace[124779674] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:626; }","duration":"148.292132ms","start":"2026-05-11T10:50:07.689029Z","end":"2026-05-11T10:50:07.837321Z","steps":["trace[124779674] 'read index received' (duration: 6.471525ms)","trace[124779674] 'applied index is now lower than readState.Index' (duration: 141.819387ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:50:07.837456Z","caller":"traceutil/trace.go:171","msg":"trace[1921699114] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"197.980038ms","start":"2026-05-11T10:50:07.639443Z","end":"2026-05-11T10:50:07.837423Z","steps":["trace[1921699114] 'process raft request' (duration: 56.059749ms)","trace[1921699114] 'compare' (duration: 141.503998ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:50:07.837538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.507857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:50:07.837748Z","caller":"traceutil/trace.go:171","msg":"trace[723725134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"148.578529ms","start":"2026-05-11T10:50:07.688987Z","end":"2026-05-11T10:50:07.837565Z","steps":["trace[723725134] 'agreement among raft nodes before linearized reading' (duration: 148.460306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:50:07.838464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.008272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-11T10:50:07.838516Z","caller":"traceutil/trace.go:171","msg":"trace[1601974791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"106.088515ms","start":"2026-05-11T10:50:07.732415Z","end":"2026-05-11T10:50:07.838503Z","steps":["trace[1601974791] 'agreement among raft nodes before linearized reading' (duration: 105.10431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:50:08.350417Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14031138459218452235,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-11T10:50:08.395775Z","caller":"traceutil/trace.go:171","msg":"trace[69136464] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"549.877364ms","start":"2026-05-11T10:50:07.845866Z","end":"2026-05-11T10:50:08.395743Z","steps":["trace[69136464] 'process raft request' (duration: 511.557416ms)","trace[69136464] 'compare' (duration: 37.984729ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:50:08.39592Z","caller":"traceutil/trace.go:171","msg":"trace[101230454] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"546.458636ms","start":"2026-05-11T10:50:07.849431Z","end":"2026-05-11T10:50:08.39589Z","steps":["trace[101230454] 'read index received' (duration: 507.946504ms)","trace[101230454] 'applied index is now lower than readState.Index' (duration: 38.510682ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:50:08.396102Z","caller":"traceutil/trace.go:171","msg":"trace[166814998] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"221.308265ms","start":"2026-05-11T10:50:08.174781Z","end":"2026-05-11T10:50:08.396089Z","steps":["trace[166814998] 'process raft request' (duration: 220.855173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:50:08.396156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:50:07.84586Z","time spent":"549.984736ms","remote":"127.0.0.1:60260","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-05-11T10:50:08.396184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.720743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:50:08.396299Z","caller":"traceutil/trace.go:171","msg":"trace[770893121] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:606; }","duration":"546.870907ms","start":"2026-05-11T10:50:07.849393Z","end":"2026-05-11T10:50:08.396264Z","steps":["trace[770893121] 'agreement among raft nodes before linearized reading' (duration: 546.689333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:50:08.396366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.82559ms","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-05-11T10:50:08.39695Z","caller":"traceutil/trace.go:171","msg":"trace[1850281578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"515.428486ms","start":"2026-05-11T10:50:07.881461Z","end":"2026-05-11T10:50:08.39689Z","steps":["trace[1850281578] 'agreement among raft nodes before linearized reading' (duration: 514.668047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:50:08.396463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.336841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zsx6t\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-05-11T10:50:08.397062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:50:07.881443Z","time spent":"515.580999ms","remote":"127.0.0.1:60260","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-05-11T10:50:08.397081Z","caller":"traceutil/trace.go:171","msg":"trace[23071240] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zsx6t; range_end:; response_count:1; response_revision:606; }","duration":"422.953196ms","start":"2026-05-11T10:50:07.97409Z","end":"2026-05-11T10:50:08.397043Z","steps":["trace[23071240] 'agreement among raft nodes before linearized reading' (duration: 422.335751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:50:08.397166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:50:07.974064Z","time spent":"423.06631ms","remote":"127.0.0.1:60164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-zsx6t\" "} {"level":"warn","ts":"2026-05-11T10:50:08.396565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.543619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-11T10:50:08.397226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-11T10:50:07.849377Z","time spent":"547.827691ms","remote":"127.0.0.1:60344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":28,"request content":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-05-11T10:50:08.397247Z","caller":"traceutil/trace.go:171","msg":"trace[531067211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"142.252458ms","start":"2026-05-11T10:50:08.254977Z","end":"2026-05-11T10:50:08.39723Z","steps":["trace[531067211] 'agreement among raft nodes before linearized reading' (duration: 141.350964ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:50:57.462501Z","caller":"traceutil/trace.go:171","msg":"trace[1959682333] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"153.413192ms","start":"2026-05-11T10:50:57.309057Z","end":"2026-05-11T10:50:57.46247Z","steps":["trace[1959682333] 'process raft request' (duration: 153.240757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:51:23.83975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.950174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:51:23.839855Z","caller":"traceutil/trace.go:171","msg":"trace[53298792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1077; }","duration":"150.081718ms","start":"2026-05-11T10:51:23.68975Z","end":"2026-05-11T10:51:23.839831Z","steps":["trace[53298792] 'range keys from in-memory index tree' (duration: 149.802871ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:51:24.425846Z","caller":"traceutil/trace.go:171","msg":"trace[1063030462] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"201.420299ms","start":"2026-05-11T10:51:24.224387Z","end":"2026-05-11T10:51:24.425807Z","steps":["trace[1063030462] 'process raft request' (duration: 201.110522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:51:36.595634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.454993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-11T10:51:36.595725Z","caller":"traceutil/trace.go:171","msg":"trace[540538421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1169; }","duration":"113.607957ms","start":"2026-05-11T10:51:36.482098Z","end":"2026-05-11T10:51:36.595706Z","steps":["trace[540538421] 'range keys from in-memory index tree' (duration: 113.235148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:51:55.663919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.072132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-11T10:51:55.664134Z","caller":"traceutil/trace.go:171","msg":"trace[300173668] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"162.153579ms","start":"2026-05-11T10:51:55.501941Z","end":"2026-05-11T10:51:55.664095Z","steps":["trace[300173668] 'read index received' (duration: 10.720668ms)","trace[300173668] 'applied index is now lower than readState.Index' (duration: 151.430431ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:51:55.664324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.374964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:51:55.664365Z","caller":"traceutil/trace.go:171","msg":"trace[959734785] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1269; }","duration":"162.466586ms","start":"2026-05-11T10:51:55.501886Z","end":"2026-05-11T10:51:55.664352Z","steps":["trace[959734785] 'agreement among raft nodes before linearized reading' (duration: 162.288772ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:51:55.664629Z","caller":"traceutil/trace.go:171","msg":"trace[1317226808] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"281.650611ms","start":"2026-05-11T10:51:55.382963Z","end":"2026-05-11T10:51:55.664613Z","steps":["trace[1317226808] 'process raft request' (duration: 129.755149ms)","trace[1317226808] 'compare' (duration: 150.628551ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:51:55.861112Z","caller":"traceutil/trace.go:171","msg":"trace[2096392197] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"191.560343ms","start":"2026-05-11T10:51:55.669523Z","end":"2026-05-11T10:51:55.861083Z","steps":["trace[2096392197] 'process raft request' (duration: 191.387158ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:51:55.885688Z","caller":"traceutil/trace.go:171","msg":"trace[2072879511] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"215.844309ms","start":"2026-05-11T10:51:55.669806Z","end":"2026-05-11T10:51:55.885651Z","steps":["trace[2072879511] 'process raft request' (duration: 215.548452ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:51:55.885734Z","caller":"traceutil/trace.go:171","msg":"trace[1786306106] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1321; }","duration":"196.487096ms","start":"2026-05-11T10:51:55.689182Z","end":"2026-05-11T10:51:55.885669Z","steps":["trace[1786306106] 'read index received' (duration: 171.766618ms)","trace[1786306106] 'applied index is now lower than readState.Index' (duration: 24.718758ms)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:51:55.885793Z","caller":"traceutil/trace.go:171","msg":"trace[225126493] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"155.83888ms","start":"2026-05-11T10:51:55.729923Z","end":"2026-05-11T10:51:55.885762Z","steps":["trace[225126493] 'process raft request' (duration: 155.608505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:51:55.885899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.703872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-11T10:51:55.885964Z","caller":"traceutil/trace.go:171","msg":"trace[1572288125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"196.816116ms","start":"2026-05-11T10:51:55.689132Z","end":"2026-05-11T10:51:55.885948Z","steps":["trace[1572288125] 'agreement among raft nodes before linearized reading' (duration: 196.694702ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:52:09.028843Z","caller":"traceutil/trace.go:171","msg":"trace[236324130] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1403; }","duration":"266.801481ms","start":"2026-05-11T10:52:08.762006Z","end":"2026-05-11T10:52:09.028808Z","steps":["trace[236324130] 'read index received' (duration: 266.627706ms)","trace[236324130] 'applied index is now lower than readState.Index' (duration: 172.155µs)"],"step_count":2} {"level":"info","ts":"2026-05-11T10:52:09.028972Z","caller":"traceutil/trace.go:171","msg":"trace[1509495563] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"278.778041ms","start":"2026-05-11T10:52:08.750149Z","end":"2026-05-11T10:52:09.028927Z","steps":["trace[1509495563] 'process raft request' (duration: 278.436041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:52:09.029118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.079217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-11T10:52:09.029169Z","caller":"traceutil/trace.go:171","msg":"trace[756260296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1350; }","duration":"267.208941ms","start":"2026-05-11T10:52:08.761948Z","end":"2026-05-11T10:52:09.029157Z","steps":["trace[756260296] 'agreement among raft nodes before linearized reading' (duration: 267.000286ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:52:09.200901Z","caller":"traceutil/trace.go:171","msg":"trace[830100135] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1404; }","duration":"157.336489ms","start":"2026-05-11T10:52:09.04354Z","end":"2026-05-11T10:52:09.200877Z","steps":["trace[830100135] 'read index received' (duration: 125.094384ms)","trace[830100135] 'applied index is now lower than readState.Index' (duration: 32.240695ms)"],"step_count":2} {"level":"warn","ts":"2026-05-11T10:52:09.201403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.155726ms","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-05-11T10:52:09.201462Z","caller":"traceutil/trace.go:171","msg":"trace[458692804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1351; }","duration":"109.260048ms","start":"2026-05-11T10:52:09.092189Z","end":"2026-05-11T10:52:09.201449Z","steps":["trace[458692804] 'agreement among raft nodes before linearized reading' (duration: 109.042733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-11T10:52:09.201586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.047146ms","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-05-11T10:52:09.201654Z","caller":"traceutil/trace.go:171","msg":"trace[1822483258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1351; }","duration":"158.158369ms","start":"2026-05-11T10:52:09.043478Z","end":"2026-05-11T10:52:09.201636Z","steps":["trace[1822483258] 'agreement among raft nodes before linearized reading' (duration: 157.985675ms)"],"step_count":1} {"level":"info","ts":"2026-05-11T10:52:09.202011Z","caller":"traceutil/trace.go:171","msg":"trace[1261540879] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"166.512767ms","start":"2026-05-11T10:52:09.035061Z","end":"2026-05-11T10:52:09.201573Z","steps":["trace[1261540879] 'process raft request' (duration: 133.629986ms)","trace[1261540879] 'compare' (duration: 31.901257ms)"],"step_count":2}