{"level":"warn","ts":"2026-04-17T08:38:26.413958Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T08:38:26.41404Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.222: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.222:2380","--initial-cluster=instance=https://199.19.213.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.222: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-04-17T08:38:26.414098Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-17T08:38:26.414113Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.222:2380"]} {"level":"info","ts":"2026-04-17T08:38:26.414134Z","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-04-17T08:38:26.414744Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"]} {"level":"info","ts":"2026-04-17T08:38:26.414887Z","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.222:2380"],"listen-peer-urls":["https://199.19.213.222:2380"],"advertise-client-urls":["https://199.19.213.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.222: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-04-17T08:38:26.419744Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.672898ms"} {"level":"info","ts":"2026-04-17T08:38:26.429955Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b78f8b1ca6063edc","cluster-id":"eff609df5818f5a9"} {"level":"info","ts":"2026-04-17T08:38:26.430048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=()"} {"level":"info","ts":"2026-04-17T08:38:26.430135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became follower at term 0"} {"level":"info","ts":"2026-04-17T08:38:26.430151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b78f8b1ca6063edc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-17T08:38:26.430161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became follower at term 1"} {"level":"info","ts":"2026-04-17T08:38:26.430238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=(13226943585771208412)"} {"level":"warn","ts":"2026-04-17T08:38:26.43862Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-17T08:38:26.440893Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-17T08:38:26.442075Z","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-04-17T08:38:26.445006Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b78f8b1ca6063edc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-17T08:38:26.445174Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b78f8b1ca6063edc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-17T08:38:26.445376Z","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-04-17T08:38:26.44544Z","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-04-17T08:38:26.445458Z","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-04-17T08:38:26.448215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=(13226943585771208412)"} {"level":"info","ts":"2026-04-17T08:38:26.448478Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"eff609df5818f5a9","local-member-id":"b78f8b1ca6063edc","added-peer-id":"b78f8b1ca6063edc","added-peer-peer-urls":["https://199.19.213.222:2380"]} {"level":"info","ts":"2026-04-17T08:38:26.450326Z","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-04-17T08:38:26.450397Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.222:2380"} {"level":"info","ts":"2026-04-17T08:38:26.45045Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.222:2380"} {"level":"info","ts":"2026-04-17T08:38:26.450573Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b78f8b1ca6063edc","initial-advertise-peer-urls":["https://199.19.213.222:2380"],"listen-peer-urls":["https://199.19.213.222:2380"],"advertise-client-urls":["https://199.19.213.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-17T08:38:26.450612Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-17T08:38:26.730966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc is starting a new election at term 1"} {"level":"info","ts":"2026-04-17T08:38:26.731029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became pre-candidate at term 1"} {"level":"info","ts":"2026-04-17T08:38:26.731053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc received MsgPreVoteResp from b78f8b1ca6063edc at term 1"} {"level":"info","ts":"2026-04-17T08:38:26.731074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became candidate at term 2"} {"level":"info","ts":"2026-04-17T08:38:26.731085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc received MsgVoteResp from b78f8b1ca6063edc at term 2"} {"level":"info","ts":"2026-04-17T08:38:26.731101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became leader at term 2"} {"level":"info","ts":"2026-04-17T08:38:26.731115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b78f8b1ca6063edc elected leader b78f8b1ca6063edc at term 2"} {"level":"info","ts":"2026-04-17T08:38:26.734841Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T08:38:26.738822Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b78f8b1ca6063edc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.222:2379]}","request-path":"/0/members/b78f8b1ca6063edc/attributes","cluster-id":"eff609df5818f5a9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-17T08:38:26.739016Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T08:38:26.739068Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-17T08:38:26.739405Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-17T08:38:26.739458Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-17T08:38:26.742831Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-17T08:38:26.74284Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.222:2379"} {"level":"info","ts":"2026-04-17T08:38:26.746886Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"eff609df5818f5a9","local-member-id":"b78f8b1ca6063edc","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T08:38:26.747097Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T08:38:26.747164Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-17T08:38:29.20973Z","caller":"traceutil/trace.go:171","msg":"trace[1583020871] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"104.778986ms","start":"2026-04-17T08:38:29.104887Z","end":"2026-04-17T08:38:29.209666Z","steps":["trace[1583020871] 'process raft request' (duration: 70.025291ms)","trace[1583020871] 'compare' (duration: 34.55647ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T08:38:29.914663Z","caller":"traceutil/trace.go:171","msg":"trace[959797910] transaction","detail":"{read_only:false; response_revision:158; number_of_response:1; }","duration":"215.681709ms","start":"2026-04-17T08:38:29.69894Z","end":"2026-04-17T08:38:29.914621Z","steps":["trace[959797910] 'process raft request' (duration: 211.160558ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T08:38:31.446412Z","caller":"traceutil/trace.go:171","msg":"trace[105427883] linearizableReadLoop","detail":"{readStateIndex:222; appliedIndex:221; }","duration":"162.196946ms","start":"2026-04-17T08:38:31.284188Z","end":"2026-04-17T08:38:31.446385Z","steps":["trace[105427883] 'read index received' (duration: 156.817206ms)","trace[105427883] 'applied index is now lower than readState.Index' (duration: 5.37871ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T08:38:31.446589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.38114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T08:38:31.446536Z","caller":"traceutil/trace.go:171","msg":"trace[468091307] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"195.719592ms","start":"2026-04-17T08:38:31.250737Z","end":"2026-04-17T08:38:31.446457Z","steps":["trace[468091307] 'process raft request' (duration: 190.25162ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T08:38:31.446671Z","caller":"traceutil/trace.go:171","msg":"trace[719743232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:216; }","duration":"162.523363ms","start":"2026-04-17T08:38:31.284136Z","end":"2026-04-17T08:38:31.446659Z","steps":["trace[719743232] 'agreement among raft nodes before linearized reading' (duration: 162.3542ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T08:38:35.835912Z","caller":"traceutil/trace.go:171","msg":"trace[1591769810] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:316; }","duration":"157.899358ms","start":"2026-04-17T08:38:35.677972Z","end":"2026-04-17T08:38:35.835871Z","steps":["trace[1591769810] 'read index received' (duration: 104.531266ms)","trace[1591769810] 'applied index is now lower than readState.Index' (duration: 53.367042ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T08:38:35.836046Z","caller":"traceutil/trace.go:171","msg":"trace[189469311] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"168.450206ms","start":"2026-04-17T08:38:35.667581Z","end":"2026-04-17T08:38:35.836031Z","steps":["trace[189469311] 'process raft request' (duration: 114.975031ms)","trace[189469311] 'compare' (duration: 53.206499ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T08:38:35.836218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.234455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-17T08:38:35.83626Z","caller":"traceutil/trace.go:171","msg":"trace[1116678351] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:303; }","duration":"158.309996ms","start":"2026-04-17T08:38:35.677939Z","end":"2026-04-17T08:38:35.836249Z","steps":["trace[1116678351] 'agreement among raft nodes before linearized reading' (duration: 158.227344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:35.836477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.626955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-17T08:38:35.836517Z","caller":"traceutil/trace.go:171","msg":"trace[284793307] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:303; }","duration":"108.712407ms","start":"2026-04-17T08:38:35.727796Z","end":"2026-04-17T08:38:35.836509Z","steps":["trace[284793307] 'agreement among raft nodes before linearized reading' (duration: 108.645865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:36.588114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.889358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T08:38:36.588242Z","caller":"traceutil/trace.go:171","msg":"trace[1977138026] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:330; }","duration":"260.806258ms","start":"2026-04-17T08:38:36.327414Z","end":"2026-04-17T08:38:36.58822Z","steps":["trace[1977138026] 'read index received' (duration: 120.194075ms)","trace[1977138026] 'applied index is now lower than readState.Index' (duration: 140.610783ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T08:38:36.588283Z","caller":"traceutil/trace.go:171","msg":"trace[1386398528] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"303.220417ms","start":"2026-04-17T08:38:36.285032Z","end":"2026-04-17T08:38:36.588252Z","steps":["trace[1386398528] 'process raft request' (duration: 162.632244ms)","trace[1386398528] 'compare' (duration: 139.727464ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T08:38:36.588387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.974082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-17T08:38:36.588423Z","caller":"traceutil/trace.go:171","msg":"trace[916922508] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:317; }","duration":"261.089135ms","start":"2026-04-17T08:38:36.327325Z","end":"2026-04-17T08:38:36.588414Z","steps":["trace[916922508] 'agreement among raft nodes before linearized reading' (duration: 260.989102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:36.58852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.837286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-17T08:38:36.588514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.853078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-17T08:38:36.588589Z","caller":"traceutil/trace.go:171","msg":"trace[1097452304] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:317; }","duration":"110.935517ms","start":"2026-04-17T08:38:36.477639Z","end":"2026-04-17T08:38:36.588574Z","steps":["trace[1097452304] 'agreement among raft nodes before linearized reading' (duration: 110.811615ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T08:38:36.588606Z","caller":"traceutil/trace.go:171","msg":"trace[1304820150] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:317; }","duration":"211.003922ms","start":"2026-04-17T08:38:36.377581Z","end":"2026-04-17T08:38:36.588585Z","steps":["trace[1304820150] 'agreement among raft nodes before linearized reading' (duration: 210.816928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:36.588406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:36.285013Z","time spent":"303.3445ms","remote":"127.0.0.1:33000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T08:38:36.589632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.158464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-17T08:38:36.589717Z","caller":"traceutil/trace.go:171","msg":"trace[1208816057] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:317; }","duration":"161.47126ms","start":"2026-04-17T08:38:36.428217Z","end":"2026-04-17T08:38:36.589689Z","steps":["trace[1208816057] 'agreement among raft nodes before linearized reading' (duration: 160.308785ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T08:38:37.041748Z","caller":"traceutil/trace.go:171","msg":"trace[806783986] linearizableReadLoop","detail":"{readStateIndex:338; appliedIndex:332; }","duration":"293.101714ms","start":"2026-04-17T08:38:36.748629Z","end":"2026-04-17T08:38:37.041731Z","steps":["trace[806783986] 'read index received' (duration: 243.539438ms)","trace[806783986] 'applied index is now lower than readState.Index' (duration: 49.561566ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T08:38:37.041736Z","caller":"traceutil/trace.go:171","msg":"trace[72205437] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"444.362025ms","start":"2026-04-17T08:38:36.597316Z","end":"2026-04-17T08:38:37.041678Z","steps":["trace[72205437] 'process raft request' (duration: 394.926842ms)","trace[72205437] 'compare' (duration: 49.144907ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T08:38:37.041868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.230516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-04-17T08:38:37.041893Z","caller":"traceutil/trace.go:171","msg":"trace[1101096638] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:324; }","duration":"293.301717ms","start":"2026-04-17T08:38:36.748584Z","end":"2026-04-17T08:38:37.041886Z","steps":["trace[1101096638] 'agreement among raft nodes before linearized reading' (duration: 293.227716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:37.042003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:36.597282Z","time spent":"444.584049ms","remote":"127.0.0.1:32828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T08:38:37.042042Z","caller":"traceutil/trace.go:171","msg":"trace[912222456] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"443.369943ms","start":"2026-04-17T08:38:36.598664Z","end":"2026-04-17T08:38:37.042034Z","steps":["trace[912222456] 'process raft request' (duration: 443.013136ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T08:38:37.042047Z","caller":"traceutil/trace.go:171","msg":"trace[798036661] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"444.010937ms","start":"2026-04-17T08:38:36.598003Z","end":"2026-04-17T08:38:37.042014Z","steps":["trace[798036661] 'process raft request' (duration: 443.648719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:37.042295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:36.598653Z","time spent":"443.409075ms","remote":"127.0.0.1:33072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T08:38:37.042419Z","caller":"traceutil/trace.go:171","msg":"trace[1074646858] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"441.973954ms","start":"2026-04-17T08:38:36.600439Z","end":"2026-04-17T08:38:37.042412Z","steps":["trace[1074646858] 'process raft request' (duration: 441.260548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:37.042452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:36.600428Z","time spent":"442.008855ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T08:38:37.04247Z","caller":"traceutil/trace.go:171","msg":"trace[1044088374] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"444.996949ms","start":"2026-04-17T08:38:36.597458Z","end":"2026-04-17T08:38:37.042455Z","steps":["trace[1044088374] 'process raft request' (duration: 444.063308ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T08:38:37.042509Z","caller":"traceutil/trace.go:171","msg":"trace[488742039] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"444.886037ms","start":"2026-04-17T08:38:36.597608Z","end":"2026-04-17T08:38:37.042494Z","steps":["trace[488742039] 'process raft request' (duration: 444.019908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:37.04256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:36.597994Z","time spent":"444.146969ms","remote":"127.0.0.1:33072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T08:38:37.042582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:36.5976Z","time spent":"444.953918ms","remote":"127.0.0.1:33212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-17T08:38:37.042555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:36.597435Z","time spent":"445.07691ms","remote":"127.0.0.1:33072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T08:38:37.705351Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529668612518412551,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-17T08:38:38.62917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.146086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T08:38:38.629307Z","caller":"traceutil/trace.go:171","msg":"trace[1255335123] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"1.577096222s","start":"2026-04-17T08:38:37.052183Z","end":"2026-04-17T08:38:38.629279Z","steps":["trace[1255335123] 'process raft request' (duration: 894.706481ms)","trace[1255335123] 'compare' (duration: 681.112275ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T08:38:38.629397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:37.052158Z","time spent":"1.577193335s","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T08:38:39.088549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.583535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-17T08:38:39.089273Z","caller":"traceutil/trace.go:171","msg":"trace[580346292] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"2.0350155s","start":"2026-04-17T08:38:37.053636Z","end":"2026-04-17T08:38:39.088651Z","steps":["trace[580346292] 'process raft request' (duration: 1.716264851s)","trace[580346292] 'compare' (duration: 318.432422ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T08:38:39.089382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:37.053608Z","time spent":"2.035732935s","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T08:38:39.090211Z","caller":"traceutil/trace.go:171","msg":"trace[104391789] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"2.035969039s","start":"2026-04-17T08:38:37.053958Z","end":"2026-04-17T08:38:39.089927Z","steps":["trace[104391789] 'process raft request' (duration: 2.035668283s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:39.09041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:37.053951Z","time spent":"2.036372989s","remote":"127.0.0.1:32828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T08:38:39.090735Z","caller":"traceutil/trace.go:171","msg":"trace[1717347190] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"2.036804878s","start":"2026-04-17T08:38:37.053901Z","end":"2026-04-17T08:38:39.090706Z","steps":["trace[1717347190] 'process raft request' (duration: 2.035299436s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:39.090816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:37.053891Z","time spent":"2.03688617s","remote":"127.0.0.1:32828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T08:38:39.090986Z","caller":"traceutil/trace.go:171","msg":"trace[432438268] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"862.807009ms","start":"2026-04-17T08:38:38.228132Z","end":"2026-04-17T08:38:39.090939Z","steps":["trace[432438268] 'process raft request' (duration: 862.463062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:39.091397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:38.228099Z","time spent":"862.988433ms","remote":"127.0.0.1:32986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T08:38:39.091436Z","caller":"traceutil/trace.go:171","msg":"trace[604059449] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"2.032984217s","start":"2026-04-17T08:38:37.058414Z","end":"2026-04-17T08:38:39.091398Z","steps":["trace[604059449] 'process raft request' (duration: 2.031869394s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:39.091574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:37.058395Z","time spent":"2.033084529s","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T08:38:39.091859Z","caller":"traceutil/trace.go:171","msg":"trace[1415651476] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"2.034810425s","start":"2026-04-17T08:38:37.057022Z","end":"2026-04-17T08:38:39.091833Z","steps":["trace[1415651476] 'process raft request' (duration: 2.032940656s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:39.09195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:37.057015Z","time spent":"2.034892767s","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T08:38:39.092031Z","caller":"traceutil/trace.go:171","msg":"trace[781825063] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:338; }","duration":"1.887715576s","start":"2026-04-17T08:38:37.204249Z","end":"2026-04-17T08:38:39.091965Z","steps":["trace[781825063] 'read index received' (duration: 742.702818ms)","trace[781825063] 'applied index is now lower than readState.Index' (duration: 1.144881546s)"],"step_count":2} {"level":"info","ts":"2026-04-17T08:38:39.092551Z","caller":"traceutil/trace.go:171","msg":"trace[985287872] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"2.038032833s","start":"2026-04-17T08:38:37.054483Z","end":"2026-04-17T08:38:39.092516Z","steps":["trace[985287872] 'process raft request' (duration: 2.035220354s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:39.092733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:37.054475Z","time spent":"2.038162196s","remote":"127.0.0.1:33072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T08:38:39.093672Z","caller":"traceutil/trace.go:171","msg":"trace[1252429351] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"2.037986373s","start":"2026-04-17T08:38:37.055666Z","end":"2026-04-17T08:38:39.093653Z","steps":["trace[1252429351] 'process raft request' (duration: 2.034098091s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:39.093736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:37.055657Z","time spent":"2.038046334s","remote":"127.0.0.1:33072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T08:38:39.094068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.88981735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-17T08:38:39.094116Z","caller":"traceutil/trace.go:171","msg":"trace[428998061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:333; }","duration":"1.889913872s","start":"2026-04-17T08:38:37.20419Z","end":"2026-04-17T08:38:39.094104Z","steps":["trace[428998061] 'agreement among raft nodes before linearized reading' (duration: 1.889743009s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:39.094156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:37.204166Z","time spent":"1.889983374s","remote":"127.0.0.1:32986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-17T08:38:39.094468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.601324664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-17T08:38:39.094627Z","caller":"traceutil/trace.go:171","msg":"trace[613848214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:333; }","duration":"1.601411966s","start":"2026-04-17T08:38:37.493083Z","end":"2026-04-17T08:38:39.094495Z","steps":["trace[613848214] 'agreement among raft nodes before linearized reading' (duration: 1.601320364s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:39.094667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:37.493055Z","time spent":"1.60160545s","remote":"127.0.0.1:32986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-17T08:38:39.094669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.288618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-04-17T08:38:39.094708Z","caller":"traceutil/trace.go:171","msg":"trace[149796120] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:333; }","duration":"461.338739ms","start":"2026-04-17T08:38:38.633351Z","end":"2026-04-17T08:38:39.094689Z","steps":["trace[149796120] 'agreement among raft nodes before linearized reading' (duration: 461.270317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:39.094745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:38.633339Z","time spent":"461.399601ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4350,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-17T08:38:39.094866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.715362ms","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-04-17T08:38:39.094952Z","caller":"traceutil/trace.go:171","msg":"trace[1869464352] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:333; }","duration":"456.820453ms","start":"2026-04-17T08:38:38.638103Z","end":"2026-04-17T08:38:39.094923Z","steps":["trace[1869464352] 'agreement among raft nodes before linearized reading' (duration: 456.694781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:39.095003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:38.638089Z","time spent":"456.900026ms","remote":"127.0.0.1:32986","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-04-17T08:38:39.095079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185262133s","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-04-17T08:38:39.095111Z","caller":"traceutil/trace.go:171","msg":"trace[1998468934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:333; }","duration":"1.185347155s","start":"2026-04-17T08:38:37.909755Z","end":"2026-04-17T08:38:39.095102Z","steps":["trace[1998468934] 'agreement among raft nodes before linearized reading' (duration: 1.185280224s)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:39.095167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:37.90973Z","time spent":"1.185408246s","remote":"127.0.0.1:32986","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-04-17T08:38:39.095052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.968727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-04-17T08:38:39.095518Z","caller":"traceutil/trace.go:171","msg":"trace[1891489581] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:333; }","duration":"938.226401ms","start":"2026-04-17T08:38:38.15704Z","end":"2026-04-17T08:38:39.095267Z","steps":["trace[1891489581] 'agreement among raft nodes before linearized reading' (duration: 937.840653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:38:39.095577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:38.157025Z","time spent":"938.536158ms","remote":"127.0.0.1:32912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4393,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-17T08:38:50.572743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:38:50.248418Z","time spent":"324.322666ms","remote":"127.0.0.1:32804","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-17T08:38:54.207467Z","caller":"traceutil/trace.go:171","msg":"trace[187806813] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"106.487983ms","start":"2026-04-17T08:38:54.100946Z","end":"2026-04-17T08:38:54.207434Z","steps":["trace[187806813] 'process raft request' (duration: 62.66148ms)","trace[187806813] 'compare' (duration: 43.64073ms)"],"step_count":2} {"level":"info","ts":"2026-04-17T08:38:54.530994Z","caller":"traceutil/trace.go:171","msg":"trace[368244470] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"142.927025ms","start":"2026-04-17T08:38:54.388011Z","end":"2026-04-17T08:38:54.530939Z","steps":["trace[368244470] 'process raft request' (duration: 142.715201ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T08:38:54.572179Z","caller":"traceutil/trace.go:171","msg":"trace[1845209557] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"182.433165ms","start":"2026-04-17T08:38:54.389715Z","end":"2026-04-17T08:38:54.572148Z","steps":["trace[1845209557] 'process raft request' (duration: 182.294493ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T08:38:54.703477Z","caller":"traceutil/trace.go:171","msg":"trace[938122268] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"159.956308ms","start":"2026-04-17T08:38:54.54349Z","end":"2026-04-17T08:38:54.703447Z","steps":["trace[938122268] 'process raft request' (duration: 159.459259ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T08:38:54.713089Z","caller":"traceutil/trace.go:171","msg":"trace[371046454] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"132.241891ms","start":"2026-04-17T08:38:54.580826Z","end":"2026-04-17T08:38:54.713068Z","steps":["trace[371046454] 'process raft request' (duration: 132.026437ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T08:38:54.713784Z","caller":"traceutil/trace.go:171","msg":"trace[2124512410] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"103.18006ms","start":"2026-04-17T08:38:54.610585Z","end":"2026-04-17T08:38:54.713765Z","steps":["trace[2124512410] 'process raft request' (duration: 102.545497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:39:03.272073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.146971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-87l5v\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-17T08:39:03.272242Z","caller":"traceutil/trace.go:171","msg":"trace[475682638] range","detail":"{range_begin:/registry/pods/kube-system/cilium-87l5v; range_end:; response_count:1; response_revision:571; }","duration":"202.375064ms","start":"2026-04-17T08:39:03.069812Z","end":"2026-04-17T08:39:03.272187Z","steps":["trace[475682638] 'range keys from in-memory index tree' (duration: 202.011419ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T08:39:03.36837Z","caller":"traceutil/trace.go:171","msg":"trace[426358473] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"130.059832ms","start":"2026-04-17T08:39:03.238279Z","end":"2026-04-17T08:39:03.368339Z","steps":["trace[426358473] 'read index received' (duration: 129.236498ms)","trace[426358473] 'applied index is now lower than readState.Index' (duration: 822.154µs)"],"step_count":2} {"level":"info","ts":"2026-04-17T08:39:03.368521Z","caller":"traceutil/trace.go:171","msg":"trace[341485808] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"133.904782ms","start":"2026-04-17T08:39:03.23459Z","end":"2026-04-17T08:39:03.368495Z","steps":["trace[341485808] 'process raft request' (duration: 133.161789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:39:03.368559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.249486ms","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-04-17T08:39:03.368609Z","caller":"traceutil/trace.go:171","msg":"trace[795667341] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:572; }","duration":"130.355278ms","start":"2026-04-17T08:39:03.238238Z","end":"2026-04-17T08:39:03.368593Z","steps":["trace[795667341] 'agreement among raft nodes before linearized reading' (duration: 130.240106ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T08:39:03.611274Z","caller":"traceutil/trace.go:171","msg":"trace[53587996] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"324.244041ms","start":"2026-04-17T08:39:03.287005Z","end":"2026-04-17T08:39:03.611249Z","steps":["trace[53587996] 'process raft request' (duration: 324.081248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:39:03.611514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:39:03.286963Z","time spent":"324.386803ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T08:39:03.611703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.995392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-17T08:39:03.611745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.299026ms","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-04-17T08:39:03.611797Z","caller":"traceutil/trace.go:171","msg":"trace[92268021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"351.124824ms","start":"2026-04-17T08:39:03.26065Z","end":"2026-04-17T08:39:03.611775Z","steps":["trace[92268021] 'agreement among raft nodes before linearized reading' (duration: 350.830399ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T08:39:03.611799Z","caller":"traceutil/trace.go:171","msg":"trace[1008124279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"352.410078ms","start":"2026-04-17T08:39:03.259376Z","end":"2026-04-17T08:39:03.611786Z","steps":["trace[1008124279] 'agreement among raft nodes before linearized reading' (duration: 352.189244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:39:03.611843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:39:03.260635Z","time spent":"351.197905ms","remote":"127.0.0.1:32986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-17T08:39:03.611944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:39:03.259337Z","time spent":"352.53219ms","remote":"127.0.0.1:32986","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-04-17T08:39:03.612014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.500622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-17T08:39:03.612082Z","caller":"traceutil/trace.go:171","msg":"trace[2039033054] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"371.618144ms","start":"2026-04-17T08:39:03.240449Z","end":"2026-04-17T08:39:03.612067Z","steps":["trace[2039033054] 'agreement among raft nodes before linearized reading' (duration: 371.499052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:39:03.612116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:39:03.240422Z","time spent":"371.687185ms","remote":"127.0.0.1:32986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-17T08:39:03.611464Z","caller":"traceutil/trace.go:171","msg":"trace[1813853130] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:595; }","duration":"242.970907ms","start":"2026-04-17T08:39:03.368445Z","end":"2026-04-17T08:39:03.611416Z","steps":["trace[1813853130] 'read index received' (duration: 242.956436ms)","trace[1813853130] 'applied index is now lower than readState.Index' (duration: 12.401µs)"],"step_count":2} {"level":"warn","ts":"2026-04-17T08:39:03.812025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.948222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-17T08:39:03.812255Z","caller":"traceutil/trace.go:171","msg":"trace[1200130460] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"438.416257ms","start":"2026-04-17T08:39:03.37381Z","end":"2026-04-17T08:39:03.812226Z","steps":["trace[1200130460] 'process raft request' (duration: 299.165189ms)","trace[1200130460] 'compare' (duration: 138.741068ms)"],"step_count":2} {"level":"warn","ts":"2026-04-17T08:39:03.812425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:39:03.373783Z","time spent":"438.567281ms","remote":"127.0.0.1:32986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-17T08:39:03.812552Z","caller":"traceutil/trace.go:171","msg":"trace[1689321605] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"194.387372ms","start":"2026-04-17T08:39:03.618144Z","end":"2026-04-17T08:39:03.812531Z","steps":["trace[1689321605] 'process raft request' (duration: 194.28325ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T08:39:03.812565Z","caller":"traceutil/trace.go:171","msg":"trace[674551835] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"195.211747ms","start":"2026-04-17T08:39:03.617324Z","end":"2026-04-17T08:39:03.812535Z","steps":["trace[674551835] 'process raft request' (duration: 195.066004ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T08:39:03.812565Z","caller":"traceutil/trace.go:171","msg":"trace[158792768] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"195.763736ms","start":"2026-04-17T08:39:03.616753Z","end":"2026-04-17T08:39:03.812517Z","steps":["trace[158792768] 'process raft request' (duration: 195.435471ms)"],"step_count":1} {"level":"info","ts":"2026-04-17T08:39:03.81271Z","caller":"traceutil/trace.go:171","msg":"trace[200587138] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"388.878355ms","start":"2026-04-17T08:39:03.423819Z","end":"2026-04-17T08:39:03.812698Z","steps":["trace[200587138] 'process raft request' (duration: 388.309605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:39:03.812772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:39:03.42379Z","time spent":"388.950876ms","remote":"127.0.0.1:33230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-17T08:39:04.799692Z","caller":"traceutil/trace.go:171","msg":"trace[950070582] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"579.193165ms","start":"2026-04-17T08:39:04.220451Z","end":"2026-04-17T08:39:04.799644Z","steps":["trace[950070582] 'process raft request' (duration: 578.307669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:39:04.80001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:39:04.220424Z","time spent":"579.357618ms","remote":"127.0.0.1:32840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-17T08:39:19.690262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.70037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-17T08:39:19.690374Z","caller":"traceutil/trace.go:171","msg":"trace[988637520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:735; }","duration":"852.871694ms","start":"2026-04-17T08:39:18.837477Z","end":"2026-04-17T08:39:19.690349Z","steps":["trace[988637520] 'range keys from in-memory index tree' (duration: 852.539448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:39:19.690817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.556093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-d8z6l\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-17T08:39:19.690916Z","caller":"traceutil/trace.go:171","msg":"trace[549188525] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-d8z6l; range_end:; response_count:1; response_revision:735; }","duration":"531.714025ms","start":"2026-04-17T08:39:19.159185Z","end":"2026-04-17T08:39:19.690899Z","steps":["trace[549188525] 'range keys from in-memory index tree' (duration: 531.370739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-17T08:39:19.690964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:39:19.159156Z","time spent":"531.799996ms","remote":"127.0.0.1:32918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-d8z6l\" "} {"level":"warn","ts":"2026-04-17T08:39:19.691084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-17T08:39:18.837456Z","time spent":"853.410402ms","remote":"127.0.0.1:60996","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-04-17T08:39:19.803769Z","caller":"traceutil/trace.go:171","msg":"trace[1019879627] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"106.021558ms","start":"2026-04-17T08:39:19.697727Z","end":"2026-04-17T08:39:19.803749Z","steps":["trace[1019879627] 'process raft request' (duration: 105.882386ms)"],"step_count":1}