{"level":"warn","ts":"2026-02-27T01:20:13.015495Z","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-27T01:20:13.015603Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.99:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.99:2380","--initial-cluster=instance=https://199.204.45.99:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.99:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.99: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-27T01:20:13.015683Z","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-27T01:20:13.015693Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.99:2380"]} {"level":"info","ts":"2026-02-27T01:20:13.018142Z","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-27T01:20:13.019168Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"]} {"level":"info","ts":"2026-02-27T01:20:13.01953Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.99:2380"],"listen-peer-urls":["https://199.204.45.99:2380"],"advertise-client-urls":["https://199.204.45.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.99: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-27T01:20:13.024152Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.102075ms"} {"level":"info","ts":"2026-02-27T01:20:13.032387Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"21032583b6cd9182","cluster-id":"a96cbe7391f279b7"} {"level":"info","ts":"2026-02-27T01:20:13.032497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T01:20:13.032527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became follower at term 0"} {"level":"info","ts":"2026-02-27T01:20:13.03254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 21032583b6cd9182 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T01:20:13.032554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became follower at term 1"} {"level":"info","ts":"2026-02-27T01:20:13.032593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=(2378786275819622786)"} {"level":"warn","ts":"2026-02-27T01:20:13.038086Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T01:20:13.039574Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T01:20:13.04071Z","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-27T01:20:13.042955Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"21032583b6cd9182","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T01:20:13.043252Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"21032583b6cd9182","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T01:20:13.043498Z","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-27T01:20:13.046517Z","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-27T01:20:13.046532Z","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-27T01:20:13.047142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 switched to configuration voters=(2378786275819622786)"} {"level":"info","ts":"2026-02-27T01:20:13.047756Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a96cbe7391f279b7","local-member-id":"21032583b6cd9182","added-peer-id":"21032583b6cd9182","added-peer-peer-urls":["https://199.204.45.99:2380"]} {"level":"info","ts":"2026-02-27T01:20:13.049677Z","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-27T01:20:13.049928Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.99:2380"} {"level":"info","ts":"2026-02-27T01:20:13.050014Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.99:2380"} {"level":"info","ts":"2026-02-27T01:20:13.050087Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T01:20:13.050034Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"21032583b6cd9182","initial-advertise-peer-urls":["https://199.204.45.99:2380"],"listen-peer-urls":["https://199.204.45.99:2380"],"advertise-client-urls":["https://199.204.45.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T01:20:13.732986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T01:20:13.733046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T01:20:13.733089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 received MsgPreVoteResp from 21032583b6cd9182 at term 1"} {"level":"info","ts":"2026-02-27T01:20:13.733116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became candidate at term 2"} {"level":"info","ts":"2026-02-27T01:20:13.733135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 received MsgVoteResp from 21032583b6cd9182 at term 2"} {"level":"info","ts":"2026-02-27T01:20:13.733157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"21032583b6cd9182 became leader at term 2"} {"level":"info","ts":"2026-02-27T01:20:13.733172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 21032583b6cd9182 elected leader 21032583b6cd9182 at term 2"} {"level":"info","ts":"2026-02-27T01:20:13.734399Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:20:13.735204Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"21032583b6cd9182","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.99:2379]}","request-path":"/0/members/21032583b6cd9182/attributes","cluster-id":"a96cbe7391f279b7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T01:20:13.735404Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T01:20:13.735507Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T01:20:13.735776Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a96cbe7391f279b7","local-member-id":"21032583b6cd9182","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:20:13.73591Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:20:13.735961Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T01:20:13.739068Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T01:20:13.743232Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T01:20:13.743291Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T01:20:13.743518Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.99:2379"} {"level":"info","ts":"2026-02-27T01:20:18.471615Z","caller":"traceutil/trace.go:171","msg":"trace[379663073] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"576.789102ms","start":"2026-02-27T01:20:17.894804Z","end":"2026-02-27T01:20:18.471594Z","steps":["trace[379663073] 'process raft request' (duration: 576.64349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:18.472142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:17.894784Z","time spent":"576.905452ms","remote":"127.0.0.1:43544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:20:19.166271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.482861ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-27T01:20:19.166502Z","caller":"traceutil/trace.go:171","msg":"trace[180468239] linearizableReadLoop","detail":"{readStateIndex:218; appliedIndex:217; }","duration":"690.627298ms","start":"2026-02-27T01:20:18.475854Z","end":"2026-02-27T01:20:19.166481Z","steps":["trace[180468239] 'read index received' (duration: 117.725922ms)","trace[180468239] 'applied index is now lower than readState.Index' (duration: 572.899586ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:20:19.166631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.776369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-z8d3s5\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:20:19.166703Z","caller":"traceutil/trace.go:171","msg":"trace[1939117330] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-z8d3s5; range_end:; response_count:0; response_revision:212; }","duration":"690.907061ms","start":"2026-02-27T01:20:18.475782Z","end":"2026-02-27T01:20:19.166689Z","steps":["trace[1939117330] 'agreement among raft nodes before linearized reading' (duration: 690.80236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:19.166767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:18.475752Z","time spent":"691.003532ms","remote":"127.0.0.1:43466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-z8d3s5\" "} {"level":"warn","ts":"2026-02-27T01:20:19.166661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:18.312175Z","time spent":"854.469028ms","remote":"127.0.0.1:43446","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-27T01:20:45.130513Z","caller":"traceutil/trace.go:171","msg":"trace[1097462505] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"212.097383ms","start":"2026-02-27T01:20:44.918392Z","end":"2026-02-27T01:20:45.130489Z","steps":["trace[1097462505] 'process raft request' (duration: 211.969488ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:20:50.983262Z","caller":"traceutil/trace.go:171","msg":"trace[1344364636] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:565; }","duration":"342.290552ms","start":"2026-02-27T01:20:50.640923Z","end":"2026-02-27T01:20:50.983214Z","steps":["trace[1344364636] 'read index received' (duration: 342.278901ms)","trace[1344364636] 'applied index is now lower than readState.Index' (duration: 9.161µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:20:50.98328Z","caller":"traceutil/trace.go:171","msg":"trace[1244160560] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"441.170415ms","start":"2026-02-27T01:20:50.542064Z","end":"2026-02-27T01:20:50.983235Z","steps":["trace[1244160560] 'process raft request' (duration: 440.970467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:50.983388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.453307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:20:50.983454Z","caller":"traceutil/trace.go:171","msg":"trace[597422826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"342.579922ms","start":"2026-02-27T01:20:50.640857Z","end":"2026-02-27T01:20:50.983437Z","steps":["trace[597422826] 'agreement among raft nodes before linearized reading' (duration: 342.454357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:50.983505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:50.640832Z","time spent":"342.654975ms","remote":"127.0.0.1:43342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T01:20:50.983534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:50.542039Z","time spent":"441.372881ms","remote":"127.0.0.1:43642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:20:52.27816Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485115079229762754,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T01:20:52.278553Z","caller":"traceutil/trace.go:171","msg":"trace[296019729] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"542.976176ms","start":"2026-02-27T01:20:51.73556Z","end":"2026-02-27T01:20:52.278536Z","steps":["trace[296019729] 'process raft request' (duration: 542.842791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:52.278797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:51.735537Z","time spent":"543.205424ms","remote":"127.0.0.1:43642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:20:52.503879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.504585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T01:20:52.504147Z","caller":"traceutil/trace.go:171","msg":"trace[1254947659] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"768.383875ms","start":"2026-02-27T01:20:51.735686Z","end":"2026-02-27T01:20:52.50407Z","steps":["trace[1254947659] 'process raft request' (duration: 603.61543ms)","trace[1254947659] 'compare' (duration: 164.407682ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:20:52.504251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:51.735637Z","time spent":"768.561611ms","remote":"127.0.0.1:43642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:20:53.44943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.461073ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-27T01:20:53.449549Z","caller":"traceutil/trace.go:171","msg":"trace[643419165] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:571; }","duration":"1.671402011s","start":"2026-02-27T01:20:51.778127Z","end":"2026-02-27T01:20:53.449529Z","steps":["trace[643419165] 'read index received' (duration: 500.299822ms)","trace[643419165] 'applied index is now lower than readState.Index' (duration: 1.171100729s)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:20:53.449692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.671556967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8hkbl\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-02-27T01:20:53.449728Z","caller":"traceutil/trace.go:171","msg":"trace[1102517829] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8hkbl; range_end:; response_count:1; response_revision:554; }","duration":"1.671627469s","start":"2026-02-27T01:20:51.778091Z","end":"2026-02-27T01:20:53.449718Z","steps":["trace[1102517829] 'agreement among raft nodes before linearized reading' (duration: 1.671537536s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:53.449774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:51.778077Z","time spent":"1.671675561s","remote":"127.0.0.1:43558","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-8hkbl\" "} {"level":"warn","ts":"2026-02-27T01:20:53.449754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.270841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:20:53.449831Z","caller":"traceutil/trace.go:171","msg":"trace[1608045861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"808.384085ms","start":"2026-02-27T01:20:52.64143Z","end":"2026-02-27T01:20:53.449814Z","steps":["trace[1608045861] 'agreement among raft nodes before linearized reading' (duration: 808.26035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:53.449867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:52.641414Z","time spent":"808.447097ms","remote":"127.0.0.1:43342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T01:20:53.596793Z","caller":"traceutil/trace.go:171","msg":"trace[978162381] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"115.150763ms","start":"2026-02-27T01:20:53.481619Z","end":"2026-02-27T01:20:53.59677Z","steps":["trace[978162381] 'process raft request' (duration: 114.937484ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:20:53.613916Z","caller":"traceutil/trace.go:171","msg":"trace[151305508] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"130.139307ms","start":"2026-02-27T01:20:53.483746Z","end":"2026-02-27T01:20:53.613886Z","steps":["trace[151305508] 'process raft request' (duration: 129.691421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:55.045664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.539322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:20:55.04577Z","caller":"traceutil/trace.go:171","msg":"trace[1625144197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"801.701898ms","start":"2026-02-27T01:20:54.244047Z","end":"2026-02-27T01:20:55.045749Z","steps":["trace[1625144197] 'range keys from in-memory index tree' (duration: 801.397007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:55.045807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:54.244Z","time spent":"801.798681ms","remote":"127.0.0.1:43342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T01:20:55.046127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.15216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-27T01:20:55.046154Z","caller":"traceutil/trace.go:171","msg":"trace[719052513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:557; }","duration":"763.214203ms","start":"2026-02-27T01:20:54.28293Z","end":"2026-02-27T01:20:55.046145Z","steps":["trace[719052513] 'range keys from in-memory index tree' (duration: 762.896111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:55.046172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:54.282907Z","time spent":"763.260374ms","remote":"127.0.0.1:43642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T01:20:55.046479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.884932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:20:55.046504Z","caller":"traceutil/trace.go:171","msg":"trace[1391221526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"406.934794ms","start":"2026-02-27T01:20:54.639563Z","end":"2026-02-27T01:20:55.046498Z","steps":["trace[1391221526] 'range keys from in-memory index tree' (duration: 406.787189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:55.04656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:54.639548Z","time spent":"406.970125ms","remote":"127.0.0.1:43344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T01:20:55.046732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.148036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-27T01:20:55.046823Z","caller":"traceutil/trace.go:171","msg":"trace[205670660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:557; }","duration":"538.28847ms","start":"2026-02-27T01:20:54.50851Z","end":"2026-02-27T01:20:55.046799Z","steps":["trace[205670660] 'range keys from in-memory index tree' (duration: 537.894256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:55.046871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:54.508489Z","time spent":"538.372973ms","remote":"127.0.0.1:43642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T01:20:55.810221Z","caller":"traceutil/trace.go:171","msg":"trace[305285604] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"758.318863ms","start":"2026-02-27T01:20:55.051876Z","end":"2026-02-27T01:20:55.810195Z","steps":["trace[305285604] 'process raft request' (duration: 758.129056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:55.810386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:55.051858Z","time spent":"758.443037ms","remote":"127.0.0.1:43642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:20:55.941178Z","caller":"traceutil/trace.go:171","msg":"trace[807861703] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:577; }","duration":"322.552984ms","start":"2026-02-27T01:20:55.618598Z","end":"2026-02-27T01:20:55.941151Z","steps":["trace[807861703] 'read index received' (duration: 191.432178ms)","trace[807861703] 'applied index is now lower than readState.Index' (duration: 131.119826ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:20:55.941236Z","caller":"traceutil/trace.go:171","msg":"trace[605298904] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"889.262642ms","start":"2026-02-27T01:20:55.051951Z","end":"2026-02-27T01:20:55.941213Z","steps":["trace[605298904] 'process raft request' (duration: 887.700238ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:20:55.941271Z","caller":"traceutil/trace.go:171","msg":"trace[1120461980] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"253.24768ms","start":"2026-02-27T01:20:55.687986Z","end":"2026-02-27T01:20:55.941234Z","steps":["trace[1120461980] 'process raft request' (duration: 253.102265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:55.941376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.762391ms","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-27T01:20:55.941382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:55.051927Z","time spent":"889.362786ms","remote":"127.0.0.1:43642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T01:20:55.941434Z","caller":"traceutil/trace.go:171","msg":"trace[1047646869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"322.860876ms","start":"2026-02-27T01:20:55.618557Z","end":"2026-02-27T01:20:55.941418Z","steps":["trace[1047646869] 'agreement among raft nodes before linearized reading' (duration: 322.737181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:55.941476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:55.618537Z","time spent":"322.927508ms","remote":"127.0.0.1:43642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T01:20:55.941476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.493497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T01:20:55.941524Z","caller":"traceutil/trace.go:171","msg":"trace[2077088779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"300.572269ms","start":"2026-02-27T01:20:55.640937Z","end":"2026-02-27T01:20:55.941509Z","steps":["trace[2077088779] 'agreement among raft nodes before linearized reading' (duration: 300.499197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:55.94156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:55.640918Z","time spent":"300.633312ms","remote":"127.0.0.1:43342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T01:20:55.941578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.124618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T01:20:55.941603Z","caller":"traceutil/trace.go:171","msg":"trace[996759860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"247.19035ms","start":"2026-02-27T01:20:55.694405Z","end":"2026-02-27T01:20:55.941596Z","steps":["trace[996759860] 'agreement among raft nodes before linearized reading' (duration: 247.103257ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:20:58.273269Z","caller":"traceutil/trace.go:171","msg":"trace[398259527] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"359.896811ms","start":"2026-02-27T01:20:57.913346Z","end":"2026-02-27T01:20:58.273243Z","steps":["trace[398259527] 'process raft request' (duration: 355.497042ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:20:58.273286Z","caller":"traceutil/trace.go:171","msg":"trace[1341175291] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"326.374518ms","start":"2026-02-27T01:20:57.946853Z","end":"2026-02-27T01:20:58.273227Z","steps":["trace[1341175291] 'read index received' (duration: 322.054612ms)","trace[1341175291] 'applied index is now lower than readState.Index' (duration: 4.318476ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T01:20:58.273401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:57.913317Z","time spent":"360.010455ms","remote":"127.0.0.1:43412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:20:58.273587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.71123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-27T01:20:58.273636Z","caller":"traceutil/trace.go:171","msg":"trace[1679794111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:587; }","duration":"326.859084ms","start":"2026-02-27T01:20:57.946764Z","end":"2026-02-27T01:20:58.273623Z","steps":["trace[1679794111] 'agreement among raft nodes before linearized reading' (duration: 326.669858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:58.273689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:57.946729Z","time spent":"326.950667ms","remote":"127.0.0.1:43642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T01:20:58.278045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.319861ms","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-27T01:20:58.27833Z","caller":"traceutil/trace.go:171","msg":"trace[561774185] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:588; }","duration":"298.644952ms","start":"2026-02-27T01:20:57.979651Z","end":"2026-02-27T01:20:58.278296Z","steps":["trace[561774185] 'agreement among raft nodes before linearized reading' (duration: 298.29307ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T01:20:58.27864Z","caller":"traceutil/trace.go:171","msg":"trace[1018456040] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"197.02372ms","start":"2026-02-27T01:20:58.081572Z","end":"2026-02-27T01:20:58.278596Z","steps":["trace[1018456040] 'process raft request' (duration: 196.257934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:58.279487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.739088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T01:20:58.27985Z","caller":"traceutil/trace.go:171","msg":"trace[1143316144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"300.09932ms","start":"2026-02-27T01:20:57.979735Z","end":"2026-02-27T01:20:58.279834Z","steps":["trace[1143316144] 'agreement among raft nodes before linearized reading' (duration: 299.055565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:20:58.280031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:20:57.979711Z","time spent":"300.304697ms","remote":"127.0.0.1:43642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T01:22:29.275053Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10485115079229763855,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T01:22:29.290943Z","caller":"traceutil/trace.go:171","msg":"trace[572631575] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"516.864336ms","start":"2026-02-27T01:22:28.77406Z","end":"2026-02-27T01:22:29.290924Z","steps":["trace[572631575] 'read index received' (duration: 516.685004ms)","trace[572631575] 'applied index is now lower than readState.Index' (duration: 178.532µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T01:22:29.291019Z","caller":"traceutil/trace.go:171","msg":"trace[1707166064] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"530.152508ms","start":"2026-02-27T01:22:28.760829Z","end":"2026-02-27T01:22:29.290982Z","steps":["trace[1707166064] 'process raft request' (duration: 529.988246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:22:29.291107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.039759ms","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-27T01:22:29.291155Z","caller":"traceutil/trace.go:171","msg":"trace[2057538468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1013; }","duration":"517.09396ms","start":"2026-02-27T01:22:28.774046Z","end":"2026-02-27T01:22:29.29114Z","steps":["trace[2057538468] 'agreement among raft nodes before linearized reading' (duration: 516.971298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:22:29.2912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:22:28.773994Z","time spent":"517.189991ms","remote":"127.0.0.1:43642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T01:22:29.291194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:22:28.760805Z","time spent":"530.30275ms","remote":"127.0.0.1:43642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T01:22:29.291438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.781032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T01:22:29.291597Z","caller":"traceutil/trace.go:171","msg":"trace[443036713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1013; }","duration":"507.974004ms","start":"2026-02-27T01:22:28.783593Z","end":"2026-02-27T01:22:29.291567Z","steps":["trace[443036713] 'agreement among raft nodes before linearized reading' (duration: 507.533218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T01:22:29.291691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T01:22:28.783581Z","time spent":"508.088967ms","remote":"127.0.0.1:43642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}