{"level":"warn","ts":"2026-08-14T09:00:07.652777Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T09:00:07.652909Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.244: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://199.19.213.244:2380","--initial-cluster=instance=https://199.19.213.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.244: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-08-14T09:00:07.653162Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-14T09:00:07.653184Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.244:2380"]} {"level":"info","ts":"2026-08-14T09:00:07.653294Z","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-08-14T09:00:07.654335Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.244:2379"]} {"level":"info","ts":"2026-08-14T09:00:07.654517Z","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://199.19.213.244:2380"],"listen-peer-urls":["https://199.19.213.244:2380"],"advertise-client-urls":["https://199.19.213.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.244: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-08-14T09:00:07.659045Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.143464ms"} {"level":"info","ts":"2026-08-14T09:00:07.66969Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"95a1d32286abc938","cluster-id":"f9243fb70f4df4f4"} {"level":"info","ts":"2026-08-14T09:00:07.669942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 switched to configuration voters=()"} {"level":"info","ts":"2026-08-14T09:00:07.670035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became follower at term 0"} {"level":"info","ts":"2026-08-14T09:00:07.67006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 95a1d32286abc938 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-14T09:00:07.670196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became follower at term 1"} {"level":"info","ts":"2026-08-14T09:00:07.670352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 switched to configuration voters=(10782131128143431992)"} {"level":"warn","ts":"2026-08-14T09:00:07.678313Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-14T09:00:07.680107Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-14T09:00:07.681259Z","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-08-14T09:00:07.683442Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"95a1d32286abc938","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-14T09:00:07.683701Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"95a1d32286abc938","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-14T09:00:07.683912Z","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-08-14T09:00:07.683999Z","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-08-14T09:00:07.684015Z","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-08-14T09:00:07.687392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 switched to configuration voters=(10782131128143431992)"} {"level":"info","ts":"2026-08-14T09:00:07.687754Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f9243fb70f4df4f4","local-member-id":"95a1d32286abc938","added-peer-id":"95a1d32286abc938","added-peer-peer-urls":["https://199.19.213.244:2380"]} {"level":"info","ts":"2026-08-14T09:00:07.689276Z","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-08-14T09:00:07.689512Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.244:2380"} {"level":"info","ts":"2026-08-14T09:00:07.689548Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.244:2380"} {"level":"info","ts":"2026-08-14T09:00:07.689607Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"95a1d32286abc938","initial-advertise-peer-urls":["https://199.19.213.244:2380"],"listen-peer-urls":["https://199.19.213.244:2380"],"advertise-client-urls":["https://199.19.213.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-14T09:00:07.689684Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-14T09:00:07.871449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 is starting a new election at term 1"} {"level":"info","ts":"2026-08-14T09:00:07.871526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-14T09:00:07.871574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 received MsgPreVoteResp from 95a1d32286abc938 at term 1"} {"level":"info","ts":"2026-08-14T09:00:07.871594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became candidate at term 2"} {"level":"info","ts":"2026-08-14T09:00:07.87161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 received MsgVoteResp from 95a1d32286abc938 at term 2"} {"level":"info","ts":"2026-08-14T09:00:07.871629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"95a1d32286abc938 became leader at term 2"} {"level":"info","ts":"2026-08-14T09:00:07.871646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 95a1d32286abc938 elected leader 95a1d32286abc938 at term 2"} {"level":"info","ts":"2026-08-14T09:00:07.93341Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:00:07.934936Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T09:00:07.934938Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"95a1d32286abc938","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.244:2379]}","request-path":"/0/members/95a1d32286abc938/attributes","cluster-id":"f9243fb70f4df4f4","publish-timeout":"7s"} {"level":"info","ts":"2026-08-14T09:00:07.934962Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-14T09:00:07.935608Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-14T09:00:07.935642Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-14T09:00:07.935805Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f9243fb70f4df4f4","local-member-id":"95a1d32286abc938","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:00:07.935933Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:00:07.935969Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-14T09:00:07.938161Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.244:2379"} {"level":"info","ts":"2026-08-14T09:00:07.938886Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-14T09:00:14.266142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.184008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:00:14.266335Z","caller":"traceutil/trace.go:171","msg":"trace[1989222803] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"1.030133351s","start":"2026-08-14T09:00:13.236176Z","end":"2026-08-14T09:00:14.26631Z","steps":["trace[1989222803] 'process raft request' (duration: 367.104576ms)","trace[1989222803] 'compare' (duration: 661.783621ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:00:14.266419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:13.236164Z","time spent":"1.030219062s","remote":"127.0.0.1:43234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:00:14.838007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.452782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:00:14.838525Z","caller":"traceutil/trace.go:171","msg":"trace[943748457] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"567.769032ms","start":"2026-08-14T09:00:14.270732Z","end":"2026-08-14T09:00:14.838501Z","steps":["trace[943748457] 'process raft request' (duration: 567.718861ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:00:14.838599Z","caller":"traceutil/trace.go:171","msg":"trace[1992861094] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"1.601505213s","start":"2026-08-14T09:00:13.237064Z","end":"2026-08-14T09:00:14.838569Z","steps":["trace[1992861094] 'process raft request' (duration: 1.300396228s)","trace[1992861094] 'compare' (duration: 300.266859ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:00:14.838618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:14.270709Z","time spent":"567.873585ms","remote":"127.0.0.1:43234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:00:14.838617Z","caller":"traceutil/trace.go:171","msg":"trace[826431926] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"1.494115724s","start":"2026-08-14T09:00:13.344451Z","end":"2026-08-14T09:00:14.838566Z","steps":["trace[826431926] 'process raft request' (duration: 1.493743857s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:14.83869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:13.237057Z","time spent":"1.601594405s","remote":"127.0.0.1:41918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:00:14.83866Z","caller":"traceutil/trace.go:171","msg":"trace[1433690082] transaction","detail":"{read_only:false; number_of_response:0; response_revision:279; }","duration":"1.49390341s","start":"2026-08-14T09:00:13.34472Z","end":"2026-08-14T09:00:14.838624Z","steps":["trace[1433690082] 'process raft request' (duration: 1.493524453s)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:00:14.83868Z","caller":"traceutil/trace.go:171","msg":"trace[1452532143] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"1.493570303s","start":"2026-08-14T09:00:13.344986Z","end":"2026-08-14T09:00:14.838556Z","steps":["trace[1452532143] 'process raft request' (duration: 1.493281658s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:14.838802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:13.344413Z","time spent":"1.494304048s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:00:14.83884Z","caller":"traceutil/trace.go:171","msg":"trace[517388532] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"1.492691036s","start":"2026-08-14T09:00:13.346137Z","end":"2026-08-14T09:00:14.838828Z","steps":["trace[517388532] 'process raft request' (duration: 1.492236218s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:14.838842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:13.344686Z","time spent":"1.494079763s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:00:14.838781Z","caller":"traceutil/trace.go:171","msg":"trace[1057479171] transaction","detail":"{read_only:false; number_of_response:0; response_revision:278; }","duration":"1.523712222s","start":"2026-08-14T09:00:13.315Z","end":"2026-08-14T09:00:14.838713Z","steps":["trace[1057479171] 'process raft request' (duration: 1.523131381s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:14.838893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:13.346125Z","time spent":"1.492740837s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:00:14.838942Z","caller":"traceutil/trace.go:171","msg":"trace[350111667] linearizableReadLoop","detail":"{readStateIndex:284; appliedIndex:282; }","duration":"1.588542909s","start":"2026-08-14T09:00:13.250385Z","end":"2026-08-14T09:00:14.838928Z","steps":["trace[350111667] 'read index received' (duration: 352.911758ms)","trace[350111667] 'applied index is now lower than readState.Index' (duration: 1.235628851s)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:00:14.839051Z","caller":"traceutil/trace.go:171","msg":"trace[1563168179] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"1.49285804s","start":"2026-08-14T09:00:13.346183Z","end":"2026-08-14T09:00:14.839041Z","steps":["trace[1563168179] 'process raft request' (duration: 1.492225127s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:14.83904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:13.344921Z","time spent":"1.493917471s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:00:14.839049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:13.314964Z","time spent":"1.523958357s","remote":"127.0.0.1:43328","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:00:14.839254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.93706ms","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-08-14T09:00:14.839257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.588871127s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"warn","ts":"2026-08-14T09:00:14.839262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088392743s","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-08-14T09:00:14.839292Z","caller":"traceutil/trace.go:171","msg":"trace[451648745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:283; }","duration":"118.071743ms","start":"2026-08-14T09:00:14.721208Z","end":"2026-08-14T09:00:14.83928Z","steps":["trace[451648745] 'agreement among raft nodes before linearized reading' (duration: 117.951461ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:00:14.839295Z","caller":"traceutil/trace.go:171","msg":"trace[574952291] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:283; }","duration":"1.588927817s","start":"2026-08-14T09:00:13.250356Z","end":"2026-08-14T09:00:14.839284Z","steps":["trace[574952291] 'agreement among raft nodes before linearized reading' (duration: 1.588849676s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:14.83929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.576335271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-14T09:00:14.839308Z","caller":"traceutil/trace.go:171","msg":"trace[149752916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:283; }","duration":"1.088469655s","start":"2026-08-14T09:00:13.750825Z","end":"2026-08-14T09:00:14.839294Z","steps":["trace[149752916] 'agreement among raft nodes before linearized reading' (duration: 1.088351482s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:14.839329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:13.250346Z","time spent":"1.588977038s","remote":"127.0.0.1:43216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":139,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-08-14T09:00:14.839344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:13.75076Z","time spent":"1.088576526s","remote":"127.0.0.1:41918","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-08-14T09:00:14.839358Z","caller":"traceutil/trace.go:171","msg":"trace[990432237] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:283; }","duration":"1.576515844s","start":"2026-08-14T09:00:13.262828Z","end":"2026-08-14T09:00:14.839344Z","steps":["trace[990432237] 'agreement among raft nodes before linearized reading' (duration: 1.57629363s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:14.839107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:13.346147Z","time spent":"1.492932741s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:00:14.839406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:13.262799Z","time spent":"1.576598597s","remote":"127.0.0.1:43272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-08-14T09:00:18.66356Z","caller":"traceutil/trace.go:171","msg":"trace[284491355] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:387; }","duration":"367.367036ms","start":"2026-08-14T09:00:18.296166Z","end":"2026-08-14T09:00:18.663533Z","steps":["trace[284491355] 'read index received' (duration: 366.570571ms)","trace[284491355] 'applied index is now lower than readState.Index' (duration: 795.315µs)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:00:18.66372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.529269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4170"} {"level":"info","ts":"2026-08-14T09:00:18.663719Z","caller":"traceutil/trace.go:171","msg":"trace[653628736] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"371.133227ms","start":"2026-08-14T09:00:18.292535Z","end":"2026-08-14T09:00:18.663668Z","steps":["trace[653628736] 'process raft request' (duration: 370.276511ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:00:18.663759Z","caller":"traceutil/trace.go:171","msg":"trace[1483473021] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:380; }","duration":"367.61476ms","start":"2026-08-14T09:00:18.296132Z","end":"2026-08-14T09:00:18.663747Z","steps":["trace[1483473021] 'agreement among raft nodes before linearized reading' (duration: 367.502748ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:18.664057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:18.296119Z","time spent":"367.904897ms","remote":"127.0.0.1:43328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4194,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-08-14T09:00:18.664085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:18.292506Z","time spent":"371.312291ms","remote":"127.0.0.1:42134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2903,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:00:18.666472Z","caller":"traceutil/trace.go:171","msg":"trace[2031244647] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"367.121901ms","start":"2026-08-14T09:00:18.299318Z","end":"2026-08-14T09:00:18.66644Z","steps":["trace[2031244647] 'process raft request' (duration: 366.960258ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:18.666896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:18.299305Z","time spent":"367.275164ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:00:47.941513Z","caller":"traceutil/trace.go:171","msg":"trace[1866074178] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"619.015304ms","start":"2026-08-14T09:00:47.322452Z","end":"2026-08-14T09:00:47.941468Z","steps":["trace[1866074178] 'process raft request' (duration: 618.905942ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:47.941719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:47.322425Z","time spent":"619.165087ms","remote":"127.0.0.1:43214","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-08-14T09:00:47.959676Z","caller":"traceutil/trace.go:171","msg":"trace[207399742] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"631.710128ms","start":"2026-08-14T09:00:47.327934Z","end":"2026-08-14T09:00:47.959644Z","steps":["trace[207399742] 'process raft request' (duration: 631.471924ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:00:47.95976Z","caller":"traceutil/trace.go:171","msg":"trace[787165132] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"631.751018ms","start":"2026-08-14T09:00:47.327992Z","end":"2026-08-14T09:00:47.959743Z","steps":["trace[787165132] 'process raft request' (duration: 631.559725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:47.959818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:47.327987Z","time spent":"631.798529ms","remote":"127.0.0.1:43214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:00:47.959818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:47.327915Z","time spent":"631.844371ms","remote":"127.0.0.1:43214","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-08-14T09:00:47.959919Z","caller":"traceutil/trace.go:171","msg":"trace[131066466] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"614.484362ms","start":"2026-08-14T09:00:47.345422Z","end":"2026-08-14T09:00:47.959906Z","steps":["trace[131066466] 'process raft request' (duration: 614.263678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:47.959945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:47.345402Z","time spent":"614.531972ms","remote":"127.0.0.1:43214","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-08-14T09:00:47.959962Z","caller":"traceutil/trace.go:171","msg":"trace[1950365633] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"612.327817ms","start":"2026-08-14T09:00:47.347625Z","end":"2026-08-14T09:00:47.959953Z","steps":["trace[1950365633] 'process raft request' (duration: 612.097873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:47.960033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:47.347611Z","time spent":"612.402229ms","remote":"127.0.0.1:43214","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-08-14T09:00:47.960031Z","caller":"traceutil/trace.go:171","msg":"trace[1881024159] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"621.498034ms","start":"2026-08-14T09:00:47.338459Z","end":"2026-08-14T09:00:47.959957Z","steps":["trace[1881024159] 'process raft request' (duration: 621.159178ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:00:47.960153Z","caller":"traceutil/trace.go:171","msg":"trace[203009008] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"607.590511ms","start":"2026-08-14T09:00:47.35255Z","end":"2026-08-14T09:00:47.96014Z","steps":["trace[203009008] 'process raft request' (duration: 607.311286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:47.960192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:47.352538Z","time spent":"607.637061ms","remote":"127.0.0.1:43214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:00:47.960211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:47.338448Z","time spent":"621.670806ms","remote":"127.0.0.1:43214","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-08-14T09:00:47.960334Z","caller":"traceutil/trace.go:171","msg":"trace[563900346] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"611.838299ms","start":"2026-08-14T09:00:47.348424Z","end":"2026-08-14T09:00:47.960262Z","steps":["trace[563900346] 'process raft request' (duration: 611.368221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:47.960466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:47.348404Z","time spent":"612.010862ms","remote":"127.0.0.1:43214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:00:47.961013Z","caller":"traceutil/trace.go:171","msg":"trace[2013716641] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"605.385705ms","start":"2026-08-14T09:00:47.355596Z","end":"2026-08-14T09:00:47.960981Z","steps":["trace[2013716641] 'process raft request' (duration: 604.324808ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:00:47.961102Z","caller":"traceutil/trace.go:171","msg":"trace[1643653677] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"596.608105ms","start":"2026-08-14T09:00:47.364462Z","end":"2026-08-14T09:00:47.96107Z","steps":["trace[1643653677] 'process raft request' (duration: 596.195058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:47.961134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:47.355585Z","time spent":"605.492857ms","remote":"127.0.0.1:43214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:00:47.961317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:47.364442Z","time spent":"596.805509ms","remote":"127.0.0.1:43214","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-08-14T09:00:47.961407Z","caller":"traceutil/trace.go:171","msg":"trace[489667120] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"587.271425ms","start":"2026-08-14T09:00:47.374017Z","end":"2026-08-14T09:00:47.961288Z","steps":["trace[489667120] 'process raft request' (duration: 586.987101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:47.961493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:47.373997Z","time spent":"587.455948ms","remote":"127.0.0.1:43214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:00:47.961932Z","caller":"traceutil/trace.go:171","msg":"trace[1308947259] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"469.654197ms","start":"2026-08-14T09:00:47.492261Z","end":"2026-08-14T09:00:47.961916Z","steps":["trace[1308947259] 'process raft request' (duration: 469.459594ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:00:47.96193Z","caller":"traceutil/trace.go:171","msg":"trace[332387488] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:543; }","duration":"111.583681ms","start":"2026-08-14T09:00:47.850324Z","end":"2026-08-14T09:00:47.961908Z","steps":["trace[332387488] 'read index received' (duration: 91.520299ms)","trace[332387488] 'applied index is now lower than readState.Index' (duration: 20.062172ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:00:47.961997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:47.492234Z","time spent":"469.729349ms","remote":"127.0.0.1:43214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-14T09:00:47.962051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.722753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:00:47.962106Z","caller":"traceutil/trace.go:171","msg":"trace[805318892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"111.825115ms","start":"2026-08-14T09:00:47.850267Z","end":"2026-08-14T09:00:47.962092Z","steps":["trace[805318892] 'agreement among raft nodes before linearized reading' (duration: 111.711883ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:00:49.345872Z","caller":"traceutil/trace.go:171","msg":"trace[1155843621] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"317.281733ms","start":"2026-08-14T09:00:49.028552Z","end":"2026-08-14T09:00:49.345833Z","steps":["trace[1155843621] 'process raft request' (duration: 259.166699ms)","trace[1155843621] 'compare' (duration: 57.422813ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:00:49.345979Z","caller":"traceutil/trace.go:171","msg":"trace[1577602917] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:617; }","duration":"312.154432ms","start":"2026-08-14T09:00:49.033815Z","end":"2026-08-14T09:00:49.345969Z","steps":["trace[1577602917] 'read index received' (duration: 32.338205ms)","trace[1577602917] 'applied index is now lower than readState.Index' (duration: 279.815677ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:00:49.346107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.282004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-08-14T09:00:49.346126Z","caller":"traceutil/trace.go:171","msg":"trace[841419665] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:603; }","duration":"312.328994ms","start":"2026-08-14T09:00:49.03379Z","end":"2026-08-14T09:00:49.346119Z","steps":["trace[841419665] 'agreement among raft nodes before linearized reading' (duration: 312.210373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:49.346142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:49.033781Z","time spent":"312.357055ms","remote":"127.0.0.1:43214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":80727,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-08-14T09:00:49.346154Z","caller":"traceutil/trace.go:171","msg":"trace[1297124911] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"303.684807ms","start":"2026-08-14T09:00:49.042443Z","end":"2026-08-14T09:00:49.346128Z","steps":["trace[1297124911] 'process raft request' (duration: 302.837383ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:49.346549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:49.028513Z","time spent":"317.434326ms","remote":"127.0.0.1:41918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:00:49.353158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:49.042407Z","time spent":"303.808899ms","remote":"127.0.0.1:43214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:00:49.38213Z","caller":"traceutil/trace.go:171","msg":"trace[559250798] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"252.765538ms","start":"2026-08-14T09:00:49.129343Z","end":"2026-08-14T09:00:49.382109Z","steps":["trace[559250798] 'process raft request' (duration: 252.110667ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:00:51.027888Z","caller":"traceutil/trace.go:171","msg":"trace[1490430616] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:623; }","duration":"217.882785ms","start":"2026-08-14T09:00:50.809952Z","end":"2026-08-14T09:00:51.027835Z","steps":["trace[1490430616] 'read index received' (duration: 21.518551ms)","trace[1490430616] 'applied index is now lower than readState.Index' (duration: 196.362114ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:00:51.028049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.062248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:00:51.028111Z","caller":"traceutil/trace.go:171","msg":"trace[1529089067] range","detail":"{range_begin:/registry/services/specs/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:608; }","duration":"218.160859ms","start":"2026-08-14T09:00:50.809936Z","end":"2026-08-14T09:00:51.028097Z","steps":["trace[1529089067] 'agreement among raft nodes before linearized reading' (duration: 218.031947ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:00:51.028308Z","caller":"traceutil/trace.go:171","msg":"trace[1338585879] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"219.327849ms","start":"2026-08-14T09:00:50.808968Z","end":"2026-08-14T09:00:51.028296Z","steps":["trace[1338585879] 'process raft request' (duration: 218.564387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:51.029809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.949485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:00:51.029885Z","caller":"traceutil/trace.go:171","msg":"trace[1176468252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"180.068166ms","start":"2026-08-14T09:00:50.849798Z","end":"2026-08-14T09:00:51.029866Z","steps":["trace[1176468252] 'agreement among raft nodes before linearized reading' (duration: 179.932304ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:00:51.030052Z","caller":"traceutil/trace.go:171","msg":"trace[2098429025] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"108.046478ms","start":"2026-08-14T09:00:50.92192Z","end":"2026-08-14T09:00:51.029966Z","steps":["trace[2098429025] 'process raft request' (duration: 107.727893ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:00:54.520002Z","caller":"traceutil/trace.go:171","msg":"trace[1622993385] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"113.846478ms","start":"2026-08-14T09:00:54.406132Z","end":"2026-08-14T09:00:54.519979Z","steps":["trace[1622993385] 'process raft request' (duration: 113.362961ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:00:55.161471Z","caller":"traceutil/trace.go:171","msg":"trace[1643854105] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"312.054806ms","start":"2026-08-14T09:00:54.849373Z","end":"2026-08-14T09:00:55.161428Z","steps":["trace[1643854105] 'read index received' (duration: 311.971894ms)","trace[1643854105] 'applied index is now lower than readState.Index' (duration: 81.962µs)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:00:55.161746Z","caller":"traceutil/trace.go:171","msg":"trace[1279314090] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"314.188189ms","start":"2026-08-14T09:00:54.847491Z","end":"2026-08-14T09:00:55.161679Z","steps":["trace[1279314090] 'process raft request' (duration: 313.784852ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:55.161789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.394621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:00:55.161943Z","caller":"traceutil/trace.go:171","msg":"trace[404938025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"312.565503ms","start":"2026-08-14T09:00:54.849358Z","end":"2026-08-14T09:00:55.161924Z","steps":["trace[404938025] 'agreement among raft nodes before linearized reading' (duration: 312.309929ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:55.16201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:54.849352Z","time spent":"312.640614ms","remote":"127.0.0.1:43152","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-08-14T09:00:55.162124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:54.847475Z","time spent":"314.380092ms","remote":"127.0.0.1:41918","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-08-14T09:00:55.662632Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499514920032566074,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-14T09:00:55.906846Z","caller":"traceutil/trace.go:171","msg":"trace[2058424959] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"826.371459ms","start":"2026-08-14T09:00:55.080451Z","end":"2026-08-14T09:00:55.906823Z","steps":["trace[2058424959] 'process raft request' (duration: 824.891106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:55.906989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:55.080422Z","time spent":"826.492701ms","remote":"127.0.0.1:43252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:00:56.416498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.34517ms","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-08-14T09:00:56.416629Z","caller":"traceutil/trace.go:171","msg":"trace[1360361111] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:638; }","duration":"379.484802ms","start":"2026-08-14T09:00:56.037124Z","end":"2026-08-14T09:00:56.416609Z","steps":["trace[1360361111] 'range keys from in-memory index tree' (duration: 379.31939ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:56.416698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.995556ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:00:56.416836Z","caller":"traceutil/trace.go:171","msg":"trace[1847706713] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:655; }","duration":"1.255169431s","start":"2026-08-14T09:00:55.16162Z","end":"2026-08-14T09:00:56.416789Z","steps":["trace[1847706713] 'read index received' (duration: 743.470046ms)","trace[1847706713] 'applied index is now lower than readState.Index' (duration: 511.698245ms)"],"step_count":2} {"level":"info","ts":"2026-08-14T09:00:56.416876Z","caller":"traceutil/trace.go:171","msg":"trace[1994214708] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"771.99214ms","start":"2026-08-14T09:00:55.644837Z","end":"2026-08-14T09:00:56.416829Z","steps":["trace[1994214708] 'process raft request' (duration: 640.78699ms)","trace[1994214708] 'compare' (duration: 130.877495ms)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:00:56.417033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:55.644808Z","time spent":"772.179623ms","remote":"127.0.0.1:41918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-14T09:00:56.417071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.329668443s","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-08-14T09:00:56.417147Z","caller":"traceutil/trace.go:171","msg":"trace[1435660537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"1.329761785s","start":"2026-08-14T09:00:55.087371Z","end":"2026-08-14T09:00:56.417133Z","steps":["trace[1435660537] 'agreement among raft nodes before linearized reading' (duration: 1.329468031s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:56.417043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.378516519s","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-08-14T09:00:56.417239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:55.087363Z","time spent":"1.329844436s","remote":"127.0.0.1:41918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-14T09:00:56.417303Z","caller":"traceutil/trace.go:171","msg":"trace[401946451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"1.378808124s","start":"2026-08-14T09:00:55.038479Z","end":"2026-08-14T09:00:56.417287Z","steps":["trace[401946451] 'agreement among raft nodes before linearized reading' (duration: 1.378416798s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:56.417349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:55.038459Z","time spent":"1.378876414s","remote":"127.0.0.1:41918","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-08-14T09:00:56.917761Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499514920032566077,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:00:57.418687Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14499514920032566077,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-14T09:00:57.59574Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.3101022s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-14T09:00:57.848456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000162316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-14T09:00:57.848591Z","caller":"traceutil/trace.go:171","msg":"trace[325614431] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000355089s","start":"2026-08-14T09:00:55.84821Z","end":"2026-08-14T09:00:57.848565Z","steps":["trace[325614431] 'agreement among raft nodes before linearized reading' (duration: 2.000200487s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:57.848665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:55.848189Z","time spent":"2.000460961s","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/14 09:00:57 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-14T09:00:58.652483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05644136s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-14T09:00:58.653006Z","caller":"traceutil/trace.go:171","msg":"trace[2047926489] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"2.689001967s","start":"2026-08-14T09:00:55.96398Z","end":"2026-08-14T09:00:58.652982Z","steps":["trace[2047926489] 'process raft request' (duration: 1.631992899s)","trace[2047926489] 'compare' (duration: 1.056237686s)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:00:58.653103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:55.963961Z","time spent":"2.689089068s","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-14T09:00:58.65307Z","caller":"traceutil/trace.go:171","msg":"trace[2020595527] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"2.230956172s","start":"2026-08-14T09:00:56.422065Z","end":"2026-08-14T09:00:58.653021Z","steps":["trace[2020595527] 'process raft request' (duration: 2.230518965s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:58.65326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:56.422042Z","time spent":"2.231124395s","remote":"127.0.0.1:41918","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-08-14T09:00:58.653365Z","caller":"traceutil/trace.go:171","msg":"trace[1886775652] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"2.230647538s","start":"2026-08-14T09:00:56.422709Z","end":"2026-08-14T09:00:58.653357Z","steps":["trace[1886775652] 'process raft request' (duration: 2.229931197s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:58.65343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:56.422696Z","time spent":"2.230699248s","remote":"127.0.0.1:41918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-14T09:00:58.703466Z","caller":"traceutil/trace.go:171","msg":"trace[1600870036] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:657; }","duration":"2.286527342s","start":"2026-08-14T09:00:56.416874Z","end":"2026-08-14T09:00:58.703401Z","steps":["trace[1600870036] 'read index received' (duration: 1.179064033s)","trace[1600870036] 'applied index is now lower than readState.Index' (duration: 1.107461469s)"],"step_count":2} {"level":"warn","ts":"2026-08-14T09:00:58.7038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.326100899s","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-08-14T09:00:58.703867Z","caller":"traceutil/trace.go:171","msg":"trace[747455982] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"3.326211141s","start":"2026-08-14T09:00:55.377642Z","end":"2026-08-14T09:00:58.703853Z","steps":["trace[747455982] 'agreement among raft nodes before linearized reading' (duration: 3.326015928s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:58.703903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:55.377623Z","time spent":"3.326271501s","remote":"127.0.0.1:41918","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-08-14T09:00:58.704174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.537553201s","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-08-14T09:00:58.704276Z","caller":"traceutil/trace.go:171","msg":"trace[426626364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"1.537695434s","start":"2026-08-14T09:00:57.166565Z","end":"2026-08-14T09:00:58.70426Z","steps":["trace[426626364] 'agreement among raft nodes before linearized reading' (duration: 1.537201526s)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:58.704285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.287244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:00:58.704348Z","caller":"traceutil/trace.go:171","msg":"trace[2046428617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"855.418216ms","start":"2026-08-14T09:00:57.848919Z","end":"2026-08-14T09:00:58.704337Z","steps":["trace[2046428617] 'agreement among raft nodes before linearized reading' (duration: 855.333455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:00:58.704388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:57.848904Z","time spent":"855.476477ms","remote":"127.0.0.1:43152","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-08-14T09:00:58.704631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-14T09:00:57.166544Z","time spent":"1.538067439s","remote":"127.0.0.1:41918","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-08-14T09:01:00.813829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.80495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:01:00.814011Z","caller":"traceutil/trace.go:171","msg":"trace[480221586] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:680; }","duration":"140.927812ms","start":"2026-08-14T09:01:00.672966Z","end":"2026-08-14T09:01:00.813894Z","steps":["trace[480221586] 'range keys from in-memory index tree' (duration: 140.690699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:01:00.814291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.955647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-14T09:01:00.814292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.142682ms","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-08-14T09:01:00.814325Z","caller":"traceutil/trace.go:171","msg":"trace[1395888960] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:680; }","duration":"101.019969ms","start":"2026-08-14T09:01:00.713296Z","end":"2026-08-14T09:01:00.814316Z","steps":["trace[1395888960] 'range keys from in-memory index tree' (duration: 100.853526ms)"],"step_count":1} {"level":"info","ts":"2026-08-14T09:01:00.814352Z","caller":"traceutil/trace.go:171","msg":"trace[1982335551] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:680; }","duration":"156.243024ms","start":"2026-08-14T09:01:00.658092Z","end":"2026-08-14T09:01:00.814335Z","steps":["trace[1982335551] 'range keys from in-memory index tree' (duration: 156.00458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:01:00.814749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.960835ms","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-08-14T09:01:00.814823Z","caller":"traceutil/trace.go:171","msg":"trace[1936636502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:680; }","duration":"157.064756ms","start":"2026-08-14T09:01:00.657746Z","end":"2026-08-14T09:01:00.814811Z","steps":["trace[1936636502] 'range keys from in-memory index tree' (duration: 156.308155ms)"],"step_count":1} {"level":"warn","ts":"2026-08-14T09:01:13.855695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.526641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-14T09:01:13.855825Z","caller":"traceutil/trace.go:171","msg":"trace[1238558367] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:803; }","duration":"224.734323ms","start":"2026-08-14T09:01:13.631063Z","end":"2026-08-14T09:01:13.855797Z","steps":["trace[1238558367] 'count revisions from in-memory index tree' (duration: 224.394619ms)"],"step_count":1}