{"level":"warn","ts":"2026-09-19T16:53:55.385311Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T16:53:55.385434Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.191: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.191:2380","--initial-cluster=instance=https://162.253.55.191:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.191:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.191: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-09-19T16:53:55.385548Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T16:53:55.385562Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.191:2380"]} {"level":"info","ts":"2026-09-19T16:53:55.385586Z","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-09-19T16:53:55.386481Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.191:2379"]} {"level":"info","ts":"2026-09-19T16:53:55.386726Z","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.191:2380"],"listen-peer-urls":["https://162.253.55.191:2380"],"advertise-client-urls":["https://162.253.55.191:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.191:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.191: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-09-19T16:53:55.399018Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.910687ms"} {"level":"info","ts":"2026-09-19T16:53:55.407996Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"adb6690514c92321","cluster-id":"ee04900e3a861beb"} {"level":"info","ts":"2026-09-19T16:53:55.408084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T16:53:55.408113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 became follower at term 0"} {"level":"info","ts":"2026-09-19T16:53:55.408127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft adb6690514c92321 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T16:53:55.408139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 became follower at term 1"} {"level":"info","ts":"2026-09-19T16:53:55.408199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 switched to configuration voters=(12517307684867351329)"} {"level":"warn","ts":"2026-09-19T16:53:55.417509Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T16:53:55.419092Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T16:53:55.42058Z","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-09-19T16:53:55.422611Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"adb6690514c92321","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T16:53:55.42289Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"adb6690514c92321","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T16:53:55.424376Z","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-09-19T16:53:55.424473Z","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-09-19T16:53:55.424486Z","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-09-19T16:53:55.427381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 switched to configuration voters=(12517307684867351329)"} {"level":"info","ts":"2026-09-19T16:53:55.427605Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ee04900e3a861beb","local-member-id":"adb6690514c92321","added-peer-id":"adb6690514c92321","added-peer-peer-urls":["https://162.253.55.191:2380"]} {"level":"info","ts":"2026-09-19T16:53:55.429169Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.191:2380"} {"level":"info","ts":"2026-09-19T16:53:55.429195Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.191:2380"} {"level":"info","ts":"2026-09-19T16:53:55.429485Z","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-09-19T16:53:55.429933Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"adb6690514c92321","initial-advertise-peer-urls":["https://162.253.55.191:2380"],"listen-peer-urls":["https://162.253.55.191:2380"],"advertise-client-urls":["https://162.253.55.191:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.191:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T16:53:55.429977Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T16:53:56.40863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T16:53:56.408704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T16:53:56.40872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 received MsgPreVoteResp from adb6690514c92321 at term 1"} {"level":"info","ts":"2026-09-19T16:53:56.408733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 became candidate at term 2"} {"level":"info","ts":"2026-09-19T16:53:56.408741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 received MsgVoteResp from adb6690514c92321 at term 2"} {"level":"info","ts":"2026-09-19T16:53:56.408757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"adb6690514c92321 became leader at term 2"} {"level":"info","ts":"2026-09-19T16:53:56.408764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: adb6690514c92321 elected leader adb6690514c92321 at term 2"} {"level":"info","ts":"2026-09-19T16:53:56.410059Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"adb6690514c92321","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.191:2379]}","request-path":"/0/members/adb6690514c92321/attributes","cluster-id":"ee04900e3a861beb","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T16:53:56.410111Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T16:53:56.410404Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T16:53:56.410506Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T16:53:56.410432Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T16:53:56.410537Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T16:53:56.411656Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ee04900e3a861beb","local-member-id":"adb6690514c92321","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T16:53:56.411904Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T16:53:56.411976Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T16:53:56.413093Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T16:53:56.414287Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.191:2379"} {"level":"warn","ts":"2026-09-19T16:54:02.082498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.50418622s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-09-19T16:54:02.082635Z","caller":"traceutil/trace.go:171","msg":"trace[1225468702] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:210; }","duration":"1.18904108s","start":"2026-09-19T16:54:00.893573Z","end":"2026-09-19T16:54:02.082614Z","steps":["trace[1225468702] 'read index received' (duration: 73.042µs)","trace[1225468702] 'applied index is now lower than readState.Index' (duration: 1.188966128s)"],"step_count":2} {"level":"warn","ts":"2026-09-19T16:54:02.08289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:00.483568Z","time spent":"1.599309799s","remote":"127.0.0.1:49674","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-19T16:54:02.083004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189416418s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T16:54:02.083073Z","caller":"traceutil/trace.go:171","msg":"trace[1014852105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:205; }","duration":"1.18954758s","start":"2026-09-19T16:54:00.893513Z","end":"2026-09-19T16:54:02.083061Z","steps":["trace[1014852105] 'agreement among raft nodes before linearized reading' (duration: 1.189395987s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:02.083162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:00.893486Z","time spent":"1.189625872s","remote":"127.0.0.1:49900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-19T16:54:02.083259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.675070787s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3492"} {"level":"info","ts":"2026-09-19T16:54:02.083485Z","caller":"traceutil/trace.go:171","msg":"trace[1328481388] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:205; }","duration":"1.676598861s","start":"2026-09-19T16:54:00.406855Z","end":"2026-09-19T16:54:02.083454Z","steps":["trace[1328481388] 'range keys from in-memory index tree' (duration: 1.674879513s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:02.083621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:00.406841Z","time spent":"1.676762425s","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3516,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-09-19T16:54:02.780665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.401361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T16:54:02.780897Z","caller":"traceutil/trace.go:171","msg":"trace[1279503966] linearizableReadLoop","detail":"{readStateIndex:212; appliedIndex:211; }","duration":"697.99856ms","start":"2026-09-19T16:54:02.082876Z","end":"2026-09-19T16:54:02.780875Z","steps":["trace[1279503966] 'read index received' (duration: 331.955304ms)","trace[1279503966] 'applied index is now lower than readState.Index' (duration: 366.038816ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T16:54:02.781015Z","caller":"traceutil/trace.go:171","msg":"trace[624057907] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"1.362830304s","start":"2026-09-19T16:54:01.41817Z","end":"2026-09-19T16:54:02.781Z","steps":["trace[624057907] 'process raft request' (duration: 996.675226ms)","trace[624057907] 'compare' (duration: 365.183846ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T16:54:02.78118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:01.418122Z","time spent":"1.363027978s","remote":"127.0.0.1:49796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T16:54:02.781636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.070979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-19T16:54:02.781721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.506446873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T16:54:02.78174Z","caller":"traceutil/trace.go:171","msg":"trace[1090756464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:206; }","duration":"812.184341ms","start":"2026-09-19T16:54:01.969527Z","end":"2026-09-19T16:54:02.781712Z","steps":["trace[1090756464] 'agreement among raft nodes before linearized reading' (duration: 811.930236ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:54:02.78185Z","caller":"traceutil/trace.go:171","msg":"trace[629739440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:206; }","duration":"1.506634467s","start":"2026-09-19T16:54:01.275187Z","end":"2026-09-19T16:54:02.781822Z","steps":["trace[629739440] 'agreement among raft nodes before linearized reading' (duration: 1.506346001s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:02.781898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:01.275156Z","time spent":"1.50673316s","remote":"127.0.0.1:49900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-19T16:54:02.781883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:01.969454Z","time spent":"812.416005ms","remote":"127.0.0.1:49900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T16:54:02.789467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.648072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T16:54:02.789523Z","caller":"traceutil/trace.go:171","msg":"trace[897405913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:209; }","duration":"584.703073ms","start":"2026-09-19T16:54:02.204804Z","end":"2026-09-19T16:54:02.789507Z","steps":["trace[897405913] 'agreement among raft nodes before linearized reading' (duration: 584.56712ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:02.789593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:02.204745Z","time spent":"584.801555ms","remote":"127.0.0.1:49900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-19T16:54:02.789732Z","caller":"traceutil/trace.go:171","msg":"trace[18674427] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"702.276634ms","start":"2026-09-19T16:54:02.087323Z","end":"2026-09-19T16:54:02.789599Z","steps":["trace[18674427] 'process raft request' (duration: 701.933807ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:54:02.789865Z","caller":"traceutil/trace.go:171","msg":"trace[268934242] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"698.335747ms","start":"2026-09-19T16:54:02.091501Z","end":"2026-09-19T16:54:02.789837Z","steps":["trace[268934242] 'process raft request' (duration: 697.799366ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:02.790141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:02.087295Z","time spent":"702.608001ms","remote":"127.0.0.1:49900","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-09-19T16:54:02.790157Z","caller":"traceutil/trace.go:171","msg":"trace[647712474] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"705.527235ms","start":"2026-09-19T16:54:02.084554Z","end":"2026-09-19T16:54:02.790082Z","steps":["trace[647712474] 'process raft request' (duration: 704.566004ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:02.790433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:02.084525Z","time spent":"705.807101ms","remote":"127.0.0.1:49674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T16:54:02.790599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:02.091487Z","time spent":"698.548561ms","remote":"127.0.0.1:49788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T16:54:38.30916Z","caller":"traceutil/trace.go:171","msg":"trace[1914995444] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:520; }","duration":"323.758741ms","start":"2026-09-19T16:54:37.985369Z","end":"2026-09-19T16:54:38.309128Z","steps":["trace[1914995444] 'read index received' (duration: 322.838936ms)","trace[1914995444] 'applied index is now lower than readState.Index' (duration: 918.185µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T16:54:38.309263Z","caller":"traceutil/trace.go:171","msg":"trace[1781155988] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"583.685116ms","start":"2026-09-19T16:54:37.725537Z","end":"2026-09-19T16:54:38.309222Z","steps":["trace[1781155988] 'process raft request' (duration: 583.197947ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:38.309383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.993596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T16:54:38.309443Z","caller":"traceutil/trace.go:171","msg":"trace[264545006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"324.114398ms","start":"2026-09-19T16:54:37.985318Z","end":"2026-09-19T16:54:38.309432Z","steps":["trace[264545006] 'agreement among raft nodes before linearized reading' (duration: 323.949895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:38.30949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:37.725496Z","time spent":"583.820759ms","remote":"127.0.0.1:49692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317292,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T16:54:38.309567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:37.985294Z","time spent":"324.26221ms","remote":"127.0.0.1:49606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-19T16:54:40.787766Z","caller":"traceutil/trace.go:171","msg":"trace[698667016] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"473.37857ms","start":"2026-09-19T16:54:40.31436Z","end":"2026-09-19T16:54:40.787738Z","steps":["trace[698667016] 'process raft request' (duration: 473.189317ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:40.788043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:40.314327Z","time spent":"473.629333ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T16:54:40.796455Z","caller":"traceutil/trace.go:171","msg":"trace[227495089] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"469.31327ms","start":"2026-09-19T16:54:40.32712Z","end":"2026-09-19T16:54:40.796434Z","steps":["trace[227495089] 'process raft request' (duration: 469.144937ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:40.796551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:40.327095Z","time spent":"469.408472ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T16:54:40.796647Z","caller":"traceutil/trace.go:171","msg":"trace[400428883] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"455.971015ms","start":"2026-09-19T16:54:40.340666Z","end":"2026-09-19T16:54:40.796637Z","steps":["trace[400428883] 'process raft request' (duration: 455.701941ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:40.796713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:40.340661Z","time spent":"456.029116ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T16:54:40.796832Z","caller":"traceutil/trace.go:171","msg":"trace[328164947] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"456.20138ms","start":"2026-09-19T16:54:40.340589Z","end":"2026-09-19T16:54:40.79679Z","steps":["trace[328164947] 'process raft request' (duration: 455.728272ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:54:40.796466Z","caller":"traceutil/trace.go:171","msg":"trace[209277610] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"473.281628ms","start":"2026-09-19T16:54:40.323137Z","end":"2026-09-19T16:54:40.796419Z","steps":["trace[209277610] 'process raft request' (duration: 472.964633ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:40.796898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:40.340566Z","time spent":"456.309781ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T16:54:40.796982Z","caller":"traceutil/trace.go:171","msg":"trace[81849615] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"453.058426ms","start":"2026-09-19T16:54:40.343913Z","end":"2026-09-19T16:54:40.796971Z","steps":["trace[81849615] 'process raft request' (duration: 452.67256ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:40.797024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:40.343877Z","time spent":"453.130306ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T16:54:40.797159Z","caller":"traceutil/trace.go:171","msg":"trace[2014758510] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"455.959115ms","start":"2026-09-19T16:54:40.341191Z","end":"2026-09-19T16:54:40.79715Z","steps":["trace[2014758510] 'process raft request' (duration: 455.348175ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:40.797206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:40.341183Z","time spent":"456.002555ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T16:54:40.797192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:40.323117Z","time spent":"473.929848ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T16:54:40.797285Z","caller":"traceutil/trace.go:171","msg":"trace[1152505593] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"453.24653ms","start":"2026-09-19T16:54:40.344028Z","end":"2026-09-19T16:54:40.797274Z","steps":["trace[1152505593] 'process raft request' (duration: 452.611418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:40.797331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:40.344018Z","time spent":"453.29509ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T16:54:40.797615Z","caller":"traceutil/trace.go:171","msg":"trace[1095578428] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"441.842957ms","start":"2026-09-19T16:54:40.355761Z","end":"2026-09-19T16:54:40.797604Z","steps":["trace[1095578428] 'process raft request' (duration: 441.006762ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:40.797669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:40.355752Z","time spent":"441.894759ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T16:54:40.797767Z","caller":"traceutil/trace.go:171","msg":"trace[967727329] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"431.484772ms","start":"2026-09-19T16:54:40.366232Z","end":"2026-09-19T16:54:40.797717Z","steps":["trace[967727329] 'process raft request' (duration: 431.278299ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:54:40.797841Z","caller":"traceutil/trace.go:171","msg":"trace[356127342] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"403.900177ms","start":"2026-09-19T16:54:40.393932Z","end":"2026-09-19T16:54:40.797832Z","steps":["trace[356127342] 'process raft request' (duration: 403.644033ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:40.797882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:40.393903Z","time spent":"403.959158ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-19T16:54:40.797937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:40.366222Z","time spent":"431.629974ms","remote":"127.0.0.1:49642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T16:54:40.79811Z","caller":"traceutil/trace.go:171","msg":"trace[471905164] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"249.591422ms","start":"2026-09-19T16:54:40.548506Z","end":"2026-09-19T16:54:40.798098Z","steps":["trace[471905164] 'process raft request' (duration: 249.157365ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:54:47.537538Z","caller":"traceutil/trace.go:171","msg":"trace[1998074030] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"373.695601ms","start":"2026-09-19T16:54:47.163798Z","end":"2026-09-19T16:54:47.537494Z","steps":["trace[1998074030] 'read index received' (duration: 373.484737ms)","trace[1998074030] 'applied index is now lower than readState.Index' (duration: 209.174µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T16:54:47.537632Z","caller":"traceutil/trace.go:171","msg":"trace[1093646882] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"388.627584ms","start":"2026-09-19T16:54:47.148948Z","end":"2026-09-19T16:54:47.537576Z","steps":["trace[1093646882] 'process raft request' (duration: 388.247527ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:47.537803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.977756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-19T16:54:47.537853Z","caller":"traceutil/trace.go:171","msg":"trace[666931320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:646; }","duration":"374.111308ms","start":"2026-09-19T16:54:47.163729Z","end":"2026-09-19T16:54:47.53784Z","steps":["trace[666931320] 'agreement among raft nodes before linearized reading' (duration: 373.950036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:47.537892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:47.163703Z","time spent":"374.179709ms","remote":"127.0.0.1:49900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-19T16:54:47.537888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:47.148926Z","time spent":"388.830817ms","remote":"127.0.0.1:49900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T16:54:47.965559Z","caller":"traceutil/trace.go:171","msg":"trace[1032351481] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"427.89784ms","start":"2026-09-19T16:54:47.537628Z","end":"2026-09-19T16:54:47.965526Z","steps":["trace[1032351481] 'read index received' (duration: 426.837874ms)","trace[1032351481] 'applied index is now lower than readState.Index' (duration: 1.058676ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T16:54:47.965653Z","caller":"traceutil/trace.go:171","msg":"trace[740305930] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"556.314433ms","start":"2026-09-19T16:54:47.409272Z","end":"2026-09-19T16:54:47.965587Z","steps":["trace[740305930] 'process raft request' (duration: 555.47279ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:47.965764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.640688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-19T16:54:47.965858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.022273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-19T16:54:47.965855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:47.409229Z","time spent":"556.505736ms","remote":"127.0.0.1:49692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T16:54:47.965893Z","caller":"traceutil/trace.go:171","msg":"trace[361876657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"662.79861ms","start":"2026-09-19T16:54:47.303061Z","end":"2026-09-19T16:54:47.96586Z","steps":["trace[361876657] 'agreement among raft nodes before linearized reading' (duration: 662.548316ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:54:47.965904Z","caller":"traceutil/trace.go:171","msg":"trace[664583750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"682.139376ms","start":"2026-09-19T16:54:47.283749Z","end":"2026-09-19T16:54:47.965888Z","steps":["trace[664583750] 'agreement among raft nodes before linearized reading' (duration: 681.909341ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:47.965951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:47.303052Z","time spent":"662.886902ms","remote":"127.0.0.1:49900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-19T16:54:47.965975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:47.283729Z","time spent":"682.236677ms","remote":"127.0.0.1:49900","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-09-19T16:54:48.0336Z","caller":"traceutil/trace.go:171","msg":"trace[408830192] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"488.961188ms","start":"2026-09-19T16:54:47.544595Z","end":"2026-09-19T16:54:48.033556Z","steps":["trace[408830192] 'process raft request' (duration: 488.739815ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:48.033844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:47.544569Z","time spent":"489.156251ms","remote":"127.0.0.1:49900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T16:54:48.282002Z","caller":"traceutil/trace.go:171","msg":"trace[1230345796] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"310.44066ms","start":"2026-09-19T16:54:47.971527Z","end":"2026-09-19T16:54:48.281968Z","steps":["trace[1230345796] 'process raft request' (duration: 251.156914ms)","trace[1230345796] 'compare' (duration: 59.009992ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T16:54:48.282216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:47.971493Z","time spent":"310.608823ms","remote":"127.0.0.1:49900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T16:54:48.282331Z","caller":"traceutil/trace.go:171","msg":"trace[1506486585] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:675; }","duration":"297.109834ms","start":"2026-09-19T16:54:47.985119Z","end":"2026-09-19T16:54:48.282229Z","steps":["trace[1506486585] 'read index received' (duration: 48.132078ms)","trace[1506486585] 'applied index is now lower than readState.Index' (duration: 248.976286ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T16:54:48.282392Z","caller":"traceutil/trace.go:171","msg":"trace[507251742] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"310.134886ms","start":"2026-09-19T16:54:47.972216Z","end":"2026-09-19T16:54:48.282351Z","steps":["trace[507251742] 'process raft request' (duration: 309.696048ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:48.282591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:47.972193Z","time spent":"310.284029ms","remote":"127.0.0.1:49900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T16:54:48.282706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.577753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T16:54:48.282966Z","caller":"traceutil/trace.go:171","msg":"trace[127124087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"297.884847ms","start":"2026-09-19T16:54:47.985064Z","end":"2026-09-19T16:54:48.282949Z","steps":["trace[127124087] 'agreement among raft nodes before linearized reading' (duration: 297.362609ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:54:48.303806Z","caller":"traceutil/trace.go:171","msg":"trace[28241434] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"252.502444ms","start":"2026-09-19T16:54:48.051287Z","end":"2026-09-19T16:54:48.303789Z","steps":["trace[28241434] 'process raft request' (duration: 252.415872ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:49.485099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2531481188812084588,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-19T16:54:49.784328Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.00042479s","expected-duration":"1s"} {"level":"info","ts":"2026-09-19T16:54:49.785855Z","caller":"traceutil/trace.go:171","msg":"trace[1360490423] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"1.003716234s","start":"2026-09-19T16:54:48.782106Z","end":"2026-09-19T16:54:49.785823Z","steps":["trace[1360490423] 'process raft request' (duration: 1.002895841s)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:49.786051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:48.782066Z","time spent":"1.003883517s","remote":"127.0.0.1:49692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T16:54:49.799179Z","caller":"traceutil/trace.go:171","msg":"trace[1709163714] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:679; }","duration":"814.819896ms","start":"2026-09-19T16:54:48.984296Z","end":"2026-09-19T16:54:49.799115Z","steps":["trace[1709163714] 'read index received' (duration: 800.535837ms)","trace[1709163714] 'applied index is now lower than readState.Index' (duration: 14.282599ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T16:54:49.799312Z","caller":"traceutil/trace.go:171","msg":"trace[1496606791] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"638.356993ms","start":"2026-09-19T16:54:49.160939Z","end":"2026-09-19T16:54:49.799296Z","steps":["trace[1496606791] 'process raft request' (duration: 638.000587ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:49.800033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:49.160903Z","time spent":"639.069504ms","remote":"127.0.0.1:49900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T16:54:49.799616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.264192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T16:54:49.800213Z","caller":"traceutil/trace.go:171","msg":"trace[1347106924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"815.979504ms","start":"2026-09-19T16:54:48.984218Z","end":"2026-09-19T16:54:49.800197Z","steps":["trace[1347106924] 'agreement among raft nodes before linearized reading' (duration: 815.208882ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:54:49.800309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:54:48.98419Z","time spent":"816.108486ms","remote":"127.0.0.1:49606","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-09-19T16:54:49.799917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.881611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-19T16:54:49.801123Z","caller":"traceutil/trace.go:171","msg":"trace[899598237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:653; }","duration":"258.106711ms","start":"2026-09-19T16:54:49.542968Z","end":"2026-09-19T16:54:49.801074Z","steps":["trace[899598237] 'agreement among raft nodes before linearized reading' (duration: 256.80582ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:54:51.49636Z","caller":"traceutil/trace.go:171","msg":"trace[2062334348] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"299.365643ms","start":"2026-09-19T16:54:51.196963Z","end":"2026-09-19T16:54:51.496329Z","steps":["trace[2062334348] 'process raft request' (duration: 292.991852ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:55:05.781253Z","caller":"traceutil/trace.go:171","msg":"trace[803584442] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"163.866079ms","start":"2026-09-19T16:55:05.617332Z","end":"2026-09-19T16:55:05.781198Z","steps":["trace[803584442] 'process raft request' (duration: 163.725046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:55:06.374004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.307942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-19T16:55:06.374026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.331076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-19T16:55:06.374102Z","caller":"traceutil/trace.go:171","msg":"trace[1657884821] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:813; }","duration":"485.473325ms","start":"2026-09-19T16:55:05.888608Z","end":"2026-09-19T16:55:06.374081Z","steps":["trace[1657884821] 'range keys from in-memory index tree' (duration: 485.13835ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:55:06.374145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T16:55:05.888583Z","time spent":"485.553296ms","remote":"127.0.0.1:49900","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-09-19T16:55:06.374139Z","caller":"traceutil/trace.go:171","msg":"trace[37305390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:813; }","duration":"248.508488ms","start":"2026-09-19T16:55:06.125606Z","end":"2026-09-19T16:55:06.374115Z","steps":["trace[37305390] 'range keys from in-memory index tree' (duration: 248.128973ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:55:06.37984Z","caller":"traceutil/trace.go:171","msg":"trace[1598215378] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:847; }","duration":"120.493039ms","start":"2026-09-19T16:55:06.259331Z","end":"2026-09-19T16:55:06.379824Z","steps":["trace[1598215378] 'read index received' (duration: 120.455718ms)","trace[1598215378] 'applied index is now lower than readState.Index' (duration: 36.261µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T16:55:06.379979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.654092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-jcvxb\" ","response":"range_response_count:1 size:5044"} {"level":"info","ts":"2026-09-19T16:55:06.380017Z","caller":"traceutil/trace.go:171","msg":"trace[386120002] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-jcvxb; range_end:; response_count:1; response_revision:814; }","duration":"120.739582ms","start":"2026-09-19T16:55:06.259265Z","end":"2026-09-19T16:55:06.380004Z","steps":["trace[386120002] 'agreement among raft nodes before linearized reading' (duration: 120.638531ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:55:06.380122Z","caller":"traceutil/trace.go:171","msg":"trace[999443235] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"219.565002ms","start":"2026-09-19T16:55:06.160405Z","end":"2026-09-19T16:55:06.37997Z","steps":["trace[999443235] 'process raft request' (duration: 219.297748ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:55:56.839333Z","caller":"traceutil/trace.go:171","msg":"trace[1471047647] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1160; }","duration":"108.570255ms","start":"2026-09-19T16:55:56.730742Z","end":"2026-09-19T16:55:56.839312Z","steps":["trace[1471047647] 'read index received' (duration: 14.625302ms)","trace[1471047647] 'applied index is now lower than readState.Index' (duration: 93.944013ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T16:55:56.839441Z","caller":"traceutil/trace.go:171","msg":"trace[1230710452] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"117.391165ms","start":"2026-09-19T16:55:56.722009Z","end":"2026-09-19T16:55:56.839401Z","steps":["trace[1230710452] 'process raft request' (duration: 107.782036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:55:56.839547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.787628ms","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-09-19T16:55:56.839585Z","caller":"traceutil/trace.go:171","msg":"trace[1814818542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1118; }","duration":"108.86518ms","start":"2026-09-19T16:55:56.730706Z","end":"2026-09-19T16:55:56.839571Z","steps":["trace[1814818542] 'agreement among raft nodes before linearized reading' (duration: 108.742067ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:55:56.83966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.515959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"info","ts":"2026-09-19T16:55:56.839761Z","caller":"traceutil/trace.go:171","msg":"trace[1161664222] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1118; }","duration":"106.641782ms","start":"2026-09-19T16:55:56.733087Z","end":"2026-09-19T16:55:56.839729Z","steps":["trace[1161664222] 'agreement among raft nodes before linearized reading' (duration: 106.48721ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T16:56:15.05594Z","caller":"traceutil/trace.go:171","msg":"trace[66971495] linearizableReadLoop","detail":"{readStateIndex:1223; appliedIndex:1222; }","duration":"118.480772ms","start":"2026-09-19T16:56:14.937435Z","end":"2026-09-19T16:56:15.055916Z","steps":["trace[66971495] 'read index received' (duration: 118.22829ms)","trace[66971495] 'applied index is now lower than readState.Index' (duration: 251.362µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T16:56:15.056041Z","caller":"traceutil/trace.go:171","msg":"trace[264089851] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"154.516695ms","start":"2026-09-19T16:56:14.901487Z","end":"2026-09-19T16:56:15.056004Z","steps":["trace[264089851] 'process raft request' (duration: 154.237172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T16:56:15.056416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.842726ms","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-09-19T16:56:15.056545Z","caller":"traceutil/trace.go:171","msg":"trace[743632352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1176; }","duration":"119.115821ms","start":"2026-09-19T16:56:14.9374Z","end":"2026-09-19T16:56:15.056516Z","steps":["trace[743632352] 'agreement among raft nodes before linearized reading' (duration: 118.645745ms)"],"step_count":1}