{"level":"warn","ts":"2026-01-30T18:07:51.743028Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T18:07:51.743129Z","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-01-30T18:07:51.74327Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T18:07:51.743285Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-01-30T18:07:51.743368Z","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-01-30T18:07:51.744158Z","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-01-30T18:07:51.744284Z","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-01-30T18:07:51.748849Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.263536ms"} {"level":"info","ts":"2026-01-30T18:07:51.75826Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a11c20306510c1f7","cluster-id":"97da89d0cbebd2b5"} {"level":"info","ts":"2026-01-30T18:07:51.758303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=()"} {"level":"info","ts":"2026-01-30T18:07:51.758401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 0"} {"level":"info","ts":"2026-01-30T18:07:51.758419Z","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-01-30T18:07:51.758496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 1"} {"level":"info","ts":"2026-01-30T18:07:51.758576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"warn","ts":"2026-01-30T18:07:51.766154Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-30T18:07:51.770011Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-30T18:07:51.771397Z","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-01-30T18:07:51.773139Z","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-01-30T18:07:51.774562Z","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-01-30T18:07:51.774926Z","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-01-30T18:07:51.774964Z","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-01-30T18:07:51.775219Z","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-01-30T18:07:51.791199Z","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-01-30T18:07:51.791572Z","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-01-30T18:07:51.791595Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-30T18:07:51.79224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"info","ts":"2026-01-30T18:07:51.792313Z","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-01-30T18:07:51.792951Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-01-30T18:07:51.792965Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-01-30T18:07:52.359021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 is starting a new election at term 1"} {"level":"info","ts":"2026-01-30T18:07:52.359207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-30T18:07:52.359242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgPreVoteResp from a11c20306510c1f7 at term 1"} {"level":"info","ts":"2026-01-30T18:07:52.359303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became candidate at term 2"} {"level":"info","ts":"2026-01-30T18:07:52.359327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgVoteResp from a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-01-30T18:07:52.359402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became leader at term 2"} {"level":"info","ts":"2026-01-30T18:07:52.359429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a11c20306510c1f7 elected leader a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-01-30T18:07:52.370372Z","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-01-30T18:07:52.370478Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T18:07:52.370636Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T18:07:52.371015Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T18:07:52.371213Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-30T18:07:52.37123Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-30T18:07:52.372877Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.204:2379"} {"level":"info","ts":"2026-01-30T18:07:52.374673Z","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-01-30T18:07:52.374816Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T18:07:52.374855Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T18:07:52.424253Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-30T18:08:16.069281Z","caller":"traceutil/trace.go:171","msg":"trace[1426128937] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"118.283677ms","start":"2026-01-30T18:08:15.950975Z","end":"2026-01-30T18:08:16.069259Z","steps":["trace[1426128937] 'process raft request' (duration: 116.749297ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:08:20.661866Z","caller":"traceutil/trace.go:171","msg":"trace[901778957] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"100.216762ms","start":"2026-01-30T18:08:20.561628Z","end":"2026-01-30T18:08:20.661845Z","steps":["trace[901778957] 'process raft request' (duration: 14.72016ms)","trace[901778957] 'compare' (duration: 85.181457ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:08:25.244344Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976811561487684165,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-30T18:08:25.496678Z","caller":"traceutil/trace.go:171","msg":"trace[68411514] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"820.359297ms","start":"2026-01-30T18:08:24.676306Z","end":"2026-01-30T18:08:25.496665Z","steps":["trace[68411514] 'process raft request' (duration: 820.284507ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:08:25.497097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:08:24.676297Z","time spent":"820.420778ms","remote":"127.0.0.1:33242","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-01-30T18:08:25.498486Z","caller":"traceutil/trace.go:171","msg":"trace[1200691905] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:514; }","duration":"754.751559ms","start":"2026-01-30T18:08:24.743718Z","end":"2026-01-30T18:08:25.498469Z","steps":["trace[1200691905] 'read index received' (duration: 753.574885ms)","trace[1200691905] 'applied index is now lower than readState.Index' (duration: 1.176294ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:08:25.498539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.81248ms","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-01-30T18:08:25.498558Z","caller":"traceutil/trace.go:171","msg":"trace[1190026712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"754.859011ms","start":"2026-01-30T18:08:24.743695Z","end":"2026-01-30T18:08:25.498554Z","steps":["trace[1190026712] 'agreement among raft nodes before linearized reading' (duration: 754.809701ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:08:25.498572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:08:24.743686Z","time spent":"754.883401ms","remote":"127.0.0.1:33242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-30T18:08:25.498692Z","caller":"traceutil/trace.go:171","msg":"trace[1377199632] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"269.088472ms","start":"2026-01-30T18:08:25.229599Z","end":"2026-01-30T18:08:25.498687Z","steps":["trace[1377199632] 'process raft request' (duration: 268.844449ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:08:25.615449Z","caller":"traceutil/trace.go:171","msg":"trace[1374219629] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"110.279265ms","start":"2026-01-30T18:08:25.505149Z","end":"2026-01-30T18:08:25.615428Z","steps":["trace[1374219629] 'process raft request' (duration: 69.451119ms)","trace[1374219629] 'compare' (duration: 40.421351ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T18:08:28.868386Z","caller":"traceutil/trace.go:171","msg":"trace[412932066] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"323.151555ms","start":"2026-01-30T18:08:28.545217Z","end":"2026-01-30T18:08:28.868369Z","steps":["trace[412932066] 'read index received' (duration: 323.006323ms)","trace[412932066] 'applied index is now lower than readState.Index' (duration: 144.552µs)"],"step_count":2} {"level":"info","ts":"2026-01-30T18:08:28.868583Z","caller":"traceutil/trace.go:171","msg":"trace[1553758562] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"403.739691ms","start":"2026-01-30T18:08:28.464835Z","end":"2026-01-30T18:08:28.868575Z","steps":["trace[1553758562] 'process raft request' (duration: 403.440207ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:08:28.868655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:08:28.464808Z","time spent":"403.795411ms","remote":"127.0.0.1:33166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T18:08:28.868783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.562349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T18:08:28.868803Z","caller":"traceutil/trace.go:171","msg":"trace[64896018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"323.60955ms","start":"2026-01-30T18:08:28.545187Z","end":"2026-01-30T18:08:28.868797Z","steps":["trace[64896018] 'agreement among raft nodes before linearized reading' (duration: 323.574329ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:08:28.86882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:08:28.545172Z","time spent":"323.6444ms","remote":"127.0.0.1:33020","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-01-30T18:08:28.868974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.712805ms","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-01-30T18:08:28.868993Z","caller":"traceutil/trace.go:171","msg":"trace[382157621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:509; }","duration":"306.744655ms","start":"2026-01-30T18:08:28.562242Z","end":"2026-01-30T18:08:28.868986Z","steps":["trace[382157621] 'agreement among raft nodes before linearized reading' (duration: 306.666774ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:08:28.869013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:08:28.562235Z","time spent":"306.773335ms","remote":"127.0.0.1:33242","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":"warn","ts":"2026-01-30T18:08:28.869246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.780738ms","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-01-30T18:08:28.869274Z","caller":"traceutil/trace.go:171","msg":"trace[421478917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:509; }","duration":"302.820698ms","start":"2026-01-30T18:08:28.566447Z","end":"2026-01-30T18:08:28.869268Z","steps":["trace[421478917] 'agreement among raft nodes before linearized reading' (duration: 302.755107ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:08:28.869293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:08:28.566442Z","time spent":"302.846368ms","remote":"127.0.0.1:33242","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-01-30T18:08:29.419047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.850595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T18:08:29.419136Z","caller":"traceutil/trace.go:171","msg":"trace[1712879804] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"542.479387ms","start":"2026-01-30T18:08:28.87664Z","end":"2026-01-30T18:08:29.41912Z","steps":["trace[1712879804] 'process raft request' (duration: 63.284437ms)","trace[1712879804] 'compare' (duration: 478.615292ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:08:29.419194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:08:28.876635Z","time spent":"542.528688ms","remote":"127.0.0.1:33242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T18:08:30.449989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.094442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-30T18:08:30.450046Z","caller":"traceutil/trace.go:171","msg":"trace[794424886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"929.181263ms","start":"2026-01-30T18:08:29.520848Z","end":"2026-01-30T18:08:30.450029Z","steps":["trace[794424886] 'range keys from in-memory index tree' (duration: 928.97438ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:08:30.450074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:08:29.520834Z","time spent":"929.234233ms","remote":"127.0.0.1:33242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-30T18:08:30.450266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.827278ms","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-01-30T18:08:30.450292Z","caller":"traceutil/trace.go:171","msg":"trace[1617726377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"819.886828ms","start":"2026-01-30T18:08:29.630397Z","end":"2026-01-30T18:08:30.450284Z","steps":["trace[1617726377] 'range keys from in-memory index tree' (duration: 819.744786ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:08:30.450314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:08:29.630379Z","time spent":"819.930839ms","remote":"127.0.0.1:33242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-30T18:08:35.841619Z","caller":"traceutil/trace.go:171","msg":"trace[171629911] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"132.885889ms","start":"2026-01-30T18:08:35.708715Z","end":"2026-01-30T18:08:35.841601Z","steps":["trace[171629911] 'process raft request' (duration: 132.561285ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:08:50.849628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.500047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-30T18:08:50.850256Z","caller":"traceutil/trace.go:171","msg":"trace[1560741424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:713; }","duration":"236.612608ms","start":"2026-01-30T18:08:50.613076Z","end":"2026-01-30T18:08:50.849688Z","steps":["trace[1560741424] 'range keys from in-memory index tree' (duration: 236.336176ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:08:50.85094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.966842ms","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-01-30T18:08:50.851356Z","caller":"traceutil/trace.go:171","msg":"trace[296843131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:713; }","duration":"224.409307ms","start":"2026-01-30T18:08:50.626933Z","end":"2026-01-30T18:08:50.851343Z","steps":["trace[296843131] 'range keys from in-memory index tree' (duration: 223.847351ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:09:59.376445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.095953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T18:09:59.376663Z","caller":"traceutil/trace.go:171","msg":"trace[1830806873] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"107.720628ms","start":"2026-01-30T18:09:59.268929Z","end":"2026-01-30T18:09:59.376649Z","steps":["trace[1830806873] 'compare' (duration: 100.953082ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T18:09:59.90084Z","caller":"traceutil/trace.go:171","msg":"trace[331863214] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"316.872383ms","start":"2026-01-30T18:09:59.583954Z","end":"2026-01-30T18:09:59.900826Z","steps":["trace[331863214] 'process raft request' (duration: 316.784012ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T18:09:59.900938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T18:09:59.583937Z","time spent":"316.948373ms","remote":"127.0.0.1:33164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5218,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T18:09:59.901054Z","caller":"traceutil/trace.go:171","msg":"trace[866681589] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:989; }","duration":"233.954731ms","start":"2026-01-30T18:09:59.667073Z","end":"2026-01-30T18:09:59.901027Z","steps":["trace[866681589] 'read index received' (duration: 233.947041ms)","trace[866681589] 'applied index is now lower than readState.Index' (duration: 6.36µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T18:09:59.901275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.154913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T18:09:59.901312Z","caller":"traceutil/trace.go:171","msg":"trace[951627876] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:951; }","duration":"234.272714ms","start":"2026-01-30T18:09:59.667027Z","end":"2026-01-30T18:09:59.901299Z","steps":["trace[951627876] 'agreement among raft nodes before linearized reading' (duration: 234.156703ms)"],"step_count":1}