{"level":"warn","ts":"2026-03-06T00:49:41.234645Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T00:49:41.234785Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.12: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.12:2380","--initial-cluster=instance=https://162.253.55.12:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.12:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.12: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-03-06T00:49:41.23491Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-06T00:49:41.23493Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-03-06T00:49:41.234967Z","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-03-06T00:49:41.23582Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"]} {"level":"info","ts":"2026-03-06T00:49:41.235939Z","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":8,"max-cpu-available":8,"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.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.12: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-03-06T00:49:41.239205Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.969752ms"} {"level":"info","ts":"2026-03-06T00:49:41.250517Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b4c6dc24a78438ca","cluster-id":"587227f9a3f73e98"} {"level":"info","ts":"2026-03-06T00:49:41.250647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=()"} {"level":"info","ts":"2026-03-06T00:49:41.250677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 0"} {"level":"info","ts":"2026-03-06T00:49:41.250703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b4c6dc24a78438ca [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-06T00:49:41.250716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became follower at term 1"} {"level":"info","ts":"2026-03-06T00:49:41.250774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"warn","ts":"2026-03-06T00:49:41.260913Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-06T00:49:41.262192Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-06T00:49:41.26314Z","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-03-06T00:49:41.265149Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b4c6dc24a78438ca","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-06T00:49:41.266106Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b4c6dc24a78438ca","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-06T00:49:41.266578Z","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-03-06T00:49:41.266702Z","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-03-06T00:49:41.266735Z","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-03-06T00:49:41.26999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca switched to configuration voters=(13026341022203132106)"} {"level":"info","ts":"2026-03-06T00:49:41.270232Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","added-peer-id":"b4c6dc24a78438ca","added-peer-peer-urls":["https://162.253.55.12:2380"]} {"level":"info","ts":"2026-03-06T00:49:41.272661Z","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-03-06T00:49:41.272827Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-03-06T00:49:41.272858Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.12:2380"} {"level":"info","ts":"2026-03-06T00:49:41.273103Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b4c6dc24a78438ca","initial-advertise-peer-urls":["https://162.253.55.12:2380"],"listen-peer-urls":["https://162.253.55.12:2380"],"advertise-client-urls":["https://162.253.55.12:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.12:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-06T00:49:41.273197Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-06T00:49:42.051876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca is starting a new election at term 1"} {"level":"info","ts":"2026-03-06T00:49:42.051941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became pre-candidate at term 1"} {"level":"info","ts":"2026-03-06T00:49:42.051983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgPreVoteResp from b4c6dc24a78438ca at term 1"} {"level":"info","ts":"2026-03-06T00:49:42.052045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became candidate at term 2"} {"level":"info","ts":"2026-03-06T00:49:42.052057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca received MsgVoteResp from b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-03-06T00:49:42.052113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b4c6dc24a78438ca became leader at term 2"} {"level":"info","ts":"2026-03-06T00:49:42.052134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b4c6dc24a78438ca elected leader b4c6dc24a78438ca at term 2"} {"level":"info","ts":"2026-03-06T00:49:42.053325Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b4c6dc24a78438ca","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.12:2379]}","request-path":"/0/members/b4c6dc24a78438ca/attributes","cluster-id":"587227f9a3f73e98","publish-timeout":"7s"} {"level":"info","ts":"2026-03-06T00:49:42.053336Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T00:49:42.053387Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T00:49:42.053387Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-06T00:49:42.053604Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-06T00:49:42.053632Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-06T00:49:42.054399Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"587227f9a3f73e98","local-member-id":"b4c6dc24a78438ca","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T00:49:42.054503Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T00:49:42.054532Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-06T00:49:42.055457Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-06T00:49:42.056952Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.12:2379"} {"level":"info","ts":"2026-03-06T00:50:03.869729Z","caller":"traceutil/trace.go:171","msg":"trace[287766637] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"232.994354ms","start":"2026-03-06T00:50:03.636711Z","end":"2026-03-06T00:50:03.869705Z","steps":["trace[287766637] 'process raft request' (duration: 232.888043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:50:08.231225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.044383ms","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-03-06T00:50:08.231347Z","caller":"traceutil/trace.go:171","msg":"trace[433116754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:426; }","duration":"139.204743ms","start":"2026-03-06T00:50:08.09212Z","end":"2026-03-06T00:50:08.231325Z","steps":["trace[433116754] 'range keys from in-memory index tree' (duration: 138.940952ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:50:11.066558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.091947ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-06T00:50:11.066795Z","caller":"traceutil/trace.go:171","msg":"trace[1699872759] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:477; }","duration":"347.334408ms","start":"2026-03-06T00:50:10.71944Z","end":"2026-03-06T00:50:11.066774Z","steps":["trace[1699872759] 'read index received' (duration: 341.721985ms)","trace[1699872759] 'applied index is now lower than readState.Index' (duration: 5.611533ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:50:11.066929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.483888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:50:11.066959Z","caller":"traceutil/trace.go:171","msg":"trace[1465770379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:464; }","duration":"347.573768ms","start":"2026-03-06T00:50:10.719376Z","end":"2026-03-06T00:50:11.06695Z","steps":["trace[1465770379] 'agreement among raft nodes before linearized reading' (duration: 347.493468ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:50:11.066988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:50:10.719354Z","time spent":"347.627679ms","remote":"127.0.0.1:55320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-06T00:50:11.06707Z","caller":"traceutil/trace.go:171","msg":"trace[185411111] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"692.219294ms","start":"2026-03-06T00:50:10.374576Z","end":"2026-03-06T00:50:11.066796Z","steps":["trace[185411111] 'process raft request' (duration: 692.085013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:50:11.067538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.218804ms","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-03-06T00:50:11.06761Z","caller":"traceutil/trace.go:171","msg":"trace[1779494714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:464; }","duration":"295.292824ms","start":"2026-03-06T00:50:10.772297Z","end":"2026-03-06T00:50:11.06759Z","steps":["trace[1779494714] 'agreement among raft nodes before linearized reading' (duration: 294.875212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:50:11.067798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:50:10.374553Z","time spent":"692.641636ms","remote":"127.0.0.1:55394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-06T00:50:13.382935Z","caller":"traceutil/trace.go:171","msg":"trace[837726560] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"301.918496ms","start":"2026-03-06T00:50:13.080988Z","end":"2026-03-06T00:50:13.382907Z","steps":["trace[837726560] 'process raft request' (duration: 301.777405ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:50:13.383329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:50:13.080963Z","time spent":"302.044677ms","remote":"127.0.0.1:55566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-06T00:50:19.938046Z","caller":"traceutil/trace.go:171","msg":"trace[1399534352] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"218.683661ms","start":"2026-03-06T00:50:19.719345Z","end":"2026-03-06T00:50:19.938029Z","steps":["trace[1399534352] 'read index received' (duration: 218.492751ms)","trace[1399534352] 'applied index is now lower than readState.Index' (duration: 190.08µs)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:50:19.938175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.808042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:50:19.938194Z","caller":"traceutil/trace.go:171","msg":"trace[1661301342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"218.855182ms","start":"2026-03-06T00:50:19.719333Z","end":"2026-03-06T00:50:19.938188Z","steps":["trace[1661301342] 'agreement among raft nodes before linearized reading' (duration: 218.761522ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:50:19.938366Z","caller":"traceutil/trace.go:171","msg":"trace[1447943069] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"219.599616ms","start":"2026-03-06T00:50:19.71876Z","end":"2026-03-06T00:50:19.93836Z","steps":["trace[1447943069] 'process raft request' (duration: 219.130184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:50:20.432078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.393641ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:50:20.432216Z","caller":"traceutil/trace.go:171","msg":"trace[1542133154] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:512; }","duration":"167.550842ms","start":"2026-03-06T00:50:20.264647Z","end":"2026-03-06T00:50:20.432197Z","steps":["trace[1542133154] 'range keys from in-memory index tree' (duration: 167.374151ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:50:20.432522Z","caller":"traceutil/trace.go:171","msg":"trace[503011000] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:528; }","duration":"434.000033ms","start":"2026-03-06T00:50:19.9985Z","end":"2026-03-06T00:50:20.4325Z","steps":["trace[503011000] 'read index received' (duration: 336.796442ms)","trace[503011000] 'applied index is now lower than readState.Index' (duration: 97.202701ms)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:50:20.432626Z","caller":"traceutil/trace.go:171","msg":"trace[262385002] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"436.815648ms","start":"2026-03-06T00:50:19.995798Z","end":"2026-03-06T00:50:20.432613Z","steps":["trace[262385002] 'process raft request' (duration: 339.493197ms)","trace[262385002] 'compare' (duration: 97.11347ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:50:20.432747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:50:19.995786Z","time spent":"436.899608ms","remote":"127.0.0.1:55566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:50:20.432792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.294535ms","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-03-06T00:50:20.432834Z","caller":"traceutil/trace.go:171","msg":"trace[1042662988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"434.376045ms","start":"2026-03-06T00:50:19.998447Z","end":"2026-03-06T00:50:20.432823Z","steps":["trace[1042662988] 'agreement among raft nodes before linearized reading' (duration: 434.303164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:50:20.432865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:50:19.998427Z","time spent":"434.431255ms","remote":"127.0.0.1:55566","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-03-06T00:50:20.897513Z","caller":"traceutil/trace.go:171","msg":"trace[484113773] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"461.963628ms","start":"2026-03-06T00:50:20.435524Z","end":"2026-03-06T00:50:20.897487Z","steps":["trace[484113773] 'process raft request' (duration: 461.843238ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:50:20.897683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:50:20.435506Z","time spent":"462.095149ms","remote":"127.0.0.1:55566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-06T00:50:20.897697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.647923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-06T00:50:20.897798Z","caller":"traceutil/trace.go:171","msg":"trace[1150282897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"615.789873ms","start":"2026-03-06T00:50:20.28198Z","end":"2026-03-06T00:50:20.89777Z","steps":["trace[1150282897] 'agreement among raft nodes before linearized reading' (duration: 615.612422ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:50:20.897545Z","caller":"traceutil/trace.go:171","msg":"trace[749108131] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"464.914904ms","start":"2026-03-06T00:50:20.432585Z","end":"2026-03-06T00:50:20.8975Z","steps":["trace[749108131] 'read index received' (duration: 461.202044ms)","trace[749108131] 'applied index is now lower than readState.Index' (duration: 3.70915ms)"],"step_count":2} {"level":"warn","ts":"2026-03-06T00:50:20.897851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-06T00:50:20.281957Z","time spent":"615.884364ms","remote":"127.0.0.1:55566","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":"warn","ts":"2026-03-06T00:50:20.8981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.364406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:50:20.898139Z","caller":"traceutil/trace.go:171","msg":"trace[670841394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"177.452126ms","start":"2026-03-06T00:50:20.720677Z","end":"2026-03-06T00:50:20.898129Z","steps":["trace[670841394] 'agreement among raft nodes before linearized reading' (duration: 177.398926ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:50:24.084132Z","caller":"traceutil/trace.go:171","msg":"trace[2060884229] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"190.685024ms","start":"2026-03-06T00:50:23.893398Z","end":"2026-03-06T00:50:24.084083Z","steps":["trace[2060884229] 'process raft request' (duration: 190.555583ms)"],"step_count":1} {"level":"info","ts":"2026-03-06T00:51:10.916837Z","caller":"traceutil/trace.go:171","msg":"trace[198425239] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"194.901823ms","start":"2026-03-06T00:51:10.721911Z","end":"2026-03-06T00:51:10.916813Z","steps":["trace[198425239] 'read index received' (duration: 194.769668ms)","trace[198425239] 'applied index is now lower than readState.Index' (duration: 131.175µs)"],"step_count":2} {"level":"info","ts":"2026-03-06T00:51:10.916899Z","caller":"traceutil/trace.go:171","msg":"trace[706292142] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"259.495327ms","start":"2026-03-06T00:51:10.657369Z","end":"2026-03-06T00:51:10.916864Z","steps":["trace[706292142] 'process raft request' (duration: 259.30872ms)"],"step_count":1} {"level":"warn","ts":"2026-03-06T00:51:10.916969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.044088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-06T00:51:10.916996Z","caller":"traceutil/trace.go:171","msg":"trace[1718671952] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:813; }","duration":"195.090481ms","start":"2026-03-06T00:51:10.721897Z","end":"2026-03-06T00:51:10.916988Z","steps":["trace[1718671952] 'agreement among raft nodes before linearized reading' (duration: 195.005137ms)"],"step_count":1}