{"level":"warn","ts":"2026-04-07T12:17:55.021073Z","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-07T12:17:55.021169Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49: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.204.45.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49: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-07T12:17:55.021249Z","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-07T12:17:55.021263Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-07T12:17:55.021283Z","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-07T12:17:55.021992Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-04-07T12:17:55.022067Z","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":16,"max-cpu-available":16,"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.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49: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-07T12:17:55.026455Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.156933ms"} {"level":"info","ts":"2026-04-07T12:17:55.037834Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-04-07T12:17:55.038319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T12:17:55.038364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-04-07T12:17:55.038388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T12:17:55.038408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-04-07T12:17:55.038456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-04-07T12:17:55.047867Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T12:17:55.051596Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T12:17:55.053196Z","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-07T12:17:55.055974Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T12:17:55.057486Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T12:17:55.057615Z","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-07T12:17:55.057649Z","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-07T12:17:55.057661Z","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-07T12:17:55.058643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-04-07T12:17:55.058892Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-07T12:17:55.059924Z","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-07T12:17:55.060072Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-07T12:17:55.06012Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-07T12:17:55.060326Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T12:17:55.060386Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T12:17:55.139198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T12:17:55.139244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T12:17:55.139274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-04-07T12:17:55.139295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-04-07T12:17:55.139306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-07T12:17:55.139323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-04-07T12:17:55.139453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-07T12:17:55.140387Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:17:55.141215Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:17:55.141214Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T12:17:55.141328Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:17:55.141519Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T12:17:55.141598Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T12:17:55.144319Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T12:17:55.144934Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:17:55.145052Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:17:55.145146Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:17:55.145801Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-04-07T12:18:01.63689Z","caller":"traceutil/trace.go:171","msg":"trace[2023864194] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:284; }","duration":"118.089576ms","start":"2026-04-07T12:18:01.518764Z","end":"2026-04-07T12:18:01.636854Z","steps":["trace[2023864194] 'read index received' (duration: 72.223769ms)","trace[2023864194] 'applied index is now lower than readState.Index' (duration: 45.864417ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:18:01.636947Z","caller":"traceutil/trace.go:171","msg":"trace[246575229] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"136.556081ms","start":"2026-04-07T12:18:01.500359Z","end":"2026-04-07T12:18:01.636915Z","steps":["trace[246575229] 'process raft request' (duration: 90.578941ms)","trace[246575229] 'compare' (duration: 45.759305ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:18:01.63717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.363203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:18:01.637324Z","caller":"traceutil/trace.go:171","msg":"trace[1779903657] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:278; }","duration":"118.595878ms","start":"2026-04-07T12:18:01.518702Z","end":"2026-04-07T12:18:01.637298Z","steps":["trace[1779903657] 'agreement among raft nodes before linearized reading' (duration: 118.307992ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:02.242666Z","caller":"traceutil/trace.go:171","msg":"trace[2065696990] transaction","detail":"{read_only:false; number_of_response:0; response_revision:281; }","duration":"112.979432ms","start":"2026-04-07T12:18:02.129646Z","end":"2026-04-07T12:18:02.242626Z","steps":["trace[2065696990] 'process raft request' (duration: 103.0641ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:02.242688Z","caller":"traceutil/trace.go:171","msg":"trace[660176597] transaction","detail":"{read_only:false; number_of_response:0; response_revision:281; }","duration":"112.642164ms","start":"2026-04-07T12:18:02.130015Z","end":"2026-04-07T12:18:02.242658Z","steps":["trace[660176597] 'process raft request' (duration: 112.594293ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:02.242701Z","caller":"traceutil/trace.go:171","msg":"trace[640217624] transaction","detail":"{read_only:false; number_of_response:0; response_revision:281; }","duration":"112.688595ms","start":"2026-04-07T12:18:02.129973Z","end":"2026-04-07T12:18:02.242661Z","steps":["trace[640217624] 'process raft request' (duration: 112.567223ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:02.242744Z","caller":"traceutil/trace.go:171","msg":"trace[447612519] transaction","detail":"{read_only:false; number_of_response:0; response_revision:281; }","duration":"112.721946ms","start":"2026-04-07T12:18:02.129996Z","end":"2026-04-07T12:18:02.242718Z","steps":["trace[447612519] 'process raft request' (duration: 112.582633ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:02.27282Z","caller":"traceutil/trace.go:171","msg":"trace[1675997708] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:288; }","duration":"104.146484ms","start":"2026-04-07T12:18:02.168646Z","end":"2026-04-07T12:18:02.272793Z","steps":["trace[1675997708] 'read index received' (duration: 64.069236ms)","trace[1675997708] 'applied index is now lower than readState.Index' (duration: 40.075618ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:18:02.272886Z","caller":"traceutil/trace.go:171","msg":"trace[1919330716] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"106.929297ms","start":"2026-04-07T12:18:02.165925Z","end":"2026-04-07T12:18:02.272855Z","steps":["trace[1919330716] 'process raft request' (duration: 106.692432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:18:02.272978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.313148ms","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-07T12:18:02.273043Z","caller":"traceutil/trace.go:171","msg":"trace[1466521042] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:282; }","duration":"104.429561ms","start":"2026-04-07T12:18:02.168599Z","end":"2026-04-07T12:18:02.273028Z","steps":["trace[1466521042] 'agreement among raft nodes before linearized reading' (duration: 104.299728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:18:04.62539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.287089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:18:04.625516Z","caller":"traceutil/trace.go:171","msg":"trace[1467496156] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:321; }","duration":"206.701863ms","start":"2026-04-07T12:18:04.418794Z","end":"2026-04-07T12:18:04.625496Z","steps":["trace[1467496156] 'read index received' (duration: 4.839559ms)","trace[1467496156] 'applied index is now lower than readState.Index' (duration: 201.860944ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:18:04.625543Z","caller":"traceutil/trace.go:171","msg":"trace[1955209172] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"212.199747ms","start":"2026-04-07T12:18:04.413308Z","end":"2026-04-07T12:18:04.625508Z","steps":["trace[1955209172] 'process raft request' (duration: 90.193512ms)","trace[1955209172] 'compare' (duration: 121.156135ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:18:04.625637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.837016ms","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-07T12:18:04.625677Z","caller":"traceutil/trace.go:171","msg":"trace[1044623974] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:311; }","duration":"206.898677ms","start":"2026-04-07T12:18:04.418768Z","end":"2026-04-07T12:18:04.625667Z","steps":["trace[1044623974] 'agreement among raft nodes before linearized reading' (duration: 206.823865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:18:04.625873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.348486ms","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-07T12:18:04.625998Z","caller":"traceutil/trace.go:171","msg":"trace[1898556031] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:311; }","duration":"157.548091ms","start":"2026-04-07T12:18:04.468421Z","end":"2026-04-07T12:18:04.625969Z","steps":["trace[1898556031] 'agreement among raft nodes before linearized reading' (duration: 157.298075ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:04.774928Z","caller":"traceutil/trace.go:171","msg":"trace[1911008883] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"139.967608ms","start":"2026-04-07T12:18:04.634927Z","end":"2026-04-07T12:18:04.774894Z","steps":["trace[1911008883] 'process raft request' (duration: 139.793294ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:04.774955Z","caller":"traceutil/trace.go:171","msg":"trace[1588598370] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:322; }","duration":"105.833782ms","start":"2026-04-07T12:18:04.6691Z","end":"2026-04-07T12:18:04.774934Z","steps":["trace[1588598370] 'read index received' (duration: 32.963029ms)","trace[1588598370] 'applied index is now lower than readState.Index' (duration: 72.869863ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:18:04.775213Z","caller":"traceutil/trace.go:171","msg":"trace[45963360] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"141.706815ms","start":"2026-04-07T12:18:04.633492Z","end":"2026-04-07T12:18:04.775199Z","steps":["trace[45963360] 'process raft request' (duration: 111.050858ms)","trace[45963360] 'compare' (duration: 29.957582ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:18:04.775264Z","caller":"traceutil/trace.go:171","msg":"trace[1245282269] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"135.373083ms","start":"2026-04-07T12:18:04.639879Z","end":"2026-04-07T12:18:04.775252Z","steps":["trace[1245282269] 'process raft request' (duration: 135.003005ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:04.775314Z","caller":"traceutil/trace.go:171","msg":"trace[703301839] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"141.805338ms","start":"2026-04-07T12:18:04.633498Z","end":"2026-04-07T12:18:04.775304Z","steps":["trace[703301839] 'process raft request' (duration: 141.152753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:18:04.775506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.328903ms","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-07T12:18:04.775556Z","caller":"traceutil/trace.go:171","msg":"trace[1259804845] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:318; }","duration":"106.485917ms","start":"2026-04-07T12:18:04.669057Z","end":"2026-04-07T12:18:04.775542Z","steps":["trace[1259804845] 'agreement among raft nodes before linearized reading' (duration: 106.328283ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:04.775549Z","caller":"traceutil/trace.go:171","msg":"trace[1860164264] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"139.24719ms","start":"2026-04-07T12:18:04.636268Z","end":"2026-04-07T12:18:04.775515Z","steps":["trace[1860164264] 'process raft request' (duration: 138.511524ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:04.775761Z","caller":"traceutil/trace.go:171","msg":"trace[739333573] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"138.156876ms","start":"2026-04-07T12:18:04.637587Z","end":"2026-04-07T12:18:04.775744Z","steps":["trace[739333573] 'process raft request' (duration: 137.247846ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:04.946693Z","caller":"traceutil/trace.go:171","msg":"trace[191525021] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"103.08136ms","start":"2026-04-07T12:18:04.843516Z","end":"2026-04-07T12:18:04.946597Z","steps":["trace[191525021] 'process raft request' (duration: 73.548618ms)","trace[191525021] 'compare' (duration: 29.282596ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:18:04.94691Z","caller":"traceutil/trace.go:171","msg":"trace[1385100432] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"102.291412ms","start":"2026-04-07T12:18:04.844571Z","end":"2026-04-07T12:18:04.946862Z","steps":["trace[1385100432] 'process raft request' (duration: 101.929224ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:04.946944Z","caller":"traceutil/trace.go:171","msg":"trace[1450229683] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"102.129538ms","start":"2026-04-07T12:18:04.844778Z","end":"2026-04-07T12:18:04.946907Z","steps":["trace[1450229683] 'process raft request' (duration: 101.819752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:18:06.40405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.72649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:18:06.404226Z","caller":"traceutil/trace.go:171","msg":"trace[2065648667] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"193.22279ms","start":"2026-04-07T12:18:06.210979Z","end":"2026-04-07T12:18:06.404202Z","steps":["trace[2065648667] 'process raft request' (duration: 79.246025ms)","trace[2065648667] 'compare' (duration: 113.33585ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:18:11.819547Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277991701176642,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T12:18:12.320413Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256277991701176642,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-07T12:18:12.431736Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.158985867s","expected-duration":"1s"} {"level":"info","ts":"2026-04-07T12:18:12.432379Z","caller":"traceutil/trace.go:171","msg":"trace[238369538] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"2.159723774s","start":"2026-04-07T12:18:10.272627Z","end":"2026-04-07T12:18:12.432351Z","steps":["trace[238369538] 'process raft request' (duration: 2.159550069s)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:18:12.432557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:18:10.272423Z","time spent":"2.160043301s","remote":"127.0.0.1:36490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5245,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:18:12.480469Z","caller":"traceutil/trace.go:171","msg":"trace[1960978759] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:382; }","duration":"1.161742218s","start":"2026-04-07T12:18:11.318696Z","end":"2026-04-07T12:18:12.480438Z","steps":["trace[1960978759] 'read index received' (duration: 1.113371443s)","trace[1960978759] 'applied index is now lower than readState.Index' (duration: 48.369435ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:18:12.480548Z","caller":"traceutil/trace.go:171","msg":"trace[544701633] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"939.237292ms","start":"2026-04-07T12:18:11.541276Z","end":"2026-04-07T12:18:12.480513Z","steps":["trace[544701633] 'process raft request' (duration: 938.972676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:18:12.480663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.865164ms","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-07T12:18:12.480707Z","caller":"traceutil/trace.go:171","msg":"trace[980997293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:371; }","duration":"897.937656ms","start":"2026-04-07T12:18:11.582756Z","end":"2026-04-07T12:18:12.480694Z","steps":["trace[980997293] 'agreement among raft nodes before linearized reading' (duration: 897.824783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:18:12.480728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.787615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-07T12:18:12.480762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:18:11.582695Z","time spent":"898.044758ms","remote":"127.0.0.1:36558","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-07T12:18:12.480734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162007954s","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-07T12:18:12.480814Z","caller":"traceutil/trace.go:171","msg":"trace[1825905204] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:371; }","duration":"608.904568ms","start":"2026-04-07T12:18:11.871889Z","end":"2026-04-07T12:18:12.480794Z","steps":["trace[1825905204] 'agreement among raft nodes before linearized reading' (duration: 608.774025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:18:12.480864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:18:11.871872Z","time spent":"608.984369ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T12:18:12.480833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.951576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T12:18:12.480861Z","caller":"traceutil/trace.go:171","msg":"trace[1729501544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:371; }","duration":"1.162191198s","start":"2026-04-07T12:18:11.318647Z","end":"2026-04-07T12:18:12.480838Z","steps":["trace[1729501544] 'agreement among raft nodes before linearized reading' (duration: 1.161938412s)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:12.480927Z","caller":"traceutil/trace.go:171","msg":"trace[144847814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:371; }","duration":"898.109789ms","start":"2026-04-07T12:18:11.582797Z","end":"2026-04-07T12:18:12.480907Z","steps":["trace[144847814] 'agreement among raft nodes before linearized reading' (duration: 897.896414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:18:12.480985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:18:11.582773Z","time spent":"898.202571ms","remote":"127.0.0.1:36558","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-07T12:18:12.480936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:18:11.318623Z","time spent":"1.1623028s","remote":"127.0.0.1:36558","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-04-07T12:18:12.480768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:18:11.541248Z","time spent":"939.375064ms","remote":"127.0.0.1:36558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:18:18.330902Z","caller":"traceutil/trace.go:171","msg":"trace[784783383] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"581.066912ms","start":"2026-04-07T12:18:17.749808Z","end":"2026-04-07T12:18:18.330875Z","steps":["trace[784783383] 'process raft request' (duration: 580.880608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:18:18.331092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:18:17.749781Z","time spent":"581.218876ms","remote":"127.0.0.1:36746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:18:23.982962Z","caller":"traceutil/trace.go:171","msg":"trace[1055737134] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:448; }","duration":"153.401498ms","start":"2026-04-07T12:18:23.829529Z","end":"2026-04-07T12:18:23.98293Z","steps":["trace[1055737134] 'read index received' (duration: 150.512994ms)","trace[1055737134] 'applied index is now lower than readState.Index' (duration: 2.852504ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:18:23.983107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.551482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T12:18:23.983143Z","caller":"traceutil/trace.go:171","msg":"trace[1390756547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:433; }","duration":"153.636944ms","start":"2026-04-07T12:18:23.829494Z","end":"2026-04-07T12:18:23.983131Z","steps":["trace[1390756547] 'agreement among raft nodes before linearized reading' (duration: 153.550692ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:18:31.056544Z","caller":"traceutil/trace.go:171","msg":"trace[1850906626] linearizableReadLoop","detail":"{readStateIndex:465; appliedIndex:464; }","duration":"194.148462ms","start":"2026-04-07T12:18:30.862362Z","end":"2026-04-07T12:18:31.05651Z","steps":["trace[1850906626] 'read index received' (duration: 193.822475ms)","trace[1850906626] 'applied index is now lower than readState.Index' (duration: 324.647µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:18:31.056616Z","caller":"traceutil/trace.go:171","msg":"trace[747341334] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"210.940847ms","start":"2026-04-07T12:18:30.845635Z","end":"2026-04-07T12:18:31.056575Z","steps":["trace[747341334] 'process raft request' (duration: 210.681081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:18:31.056796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.131401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-07T12:18:31.056844Z","caller":"traceutil/trace.go:171","msg":"trace[1087684664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:448; }","duration":"194.246824ms","start":"2026-04-07T12:18:30.862582Z","end":"2026-04-07T12:18:31.056828Z","steps":["trace[1087684664] 'agreement among raft nodes before linearized reading' (duration: 194.117591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:18:31.056827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.429478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T12:18:31.057508Z","caller":"traceutil/trace.go:171","msg":"trace[682821450] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:448; }","duration":"195.142564ms","start":"2026-04-07T12:18:30.862345Z","end":"2026-04-07T12:18:31.057487Z","steps":["trace[682821450] 'agreement among raft nodes before linearized reading' (duration: 194.330856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:18:31.056904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.021268ms","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-07T12:18:31.057618Z","caller":"traceutil/trace.go:171","msg":"trace[282809560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:448; }","duration":"194.792105ms","start":"2026-04-07T12:18:30.862798Z","end":"2026-04-07T12:18:31.05759Z","steps":["trace[282809560] 'agreement among raft nodes before linearized reading' (duration: 193.966547ms)"],"step_count":1}