{"level":"warn","ts":"2026-02-02T17:46:11.650814Z","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-02T17:46:11.650966Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.84: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.84:2380","--initial-cluster=instance=https://162.253.55.84:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.84:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.84: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-02T17:46:11.651067Z","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-02T17:46:11.651097Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-02-02T17:46:11.651125Z","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-02T17:46:11.652122Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"]} {"level":"info","ts":"2026-02-02T17:46:11.652219Z","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.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.84: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-02T17:46:11.65659Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.053241ms"} {"level":"info","ts":"2026-02-02T17:46:11.665005Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca4c710a8d5349d7","cluster-id":"8e28a51debf1c8ab"} {"level":"info","ts":"2026-02-02T17:46:11.665107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-02T17:46:11.665138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 0"} {"level":"info","ts":"2026-02-02T17:46:11.665159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ca4c710a8d5349d7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-02T17:46:11.665172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 1"} {"level":"info","ts":"2026-02-02T17:46:11.665224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"warn","ts":"2026-02-02T17:46:11.671184Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-02T17:46:11.67328Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-02T17:46:11.676037Z","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-02T17:46:11.679334Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ca4c710a8d5349d7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-02T17:46:11.679869Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca4c710a8d5349d7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-02T17:46:11.680071Z","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-02T17:46:11.680127Z","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-02T17:46:11.680153Z","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-02T17:46:11.692024Z","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-02T17:46:11.69207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"info","ts":"2026-02-02T17:46:11.692209Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","added-peer-id":"ca4c710a8d5349d7","added-peer-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-02-02T17:46:11.692269Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ca4c710a8d5349d7","initial-advertise-peer-urls":["https://162.253.55.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-02T17:46:11.692354Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-02T17:46:11.692481Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-02-02T17:46:11.692501Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-02-02T17:46:11.969568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-02T17:46:11.969823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-02T17:46:11.969903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgPreVoteResp from ca4c710a8d5349d7 at term 1"} {"level":"info","ts":"2026-02-02T17:46:11.96996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became candidate at term 2"} {"level":"info","ts":"2026-02-02T17:46:11.970004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgVoteResp from ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-02-02T17:46:11.970295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became leader at term 2"} {"level":"info","ts":"2026-02-02T17:46:11.970354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca4c710a8d5349d7 elected leader ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-02-02T17:46:11.972803Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ca4c710a8d5349d7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.84:2379]}","request-path":"/0/members/ca4c710a8d5349d7/attributes","cluster-id":"8e28a51debf1c8ab","publish-timeout":"7s"} {"level":"info","ts":"2026-02-02T17:46:11.972608Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:46:11.973098Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T17:46:11.973321Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T17:46:11.981289Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-02T17:46:11.983933Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-02T17:46:11.983955Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-02T17:46:11.984151Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:46:11.985044Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:46:11.985075Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:46:11.998046Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.84:2379"} {"level":"info","ts":"2026-02-02T17:46:23.587284Z","caller":"traceutil/trace.go:171","msg":"trace[384644244] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"331.326755ms","start":"2026-02-02T17:46:23.255797Z","end":"2026-02-02T17:46:23.587124Z","steps":["trace[384644244] 'process raft request' (duration: 274.95158ms)","trace[384644244] 'compare' (duration: 56.264002ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:46:23.587675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:46:23.255772Z","time spent":"331.567669ms","remote":"127.0.0.1:57508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3737,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:46:55.288163Z","caller":"traceutil/trace.go:171","msg":"trace[1828530530] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:522; }","duration":"156.952422ms","start":"2026-02-02T17:46:55.131175Z","end":"2026-02-02T17:46:55.288128Z","steps":["trace[1828530530] 'read index received' (duration: 156.86137ms)","trace[1828530530] 'applied index is now lower than readState.Index' (duration: 90.052µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:46:55.288333Z","caller":"traceutil/trace.go:171","msg":"trace[2042357441] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"173.524654ms","start":"2026-02-02T17:46:55.114797Z","end":"2026-02-02T17:46:55.288322Z","steps":["trace[2042357441] 'process raft request' (duration: 173.185157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:46:55.288595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.363611ms","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-02T17:46:55.288648Z","caller":"traceutil/trace.go:171","msg":"trace[1435646589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:505; }","duration":"157.520765ms","start":"2026-02-02T17:46:55.131115Z","end":"2026-02-02T17:46:55.288636Z","steps":["trace[1435646589] 'agreement among raft nodes before linearized reading' (duration: 157.330161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:46:55.288863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.864516ms","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-02T17:46:55.28889Z","caller":"traceutil/trace.go:171","msg":"trace[289677676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:505; }","duration":"105.944068ms","start":"2026-02-02T17:46:55.182938Z","end":"2026-02-02T17:46:55.288882Z","steps":["trace[289677676] 'agreement among raft nodes before linearized reading' (duration: 105.864456ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:46:57.468107Z","caller":"traceutil/trace.go:171","msg":"trace[243172868] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"113.442076ms","start":"2026-02-02T17:46:57.354642Z","end":"2026-02-02T17:46:57.468084Z","steps":["trace[243172868] 'process raft request' (duration: 113.373215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:16.18324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.790519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:47:16.183314Z","caller":"traceutil/trace.go:171","msg":"trace[734441240] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:724; }","duration":"436.272371ms","start":"2026-02-02T17:47:15.747029Z","end":"2026-02-02T17:47:16.183302Z","steps":["trace[734441240] 'read index received' (duration: 136.205247ms)","trace[734441240] 'applied index is now lower than readState.Index' (duration: 300.066364ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:47:16.183442Z","caller":"traceutil/trace.go:171","msg":"trace[963239104] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"538.478695ms","start":"2026-02-02T17:47:15.644955Z","end":"2026-02-02T17:47:16.183434Z","steps":["trace[963239104] 'process raft request' (duration: 238.306078ms)","trace[963239104] 'compare' (duration: 299.718317ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:47:16.183482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:47:15.644947Z","time spent":"538.511496ms","remote":"127.0.0.1:57276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:47:16.1836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.598399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T17:47:16.183618Z","caller":"traceutil/trace.go:171","msg":"trace[1886812205] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:702; }","duration":"436.709971ms","start":"2026-02-02T17:47:15.746902Z","end":"2026-02-02T17:47:16.183612Z","steps":["trace[1886812205] 'agreement among raft nodes before linearized reading' (duration: 436.67688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:16.183636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:47:15.746875Z","time spent":"436.756032ms","remote":"127.0.0.1:57164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:47:16.183729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.470388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:47:16.183748Z","caller":"traceutil/trace.go:171","msg":"trace[269508956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:702; }","duration":"163.51981ms","start":"2026-02-02T17:47:16.020223Z","end":"2026-02-02T17:47:16.183743Z","steps":["trace[269508956] 'agreement among raft nodes before linearized reading' (duration: 163.492169ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:47:16.393467Z","caller":"traceutil/trace.go:171","msg":"trace[2058668149] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:726; }","duration":"201.38494ms","start":"2026-02-02T17:47:16.192061Z","end":"2026-02-02T17:47:16.393446Z","steps":["trace[2058668149] 'read index received' (duration: 87.501624ms)","trace[2058668149] 'applied index is now lower than readState.Index' (duration: 113.882336ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:47:16.39362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.537873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.84\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-02T17:47:16.393643Z","caller":"traceutil/trace.go:171","msg":"trace[1710197781] range","detail":"{range_begin:/registry/masterleases/162.253.55.84; range_end:; response_count:1; response_revision:704; }","duration":"201.575654ms","start":"2026-02-02T17:47:16.192059Z","end":"2026-02-02T17:47:16.393635Z","steps":["trace[1710197781] 'agreement among raft nodes before linearized reading' (duration: 201.459322ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:47:16.393738Z","caller":"traceutil/trace.go:171","msg":"trace[1260463020] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"203.682991ms","start":"2026-02-02T17:47:16.190034Z","end":"2026-02-02T17:47:16.393717Z","steps":["trace[1260463020] 'process raft request' (duration: 203.281162ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:47:29.527853Z","caller":"traceutil/trace.go:171","msg":"trace[1802838805] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"124.920513ms","start":"2026-02-02T17:47:29.402915Z","end":"2026-02-02T17:47:29.527835Z","steps":["trace[1802838805] 'process raft request' (duration: 124.804421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:32.53044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.508339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T17:47:32.530499Z","caller":"traceutil/trace.go:171","msg":"trace[364963596] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:754; }","duration":"163.597131ms","start":"2026-02-02T17:47:32.36689Z","end":"2026-02-02T17:47:32.530487Z","steps":["trace[364963596] 'count revisions from in-memory index tree' (duration: 163.463828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:32.530745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.940544ms","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-02T17:47:32.530767Z","caller":"traceutil/trace.go:171","msg":"trace[1131307868] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"202.986984ms","start":"2026-02-02T17:47:32.327774Z","end":"2026-02-02T17:47:32.530761Z","steps":["trace[1131307868] 'range keys from in-memory index tree' (duration: 202.858182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:37.211247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.649801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:47:37.211355Z","caller":"traceutil/trace.go:171","msg":"trace[1411519394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:769; }","duration":"187.830155ms","start":"2026-02-02T17:47:37.023504Z","end":"2026-02-02T17:47:37.211334Z","steps":["trace[1411519394] 'range keys from in-memory index tree' (duration: 187.462667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:57.014075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.457783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-02T17:47:57.014136Z","caller":"traceutil/trace.go:171","msg":"trace[554801470] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:881; }","duration":"251.567346ms","start":"2026-02-02T17:47:56.762556Z","end":"2026-02-02T17:47:57.014124Z","steps":["trace[554801470] 'range keys from in-memory index tree' (duration: 251.324831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:57.014192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.086714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-02T17:47:57.014247Z","caller":"traceutil/trace.go:171","msg":"trace[403362103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:881; }","duration":"251.162957ms","start":"2026-02-02T17:47:56.763068Z","end":"2026-02-02T17:47:57.014231Z","steps":["trace[403362103] 'range keys from in-memory index tree' (duration: 250.820169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:57.014376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.489542ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-02T17:47:57.014426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.823124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T17:47:57.014435Z","caller":"traceutil/trace.go:171","msg":"trace[1680510315] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:881; }","duration":"533.550614ms","start":"2026-02-02T17:47:56.480877Z","end":"2026-02-02T17:47:57.014428Z","steps":["trace[1680510315] 'range keys from in-memory index tree' (duration: 533.483832ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:47:57.014443Z","caller":"traceutil/trace.go:171","msg":"trace[1494669652] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:881; }","duration":"121.871365ms","start":"2026-02-02T17:47:56.892567Z","end":"2026-02-02T17:47:57.014439Z","steps":["trace[1494669652] 'count revisions from in-memory index tree' (duration: 121.70019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:57.014726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.696192ms","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-02T17:47:57.014744Z","caller":"traceutil/trace.go:171","msg":"trace[179114168] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:881; }","duration":"343.739724ms","start":"2026-02-02T17:47:56.670999Z","end":"2026-02-02T17:47:57.014739Z","steps":["trace[179114168] 'range keys from in-memory index tree' (duration: 343.611341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:47:57.014771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:47:56.670984Z","time spent":"343.769924ms","remote":"127.0.0.1:57276","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-02T17:47:57.119124Z","caller":"traceutil/trace.go:171","msg":"trace[1798681339] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"101.101239ms","start":"2026-02-02T17:47:57.018007Z","end":"2026-02-02T17:47:57.119109Z","steps":["trace[1798681339] 'process raft request' (duration: 100.985526ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:47:57.173508Z","caller":"traceutil/trace.go:171","msg":"trace[1374844676] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"154.257682ms","start":"2026-02-02T17:47:57.019232Z","end":"2026-02-02T17:47:57.17349Z","steps":["trace[1374844676] 'process raft request' (duration: 153.75215ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:47:57.173832Z","caller":"traceutil/trace.go:171","msg":"trace[327301304] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"154.515128ms","start":"2026-02-02T17:47:57.019307Z","end":"2026-02-02T17:47:57.173822Z","steps":["trace[327301304] 'process raft request' (duration: 153.808352ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:47:57.174032Z","caller":"traceutil/trace.go:171","msg":"trace[625182256] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:916; }","duration":"148.099204ms","start":"2026-02-02T17:47:57.025272Z","end":"2026-02-02T17:47:57.173372Z","steps":["trace[625182256] 'read index received' (duration: 93.777675ms)","trace[625182256] 'applied index is now lower than readState.Index' (duration: 54.320459ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:47:57.174113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.82571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:47:57.174142Z","caller":"traceutil/trace.go:171","msg":"trace[1253421822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:884; }","duration":"148.882051ms","start":"2026-02-02T17:47:57.02525Z","end":"2026-02-02T17:47:57.174132Z","steps":["trace[1253421822] 'agreement among raft nodes before linearized reading' (duration: 148.82651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:48:10.218985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.047532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:48:10.219057Z","caller":"traceutil/trace.go:171","msg":"trace[1299487318] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"197.883541ms","start":"2026-02-02T17:48:10.021162Z","end":"2026-02-02T17:48:10.219045Z","steps":["trace[1299487318] 'read index received' (duration: 698.936µs)","trace[1299487318] 'applied index is now lower than readState.Index' (duration: 197.183795ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:48:10.219105Z","caller":"traceutil/trace.go:171","msg":"trace[130914171] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"242.767856ms","start":"2026-02-02T17:48:09.976324Z","end":"2026-02-02T17:48:10.219092Z","steps":["trace[130914171] 'process raft request' (duration: 45.55214ms)","trace[130914171] 'compare' (duration: 196.821227ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:48:10.219128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.965003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:48:10.219166Z","caller":"traceutil/trace.go:171","msg":"trace[405349590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"198.003164ms","start":"2026-02-02T17:48:10.021156Z","end":"2026-02-02T17:48:10.219159Z","steps":["trace[405349590] 'agreement among raft nodes before linearized reading' (duration: 197.948372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:48:10.219296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.236672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:48:10.219316Z","caller":"traceutil/trace.go:171","msg":"trace[883458196] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:920; }","duration":"187.280153ms","start":"2026-02-02T17:48:10.032031Z","end":"2026-02-02T17:48:10.219311Z","steps":["trace[883458196] 'agreement among raft nodes before linearized reading' (duration: 187.241812ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:48:26.180322Z","caller":"traceutil/trace.go:171","msg":"trace[178975102] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"151.798789ms","start":"2026-02-02T17:48:26.028499Z","end":"2026-02-02T17:48:26.180297Z","steps":["trace[178975102] 'process raft request' (duration: 151.298647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:51:44.641113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.297302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-02T17:51:44.641183Z","caller":"traceutil/trace.go:171","msg":"trace[127170707] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1958; }","duration":"351.404864ms","start":"2026-02-02T17:51:44.289762Z","end":"2026-02-02T17:51:44.641167Z","steps":["trace[127170707] 'range keys from in-memory index tree' (duration: 351.138799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:51:44.641214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:51:44.289745Z","time spent":"351.463275ms","remote":"127.0.0.1:57276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-02T17:51:44.641371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.552859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-02T17:51:44.641443Z","caller":"traceutil/trace.go:171","msg":"trace[376088190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1958; }","duration":"171.680471ms","start":"2026-02-02T17:51:44.469747Z","end":"2026-02-02T17:51:44.641428Z","steps":["trace[376088190] 'range keys from in-memory index tree' (duration: 171.419796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:51:44.641559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.338524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-02T17:51:44.641586Z","caller":"traceutil/trace.go:171","msg":"trace[71489730] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1958; }","duration":"415.395355ms","start":"2026-02-02T17:51:44.226181Z","end":"2026-02-02T17:51:44.641576Z","steps":["trace[71489730] 'range keys from in-memory index tree' (duration: 415.243982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:51:44.641603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:51:44.226163Z","time spent":"415.436216ms","remote":"127.0.0.1:57110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":645,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-02T17:51:44.641644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.587996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-02T17:51:44.641671Z","caller":"traceutil/trace.go:171","msg":"trace[133392158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1958; }","duration":"161.624317ms","start":"2026-02-02T17:51:44.480036Z","end":"2026-02-02T17:51:44.64166Z","steps":["trace[133392158] 'range keys from in-memory index tree' (duration: 161.525775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:51:46.394617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353430944s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:51:46.394867Z","caller":"traceutil/trace.go:171","msg":"trace[794705906] linearizableReadLoop","detail":"{readStateIndex:2041; appliedIndex:2040; }","duration":"1.747990822s","start":"2026-02-02T17:51:44.646861Z","end":"2026-02-02T17:51:46.394851Z","steps":["trace[794705906] 'read index received' (duration: 95.128139ms)","trace[794705906] 'applied index is now lower than readState.Index' (duration: 1.652861773s)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:51:46.395118Z","caller":"traceutil/trace.go:171","msg":"trace[1492388746] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"1.749094148s","start":"2026-02-02T17:51:44.64601Z","end":"2026-02-02T17:51:46.395105Z","steps":["trace[1492388746] 'process raft request' (duration: 395.112571ms)","trace[1492388746] 'compare' (duration: 1.352967523s)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:51:46.395226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:51:44.646005Z","time spent":"1.749184439s","remote":"127.0.0.1:57276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:51:46.395444Z","caller":"traceutil/trace.go:171","msg":"trace[161763272] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"1.744327001s","start":"2026-02-02T17:51:44.651106Z","end":"2026-02-02T17:51:46.395433Z","steps":["trace[161763272] 'process raft request' (duration: 1.743600935s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:51:46.395506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:51:44.651091Z","time spent":"1.744381412s","remote":"127.0.0.1:57276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:51:46.395657Z","caller":"traceutil/trace.go:171","msg":"trace[1487043154] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"1.726913221s","start":"2026-02-02T17:51:44.668734Z","end":"2026-02-02T17:51:46.395647Z","steps":["trace[1487043154] 'process raft request' (duration: 1.726078642s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:51:46.395706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:51:44.66872Z","time spent":"1.726959971s","remote":"127.0.0.1:57276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:51:46.395896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.749030476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-02-02T17:51:46.395927Z","caller":"traceutil/trace.go:171","msg":"trace[417891152] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1962; }","duration":"1.749081606s","start":"2026-02-02T17:51:44.646836Z","end":"2026-02-02T17:51:46.395918Z","steps":["trace[417891152] 'agreement among raft nodes before linearized reading' (duration: 1.748973954s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:51:46.395955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:51:44.646822Z","time spent":"1.749127037s","remote":"127.0.0.1:57276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-02T17:51:46.396089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.631470174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:51:46.396115Z","caller":"traceutil/trace.go:171","msg":"trace[1387289082] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1962; }","duration":"1.631531196s","start":"2026-02-02T17:51:44.764576Z","end":"2026-02-02T17:51:46.396107Z","steps":["trace[1387289082] 'agreement among raft nodes before linearized reading' (duration: 1.631491645s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:51:46.396141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:51:44.764556Z","time spent":"1.631579187s","remote":"127.0.0.1:57146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-02-02T17:51:46.396451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.143958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.84\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-02T17:51:46.396483Z","caller":"traceutil/trace.go:171","msg":"trace[178592585] range","detail":"{range_begin:/registry/masterleases/162.253.55.84; range_end:; response_count:1; response_revision:1962; }","duration":"179.21209ms","start":"2026-02-02T17:51:46.217263Z","end":"2026-02-02T17:51:46.396475Z","steps":["trace[178592585] 'agreement among raft nodes before linearized reading' (duration: 179.121268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:51:46.396615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.258563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:51:46.396641Z","caller":"traceutil/trace.go:171","msg":"trace[1256768476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1962; }","duration":"374.314123ms","start":"2026-02-02T17:51:46.022319Z","end":"2026-02-02T17:51:46.396633Z","steps":["trace[1256768476] 'agreement among raft nodes before linearized reading' (duration: 374.274542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:51:46.39667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:51:46.022291Z","time spent":"374.373525ms","remote":"127.0.0.1:57014","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-02T17:51:46.396846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.261248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-02T17:51:46.396873Z","caller":"traceutil/trace.go:171","msg":"trace[1386975403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1962; }","duration":"658.317579ms","start":"2026-02-02T17:51:45.738548Z","end":"2026-02-02T17:51:46.396865Z","steps":["trace[1386975403] 'agreement among raft nodes before linearized reading' (duration: 658.239508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:51:46.396901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:51:45.738529Z","time spent":"658.36626ms","remote":"127.0.0.1:57276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T17:51:46.397029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04629144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T17:51:46.397056Z","caller":"traceutil/trace.go:171","msg":"trace[760260997] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1962; }","duration":"1.046347651s","start":"2026-02-02T17:51:45.3507Z","end":"2026-02-02T17:51:46.397048Z","steps":["trace[760260997] 'agreement among raft nodes before linearized reading' (duration: 1.046294s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:51:46.397092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:51:45.35068Z","time spent":"1.046394522s","remote":"127.0.0.1:57110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":16,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:51:46.397291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277866603s","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-02T17:51:46.397318Z","caller":"traceutil/trace.go:171","msg":"trace[1717374752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1962; }","duration":"1.277927074s","start":"2026-02-02T17:51:45.119383Z","end":"2026-02-02T17:51:46.39731Z","steps":["trace[1717374752] 'agreement among raft nodes before linearized reading' (duration: 1.277851373s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:51:46.397346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:51:45.119365Z","time spent":"1.277973065s","remote":"127.0.0.1:57276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-02T17:51:46.609692Z","caller":"traceutil/trace.go:171","msg":"trace[278531295] linearizableReadLoop","detail":"{readStateIndex:2044; appliedIndex:2043; }","duration":"204.058826ms","start":"2026-02-02T17:51:46.405593Z","end":"2026-02-02T17:51:46.609652Z","steps":["trace[278531295] 'read index received' (duration: 168.812658ms)","trace[278531295] 'applied index is now lower than readState.Index' (duration: 35.244958ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:51:46.609945Z","caller":"traceutil/trace.go:171","msg":"trace[2034053649] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"203.158026ms","start":"2026-02-02T17:51:46.406775Z","end":"2026-02-02T17:51:46.609933Z","steps":["trace[2034053649] 'process raft request' (duration: 202.817598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:51:46.610024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.363772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:51:46.610151Z","caller":"traceutil/trace.go:171","msg":"trace[103584800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1963; }","duration":"204.559217ms","start":"2026-02-02T17:51:46.405573Z","end":"2026-02-02T17:51:46.610132Z","steps":["trace[103584800] 'agreement among raft nodes before linearized reading' (duration: 204.27315ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:51:46.610899Z","caller":"traceutil/trace.go:171","msg":"trace[429471686] transaction","detail":"{read_only:false; response_revision:1965; number_of_response:1; }","duration":"202.558422ms","start":"2026-02-02T17:51:46.408329Z","end":"2026-02-02T17:51:46.610888Z","steps":["trace[429471686] 'process raft request' (duration: 201.946368ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:51:46.610332Z","caller":"traceutil/trace.go:171","msg":"trace[1150872908] transaction","detail":"{read_only:false; response_revision:1964; number_of_response:1; }","duration":"203.227228ms","start":"2026-02-02T17:51:46.407092Z","end":"2026-02-02T17:51:46.61032Z","steps":["trace[1150872908] 'process raft request' (duration: 202.746487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:51:46.610644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.10486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:51:46.61392Z","caller":"traceutil/trace.go:171","msg":"trace[995357442] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1965; }","duration":"120.415324ms","start":"2026-02-02T17:51:46.493496Z","end":"2026-02-02T17:51:46.613911Z","steps":["trace[995357442] 'agreement among raft nodes before linearized reading' (duration: 117.08533ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:51:47.870574Z","caller":"traceutil/trace.go:171","msg":"trace[1651579008] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"187.26901ms","start":"2026-02-02T17:51:47.683281Z","end":"2026-02-02T17:51:47.87055Z","steps":["trace[1651579008] 'process raft request' (duration: 119.531745ms)","trace[1651579008] 'compare' (duration: 67.157632ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:51:47.87099Z","caller":"traceutil/trace.go:171","msg":"trace[786507687] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"186.757599ms","start":"2026-02-02T17:51:47.684037Z","end":"2026-02-02T17:51:47.870794Z","steps":["trace[786507687] 'process raft request' (duration: 186.121294ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:51:48.518437Z","caller":"traceutil/trace.go:171","msg":"trace[1676064712] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"104.378376ms","start":"2026-02-02T17:51:48.414036Z","end":"2026-02-02T17:51:48.518414Z","steps":["trace[1676064712] 'process raft request' (duration: 104.270534ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:51:48.843416Z","caller":"traceutil/trace.go:171","msg":"trace[77656671] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"112.713092ms","start":"2026-02-02T17:51:48.730688Z","end":"2026-02-02T17:51:48.843401Z","steps":["trace[77656671] 'process raft request' (duration: 64.239167ms)","trace[77656671] 'compare' (duration: 48.181129ms)"],"step_count":2}