{"level":"warn","ts":"2026-02-27T20:57:09.710344Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T20:57:09.710441Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240: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.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240: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-02-27T20:57:09.710529Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T20:57:09.710546Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-02-27T20:57:09.710574Z","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-02-27T20:57:09.711206Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-02-27T20:57:09.711279Z","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.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240: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-02-27T20:57:09.717291Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.774988ms"} {"level":"info","ts":"2026-02-27T20:57:09.724582Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-02-27T20:57:09.724718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T20:57:09.724781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-02-27T20:57:09.724805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T20:57:09.724825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-02-27T20:57:09.724891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-02-27T20:57:09.731019Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T20:57:09.732398Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T20:57:09.733535Z","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-02-27T20:57:09.740425Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T20:57:09.740544Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T20:57:09.740811Z","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-02-27T20:57:09.741326Z","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-02-27T20:57:09.741752Z","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-02-27T20:57:09.742355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-02-27T20:57:09.743497Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-02-27T20:57:09.744305Z","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-02-27T20:57:09.74449Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T20:57:09.744516Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T20:57:09.744572Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-02-27T20:57:09.744585Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-02-27T20:57:10.225865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T20:57:10.225947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T20:57:10.225997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-02-27T20:57:10.226028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-02-27T20:57:10.226047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-02-27T20:57:10.226077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-02-27T20:57:10.226104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-02-27T20:57:10.297246Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T20:57:10.29748Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T20:57:10.297564Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T20:57:10.297719Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:57:10.29785Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T20:57:10.297941Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T20:57:10.300501Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-02-27T20:57:10.301126Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T20:57:10.359542Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:57:10.359826Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:57:10.359888Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:57:11.762264Z","caller":"traceutil/trace.go:171","msg":"trace[2052109849] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"128.387481ms","start":"2026-02-27T20:57:11.633854Z","end":"2026-02-27T20:57:11.762241Z","steps":["trace[2052109849] 'process raft request' (duration: 128.203788ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:11.786791Z","caller":"traceutil/trace.go:171","msg":"trace[416954583] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"149.650024ms","start":"2026-02-27T20:57:11.637121Z","end":"2026-02-27T20:57:11.786771Z","steps":["trace[416954583] 'read index received' (duration: 125.055087ms)","trace[416954583] 'applied index is now lower than readState.Index' (duration: 24.594177ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:57:11.787041Z","caller":"traceutil/trace.go:171","msg":"trace[177078057] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"151.726146ms","start":"2026-02-27T20:57:11.635294Z","end":"2026-02-27T20:57:11.78702Z","steps":["trace[177078057] 'process raft request' (duration: 151.113691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:11.787896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.708631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:57:11.788011Z","caller":"traceutil/trace.go:171","msg":"trace[1588086548] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"150.846641ms","start":"2026-02-27T20:57:11.637143Z","end":"2026-02-27T20:57:11.787989Z","steps":["trace[1588086548] 'agreement among raft nodes before linearized reading' (duration: 150.630155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:11.788161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.521329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:57:11.788423Z","caller":"traceutil/trace.go:171","msg":"trace[831443070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"147.82163ms","start":"2026-02-27T20:57:11.640583Z","end":"2026-02-27T20:57:11.788405Z","steps":["trace[831443070] 'agreement among raft nodes before linearized reading' (duration: 147.504417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:11.78829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.769907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T20:57:11.788445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.306425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:57:11.788506Z","caller":"traceutil/trace.go:171","msg":"trace[1129694522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"148.000554ms","start":"2026-02-27T20:57:11.640491Z","end":"2026-02-27T20:57:11.788492Z","steps":["trace[1129694522] 'agreement among raft nodes before linearized reading' (duration: 147.752746ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:11.788519Z","caller":"traceutil/trace.go:171","msg":"trace[829920273] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:11; }","duration":"151.416613ms","start":"2026-02-27T20:57:11.637084Z","end":"2026-02-27T20:57:11.7885Z","steps":["trace[829920273] 'agreement among raft nodes before linearized reading' (duration: 149.964837ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:12.076383Z","caller":"traceutil/trace.go:171","msg":"trace[2040562491] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"138.89121ms","start":"2026-02-27T20:57:11.937474Z","end":"2026-02-27T20:57:12.076365Z","steps":["trace[2040562491] 'process raft request' (duration: 43.987214ms)","trace[2040562491] 'compare' (duration: 94.637666ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:57:12.076689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.018719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:57:12.076798Z","caller":"traceutil/trace.go:171","msg":"trace[256216095] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:35; }","duration":"139.16721ms","start":"2026-02-27T20:57:11.937608Z","end":"2026-02-27T20:57:12.076775Z","steps":["trace[256216095] 'agreement among raft nodes before linearized reading' (duration: 138.956805ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:12.076297Z","caller":"traceutil/trace.go:171","msg":"trace[142024760] linearizableReadLoop","detail":"{readStateIndex:39; appliedIndex:38; }","duration":"138.635981ms","start":"2026-02-27T20:57:11.937638Z","end":"2026-02-27T20:57:12.076274Z","steps":["trace[142024760] 'read index received' (duration: 43.80503ms)","trace[142024760] 'applied index is now lower than readState.Index' (duration: 94.829601ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:57:12.080894Z","caller":"traceutil/trace.go:171","msg":"trace[865208597] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"142.977889ms","start":"2026-02-27T20:57:11.93788Z","end":"2026-02-27T20:57:12.080858Z","steps":["trace[865208597] 'process raft request' (duration: 142.835149ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:12.080921Z","caller":"traceutil/trace.go:171","msg":"trace[633797389] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"143.039154ms","start":"2026-02-27T20:57:11.937845Z","end":"2026-02-27T20:57:12.080884Z","steps":["trace[633797389] 'process raft request' (duration: 142.787726ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:12.081118Z","caller":"traceutil/trace.go:171","msg":"trace[193307270] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"142.330412ms","start":"2026-02-27T20:57:11.938773Z","end":"2026-02-27T20:57:12.081104Z","steps":["trace[193307270] 'process raft request' (duration: 142.052902ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:12.081181Z","caller":"traceutil/trace.go:171","msg":"trace[583375389] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"141.733279ms","start":"2026-02-27T20:57:11.939436Z","end":"2026-02-27T20:57:12.081169Z","steps":["trace[583375389] 'process raft request' (duration: 141.448268ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:12.081186Z","caller":"traceutil/trace.go:171","msg":"trace[449473105] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"142.884253ms","start":"2026-02-27T20:57:11.93826Z","end":"2026-02-27T20:57:12.081144Z","steps":["trace[449473105] 'process raft request' (duration: 142.514786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:12.081819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.754017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:57:12.081865Z","caller":"traceutil/trace.go:171","msg":"trace[703683190] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:40; }","duration":"143.832752ms","start":"2026-02-27T20:57:11.938021Z","end":"2026-02-27T20:57:12.081854Z","steps":["trace[703683190] 'agreement among raft nodes before linearized reading' (duration: 143.289713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:12.082026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.163257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-node-lease\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:57:12.082159Z","caller":"traceutil/trace.go:171","msg":"trace[905527304] range","detail":"{range_begin:/registry/namespaces/kube-node-lease; range_end:; response_count:0; response_revision:40; }","duration":"144.34329ms","start":"2026-02-27T20:57:11.937795Z","end":"2026-02-27T20:57:12.082138Z","steps":["trace[905527304] 'agreement among raft nodes before linearized reading' (duration: 143.125861ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:12.396269Z","caller":"traceutil/trace.go:171","msg":"trace[1887666079] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"141.33507ms","start":"2026-02-27T20:57:12.254893Z","end":"2026-02-27T20:57:12.396228Z","steps":["trace[1887666079] 'process raft request' (duration: 74.561961ms)","trace[1887666079] 'compare' (duration: 66.488238ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:57:12.397134Z","caller":"traceutil/trace.go:171","msg":"trace[630753374] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"116.447244ms","start":"2026-02-27T20:57:12.280659Z","end":"2026-02-27T20:57:12.397106Z","steps":["trace[630753374] 'process raft request' (duration: 115.495985ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:12.763167Z","caller":"traceutil/trace.go:171","msg":"trace[54092567] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"101.585773ms","start":"2026-02-27T20:57:12.661551Z","end":"2026-02-27T20:57:12.763136Z","steps":["trace[54092567] 'process raft request' (duration: 101.510758ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:12.76318Z","caller":"traceutil/trace.go:171","msg":"trace[8429959] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"127.437221ms","start":"2026-02-27T20:57:12.635691Z","end":"2026-02-27T20:57:12.763128Z","steps":["trace[8429959] 'process raft request' (duration: 80.193464ms)","trace[8429959] 'compare' (duration: 46.919303ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:57:12.763326Z","caller":"traceutil/trace.go:171","msg":"trace[2056064421] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"126.986658ms","start":"2026-02-27T20:57:12.636316Z","end":"2026-02-27T20:57:12.763303Z","steps":["trace[2056064421] 'process raft request' (duration: 126.678355ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:12.763428Z","caller":"traceutil/trace.go:171","msg":"trace[410314469] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:73; }","duration":"104.085517ms","start":"2026-02-27T20:57:12.659293Z","end":"2026-02-27T20:57:12.763378Z","steps":["trace[410314469] 'read index received' (duration: 56.5554ms)","trace[410314469] 'applied index is now lower than readState.Index' (duration: 47.519596ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:57:12.763693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.370158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:57:12.763749Z","caller":"traceutil/trace.go:171","msg":"trace[740680454] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:72; }","duration":"104.453294ms","start":"2026-02-27T20:57:12.659278Z","end":"2026-02-27T20:57:12.763732Z","steps":["trace[740680454] 'agreement among raft nodes before linearized reading' (duration: 104.321665ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:14.399346Z","caller":"traceutil/trace.go:171","msg":"trace[688675163] transaction","detail":"{read_only:false; response_revision:174; number_of_response:1; }","duration":"162.824992ms","start":"2026-02-27T20:57:14.23649Z","end":"2026-02-27T20:57:14.399315Z","steps":["trace[688675163] 'process raft request' (duration: 71.26099ms)","trace[688675163] 'compare' (duration: 91.408051ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:57:14.588315Z","caller":"traceutil/trace.go:171","msg":"trace[1990686846] transaction","detail":"{read_only:false; response_revision:181; number_of_response:1; }","duration":"101.084226ms","start":"2026-02-27T20:57:14.487195Z","end":"2026-02-27T20:57:14.588279Z","steps":["trace[1990686846] 'process raft request' (duration: 41.202978ms)","trace[1990686846] 'compare' (duration: 59.709136ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:57:14.9681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.646411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:57:14.968231Z","caller":"traceutil/trace.go:171","msg":"trace[596692639] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"146.100196ms","start":"2026-02-27T20:57:14.82211Z","end":"2026-02-27T20:57:14.96821Z","steps":["trace[596692639] 'process raft request' (duration: 17.742912ms)","trace[596692639] 'compare' (duration: 127.533243ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:57:17.495582Z","caller":"traceutil/trace.go:171","msg":"trace[838179933] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"122.636911ms","start":"2026-02-27T20:57:17.372904Z","end":"2026-02-27T20:57:17.495541Z","steps":["trace[838179933] 'process raft request' (duration: 122.330098ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:17.495733Z","caller":"traceutil/trace.go:171","msg":"trace[1933484740] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"107.065096ms","start":"2026-02-27T20:57:17.388614Z","end":"2026-02-27T20:57:17.495679Z","steps":["trace[1933484740] 'process raft request' (duration: 106.876122ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:17.495774Z","caller":"traceutil/trace.go:171","msg":"trace[2038970240] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"106.064711ms","start":"2026-02-27T20:57:17.389656Z","end":"2026-02-27T20:57:17.495721Z","steps":["trace[2038970240] 'process raft request' (duration: 105.904489ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:17.495675Z","caller":"traceutil/trace.go:171","msg":"trace[966911391] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"107.158423ms","start":"2026-02-27T20:57:17.388476Z","end":"2026-02-27T20:57:17.495634Z","steps":["trace[966911391] 'process raft request' (duration: 106.898153ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:17.49666Z","caller":"traceutil/trace.go:171","msg":"trace[481338442] transaction","detail":"{read_only:false; number_of_response:0; response_revision:310; }","duration":"105.839344ms","start":"2026-02-27T20:57:17.390804Z","end":"2026-02-27T20:57:17.496644Z","steps":["trace[481338442] 'process raft request' (duration: 105.623018ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:17.496634Z","caller":"traceutil/trace.go:171","msg":"trace[1702680513] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"105.90501ms","start":"2026-02-27T20:57:17.390654Z","end":"2026-02-27T20:57:17.496559Z","steps":["trace[1702680513] 'process raft request' (duration: 104.927067ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:17.586268Z","caller":"traceutil/trace.go:171","msg":"trace[1029584798] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"140.54523ms","start":"2026-02-27T20:57:17.445672Z","end":"2026-02-27T20:57:17.586217Z","steps":["trace[1029584798] 'process raft request' (duration: 87.224182ms)","trace[1029584798] 'compare' (duration: 53.139004ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:57:17.812067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.217585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:57:17.812188Z","caller":"traceutil/trace.go:171","msg":"trace[1794497672] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:0; response_revision:320; }","duration":"117.42052ms","start":"2026-02-27T20:57:17.694741Z","end":"2026-02-27T20:57:17.812161Z","steps":["trace[1794497672] 'range keys from in-memory index tree' (duration: 117.060614ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:20.917797Z","caller":"traceutil/trace.go:171","msg":"trace[1039259368] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:376; }","duration":"123.083386ms","start":"2026-02-27T20:57:20.794686Z","end":"2026-02-27T20:57:20.91777Z","steps":["trace[1039259368] 'read index received' (duration: 122.87934ms)","trace[1039259368] 'applied index is now lower than readState.Index' (duration: 202.695µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:57:20.917879Z","caller":"traceutil/trace.go:171","msg":"trace[523120233] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"163.00819ms","start":"2026-02-27T20:57:20.754822Z","end":"2026-02-27T20:57:20.91783Z","steps":["trace[523120233] 'process raft request' (duration: 162.826186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:20.918059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.365526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-27T20:57:20.918092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.976179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-27T20:57:20.918139Z","caller":"traceutil/trace.go:171","msg":"trace[451920494] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:364; }","duration":"116.035463ms","start":"2026-02-27T20:57:20.802091Z","end":"2026-02-27T20:57:20.918126Z","steps":["trace[451920494] 'agreement among raft nodes before linearized reading' (duration: 115.825828ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:20.918144Z","caller":"traceutil/trace.go:171","msg":"trace[1146647522] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:364; }","duration":"123.508337ms","start":"2026-02-27T20:57:20.794614Z","end":"2026-02-27T20:57:20.918123Z","steps":["trace[1146647522] 'agreement among raft nodes before linearized reading' (duration: 123.28382ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:22.034402Z","caller":"traceutil/trace.go:171","msg":"trace[656719769] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"111.265736ms","start":"2026-02-27T20:57:21.922903Z","end":"2026-02-27T20:57:22.034169Z","steps":["trace[656719769] 'process raft request' (duration: 86.7116ms)","trace[656719769] 'compare' (duration: 24.193788ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:57:38.908434Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697563431100673,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T20:57:39.040913Z","caller":"traceutil/trace.go:171","msg":"trace[1924246717] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"632.789042ms","start":"2026-02-27T20:57:38.408089Z","end":"2026-02-27T20:57:39.040879Z","steps":["trace[1924246717] 'read index received' (duration: 632.50963ms)","trace[1924246717] 'applied index is now lower than readState.Index' (duration: 277.821µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:57:39.041425Z","caller":"traceutil/trace.go:171","msg":"trace[1192716780] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"638.707617ms","start":"2026-02-27T20:57:38.402676Z","end":"2026-02-27T20:57:39.041383Z","steps":["trace[1192716780] 'process raft request' (duration: 637.953368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:39.041562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.555542ms","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-02-27T20:57:39.04168Z","caller":"traceutil/trace.go:171","msg":"trace[422447222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:474; }","duration":"609.686581ms","start":"2026-02-27T20:57:38.431951Z","end":"2026-02-27T20:57:39.041637Z","steps":["trace[422447222] 'agreement among raft nodes before linearized reading' (duration: 609.463014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:39.041738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:57:38.402653Z","time spent":"638.845188ms","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:57:39.041736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:57:38.431937Z","time spent":"609.788909ms","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T20:57:39.04178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.561312ms","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-02-27T20:57:39.042014Z","caller":"traceutil/trace.go:171","msg":"trace[1185216683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:474; }","duration":"633.832374ms","start":"2026-02-27T20:57:38.408066Z","end":"2026-02-27T20:57:39.041898Z","steps":["trace[1185216683] 'agreement among raft nodes before linearized reading' (duration: 633.28067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:39.042188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:57:38.408058Z","time spent":"634.073992ms","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T20:57:40.284391Z","caller":"traceutil/trace.go:171","msg":"trace[1188305448] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"530.252977ms","start":"2026-02-27T20:57:39.754115Z","end":"2026-02-27T20:57:40.284368Z","steps":["trace[1188305448] 'process raft request' (duration: 530.107105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:40.284552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:57:39.754078Z","time spent":"530.403009ms","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:57:44.530258Z","caller":"traceutil/trace.go:171","msg":"trace[957721978] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"171.914075ms","start":"2026-02-27T20:57:44.358318Z","end":"2026-02-27T20:57:44.530232Z","steps":["trace[957721978] 'read index received' (duration: 171.754022ms)","trace[957721978] 'applied index is now lower than readState.Index' (duration: 158.632µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:57:44.53042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.087229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:57:44.530458Z","caller":"traceutil/trace.go:171","msg":"trace[1937960631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"172.179667ms","start":"2026-02-27T20:57:44.358266Z","end":"2026-02-27T20:57:44.530446Z","steps":["trace[1937960631] 'agreement among raft nodes before linearized reading' (duration: 172.089679ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:44.530707Z","caller":"traceutil/trace.go:171","msg":"trace[1947996190] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"224.274337ms","start":"2026-02-27T20:57:44.306417Z","end":"2026-02-27T20:57:44.530691Z","steps":["trace[1947996190] 'process raft request' (duration: 223.655808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:44.765224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.593918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:57:44.765315Z","caller":"traceutil/trace.go:171","msg":"trace[1097894431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"231.750111ms","start":"2026-02-27T20:57:44.533548Z","end":"2026-02-27T20:57:44.765298Z","steps":["trace[1097894431] 'range keys from in-memory index tree' (duration: 231.481609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:44.765215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.745179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-02-27T20:57:44.765488Z","caller":"traceutil/trace.go:171","msg":"trace[1307186429] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:521; }","duration":"216.066834ms","start":"2026-02-27T20:57:44.549395Z","end":"2026-02-27T20:57:44.765461Z","steps":["trace[1307186429] 'range keys from in-memory index tree' (duration: 215.620609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:50.934219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.831019ms","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-02-27T20:57:50.934335Z","caller":"traceutil/trace.go:171","msg":"trace[1702913610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"368.991272ms","start":"2026-02-27T20:57:50.56532Z","end":"2026-02-27T20:57:50.934311Z","steps":["trace[1702913610] 'range keys from in-memory index tree' (duration: 368.630203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:50.934416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:57:50.565299Z","time spent":"369.10143ms","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-27T20:57:51.518196Z","caller":"traceutil/trace.go:171","msg":"trace[8219019] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"393.119409ms","start":"2026-02-27T20:57:51.125054Z","end":"2026-02-27T20:57:51.518174Z","steps":["trace[8219019] 'process raft request' (duration: 392.975388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:51.518345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:57:51.125042Z","time spent":"393.226618ms","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:57:51.64367Z","caller":"traceutil/trace.go:171","msg":"trace[1202106328] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:583; }","duration":"517.198628ms","start":"2026-02-27T20:57:51.126446Z","end":"2026-02-27T20:57:51.643644Z","steps":["trace[1202106328] 'read index received' (duration: 391.567194ms)","trace[1202106328] 'applied index is now lower than readState.Index' (duration: 125.630263ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:57:51.643832Z","caller":"traceutil/trace.go:171","msg":"trace[807155800] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"518.709519ms","start":"2026-02-27T20:57:51.125101Z","end":"2026-02-27T20:57:51.643811Z","steps":["trace[807155800] 'process raft request' (duration: 518.408155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:51.643932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:57:51.125095Z","time spent":"518.780915ms","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:57:51.644026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.504123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-27T20:57:51.6441Z","caller":"traceutil/trace.go:171","msg":"trace[873722869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"517.655465ms","start":"2026-02-27T20:57:51.12643Z","end":"2026-02-27T20:57:51.644085Z","steps":["trace[873722869] 'agreement among raft nodes before linearized reading' (duration: 517.365242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:51.644185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:57:51.126423Z","time spent":"517.749342ms","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T20:57:53.292152Z","caller":"traceutil/trace.go:171","msg":"trace[23046054] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"340.288927ms","start":"2026-02-27T20:57:52.951829Z","end":"2026-02-27T20:57:53.292118Z","steps":["trace[23046054] 'read index received' (duration: 340.059148ms)","trace[23046054] 'applied index is now lower than readState.Index' (duration: 228.338µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:57:53.2923Z","caller":"traceutil/trace.go:171","msg":"trace[1064076618] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"646.790468ms","start":"2026-02-27T20:57:52.645479Z","end":"2026-02-27T20:57:53.29227Z","steps":["trace[1064076618] 'process raft request' (duration: 646.482613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:53.292437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.58864ms","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-02-27T20:57:53.292471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:57:52.645456Z","time spent":"646.916737ms","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:57:53.292501Z","caller":"traceutil/trace.go:171","msg":"trace[253571940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:566; }","duration":"340.71275ms","start":"2026-02-27T20:57:52.951772Z","end":"2026-02-27T20:57:53.292484Z","steps":["trace[253571940] 'agreement among raft nodes before linearized reading' (duration: 340.508014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:53.29256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:57:52.951748Z","time spent":"340.798037ms","remote":"127.0.0.1:57350","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-02-27T20:57:54.023535Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697563431100867,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:57:54.370525Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.07445495s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-27T20:57:54.626275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.372409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:57:54.626494Z","caller":"traceutil/trace.go:171","msg":"trace[1379307475] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"1.103610154s","start":"2026-02-27T20:57:53.522745Z","end":"2026-02-27T20:57:54.626356Z","steps":["trace[1379307475] 'read index received' (duration: 847.959082ms)","trace[1379307475] 'applied index is now lower than readState.Index' (duration: 255.649891ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:57:54.626477Z","caller":"traceutil/trace.go:171","msg":"trace[1620582722] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"1.3304696s","start":"2026-02-27T20:57:53.295971Z","end":"2026-02-27T20:57:54.626441Z","steps":["trace[1620582722] 'process raft request' (duration: 1.074825239s)","trace[1620582722] 'compare' (duration: 255.229388ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:57:54.626679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103929239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-27T20:57:54.626674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:57:53.295945Z","time spent":"1.330621594s","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:57:54.626713Z","caller":"traceutil/trace.go:171","msg":"trace[1207969866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:567; }","duration":"1.103996115s","start":"2026-02-27T20:57:53.522707Z","end":"2026-02-27T20:57:54.626703Z","steps":["trace[1207969866] 'agreement among raft nodes before linearized reading' (duration: 1.103861984s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:54.626739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.626955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:57:54.626746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:57:53.52269Z","time spent":"1.104048588s","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T20:57:54.626712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.064996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-27T20:57:54.626882Z","caller":"traceutil/trace.go:171","msg":"trace[320899930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:567; }","duration":"979.266813ms","start":"2026-02-27T20:57:53.647604Z","end":"2026-02-27T20:57:54.626871Z","steps":["trace[320899930] 'agreement among raft nodes before linearized reading' (duration: 979.003782ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:57:54.626815Z","caller":"traceutil/trace.go:171","msg":"trace[1187940361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:567; }","duration":"268.702831ms","start":"2026-02-27T20:57:54.358096Z","end":"2026-02-27T20:57:54.626798Z","steps":["trace[1187940361] 'agreement among raft nodes before linearized reading' (duration: 268.599593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:54.626924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.144154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-27T20:57:54.626956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:57:53.647584Z","time spent":"979.36425ms","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T20:57:54.627025Z","caller":"traceutil/trace.go:171","msg":"trace[2124981822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:567; }","duration":"925.268134ms","start":"2026-02-27T20:57:53.701717Z","end":"2026-02-27T20:57:54.626985Z","steps":["trace[2124981822] 'agreement among raft nodes before linearized reading' (duration: 925.008053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:57:54.627083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:57:53.701699Z","time spent":"925.369262ms","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T20:57:56.723867Z","caller":"traceutil/trace.go:171","msg":"trace[692077538] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"140.146983ms","start":"2026-02-27T20:57:56.583691Z","end":"2026-02-27T20:57:56.723838Z","steps":["trace[692077538] 'process raft request' (duration: 139.810979ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:59:29.504868Z","caller":"traceutil/trace.go:171","msg":"trace[952104700] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"296.855676ms","start":"2026-02-27T20:59:29.207982Z","end":"2026-02-27T20:59:29.504837Z","steps":["trace[952104700] 'process raft request' (duration: 296.571474ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:59:29.578495Z","caller":"traceutil/trace.go:171","msg":"trace[1832495809] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1062; }","duration":"368.155546ms","start":"2026-02-27T20:59:29.210306Z","end":"2026-02-27T20:59:29.578462Z","steps":["trace[1832495809] 'read index received' (duration: 294.239517ms)","trace[1832495809] 'applied index is now lower than readState.Index' (duration: 73.914649ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:59:29.578556Z","caller":"traceutil/trace.go:171","msg":"trace[1430870955] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"369.314005ms","start":"2026-02-27T20:59:29.209201Z","end":"2026-02-27T20:59:29.578515Z","steps":["trace[1430870955] 'process raft request' (duration: 369.059114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:59:29.578898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.545668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-27T20:59:29.578792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.46206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-27T20:59:29.578975Z","caller":"traceutil/trace.go:171","msg":"trace[158491077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1020; }","duration":"365.626261ms","start":"2026-02-27T20:59:29.213328Z","end":"2026-02-27T20:59:29.578955Z","steps":["trace[158491077] 'agreement among raft nodes before linearized reading' (duration: 365.3751ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:59:29.579019Z","caller":"traceutil/trace.go:171","msg":"trace[589160246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"368.70477ms","start":"2026-02-27T20:59:29.210272Z","end":"2026-02-27T20:59:29.578977Z","steps":["trace[589160246] 'agreement among raft nodes before linearized reading' (duration: 368.355695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:59:29.579068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:59:29.213284Z","time spent":"365.733706ms","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T20:59:29.579133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:59:29.210265Z","time spent":"368.831535ms","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T20:59:29.579068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:59:29.209178Z","time spent":"369.706631ms","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}