{"level":"warn","ts":"2026-02-09T18:49:22.917522Z","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-09T18:49:22.917827Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.102: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.102:2380","--initial-cluster=instance=https://199.204.45.102:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.102:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.102: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-09T18:49:22.917986Z","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-09T18:49:22.918031Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.102:2380"]}
{"level":"info","ts":"2026-02-09T18:49:22.918063Z","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-09T18:49:22.918958Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.102:2379"]}
{"level":"info","ts":"2026-02-09T18:49:22.91912Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.102:2380"],"listen-peer-urls":["https://199.204.45.102:2380"],"advertise-client-urls":["https://199.204.45.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.102: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-09T18:49:22.926113Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.762948ms"}
{"level":"info","ts":"2026-02-09T18:49:22.95306Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6e7960f761c1028e","cluster-id":"502e2b31b867adf"}
{"level":"info","ts":"2026-02-09T18:49:22.953282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e switched to configuration voters=()"}
{"level":"info","ts":"2026-02-09T18:49:22.953322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became follower at term 0"}
{"level":"info","ts":"2026-02-09T18:49:22.953356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6e7960f761c1028e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-02-09T18:49:22.9534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became follower at term 1"}
{"level":"info","ts":"2026-02-09T18:49:22.953467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e switched to configuration voters=(7960500431967289998)"}
{"level":"warn","ts":"2026-02-09T18:49:22.961137Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-02-09T18:49:22.96652Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-02-09T18:49:22.96954Z","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-09T18:49:22.97182Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6e7960f761c1028e","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-02-09T18:49:22.977442Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6e7960f761c1028e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-02-09T18:49:22.977965Z","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-09T18:49:22.978057Z","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-09T18:49:22.978124Z","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-09T18:49:22.97875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e switched to configuration voters=(7960500431967289998)"}
{"level":"info","ts":"2026-02-09T18:49:22.979076Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"502e2b31b867adf","local-member-id":"6e7960f761c1028e","added-peer-id":"6e7960f761c1028e","added-peer-peer-urls":["https://199.204.45.102:2380"]}
{"level":"info","ts":"2026-02-09T18:49:22.979478Z","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-09T18:49:22.979868Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6e7960f761c1028e","initial-advertise-peer-urls":["https://199.204.45.102:2380"],"listen-peer-urls":["https://199.204.45.102:2380"],"advertise-client-urls":["https://199.204.45.102:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.102:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-02-09T18:49:22.979733Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.102:2380"}
{"level":"info","ts":"2026-02-09T18:49:22.980939Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.102:2380"}
{"level":"info","ts":"2026-02-09T18:49:22.980791Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-02-09T18:49:23.856267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e is starting a new election at term 1"}
{"level":"info","ts":"2026-02-09T18:49:23.856304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became pre-candidate at term 1"}
{"level":"info","ts":"2026-02-09T18:49:23.856325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e received MsgPreVoteResp from 6e7960f761c1028e at term 1"}
{"level":"info","ts":"2026-02-09T18:49:23.856343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became candidate at term 2"}
{"level":"info","ts":"2026-02-09T18:49:23.856351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e received MsgVoteResp from 6e7960f761c1028e at term 2"}
{"level":"info","ts":"2026-02-09T18:49:23.856359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6e7960f761c1028e became leader at term 2"}
{"level":"info","ts":"2026-02-09T18:49:23.85637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6e7960f761c1028e elected leader 6e7960f761c1028e at term 2"}
{"level":"info","ts":"2026-02-09T18:49:23.860349Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6e7960f761c1028e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.102:2379]}","request-path":"/0/members/6e7960f761c1028e/attributes","cluster-id":"502e2b31b867adf","publish-timeout":"7s"}
{"level":"info","ts":"2026-02-09T18:49:23.860374Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-02-09T18:49:23.860832Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-02-09T18:49:23.865301Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-02-09T18:49:23.870467Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-02-09T18:49:23.870573Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"502e2b31b867adf","local-member-id":"6e7960f761c1028e","cluster-version":"3.5"}
{"level":"info","ts":"2026-02-09T18:49:23.870615Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-02-09T18:49:23.870629Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-02-09T18:49:23.891533Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-02-09T18:49:23.891548Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-02-09T18:49:23.909561Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.102:2379"}
{"level":"info","ts":"2026-02-09T18:49:29.96556Z","caller":"traceutil/trace.go:171","msg":"trace[561143877] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"100.933313ms","start":"2026-02-09T18:49:29.864613Z","end":"2026-02-09T18:49:29.965546Z","steps":["trace[561143877] 'process raft request'  (duration: 48.02072ms)","trace[561143877] 'compare'  (duration: 52.708681ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-09T18:49:30.184837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.372488ms","expected-duration":"100ms","prefix":"","request":"header:<ID:184256449510575053 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" mod_revision:0 > success:<request_put:<key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" value_size:121 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-02-09T18:49:30.1857Z","caller":"traceutil/trace.go:171","msg":"trace[1134040017] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"130.917685ms","start":"2026-02-09T18:49:30.054771Z","end":"2026-02-09T18:49:30.185688Z","steps":["trace[1134040017] 'process raft request'  (duration: 11.256593ms)","trace[1134040017] 'compare'  (duration: 118.262457ms)"],"step_count":2}
{"level":"info","ts":"2026-02-09T18:49:30.185657Z","caller":"traceutil/trace.go:171","msg":"trace[915580962] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:330; }","duration":"119.4751ms","start":"2026-02-09T18:49:30.066165Z","end":"2026-02-09T18:49:30.18564Z","steps":["trace[915580962] 'read index received'  (duration: 18.38µs)","trace[915580962] 'applied index is now lower than readState.Index'  (duration: 119.45522ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-09T18:49:30.187735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.527792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5098"}
{"level":"info","ts":"2026-02-09T18:49:30.187926Z","caller":"traceutil/trace.go:171","msg":"trace[1042261533] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:321; }","duration":"121.768536ms","start":"2026-02-09T18:49:30.066143Z","end":"2026-02-09T18:49:30.187912Z","steps":["trace[1042261533] 'agreement among raft nodes before linearized reading'  (duration: 120.634343ms)"],"step_count":1}
{"level":"info","ts":"2026-02-09T18:49:36.094573Z","caller":"traceutil/trace.go:171","msg":"trace[2146012421] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"181.118199ms","start":"2026-02-09T18:49:35.913131Z","end":"2026-02-09T18:49:36.094249Z","steps":["trace[2146012421] 'process raft request'  (duration: 177.173027ms)"],"step_count":1}
{"level":"info","ts":"2026-02-09T18:49:39.798776Z","caller":"traceutil/trace.go:171","msg":"trace[2002489126] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"109.73453ms","start":"2026-02-09T18:49:39.689023Z","end":"2026-02-09T18:49:39.798758Z","steps":["trace[2002489126] 'process raft request'  (duration: 47.023133ms)","trace[2002489126] 'compare'  (duration: 62.379743ms)"],"step_count":2}
{"level":"info","ts":"2026-02-09T18:49:39.801636Z","caller":"traceutil/trace.go:171","msg":"trace[130812925] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"105.624927ms","start":"2026-02-09T18:49:39.695997Z","end":"2026-02-09T18:49:39.801622Z","steps":["trace[130812925] 'process raft request'  (duration: 105.145622ms)"],"step_count":1}
{"level":"info","ts":"2026-02-09T18:49:39.801975Z","caller":"traceutil/trace.go:171","msg":"trace[1951345138] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"102.639425ms","start":"2026-02-09T18:49:39.699322Z","end":"2026-02-09T18:49:39.801962Z","steps":["trace[1951345138] 'process raft request'  (duration: 101.972338ms)"],"step_count":1}
{"level":"info","ts":"2026-02-09T18:49:39.802393Z","caller":"traceutil/trace.go:171","msg":"trace[828140684] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"104.313273ms","start":"2026-02-09T18:49:39.698067Z","end":"2026-02-09T18:49:39.802381Z","steps":["trace[828140684] 'process raft request'  (duration: 103.16086ms)"],"step_count":1}
{"level":"info","ts":"2026-02-09T18:49:39.802755Z","caller":"traceutil/trace.go:171","msg":"trace[868252329] transaction","detail":"{read_only:false; number_of_response:1; response_revision:442; }","duration":"101.887917ms","start":"2026-02-09T18:49:39.700853Z","end":"2026-02-09T18:49:39.802741Z","steps":["trace[868252329] 'process raft request'  (duration: 100.476683ms)"],"step_count":1}
{"level":"info","ts":"2026-02-09T18:49:40.039618Z","caller":"traceutil/trace.go:171","msg":"trace[163308723] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:459; }","duration":"199.69455ms","start":"2026-02-09T18:49:39.839904Z","end":"2026-02-09T18:49:40.039599Z","steps":["trace[163308723] 'read index received'  (duration: 29.071225ms)","trace[163308723] 'applied index is now lower than readState.Index'  (duration: 170.620635ms)"],"step_count":2}
{"level":"info","ts":"2026-02-09T18:49:40.039696Z","caller":"traceutil/trace.go:171","msg":"trace[1607810966] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"200.506798ms","start":"2026-02-09T18:49:39.83918Z","end":"2026-02-09T18:49:40.039687Z","steps":["trace[1607810966] 'process raft request'  (duration: 133.73202ms)","trace[1607810966] 'compare'  (duration: 66.431874ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-09T18:49:40.039823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.902742ms","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-09T18:49:40.039844Z","caller":"traceutil/trace.go:171","msg":"trace[1636236691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:445; }","duration":"199.951322ms","start":"2026-02-09T18:49:39.839886Z","end":"2026-02-09T18:49:40.039837Z","steps":["trace[1636236691] 'agreement among raft nodes before linearized reading'  (duration: 199.891152ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:49:40.044577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.126667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-09T18:49:40.044668Z","caller":"traceutil/trace.go:171","msg":"trace[1038411265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:446; }","duration":"144.278728ms","start":"2026-02-09T18:49:39.900377Z","end":"2026-02-09T18:49:40.044656Z","steps":["trace[1038411265] 'agreement among raft nodes before linearized reading'  (duration: 144.156117ms)"],"step_count":1}
{"level":"info","ts":"2026-02-09T18:49:40.044819Z","caller":"traceutil/trace.go:171","msg":"trace[2145400955] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"168.29724ms","start":"2026-02-09T18:49:39.876514Z","end":"2026-02-09T18:49:40.044811Z","steps":["trace[2145400955] 'process raft request'  (duration: 167.932206ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:49:42.159984Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256449510575309,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-02-09T18:49:42.660889Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256449510575309,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-02-09T18:49:43.16128Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256449510575309,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-02-09T18:49:43.372167Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.936085359s","expected-duration":"1s"}
{"level":"info","ts":"2026-02-09T18:49:43.37242Z","caller":"traceutil/trace.go:171","msg":"trace[67783415] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"1.936398063s","start":"2026-02-09T18:49:41.436007Z","end":"2026-02-09T18:49:43.372405Z","steps":["trace[67783415] 'process raft request'  (duration: 1.936305762s)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:49:43.372591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:41.435995Z","time spent":"1.936447513s","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5083,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/etcd-instance\" mod_revision:322 > success:<request_put:<key:\"/registry/pods/kube-system/etcd-instance\" value_size:5035 >> failure:<request_range:<key:\"/registry/pods/kube-system/etcd-instance\" > >"}
{"level":"warn","ts":"2026-02-09T18:49:43.896381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000138688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"}
{"level":"info","ts":"2026-02-09T18:49:43.896439Z","caller":"traceutil/trace.go:171","msg":"trace[1741492134] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000246869s","start":"2026-02-09T18:49:41.896179Z","end":"2026-02-09T18:49:43.896426Z","steps":["trace[1741492134] 'agreement among raft nodes before linearized reading'  (duration: 2.000169238s)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:49:43.896474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:41.896159Z","time spent":"2.00030835s","remote":"127.0.0.1:44652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "}
2026/02/09 18:49:43 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing"
{"level":"info","ts":"2026-02-09T18:49:44.339336Z","caller":"traceutil/trace.go:171","msg":"trace[323065099] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:467; }","duration":"2.680261045s","start":"2026-02-09T18:49:41.659053Z","end":"2026-02-09T18:49:44.339314Z","steps":["trace[323065099] 'read index received'  (duration: 1.713323407s)","trace[323065099] 'applied index is now lower than readState.Index'  (duration: 966.936618ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-09T18:49:44.339443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.680369146s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"warn","ts":"2026-02-09T18:49:44.339486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.636122837s","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-09T18:49:44.339507Z","caller":"traceutil/trace.go:171","msg":"trace[885612184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:452; }","duration":"2.636178697s","start":"2026-02-09T18:49:41.703322Z","end":"2026-02-09T18:49:44.339501Z","steps":["trace[885612184] 'agreement among raft nodes before linearized reading'  (duration: 2.636100876s)"],"step_count":1}
{"level":"info","ts":"2026-02-09T18:49:44.339492Z","caller":"traceutil/trace.go:171","msg":"trace[618648269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:452; }","duration":"2.680463917s","start":"2026-02-09T18:49:41.659014Z","end":"2026-02-09T18:49:44.339478Z","steps":["trace[618648269] 'agreement among raft nodes before linearized reading'  (duration: 2.680351716s)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:49:44.33953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:41.703312Z","time spent":"2.636213668s","remote":"127.0.0.1:44898","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-09T18:49:44.339725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:41.658997Z","time spent":"2.680535267s","remote":"127.0.0.1:44898","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-09T18:49:44.339764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.300169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-09T18:49:44.339787Z","caller":"traceutil/trace.go:171","msg":"trace[2131247208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:452; }","duration":"443.34889ms","start":"2026-02-09T18:49:43.896431Z","end":"2026-02-09T18:49:44.33978Z","steps":["trace[2131247208] 'agreement among raft nodes before linearized reading'  (duration: 443.272379ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:49:44.339892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:43.896419Z","time spent":"443.38789ms","remote":"127.0.0.1:44648","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-09T18:49:44.34035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.794993361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-02-09T18:49:44.340375Z","caller":"traceutil/trace.go:171","msg":"trace[1799170318] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:452; }","duration":"1.795045342s","start":"2026-02-09T18:49:42.545316Z","end":"2026-02-09T18:49:44.340361Z","steps":["trace[1799170318] 'agreement among raft nodes before linearized reading'  (duration: 1.79487334s)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:49:44.340378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.286202468s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"warn","ts":"2026-02-09T18:49:44.340391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:42.545297Z","time spent":"1.795089082s","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-02-09T18:49:44.340405Z","caller":"traceutil/trace.go:171","msg":"trace[1742391168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:452; }","duration":"2.286260408s","start":"2026-02-09T18:49:42.054138Z","end":"2026-02-09T18:49:44.340398Z","steps":["trace[1742391168] 'agreement among raft nodes before linearized reading'  (duration: 2.286213488s)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:49:44.340567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:42.054121Z","time spent":"2.286297558s","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-02-09T18:49:54.142245Z","caller":"traceutil/trace.go:171","msg":"trace[1511017523] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"325.092133ms","start":"2026-02-09T18:49:53.817126Z","end":"2026-02-09T18:49:54.142218Z","steps":["trace[1511017523] 'process raft request'  (duration: 324.850544ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:49:54.142469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:49:53.817109Z","time spent":"325.216179ms","remote":"127.0.0.1:44806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/minions/instance\" mod_revision:412 > success:<request_put:<key:\"/registry/minions/instance\" value_size:4177 >> failure:<request_range:<key:\"/registry/minions/instance\" > >"}
{"level":"info","ts":"2026-02-09T18:49:54.142503Z","caller":"traceutil/trace.go:171","msg":"trace[1521470001] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:530; }","duration":"242.117782ms","start":"2026-02-09T18:49:53.900372Z","end":"2026-02-09T18:49:54.14249Z","steps":["trace[1521470001] 'read index received'  (duration: 242.112992ms)","trace[1521470001] 'applied index is now lower than readState.Index'  (duration: 3.45µs)"],"step_count":2}
{"level":"warn","ts":"2026-02-09T18:49:54.142621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.241118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-09T18:49:54.142642Z","caller":"traceutil/trace.go:171","msg":"trace[2130452078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"242.283749ms","start":"2026-02-09T18:49:53.90035Z","end":"2026-02-09T18:49:54.142634Z","steps":["trace[2130452078] 'agreement among raft nodes before linearized reading'  (duration: 242.200096ms)"],"step_count":1}
{"level":"info","ts":"2026-02-09T18:50:00.67302Z","caller":"traceutil/trace.go:171","msg":"trace[221695157] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"114.477749ms","start":"2026-02-09T18:50:00.558528Z","end":"2026-02-09T18:50:00.673006Z","steps":["trace[221695157] 'process raft request'  (duration: 114.003491ms)"],"step_count":1}
{"level":"info","ts":"2026-02-09T18:50:00.833809Z","caller":"traceutil/trace.go:171","msg":"trace[51508083] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"154.06765ms","start":"2026-02-09T18:50:00.679724Z","end":"2026-02-09T18:50:00.833791Z","steps":["trace[51508083] 'process raft request'  (duration: 154.044379ms)"],"step_count":1}
{"level":"info","ts":"2026-02-09T18:50:00.834054Z","caller":"traceutil/trace.go:171","msg":"trace[397191037] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"156.12969ms","start":"2026-02-09T18:50:00.677917Z","end":"2026-02-09T18:50:00.834046Z","steps":["trace[397191037] 'process raft request'  (duration: 87.724999ms)","trace[397191037] 'compare'  (duration: 68.002836ms)"],"step_count":2}
{"level":"info","ts":"2026-02-09T18:50:00.834127Z","caller":"traceutil/trace.go:171","msg":"trace[1666946219] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"156.079068ms","start":"2026-02-09T18:50:00.678042Z","end":"2026-02-09T18:50:00.834121Z","steps":["trace[1666946219] 'process raft request'  (duration: 155.684903ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:50:01.401312Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256449510575524,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-02-09T18:50:01.885574Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.027182689s","expected-duration":"1s"}
{"level":"info","ts":"2026-02-09T18:50:01.88601Z","caller":"traceutil/trace.go:171","msg":"trace[1571271519] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"989.185129ms","start":"2026-02-09T18:50:00.896803Z","end":"2026-02-09T18:50:01.885988Z","steps":["trace[1571271519] 'read index received'  (duration: 988.930599ms)","trace[1571271519] 'applied index is now lower than readState.Index'  (duration: 253.64µs)"],"step_count":2}
{"level":"warn","ts":"2026-02-09T18:50:01.886158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.345864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-09T18:50:01.886209Z","caller":"traceutil/trace.go:171","msg":"trace[186100116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"989.429708ms","start":"2026-02-09T18:50:00.89675Z","end":"2026-02-09T18:50:01.88618Z","steps":["trace[186100116] 'agreement among raft nodes before linearized reading'  (duration: 989.333525ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:50:01.886242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:50:00.896734Z","time spent":"989.50026ms","remote":"127.0.0.1:44652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-02-09T18:50:01.886316Z","caller":"traceutil/trace.go:171","msg":"trace[1604187280] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.038893206s","start":"2026-02-09T18:50:00.847403Z","end":"2026-02-09T18:50:01.886296Z","steps":["trace[1604187280] 'process raft request'  (duration: 1.038418588s)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:50:01.886409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:50:00.847392Z","time spent":"1.038964919s","remote":"127.0.0.1:44812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-xplsp\" mod_revision:504 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-xplsp\" value_size:16754 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-xplsp\" > >"}
{"level":"info","ts":"2026-02-09T18:50:02.784831Z","caller":"traceutil/trace.go:171","msg":"trace[1932574730] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"105.032007ms","start":"2026-02-09T18:50:02.679776Z","end":"2026-02-09T18:50:02.784808Z","steps":["trace[1932574730] 'process raft request'  (duration: 104.868731ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:50:04.266245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.531108ms","expected-duration":"100ms","prefix":"","request":"header:<ID:184256449510575574 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/apiregistration.k8s.io/apiservices/v2.cilium.io\" mod_revision:0 > success:<request_put:<key:\"/registry/apiregistration.k8s.io/apiservices/v2.cilium.io\" value_size:867 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-02-09T18:50:04.266449Z","caller":"traceutil/trace.go:171","msg":"trace[532421863] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"233.455982ms","start":"2026-02-09T18:50:04.032981Z","end":"2026-02-09T18:50:04.266437Z","steps":["trace[532421863] 'process raft request'  (duration: 12.690495ms)","trace[532421863] 'compare'  (duration: 220.438405ms)"],"step_count":2}
{"level":"info","ts":"2026-02-09T18:50:04.391672Z","caller":"traceutil/trace.go:171","msg":"trace[825282762] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"345.408622ms","start":"2026-02-09T18:50:04.045909Z","end":"2026-02-09T18:50:04.391318Z","steps":["trace[825282762] 'process raft request'  (duration: 334.497162ms)","trace[825282762] 'compare'  (duration: 10.765375ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-09T18:50:04.391843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:50:04.045892Z","time spent":"345.860819ms","remote":"127.0.0.1:44696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" mod_revision:577 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" value_size:4218 >> failure:<request_range:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" > >"}
{"level":"info","ts":"2026-02-09T18:50:04.395457Z","caller":"traceutil/trace.go:171","msg":"trace[540994219] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:600; }","duration":"104.072572ms","start":"2026-02-09T18:50:04.291375Z","end":"2026-02-09T18:50:04.395448Z","steps":["trace[540994219] 'read index received'  (duration: 88.953618ms)","trace[540994219] 'applied index is now lower than readState.Index'  (duration: 15.118314ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-09T18:50:04.395533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.152365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-09T18:50:04.395593Z","caller":"traceutil/trace.go:171","msg":"trace[191300549] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:582; }","duration":"104.237718ms","start":"2026-02-09T18:50:04.291347Z","end":"2026-02-09T18:50:04.395584Z","steps":["trace[191300549] 'agreement among raft nodes before linearized reading'  (duration: 104.153955ms)"],"step_count":1}
{"level":"info","ts":"2026-02-09T18:50:04.39582Z","caller":"traceutil/trace.go:171","msg":"trace[1613789715] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"337.829015ms","start":"2026-02-09T18:50:04.057957Z","end":"2026-02-09T18:50:04.395786Z","steps":["trace[1613789715] 'process raft request'  (duration: 337.410609ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:50:04.395895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:50:04.057946Z","time spent":"337.896817ms","remote":"127.0.0.1:44696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" mod_revision:578 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" value_size:5473 >> failure:<request_range:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" > >"}
{"level":"info","ts":"2026-02-09T18:50:04.396019Z","caller":"traceutil/trace.go:171","msg":"trace[385123027] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"117.010315ms","start":"2026-02-09T18:50:04.278991Z","end":"2026-02-09T18:50:04.396002Z","steps":["trace[385123027] 'process raft request'  (duration: 116.436614ms)"],"step_count":1}
{"level":"info","ts":"2026-02-09T18:50:12.813075Z","caller":"traceutil/trace.go:171","msg":"trace[1896338780] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"425.897947ms","start":"2026-02-09T18:50:12.387161Z","end":"2026-02-09T18:50:12.813059Z","steps":["trace[1896338780] 'process raft request'  (duration: 425.816264ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:50:12.813164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:50:12.387147Z","time spent":"425.980539ms","remote":"127.0.0.1:34570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1236,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/cilium.io/ciliumidentities/1756\" mod_revision:0 > success:<request_put:<key:\"/registry/cilium.io/ciliumidentities/1756\" value_size:1187 >> failure:<>"}
{"level":"warn","ts":"2026-02-09T18:50:21.584172Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256449510575908,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-02-09T18:50:22.085314Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256449510575908,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-02-09T18:50:22.585701Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256449510575908,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-02-09T18:50:22.717292Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.637876522s","expected-duration":"1s"}
{"level":"info","ts":"2026-02-09T18:50:22.727864Z","caller":"traceutil/trace.go:171","msg":"trace[1069194875] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"1.648520419s","start":"2026-02-09T18:50:21.079329Z","end":"2026-02-09T18:50:22.727849Z","steps":["trace[1069194875] 'process raft request'  (duration: 1.638071408s)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:50:22.727951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:50:21.079301Z","time spent":"1.648591022s","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:754 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-02-09T18:50:22.728739Z","caller":"traceutil/trace.go:171","msg":"trace[1601571915] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:786; }","duration":"1.645276549s","start":"2026-02-09T18:50:21.083455Z","end":"2026-02-09T18:50:22.728732Z","steps":["trace[1601571915] 'read index received'  (duration: 1.6345977s)","trace[1601571915] 'applied index is now lower than readState.Index'  (duration: 10.678219ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-09T18:50:22.728928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.645469874s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"info","ts":"2026-02-09T18:50:22.728948Z","caller":"traceutil/trace.go:171","msg":"trace[399328246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:761; }","duration":"1.645507876s","start":"2026-02-09T18:50:21.083433Z","end":"2026-02-09T18:50:22.728941Z","steps":["trace[399328246] 'agreement among raft nodes before linearized reading'  (duration: 1.645428684s)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:50:22.728962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:50:21.083418Z","time spent":"1.645540917s","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-02-09T18:50:22.729049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.645022111s","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-09T18:50:22.729062Z","caller":"traceutil/trace.go:171","msg":"trace[1975661464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:761; }","duration":"1.645044372s","start":"2026-02-09T18:50:21.084013Z","end":"2026-02-09T18:50:22.729058Z","steps":["trace[1975661464] 'agreement among raft nodes before linearized reading'  (duration: 1.64500672s)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:50:22.729074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:50:21.084009Z","time spent":"1.645060972s","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-02-09T18:50:22.729177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.363383ms","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-09T18:50:22.72919Z","caller":"traceutil/trace.go:171","msg":"trace[1266227598] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:761; }","duration":"547.408795ms","start":"2026-02-09T18:50:22.181777Z","end":"2026-02-09T18:50:22.729186Z","steps":["trace[1266227598] 'agreement among raft nodes before linearized reading'  (duration: 547.368793ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:50:22.729202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:50:22.18176Z","time spent":"547.438716ms","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-02-09T18:50:22.729287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.232874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-09T18:50:22.729443Z","caller":"traceutil/trace.go:171","msg":"trace[1182356381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"831.410489ms","start":"2026-02-09T18:50:21.898028Z","end":"2026-02-09T18:50:22.729438Z","steps":["trace[1182356381] 'agreement among raft nodes before linearized reading'  (duration: 831.248484ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:50:22.729458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:50:21.898015Z","time spent":"831.439161ms","remote":"127.0.0.1:44652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-02-09T18:50:23.205753Z","caller":"traceutil/trace.go:171","msg":"trace[1270889848] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"112.279725ms","start":"2026-02-09T18:50:23.093457Z","end":"2026-02-09T18:50:23.205737Z","steps":["trace[1270889848] 'process raft request'  (duration: 112.171852ms)"],"step_count":1}
{"level":"info","ts":"2026-02-09T18:51:29.127536Z","caller":"traceutil/trace.go:171","msg":"trace[341083312] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"122.524629ms","start":"2026-02-09T18:51:29.00499Z","end":"2026-02-09T18:51:29.127514Z","steps":["trace[341083312] 'process raft request'  (duration: 121.929508ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:51:30.821748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.607049239s","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-09T18:51:30.821816Z","caller":"traceutil/trace.go:171","msg":"trace[1643666345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:991; }","duration":"1.607164782s","start":"2026-02-09T18:51:29.214635Z","end":"2026-02-09T18:51:30.8218Z","steps":["trace[1643666345] 'range keys from in-memory index tree'  (duration: 1.606875276s)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:51:30.821853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:51:29.214615Z","time spent":"1.607229344s","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-02-09T18:51:30.822124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.595474698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"info","ts":"2026-02-09T18:51:30.822153Z","caller":"traceutil/trace.go:171","msg":"trace[1841419652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:991; }","duration":"1.595504458s","start":"2026-02-09T18:51:29.22664Z","end":"2026-02-09T18:51:30.822145Z","steps":["trace[1841419652] 'range keys from in-memory index tree'  (duration: 1.595391836s)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:51:30.822234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:51:29.226614Z","time spent":"1.59561262s","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-02-09T18:51:30.822402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.573988ms","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-09T18:51:30.822429Z","caller":"traceutil/trace.go:171","msg":"trace[1937500633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:991; }","duration":"834.632399ms","start":"2026-02-09T18:51:29.987788Z","end":"2026-02-09T18:51:30.822421Z","steps":["trace[1937500633] 'range keys from in-memory index tree'  (duration: 834.482676ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:51:30.822456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:51:29.987769Z","time spent":"834.67798ms","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-02-09T18:51:30.822559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.562278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-02-09T18:51:30.822577Z","caller":"traceutil/trace.go:171","msg":"trace[2109704112] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:991; }","duration":"834.603079ms","start":"2026-02-09T18:51:29.987969Z","end":"2026-02-09T18:51:30.822572Z","steps":["trace[2109704112] 'count revisions from in-memory index tree'  (duration: 834.530257ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:51:30.822595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:51:29.987963Z","time spent":"834.627359ms","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "}
{"level":"warn","ts":"2026-02-09T18:51:30.822672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.579534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-09T18:51:30.82269Z","caller":"traceutil/trace.go:171","msg":"trace[344790367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"827.604225ms","start":"2026-02-09T18:51:29.995078Z","end":"2026-02-09T18:51:30.822682Z","steps":["trace[344790367] 'range keys from in-memory index tree'  (duration: 827.552334ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:51:30.822705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:51:29.995072Z","time spent":"827.629366ms","remote":"127.0.0.1:44652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-02-09T18:51:33.766112Z","caller":"traceutil/trace.go:171","msg":"trace[322319191] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"460.74819ms","start":"2026-02-09T18:51:33.305338Z","end":"2026-02-09T18:51:33.766086Z","steps":["trace[322319191] 'process raft request'  (duration: 446.358949ms)","trace[322319191] 'compare'  (duration: 13.953002ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-09T18:51:33.76625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:51:33.305324Z","time spent":"460.866191ms","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:997 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-02-09T18:51:35.881013Z","caller":"traceutil/trace.go:171","msg":"trace[883253818] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"106.164367ms","start":"2026-02-09T18:51:35.774828Z","end":"2026-02-09T18:51:35.880992Z","steps":["trace[883253818] 'process raft request'  (duration: 106.046845ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:51:37.484695Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":184256449510576636,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-02-09T18:51:37.689776Z","caller":"traceutil/trace.go:171","msg":"trace[1015333229] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1057; }","duration":"705.260554ms","start":"2026-02-09T18:51:36.984495Z","end":"2026-02-09T18:51:37.689756Z","steps":["trace[1015333229] 'read index received'  (duration: 705.095091ms)","trace[1015333229] 'applied index is now lower than readState.Index'  (duration: 164.343µs)"],"step_count":2}
{"level":"info","ts":"2026-02-09T18:51:37.689811Z","caller":"traceutil/trace.go:171","msg":"trace[788935580] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"705.51576ms","start":"2026-02-09T18:51:36.984257Z","end":"2026-02-09T18:51:37.689773Z","steps":["trace[788935580] 'process raft request'  (duration: 705.349547ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:51:37.689949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:51:36.984236Z","time spent":"705.651522ms","remote":"127.0.0.1:44686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/masterleases/199.204.45.102\" mod_revision:981 > success:<request_put:<key:\"/registry/masterleases/199.204.45.102\" value_size:67 lease:184256449510576634 >> failure:<request_range:<key:\"/registry/masterleases/199.204.45.102\" > >"}
{"level":"warn","ts":"2026-02-09T18:51:37.689953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.442139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-09T18:51:37.689981Z","caller":"traceutil/trace.go:171","msg":"trace[1980763930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"705.505669ms","start":"2026-02-09T18:51:36.984466Z","end":"2026-02-09T18:51:37.689972Z","steps":["trace[1980763930] 'agreement among raft nodes before linearized reading'  (duration: 705.396597ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:51:37.690004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:51:36.984454Z","time spent":"705.54498ms","remote":"127.0.0.1:44648","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-09T18:51:37.690204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.04305ms","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-09T18:51:37.690223Z","caller":"traceutil/trace.go:171","msg":"trace[184097072] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1013; }","duration":"436.06292ms","start":"2026-02-09T18:51:37.254155Z","end":"2026-02-09T18:51:37.690217Z","steps":["trace[184097072] 'agreement among raft nodes before linearized reading'  (duration: 435.993759ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:51:37.690239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:51:37.254119Z","time spent":"436.1164ms","remote":"127.0.0.1:44898","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-09T18:51:37.690265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.029291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-02-09T18:51:37.690292Z","caller":"traceutil/trace.go:171","msg":"trace[1411178746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1013; }","duration":"371.079831ms","start":"2026-02-09T18:51:37.319204Z","end":"2026-02-09T18:51:37.690284Z","steps":["trace[1411178746] 'agreement among raft nodes before linearized reading'  (duration: 370.966159ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:51:37.690314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:51:37.319193Z","time spent":"371.116602ms","remote":"127.0.0.1:44898","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-09T18:51:37.690356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.124883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"info","ts":"2026-02-09T18:51:37.690414Z","caller":"traceutil/trace.go:171","msg":"trace[1999304311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1013; }","duration":"434.162785ms","start":"2026-02-09T18:51:37.256206Z","end":"2026-02-09T18:51:37.690369Z","steps":["trace[1999304311] 'agreement among raft nodes before linearized reading'  (duration: 434.113294ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:51:37.690432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:51:37.256197Z","time spent":"434.231196ms","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-02-09T18:51:37.690517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.962529ms","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-09T18:51:37.690534Z","caller":"traceutil/trace.go:171","msg":"trace[1177049645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1013; }","duration":"435.00204ms","start":"2026-02-09T18:51:37.255526Z","end":"2026-02-09T18:51:37.690528Z","steps":["trace[1177049645] 'agreement among raft nodes before linearized reading'  (duration: 434.946869ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-09T18:51:37.690549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:51:37.255515Z","time spent":"435.030811ms","remote":"127.0.0.1:44898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}