{"level":"warn","ts":"2026-08-05T11:02:46.46573Z","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-05T11:02:46.465818Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.74: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.74:2380","--initial-cluster=instance=https://199.19.213.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.74: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-05T11:02:46.46589Z","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-05T11:02:46.465904Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.74:2380"]} {"level":"info","ts":"2026-08-05T11:02:46.465919Z","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-05T11:02:46.46652Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.74:2379"]} {"level":"info","ts":"2026-08-05T11:02:46.466593Z","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.74:2380"],"listen-peer-urls":["https://199.19.213.74:2380"],"advertise-client-urls":["https://199.19.213.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.74: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-05T11:02:46.478706Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.874156ms"} {"level":"info","ts":"2026-08-05T11:02:46.526822Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d4171f3ea72ed207","cluster-id":"d40b55e346dc63dc"} {"level":"info","ts":"2026-08-05T11:02:46.526954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T11:02:46.527174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became follower at term 0"} {"level":"info","ts":"2026-08-05T11:02:46.527273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d4171f3ea72ed207 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-05T11:02:46.52735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became follower at term 1"} {"level":"info","ts":"2026-08-05T11:02:46.527494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 switched to configuration voters=(15282718214458364423)"} {"level":"warn","ts":"2026-08-05T11:02:46.54381Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T11:02:46.547294Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T11:02:46.548498Z","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-05T11:02:46.55176Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d4171f3ea72ed207","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-05T11:02:46.551969Z","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-05T11:02:46.552073Z","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-05T11:02:46.552095Z","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-05T11:02:46.551988Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d4171f3ea72ed207","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-05T11:02:46.553023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 switched to configuration voters=(15282718214458364423)"} {"level":"info","ts":"2026-08-05T11:02:46.554421Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d40b55e346dc63dc","local-member-id":"d4171f3ea72ed207","added-peer-id":"d4171f3ea72ed207","added-peer-peer-urls":["https://199.19.213.74:2380"]} {"level":"info","ts":"2026-08-05T11:02:46.557669Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.74:2380"} {"level":"info","ts":"2026-08-05T11:02:46.557707Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.74:2380"} {"level":"info","ts":"2026-08-05T11:02:46.557421Z","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-05T11:02:46.557978Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d4171f3ea72ed207","initial-advertise-peer-urls":["https://199.19.213.74:2380"],"listen-peer-urls":["https://199.19.213.74:2380"],"advertise-client-urls":["https://199.19.213.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-05T11:02:46.55802Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T11:02:46.628194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T11:02:46.628227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T11:02:46.628239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 received MsgPreVoteResp from d4171f3ea72ed207 at term 1"} {"level":"info","ts":"2026-08-05T11:02:46.628251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became candidate at term 2"} {"level":"info","ts":"2026-08-05T11:02:46.628259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 received MsgVoteResp from d4171f3ea72ed207 at term 2"} {"level":"info","ts":"2026-08-05T11:02:46.628269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d4171f3ea72ed207 became leader at term 2"} {"level":"info","ts":"2026-08-05T11:02:46.628281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d4171f3ea72ed207 elected leader d4171f3ea72ed207 at term 2"} {"level":"info","ts":"2026-08-05T11:02:46.629025Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d4171f3ea72ed207","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.74:2379]}","request-path":"/0/members/d4171f3ea72ed207/attributes","cluster-id":"d40b55e346dc63dc","publish-timeout":"7s"} {"level":"info","ts":"2026-08-05T11:02:46.633062Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T11:02:46.633115Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T11:02:46.6335Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T11:02:46.633529Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T11:02:46.63363Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:02:46.636317Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.74:2379"} {"level":"info","ts":"2026-08-05T11:02:46.637028Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d40b55e346dc63dc","local-member-id":"d4171f3ea72ed207","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:02:46.637181Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:02:46.637219Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T11:02:46.638253Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-05T11:03:08.728694Z","caller":"traceutil/trace.go:171","msg":"trace[839016186] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"101.063812ms","start":"2026-08-05T11:03:08.627597Z","end":"2026-08-05T11:03:08.728661Z","steps":["trace[839016186] 'process raft request' (duration: 100.801937ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:03:13.971082Z","caller":"traceutil/trace.go:171","msg":"trace[1759076847] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"289.798826ms","start":"2026-08-05T11:03:13.681259Z","end":"2026-08-05T11:03:13.971058Z","steps":["trace[1759076847] 'process raft request' (duration: 289.598712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:03:16.372886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.960936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:03:16.372988Z","caller":"traceutil/trace.go:171","msg":"trace[1934791631] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"311.698268ms","start":"2026-08-05T11:03:16.061277Z","end":"2026-08-05T11:03:16.372976Z","steps":["trace[1934791631] 'read index received' (duration: 73.909815ms)","trace[1934791631] 'applied index is now lower than readState.Index' (duration: 237.787053ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:03:16.373059Z","caller":"traceutil/trace.go:171","msg":"trace[292328918] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"386.719763ms","start":"2026-08-05T11:03:15.98628Z","end":"2026-08-05T11:03:16.373Z","steps":["trace[292328918] 'process raft request' (duration: 149.025471ms)","trace[292328918] 'compare' (duration: 236.840994ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:03:16.37313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.850711ms","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-05T11:03:16.373166Z","caller":"traceutil/trace.go:171","msg":"trace[1561582575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:433; }","duration":"311.942883ms","start":"2026-08-05T11:03:16.061214Z","end":"2026-08-05T11:03:16.373157Z","steps":["trace[1561582575] 'agreement among raft nodes before linearized reading' (duration: 311.850031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:03:16.373205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:03:16.061188Z","time spent":"312.009175ms","remote":"127.0.0.1:47110","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":"warn","ts":"2026-08-05T11:03:16.373196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:03:15.986253Z","time spent":"386.875606ms","remote":"127.0.0.1:47110","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-05T11:03:16.373378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.204364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-05T11:03:16.373417Z","caller":"traceutil/trace.go:171","msg":"trace[1531294806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:433; }","duration":"182.280946ms","start":"2026-08-05T11:03:16.191125Z","end":"2026-08-05T11:03:16.373406Z","steps":["trace[1531294806] 'agreement among raft nodes before linearized reading' (duration: 182.214945ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:03:16.946952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.630688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T11:03:16.947229Z","caller":"traceutil/trace.go:171","msg":"trace[1751362950] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"568.752717ms","start":"2026-08-05T11:03:16.378448Z","end":"2026-08-05T11:03:16.947201Z","steps":["trace[1751362950] 'process raft request' (duration: 155.790013ms)","trace[1751362950] 'compare' (duration: 412.437263ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:03:16.947359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:03:16.378435Z","time spent":"568.857519ms","remote":"127.0.0.1:47110","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-05T11:03:32.529575Z","caller":"traceutil/trace.go:171","msg":"trace[1832928971] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"126.869948ms","start":"2026-08-05T11:03:32.402675Z","end":"2026-08-05T11:03:32.529545Z","steps":["trace[1832928971] 'read index received' (duration: 51.667868ms)","trace[1832928971] 'applied index is now lower than readState.Index' (duration: 75.188429ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:03:32.529743Z","caller":"traceutil/trace.go:171","msg":"trace[298791668] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"371.904066ms","start":"2026-08-05T11:03:32.157817Z","end":"2026-08-05T11:03:32.529721Z","steps":["trace[298791668] 'process raft request' (duration: 296.580555ms)","trace[298791668] 'compare' (duration: 74.81752ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:03:32.529871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:03:32.157787Z","time spent":"371.994518ms","remote":"127.0.0.1:47110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:03:32.530099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.40729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xk6gr\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-05T11:03:32.530208Z","caller":"traceutil/trace.go:171","msg":"trace[1871571190] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xk6gr; range_end:; response_count:1; response_revision:606; }","duration":"127.588745ms","start":"2026-08-05T11:03:32.402595Z","end":"2026-08-05T11:03:32.530183Z","steps":["trace[1871571190] 'agreement among raft nodes before linearized reading' (duration: 127.256376ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:03:33.082422Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15134240795333362768,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-05T11:03:33.219785Z","caller":"traceutil/trace.go:171","msg":"trace[1502582429] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"637.971995ms","start":"2026-08-05T11:03:32.581742Z","end":"2026-08-05T11:03:33.219714Z","steps":["trace[1502582429] 'read index received' (duration: 637.865053ms)","trace[1502582429] 'applied index is now lower than readState.Index' (duration: 105.922µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:03:33.219879Z","caller":"traceutil/trace.go:171","msg":"trace[1275793016] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"675.994986ms","start":"2026-08-05T11:03:32.543849Z","end":"2026-08-05T11:03:33.219844Z","steps":["trace[1275793016] 'process raft request' (duration: 675.70493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:03:33.220127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:03:32.543829Z","time spent":"676.20056ms","remote":"127.0.0.1:47018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:03:33.220129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.80835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:03:33.220582Z","caller":"traceutil/trace.go:171","msg":"trace[179652015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"334.340763ms","start":"2026-08-05T11:03:32.88622Z","end":"2026-08-05T11:03:33.220561Z","steps":["trace[179652015] 'agreement among raft nodes before linearized reading' (duration: 333.737799ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:03:33.220679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:03:32.886203Z","time spent":"334.462265ms","remote":"127.0.0.1:46808","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-05T11:03:33.220585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.820935ms","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-08-05T11:03:33.220826Z","caller":"traceutil/trace.go:171","msg":"trace[2141472019] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"639.115113ms","start":"2026-08-05T11:03:32.581695Z","end":"2026-08-05T11:03:33.22081Z","steps":["trace[2141472019] 'agreement among raft nodes before linearized reading' (duration: 638.188291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:03:33.220933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:03:32.581675Z","time spent":"639.242275ms","remote":"127.0.0.1:47110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-05T11:03:35.065956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15134240795333362777,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-05T11:03:35.198275Z","caller":"traceutil/trace.go:171","msg":"trace[1988571394] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"633.000045ms","start":"2026-08-05T11:03:34.565245Z","end":"2026-08-05T11:03:35.198245Z","steps":["trace[1988571394] 'read index received' (duration: 632.617677ms)","trace[1988571394] 'applied index is now lower than readState.Index' (duration: 377.808µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:03:35.198302Z","caller":"traceutil/trace.go:171","msg":"trace[1989746219] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"660.542893ms","start":"2026-08-05T11:03:34.537731Z","end":"2026-08-05T11:03:35.198274Z","steps":["trace[1989746219] 'process raft request' (duration: 660.046362ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:03:35.19846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:03:34.537705Z","time spent":"660.686866ms","remote":"127.0.0.1:47110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T11:03:35.198622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.328052ms","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-05T11:03:35.198748Z","caller":"traceutil/trace.go:171","msg":"trace[1478594596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"633.524458ms","start":"2026-08-05T11:03:34.565195Z","end":"2026-08-05T11:03:35.19872Z","steps":["trace[1478594596] 'agreement among raft nodes before linearized reading' (duration: 633.193999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:03:35.198825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:03:34.565178Z","time spent":"633.63732ms","remote":"127.0.0.1:47110","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-05T11:03:35.223497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.648134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:03:35.223593Z","caller":"traceutil/trace.go:171","msg":"trace[546232810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"337.766357ms","start":"2026-08-05T11:03:34.885788Z","end":"2026-08-05T11:03:35.223554Z","steps":["trace[546232810] 'agreement among raft nodes before linearized reading' (duration: 337.653985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:03:35.223705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:03:34.885768Z","time spent":"337.93249ms","remote":"127.0.0.1:46814","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-05T11:03:35.223662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.399521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:03:35.223823Z","caller":"traceutil/trace.go:171","msg":"trace[860462510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"225.545586ms","start":"2026-08-05T11:03:34.998256Z","end":"2026-08-05T11:03:35.223801Z","steps":["trace[860462510] 'agreement among raft nodes before linearized reading' (duration: 225.384621ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:03:47.1156Z","caller":"traceutil/trace.go:171","msg":"trace[1416599980] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"163.722479ms","start":"2026-08-05T11:03:46.951856Z","end":"2026-08-05T11:03:47.115579Z","steps":["trace[1416599980] 'process raft request' (duration: 163.570886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:03:50.604388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:03:49.755778Z","time spent":"848.606873ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-05T11:03:50.604447Z","caller":"traceutil/trace.go:171","msg":"trace[1547914321] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:793; }","duration":"194.8491ms","start":"2026-08-05T11:03:50.409566Z","end":"2026-08-05T11:03:50.604415Z","steps":["trace[1547914321] 'read index received' (duration: 194.821289ms)","trace[1547914321] 'applied index is now lower than readState.Index' (duration: 25.181µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:03:50.604627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.038614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T11:03:50.604674Z","caller":"traceutil/trace.go:171","msg":"trace[1170180779] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:765; }","duration":"195.146006ms","start":"2026-08-05T11:03:50.409513Z","end":"2026-08-05T11:03:50.604659Z","steps":["trace[1170180779] 'agreement among raft nodes before linearized reading' (duration: 195.003653ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:03:50.899215Z","caller":"traceutil/trace.go:171","msg":"trace[1336353851] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"377.468897ms","start":"2026-08-05T11:03:50.52172Z","end":"2026-08-05T11:03:50.899188Z","steps":["trace[1336353851] 'process raft request' (duration: 376.644508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:03:50.899393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:03:50.521697Z","time spent":"377.601752ms","remote":"127.0.0.1:47080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:03:50.910871Z","caller":"traceutil/trace.go:171","msg":"trace[745178822] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"305.433999ms","start":"2026-08-05T11:03:50.605397Z","end":"2026-08-05T11:03:50.910831Z","steps":["trace[745178822] 'process raft request' (duration: 305.185993ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:03:50.911019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:03:50.605374Z","time spent":"305.570093ms","remote":"127.0.0.1:46828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:04:47.836861Z","caller":"traceutil/trace.go:171","msg":"trace[1536871199] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"155.294202ms","start":"2026-08-05T11:04:47.681535Z","end":"2026-08-05T11:04:47.83683Z","steps":["trace[1536871199] 'process raft request' (duration: 114.358097ms)","trace[1536871199] 'compare' (duration: 40.74043ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:04:47.836873Z","caller":"traceutil/trace.go:171","msg":"trace[1477300118] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"110.951175ms","start":"2026-08-05T11:04:47.7259Z","end":"2026-08-05T11:04:47.836851Z","steps":["trace[1477300118] 'read index received' (duration: 38.191469ms)","trace[1477300118] 'applied index is now lower than readState.Index' (duration: 72.758686ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:04:47.837143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.192201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T11:04:47.837232Z","caller":"traceutil/trace.go:171","msg":"trace[1863348143] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1093; }","duration":"111.354876ms","start":"2026-08-05T11:04:47.725859Z","end":"2026-08-05T11:04:47.837213Z","steps":["trace[1863348143] 'agreement among raft nodes before linearized reading' (duration: 111.062388ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:05:04.313546Z","caller":"traceutil/trace.go:171","msg":"trace[1635189861] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1190; }","duration":"236.419015ms","start":"2026-08-05T11:05:04.077099Z","end":"2026-08-05T11:05:04.313518Z","steps":["trace[1635189861] 'read index received' (duration: 164.974447ms)","trace[1635189861] 'applied index is now lower than readState.Index' (duration: 71.443238ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T11:05:04.313823Z","caller":"traceutil/trace.go:171","msg":"trace[1561723457] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"299.93689ms","start":"2026-08-05T11:05:04.013844Z","end":"2026-08-05T11:05:04.313781Z","steps":["trace[1561723457] 'process raft request' (duration: 299.571829ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:05:04.313831Z","caller":"traceutil/trace.go:171","msg":"trace[810423791] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"300.441923ms","start":"2026-08-05T11:05:04.013346Z","end":"2026-08-05T11:05:04.313788Z","steps":["trace[810423791] 'process raft request' (duration: 228.743779ms)","trace[810423791] 'compare' (duration: 70.939122ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:05:04.313974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.682122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5547"} {"level":"warn","ts":"2026-08-05T11:05:04.31399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:05:04.013784Z","time spent":"300.122164ms","remote":"127.0.0.1:47110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:05:04.31403Z","caller":"traceutil/trace.go:171","msg":"trace[1241122083] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1147; }","duration":"236.987121ms","start":"2026-08-05T11:05:04.077028Z","end":"2026-08-05T11:05:04.314015Z","steps":["trace[1241122083] 'agreement among raft nodes before linearized reading' (duration: 236.603551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:05:04.314027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:05:04.013328Z","time spent":"300.586517ms","remote":"127.0.0.1:47110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T11:05:07.767661Z","caller":"traceutil/trace.go:171","msg":"trace[1609959317] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"123.403384ms","start":"2026-08-05T11:05:07.644223Z","end":"2026-08-05T11:05:07.767627Z","steps":["trace[1609959317] 'process raft request' (duration: 123.164328ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:05:26.22969Z","caller":"traceutil/trace.go:171","msg":"trace[969306816] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"151.7224ms","start":"2026-08-05T11:05:26.077925Z","end":"2026-08-05T11:05:26.229647Z","steps":["trace[969306816] 'process raft request' (duration: 151.517704ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:05:45.121309Z","caller":"traceutil/trace.go:171","msg":"trace[1875767555] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"501.102875ms","start":"2026-08-05T11:05:44.620177Z","end":"2026-08-05T11:05:45.121279Z","steps":["trace[1875767555] 'process raft request' (duration: 500.93793ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:05:45.121397Z","caller":"traceutil/trace.go:171","msg":"trace[669896678] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1370; }","duration":"476.463189ms","start":"2026-08-05T11:05:44.644883Z","end":"2026-08-05T11:05:45.121347Z","steps":["trace[669896678] 'read index received' (duration: 476.440978ms)","trace[669896678] 'applied index is now lower than readState.Index' (duration: 17.771µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T11:05:45.121468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:05:44.620158Z","time spent":"501.229999ms","remote":"127.0.0.1:47110","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-05T11:05:45.121775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.86005ms","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-08-05T11:05:45.121834Z","caller":"traceutil/trace.go:171","msg":"trace[1295265700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1316; }","duration":"476.972134ms","start":"2026-08-05T11:05:44.644831Z","end":"2026-08-05T11:05:45.121803Z","steps":["trace[1295265700] 'agreement among raft nodes before linearized reading' (duration: 476.719656ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:05:45.12188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:05:44.644803Z","time spent":"477.066717ms","remote":"127.0.0.1:47110","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":"info","ts":"2026-08-05T11:05:45.128833Z","caller":"traceutil/trace.go:171","msg":"trace[1138146081] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"359.246235ms","start":"2026-08-05T11:05:44.769558Z","end":"2026-08-05T11:05:45.128804Z","steps":["trace[1138146081] 'process raft request' (duration: 359.004538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:05:45.129191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T11:05:44.769529Z","time spent":"359.473741ms","remote":"127.0.0.1:47110","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-05T11:05:45.172743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.025779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T11:05:45.17285Z","caller":"traceutil/trace.go:171","msg":"trace[1388733149] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1317; }","duration":"203.189864ms","start":"2026-08-05T11:05:44.969637Z","end":"2026-08-05T11:05:45.172826Z","steps":["trace[1388733149] 'agreement among raft nodes before linearized reading' (duration: 202.955787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T11:05:45.172862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.07655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T11:05:45.174028Z","caller":"traceutil/trace.go:171","msg":"trace[741368754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1317; }","duration":"288.285015ms","start":"2026-08-05T11:05:44.885717Z","end":"2026-08-05T11:05:45.174002Z","steps":["trace[741368754] 'agreement among raft nodes before linearized reading' (duration: 286.968947ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T11:05:48.174281Z","caller":"traceutil/trace.go:171","msg":"trace[1835796363] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"129.160409ms","start":"2026-08-05T11:05:48.045086Z","end":"2026-08-05T11:05:48.174246Z","steps":["trace[1835796363] 'process raft request' (duration: 93.226834ms)","trace[1835796363] 'compare' (duration: 35.503193ms)"],"step_count":2}