{"level":"warn","ts":"2026-02-04T18:16:04.338563Z","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-04T18:16:04.345669Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.204: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://162.253.55.204:2380","--initial-cluster=instance=https://162.253.55.204:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.204:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.204: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-04T18:16:04.345911Z","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-04T18:16:04.347478Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-02-04T18:16:04.347534Z","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-04T18:16:04.348313Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"]} {"level":"info","ts":"2026-02-04T18:16:04.34844Z","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://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.204: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-04T18:16:04.358129Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.437588ms"} {"level":"info","ts":"2026-02-04T18:16:04.385275Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a11c20306510c1f7","cluster-id":"97da89d0cbebd2b5"} {"level":"info","ts":"2026-02-04T18:16:04.385414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-04T18:16:04.385446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 0"} {"level":"info","ts":"2026-02-04T18:16:04.385474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a11c20306510c1f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-04T18:16:04.385498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 1"} {"level":"info","ts":"2026-02-04T18:16:04.385543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"warn","ts":"2026-02-04T18:16:04.400145Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-04T18:16:04.402607Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-04T18:16:04.403613Z","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-04T18:16:04.410937Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a11c20306510c1f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-04T18:16:04.414077Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a11c20306510c1f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-04T18:16:04.414253Z","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-04T18:16:04.414316Z","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-04T18:16:04.414343Z","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-04T18:16:04.415058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"info","ts":"2026-02-04T18:16:04.415259Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","added-peer-id":"a11c20306510c1f7","added-peer-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-02-04T18:16:04.441776Z","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-04T18:16:04.442201Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a11c20306510c1f7","initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-04T18:16:04.442447Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-04T18:16:04.442542Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-02-04T18:16:04.443889Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-02-04T18:16:04.990838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-04T18:16:04.990912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-04T18:16:04.990938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgPreVoteResp from a11c20306510c1f7 at term 1"} {"level":"info","ts":"2026-02-04T18:16:04.9911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became candidate at term 2"} {"level":"info","ts":"2026-02-04T18:16:04.991176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgVoteResp from a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-02-04T18:16:04.991247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became leader at term 2"} {"level":"info","ts":"2026-02-04T18:16:04.991264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a11c20306510c1f7 elected leader a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-02-04T18:16:04.993891Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T18:16:04.998269Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a11c20306510c1f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.204:2379]}","request-path":"/0/members/a11c20306510c1f7/attributes","cluster-id":"97da89d0cbebd2b5","publish-timeout":"7s"} {"level":"info","ts":"2026-02-04T18:16:04.998532Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T18:16:04.998623Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T18:16:04.999407Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-04T18:16:04.999431Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-04T18:16:05.000585Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-04T18:16:05.003453Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.204:2379"} {"level":"info","ts":"2026-02-04T18:16:05.005802Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T18:16:05.028589Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T18:16:05.028654Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-04T18:16:10.385846Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811672205796154,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T18:16:11.505207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.754269ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-04T18:16:11.505313Z","caller":"traceutil/trace.go:171","msg":"trace[346087316] linearizableReadLoop","detail":"{readStateIndex:245; appliedIndex:244; }","duration":"1.622922352s","start":"2026-02-04T18:16:09.882375Z","end":"2026-02-04T18:16:11.505297Z","steps":["trace[346087316] 'read index received' (duration: 699.463938ms)","trace[346087316] 'applied index is now lower than readState.Index' (duration: 923.457414ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:16:11.505367Z","caller":"traceutil/trace.go:171","msg":"trace[309335330] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"1.704128728s","start":"2026-02-04T18:16:09.801232Z","end":"2026-02-04T18:16:11.50536Z","steps":["trace[309335330] 'process raft request' (duration: 780.853905ms)","trace[309335330] 'compare' (duration: 921.204729ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:16:11.505402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:09.801224Z","time spent":"1.704161918s","remote":"127.0.0.1:33856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-04T18:16:11.50558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.623196184s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:16:11.505607Z","caller":"traceutil/trace.go:171","msg":"trace[1367999029] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:0; response_revision:238; }","duration":"1.623262775s","start":"2026-02-04T18:16:09.882334Z","end":"2026-02-04T18:16:11.505597Z","steps":["trace[1367999029] 'agreement among raft nodes before linearized reading' (duration: 1.623200865s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:11.505632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:09.882316Z","time spent":"1.623310215s","remote":"127.0.0.1:33696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"warn","ts":"2026-02-04T18:16:11.516389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.155798ms","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-04T18:16:11.516458Z","caller":"traceutil/trace.go:171","msg":"trace[1435165990] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:239; }","duration":"698.269418ms","start":"2026-02-04T18:16:10.818172Z","end":"2026-02-04T18:16:11.516441Z","steps":["trace[1435165990] 'agreement among raft nodes before linearized reading' (duration: 698.148247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:11.51656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:10.818134Z","time spent":"698.416389ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T18:16:11.516906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.382998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:16:11.51694Z","caller":"traceutil/trace.go:171","msg":"trace[1032893339] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:239; }","duration":"422.455668ms","start":"2026-02-04T18:16:11.094466Z","end":"2026-02-04T18:16:11.516922Z","steps":["trace[1032893339] 'agreement among raft nodes before linearized reading' (duration: 422.424898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:11.516958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:11.09444Z","time spent":"422.51384ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T18:16:11.517081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.368782ms","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-04T18:16:11.5171Z","caller":"traceutil/trace.go:171","msg":"trace[1140647705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:239; }","duration":"690.429673ms","start":"2026-02-04T18:16:10.826666Z","end":"2026-02-04T18:16:11.517096Z","steps":["trace[1140647705] 'agreement among raft nodes before linearized reading' (duration: 690.378702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:11.517114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:10.826653Z","time spent":"690.457553ms","remote":"127.0.0.1:33764","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-04T18:16:11.517274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.482002ms","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-04T18:16:11.517293Z","caller":"traceutil/trace.go:171","msg":"trace[1514844041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:239; }","duration":"697.542763ms","start":"2026-02-04T18:16:10.819745Z","end":"2026-02-04T18:16:11.517288Z","steps":["trace[1514844041] 'agreement among raft nodes before linearized reading' (duration: 697.510872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:11.517309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:10.819722Z","time spent":"697.582025ms","remote":"127.0.0.1:33764","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-04T18:16:32.611222Z","caller":"traceutil/trace.go:171","msg":"trace[1060573802] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"110.685039ms","start":"2026-02-04T18:16:32.500514Z","end":"2026-02-04T18:16:32.611199Z","steps":["trace[1060573802] 'process raft request' (duration: 110.536923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:38.12079Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811672205796645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T18:16:38.476744Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.172417511s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T18:16:38.477243Z","caller":"traceutil/trace.go:171","msg":"trace[1981826983] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"1.172977353s","start":"2026-02-04T18:16:37.304243Z","end":"2026-02-04T18:16:38.477221Z","steps":["trace[1981826983] 'process raft request' (duration: 1.172830507s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:38.47754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:37.304228Z","time spent":"1.173078457s","remote":"127.0.0.1:33670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4254,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:16:38.501691Z","caller":"traceutil/trace.go:171","msg":"trace[1681161849] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"880.937598ms","start":"2026-02-04T18:16:37.620737Z","end":"2026-02-04T18:16:38.501674Z","steps":["trace[1681161849] 'read index received' (duration: 856.878571ms)","trace[1681161849] 'applied index is now lower than readState.Index' (duration: 24.058287ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:16:38.501923Z","caller":"traceutil/trace.go:171","msg":"trace[834487515] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"1.016363549s","start":"2026-02-04T18:16:37.485554Z","end":"2026-02-04T18:16:38.501918Z","steps":["trace[834487515] 'process raft request' (duration: 1.008861193s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:38.501992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:37.485539Z","time spent":"1.016403841s","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T18:16:38.502116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.378535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:16:38.502131Z","caller":"traceutil/trace.go:171","msg":"trace[826620482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:481; }","duration":"881.414887ms","start":"2026-02-04T18:16:37.620712Z","end":"2026-02-04T18:16:38.502127Z","steps":["trace[826620482] 'agreement among raft nodes before linearized reading' (duration: 881.388636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:38.502144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:37.6207Z","time spent":"881.441368ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T18:16:38.502223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.398038ms","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-04T18:16:38.502236Z","caller":"traceutil/trace.go:171","msg":"trace[1885430242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:481; }","duration":"822.437919ms","start":"2026-02-04T18:16:37.679793Z","end":"2026-02-04T18:16:38.502231Z","steps":["trace[1885430242] 'agreement among raft nodes before linearized reading' (duration: 822.414358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:38.502248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:37.67978Z","time spent":"822.46444ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T18:16:38.502336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.83576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T18:16:38.502348Z","caller":"traceutil/trace.go:171","msg":"trace[363504282] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:481; }","duration":"173.891023ms","start":"2026-02-04T18:16:38.328453Z","end":"2026-02-04T18:16:38.502344Z","steps":["trace[363504282] 'agreement among raft nodes before linearized reading' (duration: 173.868092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:38.502446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.461944ms","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-04T18:16:38.502462Z","caller":"traceutil/trace.go:171","msg":"trace[1863235061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:481; }","duration":"800.488285ms","start":"2026-02-04T18:16:37.701971Z","end":"2026-02-04T18:16:38.502459Z","steps":["trace[1863235061] 'agreement among raft nodes before linearized reading' (duration: 800.428453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:38.502475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:37.701967Z","time spent":"800.504225ms","remote":"127.0.0.1:33764","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-04T18:16:38.502546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.412502ms","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-04T18:16:38.502558Z","caller":"traceutil/trace.go:171","msg":"trace[83499932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:481; }","duration":"801.451245ms","start":"2026-02-04T18:16:37.701102Z","end":"2026-02-04T18:16:38.502553Z","steps":["trace[83499932] 'agreement among raft nodes before linearized reading' (duration: 801.417773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:38.502569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:37.701089Z","time spent":"801.478135ms","remote":"127.0.0.1:33764","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":"warn","ts":"2026-02-04T18:16:38.502638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.101899ms","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-04T18:16:38.502654Z","caller":"traceutil/trace.go:171","msg":"trace[1426019075] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:481; }","duration":"812.15232ms","start":"2026-02-04T18:16:37.690497Z","end":"2026-02-04T18:16:38.50265Z","steps":["trace[1426019075] 'agreement among raft nodes before linearized reading' (duration: 812.114389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:38.502667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:37.690478Z","time spent":"812.186582ms","remote":"127.0.0.1:33764","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-04T18:16:45.73265Z","caller":"traceutil/trace.go:171","msg":"trace[1314762901] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"518.211037ms","start":"2026-02-04T18:16:45.213973Z","end":"2026-02-04T18:16:45.732184Z","steps":["trace[1314762901] 'process raft request' (duration: 518.027021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:45.732795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:45.213954Z","time spent":"518.774008ms","remote":"127.0.0.1:33672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:16:47.047944Z","caller":"traceutil/trace.go:171","msg":"trace[1046787684] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"480.81464ms","start":"2026-02-04T18:16:46.567111Z","end":"2026-02-04T18:16:47.047926Z","steps":["trace[1046787684] 'read index received' (duration: 480.697785ms)","trace[1046787684] 'applied index is now lower than readState.Index' (duration: 116.335µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:16:47.048132Z","caller":"traceutil/trace.go:171","msg":"trace[467150225] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"485.55146ms","start":"2026-02-04T18:16:46.562548Z","end":"2026-02-04T18:16:47.048099Z","steps":["trace[467150225] 'process raft request' (duration: 485.300251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:47.048191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:46.562537Z","time spent":"485.608731ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T18:16:47.048318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.204823ms","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-04T18:16:47.048334Z","caller":"traceutil/trace.go:171","msg":"trace[275964965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:527; }","duration":"481.235674ms","start":"2026-02-04T18:16:46.567093Z","end":"2026-02-04T18:16:47.048329Z","steps":["trace[275964965] 'agreement among raft nodes before linearized reading' (duration: 481.177342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:47.048348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:46.567086Z","time spent":"481.259064ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T18:16:47.048431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.870158ms","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-04T18:16:47.048444Z","caller":"traceutil/trace.go:171","msg":"trace[1636656362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:527; }","duration":"478.89203ms","start":"2026-02-04T18:16:46.569548Z","end":"2026-02-04T18:16:47.04844Z","steps":["trace[1636656362] 'agreement among raft nodes before linearized reading' (duration: 478.850898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:47.048455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:46.569543Z","time spent":"478.90983ms","remote":"127.0.0.1:33764","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":"warn","ts":"2026-02-04T18:16:47.048531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.535271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:16:47.048546Z","caller":"traceutil/trace.go:171","msg":"trace[2028817966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:527; }","duration":"418.576412ms","start":"2026-02-04T18:16:46.629964Z","end":"2026-02-04T18:16:47.048541Z","steps":["trace[2028817966] 'agreement among raft nodes before linearized reading' (duration: 418.554131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:47.048557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:46.629947Z","time spent":"418.607924ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T18:16:47.048638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.881709ms","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-04T18:16:47.04865Z","caller":"traceutil/trace.go:171","msg":"trace[1204050140] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:527; }","duration":"468.91541ms","start":"2026-02-04T18:16:46.579731Z","end":"2026-02-04T18:16:47.048646Z","steps":["trace[1204050140] 'agreement among raft nodes before linearized reading' (duration: 468.881179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:47.048662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:46.57972Z","time spent":"468.938411ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T18:16:47.555921Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811672205796745,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T18:16:48.056993Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811672205796745,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T18:16:48.272086Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.217390959s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T18:16:48.302711Z","caller":"traceutil/trace.go:171","msg":"trace[1701135899] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.248055147s","start":"2026-02-04T18:16:47.054633Z","end":"2026-02-04T18:16:48.302688Z","steps":["trace[1701135899] 'process raft request' (duration: 1.218114094s)","trace[1701135899] 'compare' (duration: 29.358032ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:16:48.30286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:47.054617Z","time spent":"1.248144331s","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:16:48.326357Z","caller":"traceutil/trace.go:171","msg":"trace[1972945485] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:544; }","duration":"1.271528823s","start":"2026-02-04T18:16:47.054807Z","end":"2026-02-04T18:16:48.326336Z","steps":["trace[1972945485] 'read index received' (duration: 1.217896667s)","trace[1972945485] 'applied index is now lower than readState.Index' (duration: 53.631195ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:16:48.326522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271695628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:16:48.326553Z","caller":"traceutil/trace.go:171","msg":"trace[1053488623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"1.27175097s","start":"2026-02-04T18:16:47.054792Z","end":"2026-02-04T18:16:48.326543Z","steps":["trace[1053488623] 'agreement among raft nodes before linearized reading' (duration: 1.271618235s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:48.326587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:47.054786Z","time spent":"1.271793642s","remote":"127.0.0.1:33510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T18:16:48.326885Z","caller":"traceutil/trace.go:171","msg":"trace[1994596581] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.27202436s","start":"2026-02-04T18:16:47.054846Z","end":"2026-02-04T18:16:48.32687Z","steps":["trace[1994596581] 'process raft request' (duration: 1.271347957s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:48.327003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:47.054839Z","time spent":"1.272086882s","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:16:48.327122Z","caller":"traceutil/trace.go:171","msg":"trace[1613635160] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.272229517s","start":"2026-02-04T18:16:47.054883Z","end":"2026-02-04T18:16:48.327112Z","steps":["trace[1613635160] 'process raft request' (duration: 1.271415708s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:16:48.327193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:16:47.054878Z","time spent":"1.272266418s","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T18:16:53.650136Z","caller":"traceutil/trace.go:171","msg":"trace[1598772335] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"141.324866ms","start":"2026-02-04T18:16:53.508787Z","end":"2026-02-04T18:16:53.650112Z","steps":["trace[1598772335] 'process raft request' (duration: 140.446967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:17:08.979981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.961301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T18:17:08.980041Z","caller":"traceutil/trace.go:171","msg":"trace[254696892] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:753; }","duration":"510.607682ms","start":"2026-02-04T18:17:08.469424Z","end":"2026-02-04T18:17:08.980032Z","steps":["trace[254696892] 'read index received' (duration: 300.535167ms)","trace[254696892] 'applied index is now lower than readState.Index' (duration: 210.071925ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:17:08.980176Z","caller":"traceutil/trace.go:171","msg":"trace[55959154] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"556.322367ms","start":"2026-02-04T18:17:08.423837Z","end":"2026-02-04T18:17:08.980159Z","steps":["trace[55959154] 'process raft request' (duration: 346.131409ms)","trace[55959154] 'compare' (duration: 209.870469ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:17:08.98021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:17:08.423822Z","time spent":"556.370368ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T18:17:08.98034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.932772ms","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-04T18:17:08.980362Z","caller":"traceutil/trace.go:171","msg":"trace[1832252240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:731; }","duration":"510.992993ms","start":"2026-02-04T18:17:08.469364Z","end":"2026-02-04T18:17:08.980357Z","steps":["trace[1832252240] 'agreement among raft nodes before linearized reading' (duration: 510.930942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:17:08.980379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:17:08.469344Z","time spent":"511.032765ms","remote":"127.0.0.1:33764","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-04T18:17:09.039769Z","caller":"traceutil/trace.go:171","msg":"trace[2078123265] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"256.48109ms","start":"2026-02-04T18:17:08.783275Z","end":"2026-02-04T18:17:09.039756Z","steps":["trace[2078123265] 'process raft request' (duration: 256.402188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:17:09.040128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.728202ms","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-04T18:17:09.040154Z","caller":"traceutil/trace.go:171","msg":"trace[187331835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:732; }","duration":"567.770273ms","start":"2026-02-04T18:17:08.472375Z","end":"2026-02-04T18:17:09.040145Z","steps":["trace[187331835] 'agreement among raft nodes before linearized reading' (duration: 567.684171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:17:09.040174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:17:08.47237Z","time spent":"567.799164ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T18:18:11.905627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.054075ms","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-04T18:18:11.905695Z","caller":"traceutil/trace.go:171","msg":"trace[1519931306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:945; }","duration":"424.184158ms","start":"2026-02-04T18:18:11.481496Z","end":"2026-02-04T18:18:11.905681Z","steps":["trace[1519931306] 'range keys from in-memory index tree' (duration: 423.918423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:18:11.905722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:18:11.481479Z","time spent":"424.237749ms","remote":"127.0.0.1:33764","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-04T18:18:11.905899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.380113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:18:11.905918Z","caller":"traceutil/trace.go:171","msg":"trace[851317488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"280.432615ms","start":"2026-02-04T18:18:11.625479Z","end":"2026-02-04T18:18:11.905912Z","steps":["trace[851317488] 'range keys from in-memory index tree' (duration: 280.315442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:18:19.901294Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811672205797818,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T18:18:20.005474Z","caller":"traceutil/trace.go:171","msg":"trace[866039190] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1010; }","duration":"605.280211ms","start":"2026-02-04T18:18:19.400178Z","end":"2026-02-04T18:18:20.005458Z","steps":["trace[866039190] 'read index received' (duration: 605.164609ms)","trace[866039190] 'applied index is now lower than readState.Index' (duration: 115.072µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:18:20.005643Z","caller":"traceutil/trace.go:171","msg":"trace[1392693499] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"650.437907ms","start":"2026-02-04T18:18:19.355196Z","end":"2026-02-04T18:18:20.005633Z","steps":["trace[1392693499] 'process raft request' (duration: 650.161351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:18:20.005928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:18:19.355178Z","time spent":"650.538618ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T18:18:20.006212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.030674ms","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-04T18:18:20.0063Z","caller":"traceutil/trace.go:171","msg":"trace[1409016962] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:972; }","duration":"606.149675ms","start":"2026-02-04T18:18:19.400141Z","end":"2026-02-04T18:18:20.00629Z","steps":["trace[1409016962] 'agreement among raft nodes before linearized reading' (duration: 606.006223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:18:20.006561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.556719ms","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-04T18:18:20.006584Z","caller":"traceutil/trace.go:171","msg":"trace[1415259897] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:972; }","duration":"601.620811ms","start":"2026-02-04T18:18:19.404956Z","end":"2026-02-04T18:18:20.006577Z","steps":["trace[1415259897] 'agreement among raft nodes before linearized reading' (duration: 601.546459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:18:20.006647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:18:19.404918Z","time spent":"601.723452ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T18:18:20.006955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.346792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:18:20.006975Z","caller":"traceutil/trace.go:171","msg":"trace[2097638936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"383.398903ms","start":"2026-02-04T18:18:19.623571Z","end":"2026-02-04T18:18:20.00697Z","steps":["trace[2097638936] 'agreement among raft nodes before linearized reading' (duration: 383.367212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:18:20.007007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:18:19.623556Z","time spent":"383.447563ms","remote":"127.0.0.1:33506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T18:18:20.00743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T18:18:19.400123Z","time spent":"606.214187ms","remote":"127.0.0.1:33764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-04T18:18:41.57582Z","caller":"traceutil/trace.go:171","msg":"trace[996847215] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"135.464262ms","start":"2026-02-04T18:18:41.440337Z","end":"2026-02-04T18:18:41.575801Z","steps":["trace[996847215] 'read index received' (duration: 135.308779ms)","trace[996847215] 'applied index is now lower than readState.Index' (duration: 154.393µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:18:41.576032Z","caller":"traceutil/trace.go:171","msg":"trace[895006655] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"138.68977ms","start":"2026-02-04T18:18:41.437327Z","end":"2026-02-04T18:18:41.576016Z","steps":["trace[895006655] 'process raft request' (duration: 138.332784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:18:41.576227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.284911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:18:41.576307Z","caller":"traceutil/trace.go:171","msg":"trace[1046374765] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-public/; range_end:/registry/persistentvolumeclaims/kube-public0; response_count:0; response_revision:1037; }","duration":"108.418073ms","start":"2026-02-04T18:18:41.467871Z","end":"2026-02-04T18:18:41.576289Z","steps":["trace[1046374765] 'agreement among raft nodes before linearized reading' (duration: 108.286091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:18:41.576643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.290235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubelet-config\" ","response":"range_response_count:1 size:1357"} {"level":"info","ts":"2026-02-04T18:18:41.576723Z","caller":"traceutil/trace.go:171","msg":"trace[1706811897] range","detail":"{range_begin:/registry/configmaps/kube-system/kubelet-config; range_end:; response_count:1; response_revision:1037; }","duration":"136.401426ms","start":"2026-02-04T18:18:41.440307Z","end":"2026-02-04T18:18:41.576709Z","steps":["trace[1706811897] 'agreement among raft nodes before linearized reading' (duration: 136.296834ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T18:18:42.337684Z","caller":"traceutil/trace.go:171","msg":"trace[723224318] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"105.506301ms","start":"2026-02-04T18:18:42.232156Z","end":"2026-02-04T18:18:42.337662Z","steps":["trace[723224318] 'process raft request' (duration: 20.497771ms)","trace[723224318] 'compare' (duration: 84.691256ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:18:44.368905Z","caller":"traceutil/trace.go:171","msg":"trace[437559213] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1088; }","duration":"137.318693ms","start":"2026-02-04T18:18:44.231575Z","end":"2026-02-04T18:18:44.368894Z","steps":["trace[437559213] 'read index received' (duration: 96.845073ms)","trace[437559213] 'applied index is now lower than readState.Index' (duration: 40.47177ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T18:18:44.368978Z","caller":"traceutil/trace.go:171","msg":"trace[776158155] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"149.039044ms","start":"2026-02-04T18:18:44.219929Z","end":"2026-02-04T18:18:44.368968Z","steps":["trace[776158155] 'process raft request' (duration: 108.524323ms)","trace[776158155] 'compare' (duration: 40.296967ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T18:18:44.369177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.040919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-04T18:18:44.369248Z","caller":"traceutil/trace.go:171","msg":"trace[9145988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1044; }","duration":"137.12888ms","start":"2026-02-04T18:18:44.232098Z","end":"2026-02-04T18:18:44.369227Z","steps":["trace[9145988] 'agreement among raft nodes before linearized reading' (duration: 136.952988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:18:44.369285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.687649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/local-path-storage/\" range_end:\"/registry/roles/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T18:18:44.369311Z","caller":"traceutil/trace.go:171","msg":"trace[1918928074] range","detail":"{range_begin:/registry/roles/local-path-storage/; range_end:/registry/roles/local-path-storage0; response_count:0; response_revision:1044; }","duration":"137.733269ms","start":"2026-02-04T18:18:44.231571Z","end":"2026-02-04T18:18:44.369304Z","steps":["trace[1918928074] 'agreement among raft nodes before linearized reading' (duration: 137.677628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T18:18:44.369674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.487605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" limit:500 ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-04T18:18:44.369712Z","caller":"traceutil/trace.go:171","msg":"trace[2025934430] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1044; }","duration":"100.558217ms","start":"2026-02-04T18:18:44.269143Z","end":"2026-02-04T18:18:44.369701Z","steps":["trace[2025934430] 'agreement among raft nodes before linearized reading' (duration: 100.462615ms)"],"step_count":1}