{"level":"warn","ts":"2026-02-10T21:24:38.995652Z","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-10T21:24:38.995806Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-10T21:24:38.99592Z","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-10T21:24:38.99595Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-02-10T21:24:38.995973Z","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-10T21:24:39.002949Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-02-10T21:24:39.003282Z","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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-10T21:24:39.012088Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.857963ms"} {"level":"info","ts":"2026-02-10T21:24:39.025442Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-02-10T21:24:39.026904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T21:24:39.026927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-02-10T21:24:39.026937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T21:24:39.026944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-02-10T21:24:39.026987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-02-10T21:24:39.038529Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T21:24:39.043986Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T21:24:39.045532Z","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-10T21:24:39.04777Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T21:24:39.048432Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T21:24:39.048761Z","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-10T21:24:39.048785Z","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-10T21:24:39.048793Z","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-10T21:24:39.049218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-02-10T21:24:39.049356Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-02-10T21:24:39.060656Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-02-10T21:24:39.060689Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-02-10T21:24:39.060533Z","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-10T21:24:39.061782Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T21:24:39.061818Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T21:24:39.129133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T21:24:39.129169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T21:24:39.129269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-02-10T21:24:39.129339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-02-10T21:24:39.12935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-02-10T21:24:39.129358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-02-10T21:24:39.129415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-02-10T21:24:39.131884Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T21:24:39.131952Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T21:24:39.132324Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:24:39.132723Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T21:24:39.133064Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T21:24:39.133104Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T21:24:39.137617Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-02-10T21:24:39.138007Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:24:39.139584Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:24:39.139609Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T21:24:39.144062Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T21:25:00.19762Z","caller":"traceutil/trace.go:171","msg":"trace[521013420] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"467.061697ms","start":"2026-02-10T21:24:59.730536Z","end":"2026-02-10T21:25:00.197598Z","steps":["trace[521013420] 'process raft request' (duration: 466.926222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:00.19817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:24:59.730525Z","time spent":"467.184151ms","remote":"127.0.0.1:59774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:25:00.200483Z","caller":"traceutil/trace.go:171","msg":"trace[447728328] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:468; }","duration":"388.514863ms","start":"2026-02-10T21:24:59.811958Z","end":"2026-02-10T21:25:00.200472Z","steps":["trace[447728328] 'read index received' (duration: 386.650552ms)","trace[447728328] 'applied index is now lower than readState.Index' (duration: 1.863351ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:25:00.200599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.639767ms","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-10T21:25:00.200674Z","caller":"traceutil/trace.go:171","msg":"trace[171296703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:454; }","duration":"388.742179ms","start":"2026-02-10T21:24:59.811924Z","end":"2026-02-10T21:25:00.200666Z","steps":["trace[171296703] 'agreement among raft nodes before linearized reading' (duration: 388.620896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:00.200744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:24:59.81191Z","time spent":"388.812922ms","remote":"127.0.0.1:59774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-10T21:25:00.200987Z","caller":"traceutil/trace.go:171","msg":"trace[2007686951] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"468.860146ms","start":"2026-02-10T21:24:59.732119Z","end":"2026-02-10T21:25:00.200979Z","steps":["trace[2007686951] 'process raft request' (duration: 468.312501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:00.201346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:24:59.732106Z","time spent":"469.210556ms","remote":"127.0.0.1:59774","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-10T21:25:09.695396Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933263778675554143,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T21:25:09.707662Z","caller":"traceutil/trace.go:171","msg":"trace[1741144283] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"548.239312ms","start":"2026-02-10T21:25:09.159401Z","end":"2026-02-10T21:25:09.707641Z","steps":["trace[1741144283] 'process raft request' (duration: 548.094998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:09.707805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:25:09.15939Z","time spent":"548.345685ms","remote":"127.0.0.1:59774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T21:25:09.708326Z","caller":"traceutil/trace.go:171","msg":"trace[1715795625] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:527; }","duration":"513.550326ms","start":"2026-02-10T21:25:09.194763Z","end":"2026-02-10T21:25:09.708314Z","steps":["trace[1715795625] 'read index received' (duration: 513.545446ms)","trace[1715795625] 'applied index is now lower than readState.Index' (duration: 3.8µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:25:09.708437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.668399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:25:09.708475Z","caller":"traceutil/trace.go:171","msg":"trace[1168356748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"513.732901ms","start":"2026-02-10T21:25:09.19473Z","end":"2026-02-10T21:25:09.708463Z","steps":["trace[1168356748] 'agreement among raft nodes before linearized reading' (duration: 513.67413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:09.708507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:25:09.194714Z","time spent":"513.787102ms","remote":"127.0.0.1:59466","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-10T21:25:09.708643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.753411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:25:09.708674Z","caller":"traceutil/trace.go:171","msg":"trace[1204728618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"180.820113ms","start":"2026-02-10T21:25:09.527846Z","end":"2026-02-10T21:25:09.708667Z","steps":["trace[1204728618] 'agreement among raft nodes before linearized reading' (duration: 180.778152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:12.626773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.220827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:25:12.627082Z","caller":"traceutil/trace.go:171","msg":"trace[1215474478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"102.558605ms","start":"2026-02-10T21:25:12.524508Z","end":"2026-02-10T21:25:12.627067Z","steps":["trace[1215474478] 'range keys from in-memory index tree' (duration: 102.170326ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:25:14.448189Z","caller":"traceutil/trace.go:171","msg":"trace[2047580879] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"120.11891ms","start":"2026-02-10T21:25:14.328044Z","end":"2026-02-10T21:25:14.448163Z","steps":["trace[2047580879] 'process raft request' (duration: 119.839402ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:25:14.564859Z","caller":"traceutil/trace.go:171","msg":"trace[292832363] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"108.705068ms","start":"2026-02-10T21:25:14.456134Z","end":"2026-02-10T21:25:14.564839Z","steps":["trace[292832363] 'process raft request' (duration: 86.864915ms)","trace[292832363] 'compare' (duration: 21.462734ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:25:19.004025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933263778675554244,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T21:25:19.20438Z","caller":"traceutil/trace.go:171","msg":"trace[2011445159] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"743.088386ms","start":"2026-02-10T21:25:18.461275Z","end":"2026-02-10T21:25:19.204364Z","steps":["trace[2011445159] 'process raft request' (duration: 742.997424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:19.204489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:25:18.461259Z","time spent":"743.173198ms","remote":"127.0.0.1:59774","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-10T21:25:19.431076Z","caller":"traceutil/trace.go:171","msg":"trace[2043140874] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"927.35384ms","start":"2026-02-10T21:25:18.503701Z","end":"2026-02-10T21:25:19.431055Z","steps":["trace[2043140874] 'read index received' (duration: 700.651568ms)","trace[2043140874] 'applied index is now lower than readState.Index' (duration: 226.701132ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:25:19.431235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.57612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:25:19.431266Z","caller":"traceutil/trace.go:171","msg":"trace[2132272849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"906.658852ms","start":"2026-02-10T21:25:18.524593Z","end":"2026-02-10T21:25:19.431252Z","steps":["trace[2132272849] 'agreement among raft nodes before linearized reading' (duration: 906.57322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:19.431291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:25:18.524582Z","time spent":"906.702653ms","remote":"127.0.0.1:59482","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-10T21:25:19.431291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.567375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-10T21:25:19.431347Z","caller":"traceutil/trace.go:171","msg":"trace[1457300569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"927.633066ms","start":"2026-02-10T21:25:18.503698Z","end":"2026-02-10T21:25:19.431331Z","steps":["trace[1457300569] 'agreement among raft nodes before linearized reading' (duration: 927.440162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:19.431378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:25:18.503657Z","time spent":"927.715468ms","remote":"127.0.0.1:59774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T21:25:19.431486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.459082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:25:19.431508Z","caller":"traceutil/trace.go:171","msg":"trace[1770619646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"238.516713ms","start":"2026-02-10T21:25:19.192985Z","end":"2026-02-10T21:25:19.431502Z","steps":["trace[1770619646] 'agreement among raft nodes before linearized reading' (duration: 238.483852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:19.431664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.101698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-crs9j\" ","response":"range_response_count:1 size:16648"} {"level":"info","ts":"2026-02-10T21:25:19.431685Z","caller":"traceutil/trace.go:171","msg":"trace[528463509] range","detail":"{range_begin:/registry/pods/kube-system/cilium-crs9j; range_end:; response_count:1; response_revision:555; }","duration":"654.156679ms","start":"2026-02-10T21:25:18.777523Z","end":"2026-02-10T21:25:19.43168Z","steps":["trace[528463509] 'agreement among raft nodes before linearized reading' (duration: 654.070267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:19.4317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.105059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-10T21:25:19.431704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:25:18.777501Z","time spent":"654.19857ms","remote":"127.0.0.1:59676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16671,"request content":"key:\"/registry/pods/kube-system/cilium-crs9j\" "} {"level":"info","ts":"2026-02-10T21:25:19.431724Z","caller":"traceutil/trace.go:171","msg":"trace[1568307886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"857.155621ms","start":"2026-02-10T21:25:18.57456Z","end":"2026-02-10T21:25:19.431716Z","steps":["trace[1568307886] 'agreement among raft nodes before linearized reading' (duration: 857.092559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:19.431743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:25:18.574544Z","time spent":"857.194952ms","remote":"127.0.0.1:59774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-10T21:25:19.632868Z","caller":"traceutil/trace.go:171","msg":"trace[82408132] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:575; }","duration":"108.07826ms","start":"2026-02-10T21:25:19.524775Z","end":"2026-02-10T21:25:19.632853Z","steps":["trace[82408132] 'read index received' (duration: 12.998407ms)","trace[82408132] 'applied index is now lower than readState.Index' (duration: 95.079383ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T21:25:19.632947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.156022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:25:19.632964Z","caller":"traceutil/trace.go:171","msg":"trace[2107019255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"108.209504ms","start":"2026-02-10T21:25:19.524748Z","end":"2026-02-10T21:25:19.632958Z","steps":["trace[2107019255] 'agreement among raft nodes before linearized reading' (duration: 108.157643ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:25:19.633175Z","caller":"traceutil/trace.go:171","msg":"trace[756853126] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"192.155073ms","start":"2026-02-10T21:25:19.441014Z","end":"2026-02-10T21:25:19.633169Z","steps":["trace[756853126] 'process raft request' (duration: 170.861766ms)","trace[756853126] 'compare' (duration: 20.840046ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:25:19.633285Z","caller":"traceutil/trace.go:171","msg":"trace[724266666] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"186.856061ms","start":"2026-02-10T21:25:19.446425Z","end":"2026-02-10T21:25:19.633281Z","steps":["trace[724266666] 'process raft request' (duration: 186.394831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:21.463611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.681905413s","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-10T21:25:21.463666Z","caller":"traceutil/trace.go:171","msg":"trace[319686277] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"1.681999356s","start":"2026-02-10T21:25:19.781654Z","end":"2026-02-10T21:25:21.463653Z","steps":["trace[319686277] 'range keys from in-memory index tree' (duration: 1.681783451s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:21.463694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:25:19.78164Z","time spent":"1.682048017s","remote":"127.0.0.1:59774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T21:25:21.463735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.113562ms","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-10T21:25:21.463799Z","caller":"traceutil/trace.go:171","msg":"trace[1812643170] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"255.209145ms","start":"2026-02-10T21:25:21.208573Z","end":"2026-02-10T21:25:21.463783Z","steps":["trace[1812643170] 'range keys from in-memory index tree' (duration: 254.958229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:21.46386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.945848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T21:25:21.463885Z","caller":"traceutil/trace.go:171","msg":"trace[1231571919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"933.996839ms","start":"2026-02-10T21:25:20.529882Z","end":"2026-02-10T21:25:21.463879Z","steps":["trace[1231571919] 'range keys from in-memory index tree' (duration: 933.885875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:21.463904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:25:20.529868Z","time spent":"934.03161ms","remote":"127.0.0.1:59466","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-10T21:25:24.266694Z","caller":"traceutil/trace.go:171","msg":"trace[710782694] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"189.49932ms","start":"2026-02-10T21:25:24.077177Z","end":"2026-02-10T21:25:24.266676Z","steps":["trace[710782694] 'process raft request' (duration: 189.381438ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:25:24.273044Z","caller":"traceutil/trace.go:171","msg":"trace[519006012] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"176.23689ms","start":"2026-02-10T21:25:24.096797Z","end":"2026-02-10T21:25:24.273034Z","steps":["trace[519006012] 'process raft request' (duration: 176.175449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:25:26.231644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.229966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-10T21:25:26.231703Z","caller":"traceutil/trace.go:171","msg":"trace[1410676474] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:632; }","duration":"143.334448ms","start":"2026-02-10T21:25:26.088354Z","end":"2026-02-10T21:25:26.231688Z","steps":["trace[1410676474] 'range keys from in-memory index tree' (duration: 143.085173ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:26:24.031946Z","caller":"traceutil/trace.go:171","msg":"trace[1568201436] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"107.495645ms","start":"2026-02-10T21:26:23.924424Z","end":"2026-02-10T21:26:24.03192Z","steps":["trace[1568201436] 'process raft request' (duration: 90.426183ms)","trace[1568201436] 'compare' (duration: 16.800158ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:26:44.235013Z","caller":"traceutil/trace.go:171","msg":"trace[232684222] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"271.496152ms","start":"2026-02-10T21:26:43.963495Z","end":"2026-02-10T21:26:44.234992Z","steps":["trace[232684222] 'read index received' (duration: 271.302009ms)","trace[232684222] 'applied index is now lower than readState.Index' (duration: 192.903µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:26:44.23514Z","caller":"traceutil/trace.go:171","msg":"trace[1357850839] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"335.453692ms","start":"2026-02-10T21:26:43.899677Z","end":"2026-02-10T21:26:44.235131Z","steps":["trace[1357850839] 'process raft request' (duration: 335.140767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:26:44.235235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T21:26:43.899653Z","time spent":"335.512453ms","remote":"127.0.0.1:59774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T21:26:44.235371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.932874ms","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-10T21:26:44.23544Z","caller":"traceutil/trace.go:171","msg":"trace[1812691039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:980; }","duration":"111.039025ms","start":"2026-02-10T21:26:44.124383Z","end":"2026-02-10T21:26:44.235422Z","steps":["trace[1812691039] 'agreement among raft nodes before linearized reading' (duration: 110.866163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:26:44.235674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.366215ms","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-10T21:26:44.235702Z","caller":"traceutil/trace.go:171","msg":"trace[331815751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:980; }","duration":"107.413576ms","start":"2026-02-10T21:26:44.12828Z","end":"2026-02-10T21:26:44.235693Z","steps":["trace[331815751] 'agreement among raft nodes before linearized reading' (duration: 107.336805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:26:44.235763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.275552ms","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-10T21:26:44.235803Z","caller":"traceutil/trace.go:171","msg":"trace[1765415245] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:980; }","duration":"272.351183ms","start":"2026-02-10T21:26:43.963441Z","end":"2026-02-10T21:26:44.235792Z","steps":["trace[1765415245] 'agreement among raft nodes before linearized reading' (duration: 272.233522ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:27:04.447975Z","caller":"traceutil/trace.go:171","msg":"trace[1325544255] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1086; }","duration":"115.889796ms","start":"2026-02-10T21:27:04.332063Z","end":"2026-02-10T21:27:04.447952Z","steps":["trace[1325544255] 'read index received' (duration: 90.551326ms)","trace[1325544255] 'applied index is now lower than readState.Index' (duration: 25.33785ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:27:04.448151Z","caller":"traceutil/trace.go:171","msg":"trace[17859143] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"119.635655ms","start":"2026-02-10T21:27:04.328509Z","end":"2026-02-10T21:27:04.448144Z","steps":["trace[17859143] 'process raft request' (duration: 119.370481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:04.448316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.250192ms","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-10T21:27:04.448338Z","caller":"traceutil/trace.go:171","msg":"trace[1140432916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1043; }","duration":"116.292952ms","start":"2026-02-10T21:27:04.33204Z","end":"2026-02-10T21:27:04.448333Z","steps":["trace[1140432916] 'agreement among raft nodes before linearized reading' (duration: 116.203141ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T21:27:07.281999Z","caller":"traceutil/trace.go:171","msg":"trace[425700930] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1097; }","duration":"114.960202ms","start":"2026-02-10T21:27:07.167017Z","end":"2026-02-10T21:27:07.281977Z","steps":["trace[425700930] 'read index received' (duration: 114.76379ms)","trace[425700930] 'applied index is now lower than readState.Index' (duration: 195.382µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T21:27:07.282113Z","caller":"traceutil/trace.go:171","msg":"trace[1792857545] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"173.949233ms","start":"2026-02-10T21:27:07.108152Z","end":"2026-02-10T21:27:07.282102Z","steps":["trace[1792857545] 'process raft request' (duration: 173.682169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T21:27:07.283342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.309018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-crs9j\" ","response":"range_response_count:1 size:18054"} {"level":"info","ts":"2026-02-10T21:27:07.283428Z","caller":"traceutil/trace.go:171","msg":"trace[1653955885] range","detail":"{range_begin:/registry/pods/kube-system/cilium-crs9j; range_end:; response_count:1; response_revision:1053; }","duration":"116.42547ms","start":"2026-02-10T21:27:07.166976Z","end":"2026-02-10T21:27:07.283415Z","steps":["trace[1653955885] 'agreement among raft nodes before linearized reading' (duration: 116.295438ms)"],"step_count":1}