{"level":"warn","ts":"2026-02-19T18:52:13.71147Z","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-19T18:52:13.711559Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140: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.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140: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-19T18:52:13.711647Z","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-19T18:52:13.711665Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-02-19T18:52:13.71169Z","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-19T18:52:13.714495Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-02-19T18:52:13.714623Z","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.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140: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-19T18:52:13.720966Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.110601ms"} {"level":"info","ts":"2026-02-19T18:52:13.726524Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-02-19T18:52:13.726594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-02-19T18:52:13.726617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-02-19T18:52:13.726634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-19T18:52:13.726648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-02-19T18:52:13.726694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-02-19T18:52:13.739351Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-19T18:52:13.741106Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-19T18:52:13.742335Z","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-19T18:52:13.744361Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-19T18:52:13.744451Z","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-19T18:52:13.744538Z","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-19T18:52:13.74455Z","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-19T18:52:13.745695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-02-19T18:52:13.745866Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-02-19T18:52:13.74603Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-19T18:52:13.750522Z","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-19T18:52:13.750608Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-02-19T18:52:13.750627Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-02-19T18:52:13.751088Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-19T18:52:13.751116Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-19T18:52:13.827773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-02-19T18:52:13.827818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-02-19T18:52:13.827887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-02-19T18:52:13.827915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-02-19T18:52:13.827935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-02-19T18:52:13.827987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-02-19T18:52:13.828007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-02-19T18:52:13.829261Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-02-19T18:52:13.829261Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T18:52:13.829464Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T18:52:13.829579Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-19T18:52:13.829611Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-19T18:52:13.83008Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T18:52:13.831956Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T18:52:13.832102Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T18:52:13.832182Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T18:52:13.832935Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-19T18:52:13.833433Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"warn","ts":"2026-02-19T18:52:16.902047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.125925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-19T18:52:16.902147Z","caller":"traceutil/trace.go:171","msg":"trace[1123342940] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"115.502475ms","start":"2026-02-19T18:52:16.786632Z","end":"2026-02-19T18:52:16.902135Z","steps":["trace[1123342940] 'process raft request' (duration: 12.868328ms)","trace[1123342940] 'compare' (duration: 102.029253ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:52:17.274474Z","caller":"traceutil/trace.go:171","msg":"trace[329685935] transaction","detail":"{read_only:false; response_revision:97; number_of_response:1; }","duration":"157.042935ms","start":"2026-02-19T18:52:17.117384Z","end":"2026-02-19T18:52:17.274427Z","steps":["trace[329685935] 'process raft request' (duration: 72.213536ms)","trace[329685935] 'compare' (duration: 84.660365ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:52:17.821037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.493038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-19T18:52:17.821273Z","caller":"traceutil/trace.go:171","msg":"trace[110043161] linearizableReadLoop","detail":"{readStateIndex:118; appliedIndex:117; }","duration":"136.075227ms","start":"2026-02-19T18:52:17.685176Z","end":"2026-02-19T18:52:17.821251Z","steps":["trace[110043161] 'read index received' (duration: 27.1692ms)","trace[110043161] 'applied index is now lower than readState.Index' (duration: 108.904437ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:52:17.821381Z","caller":"traceutil/trace.go:171","msg":"trace[2018016822] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"174.323945ms","start":"2026-02-19T18:52:17.64696Z","end":"2026-02-19T18:52:17.821283Z","steps":["trace[2018016822] 'process raft request' (duration: 65.49966ms)","trace[2018016822] 'compare' (duration: 108.317004ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:52:17.821612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.286322ms","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-19T18:52:17.821694Z","caller":"traceutil/trace.go:171","msg":"trace[1705366637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:114; }","duration":"136.549048ms","start":"2026-02-19T18:52:17.685131Z","end":"2026-02-19T18:52:17.82168Z","steps":["trace[1705366637] 'agreement among raft nodes before linearized reading' (duration: 136.247701ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:18.071229Z","caller":"traceutil/trace.go:171","msg":"trace[941477531] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"194.873306ms","start":"2026-02-19T18:52:17.876327Z","end":"2026-02-19T18:52:18.0712Z","steps":["trace[941477531] 'process raft request' (duration: 194.806084ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:18.071239Z","caller":"traceutil/trace.go:171","msg":"trace[80554411] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"195.03047ms","start":"2026-02-19T18:52:17.876175Z","end":"2026-02-19T18:52:18.071205Z","steps":["trace[80554411] 'process raft request' (duration: 101.879174ms)","trace[80554411] 'compare' (duration: 92.91423ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:52:18.917339Z","caller":"traceutil/trace.go:171","msg":"trace[812653450] transaction","detail":"{read_only:false; response_revision:152; number_of_response:1; }","duration":"114.357528ms","start":"2026-02-19T18:52:18.802952Z","end":"2026-02-19T18:52:18.917309Z","steps":["trace[812653450] 'process raft request' (duration: 66.150948ms)","trace[812653450] 'compare' (duration: 48.095328ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:52:20.377359Z","caller":"traceutil/trace.go:171","msg":"trace[1970996745] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"106.119547ms","start":"2026-02-19T18:52:20.271211Z","end":"2026-02-19T18:52:20.377331Z","steps":["trace[1970996745] 'process raft request' (duration: 75.953743ms)","trace[1970996745] 'compare' (duration: 30.055141ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:52:20.378149Z","caller":"traceutil/trace.go:171","msg":"trace[171726160] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"104.676413ms","start":"2026-02-19T18:52:20.273449Z","end":"2026-02-19T18:52:20.378125Z","steps":["trace[171726160] 'process raft request' (duration: 104.52512ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:22.530307Z","caller":"traceutil/trace.go:171","msg":"trace[1936231618] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"135.32154ms","start":"2026-02-19T18:52:22.394959Z","end":"2026-02-19T18:52:22.530281Z","steps":["trace[1936231618] 'process raft request' (duration: 135.238708ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:22.530342Z","caller":"traceutil/trace.go:171","msg":"trace[527616819] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"158.231216ms","start":"2026-02-19T18:52:22.372083Z","end":"2026-02-19T18:52:22.530315Z","steps":["trace[527616819] 'process raft request' (duration: 63.865625ms)","trace[527616819] 'compare' (duration: 94.035193ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:52:22.530388Z","caller":"traceutil/trace.go:171","msg":"trace[2027421874] linearizableReadLoop","detail":"{readStateIndex:254; appliedIndex:252; }","duration":"138.904613ms","start":"2026-02-19T18:52:22.391447Z","end":"2026-02-19T18:52:22.530352Z","steps":["trace[2027421874] 'read index received' (duration: 44.513943ms)","trace[2027421874] 'applied index is now lower than readState.Index' (duration: 94.38633ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:52:22.530406Z","caller":"traceutil/trace.go:171","msg":"trace[523843492] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"157.649352ms","start":"2026-02-19T18:52:22.372735Z","end":"2026-02-19T18:52:22.530384Z","steps":["trace[523843492] 'process raft request' (duration: 157.380816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:22.530525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.978464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-19T18:52:22.530556Z","caller":"traceutil/trace.go:171","msg":"trace[1216463978] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:249; }","duration":"159.042375ms","start":"2026-02-19T18:52:22.371505Z","end":"2026-02-19T18:52:22.530547Z","steps":["trace[1216463978] 'agreement among raft nodes before linearized reading' (duration: 158.951253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:22.562214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.828241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:52:22.562336Z","caller":"traceutil/trace.go:171","msg":"trace[1416776426] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:251; }","duration":"167.990144ms","start":"2026-02-19T18:52:22.394327Z","end":"2026-02-19T18:52:22.562317Z","steps":["trace[1416776426] 'agreement among raft nodes before linearized reading' (duration: 167.81591ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:22.750354Z","caller":"traceutil/trace.go:171","msg":"trace[579615087] linearizableReadLoop","detail":"{readStateIndex:263; appliedIndex:260; }","duration":"186.845446ms","start":"2026-02-19T18:52:22.563474Z","end":"2026-02-19T18:52:22.750319Z","steps":["trace[579615087] 'read index received' (duration: 151.611001ms)","trace[579615087] 'applied index is now lower than readState.Index' (duration: 35.232505ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:52:22.750378Z","caller":"traceutil/trace.go:171","msg":"trace[1578225866] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"209.045866ms","start":"2026-02-19T18:52:22.541293Z","end":"2026-02-19T18:52:22.750339Z","steps":["trace[1578225866] 'process raft request' (duration: 173.7667ms)","trace[1578225866] 'compare' (duration: 34.852136ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:52:22.750564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.065181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5875"} {"level":"info","ts":"2026-02-19T18:52:22.750622Z","caller":"traceutil/trace.go:171","msg":"trace[852861073] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:253; }","duration":"187.179923ms","start":"2026-02-19T18:52:22.563434Z","end":"2026-02-19T18:52:22.750614Z","steps":["trace[852861073] 'agreement among raft nodes before linearized reading' (duration: 187.01623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:22.750653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.790944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3444"} {"level":"info","ts":"2026-02-19T18:52:22.750704Z","caller":"traceutil/trace.go:171","msg":"trace[139294141] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:253; }","duration":"186.932657ms","start":"2026-02-19T18:52:22.563757Z","end":"2026-02-19T18:52:22.75069Z","steps":["trace[139294141] 'agreement among raft nodes before linearized reading' (duration: 186.783784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:22.750694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.184767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-19T18:52:22.750763Z","caller":"traceutil/trace.go:171","msg":"trace[1130037478] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:253; }","duration":"185.31029ms","start":"2026-02-19T18:52:22.565436Z","end":"2026-02-19T18:52:22.750746Z","steps":["trace[1130037478] 'agreement among raft nodes before linearized reading' (duration: 185.131455ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:22.750491Z","caller":"traceutil/trace.go:171","msg":"trace[1311705679] transaction","detail":"{read_only:false; number_of_response:0; response_revision:253; }","duration":"201.858888ms","start":"2026-02-19T18:52:22.548594Z","end":"2026-02-19T18:52:22.750453Z","steps":["trace[1311705679] 'process raft request' (duration: 201.659433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:22.750973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.848016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2898"} {"level":"info","ts":"2026-02-19T18:52:22.751008Z","caller":"traceutil/trace.go:171","msg":"trace[1295756552] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:253; }","duration":"186.908518ms","start":"2026-02-19T18:52:22.564092Z","end":"2026-02-19T18:52:22.751Z","steps":["trace[1295756552] 'agreement among raft nodes before linearized reading' (duration: 186.840906ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:22.751598Z","caller":"traceutil/trace.go:171","msg":"trace[950627474] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"203.132427ms","start":"2026-02-19T18:52:22.54842Z","end":"2026-02-19T18:52:22.751552Z","steps":["trace[950627474] 'process raft request' (duration: 201.759615ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:22.94715Z","caller":"traceutil/trace.go:171","msg":"trace[1182688605] linearizableReadLoop","detail":"{readStateIndex:268; appliedIndex:266; }","duration":"127.711711ms","start":"2026-02-19T18:52:22.819405Z","end":"2026-02-19T18:52:22.947117Z","steps":["trace[1182688605] 'read index received' (duration: 80.417504ms)","trace[1182688605] 'applied index is now lower than readState.Index' (duration: 47.292747ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:52:22.947163Z","caller":"traceutil/trace.go:171","msg":"trace[1278875024] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"146.396398ms","start":"2026-02-19T18:52:22.800721Z","end":"2026-02-19T18:52:22.947118Z","steps":["trace[1278875024] 'process raft request' (duration: 99.036069ms)","trace[1278875024] 'compare' (duration: 47.092443ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:52:22.947398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.977327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-19T18:52:22.947484Z","caller":"traceutil/trace.go:171","msg":"trace[1817157855] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:258; }","duration":"128.11455ms","start":"2026-02-19T18:52:22.819357Z","end":"2026-02-19T18:52:22.947471Z","steps":["trace[1817157855] 'agreement among raft nodes before linearized reading' (duration: 127.875195ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:22.947288Z","caller":"traceutil/trace.go:171","msg":"trace[788180317] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"145.284522ms","start":"2026-02-19T18:52:22.801926Z","end":"2026-02-19T18:52:22.947211Z","steps":["trace[788180317] 'process raft request' (duration: 145.109458ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:23.068195Z","caller":"traceutil/trace.go:171","msg":"trace[1678337533] linearizableReadLoop","detail":"{readStateIndex:271; appliedIndex:270; }","duration":"105.406601ms","start":"2026-02-19T18:52:22.962762Z","end":"2026-02-19T18:52:23.068168Z","steps":["trace[1678337533] 'read index received' (duration: 90.48469ms)","trace[1678337533] 'applied index is now lower than readState.Index' (duration: 14.92068ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:52:23.068324Z","caller":"traceutil/trace.go:171","msg":"trace[1638568116] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"110.224734ms","start":"2026-02-19T18:52:22.958053Z","end":"2026-02-19T18:52:23.068278Z","steps":["trace[1638568116] 'process raft request' (duration: 95.198292ms)","trace[1638568116] 'compare' (duration: 14.805117ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:52:23.068483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.680268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:52:23.068581Z","caller":"traceutil/trace.go:171","msg":"trace[651660759] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:0; response_revision:261; }","duration":"105.830931ms","start":"2026-02-19T18:52:22.962708Z","end":"2026-02-19T18:52:23.068539Z","steps":["trace[651660759] 'agreement among raft nodes before linearized reading' (duration: 105.611146ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:24.410944Z","caller":"traceutil/trace.go:171","msg":"trace[254750490] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"101.695439ms","start":"2026-02-19T18:52:24.309203Z","end":"2026-02-19T18:52:24.410899Z","steps":["trace[254750490] 'process raft request' (duration: 68.088369ms)","trace[254750490] 'compare' (duration: 33.418496ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:52:25.778037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.884079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:52:25.77815Z","caller":"traceutil/trace.go:171","msg":"trace[1846695135] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:0; response_revision:296; }","duration":"111.049213ms","start":"2026-02-19T18:52:25.667076Z","end":"2026-02-19T18:52:25.778126Z","steps":["trace[1846695135] 'range keys from in-memory index tree' (duration: 110.742066ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:26.543035Z","caller":"traceutil/trace.go:171","msg":"trace[2004838110] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:320; }","duration":"176.751205ms","start":"2026-02-19T18:52:26.366261Z","end":"2026-02-19T18:52:26.543012Z","steps":["trace[2004838110] 'read index received' (duration: 16.026538ms)","trace[2004838110] 'applied index is now lower than readState.Index' (duration: 160.723637ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:52:26.543966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.515635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-19T18:52:26.544333Z","caller":"traceutil/trace.go:171","msg":"trace[596621290] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:309; }","duration":"127.741341ms","start":"2026-02-19T18:52:26.416349Z","end":"2026-02-19T18:52:26.54409Z","steps":["trace[596621290] 'agreement among raft nodes before linearized reading' (duration: 127.504445ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:26.544445Z","caller":"traceutil/trace.go:171","msg":"trace[1882719320] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"214.593007ms","start":"2026-02-19T18:52:26.329822Z","end":"2026-02-19T18:52:26.544415Z","steps":["trace[1882719320] 'process raft request' (duration: 213.129413ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:26.544683Z","caller":"traceutil/trace.go:171","msg":"trace[1356841063] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"214.30766ms","start":"2026-02-19T18:52:26.328802Z","end":"2026-02-19T18:52:26.543109Z","steps":["trace[1356841063] 'process raft request' (duration: 180.74178ms)","trace[1356841063] 'compare' (duration: 33.272484ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:52:26.545264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.991148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-19T18:52:26.545326Z","caller":"traceutil/trace.go:171","msg":"trace[1271543489] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:309; }","duration":"179.09671ms","start":"2026-02-19T18:52:26.366201Z","end":"2026-02-19T18:52:26.545298Z","steps":["trace[1271543489] 'agreement among raft nodes before linearized reading' (duration: 176.95528ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:26.747128Z","caller":"traceutil/trace.go:171","msg":"trace[814742257] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:324; }","duration":"181.864316ms","start":"2026-02-19T18:52:26.56524Z","end":"2026-02-19T18:52:26.747105Z","steps":["trace[814742257] 'read index received' (duration: 41.128949ms)","trace[814742257] 'applied index is now lower than readState.Index' (duration: 140.734277ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:52:26.747437Z","caller":"traceutil/trace.go:171","msg":"trace[173704911] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"190.588682ms","start":"2026-02-19T18:52:26.556835Z","end":"2026-02-19T18:52:26.747424Z","steps":["trace[173704911] 'process raft request' (duration: 190.033519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:26.747694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.431779ms","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-02-19T18:52:26.747788Z","caller":"traceutil/trace.go:171","msg":"trace[1389904391] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:313; }","duration":"182.570773ms","start":"2026-02-19T18:52:26.565199Z","end":"2026-02-19T18:52:26.747769Z","steps":["trace[1389904391] 'agreement among raft nodes before linearized reading' (duration: 182.389678ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:26.747836Z","caller":"traceutil/trace.go:171","msg":"trace[1495440470] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"190.912699ms","start":"2026-02-19T18:52:26.556912Z","end":"2026-02-19T18:52:26.747825Z","steps":["trace[1495440470] 'process raft request' (duration: 190.10398ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:26.748039Z","caller":"traceutil/trace.go:171","msg":"trace[1217050569] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"190.642773ms","start":"2026-02-19T18:52:26.557383Z","end":"2026-02-19T18:52:26.748026Z","steps":["trace[1217050569] 'process raft request' (duration: 189.68273ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:26.794338Z","caller":"traceutil/trace.go:171","msg":"trace[252627292] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"185.317418ms","start":"2026-02-19T18:52:26.609002Z","end":"2026-02-19T18:52:26.794319Z","steps":["trace[252627292] 'process raft request' (duration: 185.178934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:26.794489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.909351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3843"} {"level":"warn","ts":"2026-02-19T18:52:26.794607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.979448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-19T18:52:26.794645Z","caller":"traceutil/trace.go:171","msg":"trace[1885986930] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:314; }","duration":"179.05782ms","start":"2026-02-19T18:52:26.615576Z","end":"2026-02-19T18:52:26.794634Z","steps":["trace[1885986930] 'agreement among raft nodes before linearized reading' (duration: 178.964338ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:26.79467Z","caller":"traceutil/trace.go:171","msg":"trace[1626963049] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:314; }","duration":"124.130846ms","start":"2026-02-19T18:52:26.6705Z","end":"2026-02-19T18:52:26.794631Z","steps":["trace[1626963049] 'agreement among raft nodes before linearized reading' (duration: 123.834039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:26.79456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.745954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-19T18:52:26.794764Z","caller":"traceutil/trace.go:171","msg":"trace[1475173376] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:314; }","duration":"128.98596ms","start":"2026-02-19T18:52:26.66576Z","end":"2026-02-19T18:52:26.794746Z","steps":["trace[1475173376] 'agreement among raft nodes before linearized reading' (duration: 128.730364ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:27.115Z","caller":"traceutil/trace.go:171","msg":"trace[1300634949] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"104.22524ms","start":"2026-02-19T18:52:27.010754Z","end":"2026-02-19T18:52:27.11498Z","steps":["trace[1300634949] 'process raft request' (duration: 104.187139ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:27.115014Z","caller":"traceutil/trace.go:171","msg":"trace[1200401942] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"105.5036ms","start":"2026-02-19T18:52:27.009483Z","end":"2026-02-19T18:52:27.114986Z","steps":["trace[1200401942] 'process raft request' (duration: 95.557126ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:27.115026Z","caller":"traceutil/trace.go:171","msg":"trace[1845117363] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:340; }","duration":"105.367826ms","start":"2026-02-19T18:52:27.009644Z","end":"2026-02-19T18:52:27.115011Z","steps":["trace[1845117363] 'read index received' (duration: 95.411642ms)","trace[1845117363] 'applied index is now lower than readState.Index' (duration: 9.954184ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:52:27.115172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.50624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-02-19T18:52:27.115375Z","caller":"traceutil/trace.go:171","msg":"trace[1484914216] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:328; }","duration":"105.614392ms","start":"2026-02-19T18:52:27.009601Z","end":"2026-02-19T18:52:27.115215Z","steps":["trace[1484914216] 'agreement among raft nodes before linearized reading' (duration: 105.448368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:27.490588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.392676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T18:52:27.490944Z","caller":"traceutil/trace.go:171","msg":"trace[1213662168] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"220.205057ms","start":"2026-02-19T18:52:27.270706Z","end":"2026-02-19T18:52:27.490911Z","steps":["trace[1213662168] 'process raft request' (duration: 103.422671ms)","trace[1213662168] 'compare' (duration: 116.259774ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:52:27.490961Z","caller":"traceutil/trace.go:171","msg":"trace[1455310022] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"218.377773ms","start":"2026-02-19T18:52:27.272518Z","end":"2026-02-19T18:52:27.490896Z","steps":["trace[1455310022] 'process raft request' (duration: 218.166108ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:27.491029Z","caller":"traceutil/trace.go:171","msg":"trace[1767750495] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"215.200499ms","start":"2026-02-19T18:52:27.275797Z","end":"2026-02-19T18:52:27.490997Z","steps":["trace[1767750495] 'process raft request' (duration: 215.071676ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:27.49097Z","caller":"traceutil/trace.go:171","msg":"trace[33014259] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"217.460422ms","start":"2026-02-19T18:52:27.273494Z","end":"2026-02-19T18:52:27.490954Z","steps":["trace[33014259] 'process raft request' (duration: 217.263787ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:27.656856Z","caller":"traceutil/trace.go:171","msg":"trace[2067310469] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"105.364487ms","start":"2026-02-19T18:52:27.551466Z","end":"2026-02-19T18:52:27.65683Z","steps":["trace[2067310469] 'process raft request' (duration: 103.666416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:28.093774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.862468ms","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-19T18:52:28.093861Z","caller":"traceutil/trace.go:171","msg":"trace[1314088931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:346; }","duration":"180.017992ms","start":"2026-02-19T18:52:27.913822Z","end":"2026-02-19T18:52:28.09384Z","steps":["trace[1314088931] 'range keys from in-memory index tree' (duration: 179.765806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:28.093768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.14239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-19T18:52:28.094Z","caller":"traceutil/trace.go:171","msg":"trace[512671691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:346; }","duration":"276.473358ms","start":"2026-02-19T18:52:27.817505Z","end":"2026-02-19T18:52:28.093978Z","steps":["trace[512671691] 'range keys from in-memory index tree' (duration: 276.030477ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:28.665871Z","caller":"traceutil/trace.go:171","msg":"trace[1787342704] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"567.46518ms","start":"2026-02-19T18:52:28.098381Z","end":"2026-02-19T18:52:28.665846Z","steps":["trace[1787342704] 'process raft request' (duration: 567.286145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:28.666235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:28.09835Z","time spent":"567.605412ms","remote":"127.0.0.1:57526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:52:29.393491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.545304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T18:52:29.393575Z","caller":"traceutil/trace.go:171","msg":"trace[1852415998] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:360; }","duration":"1.077085426s","start":"2026-02-19T18:52:28.316477Z","end":"2026-02-19T18:52:29.393562Z","steps":["trace[1852415998] 'read index received' (duration: 349.264353ms)","trace[1852415998] 'applied index is now lower than readState.Index' (duration: 727.820273ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:52:29.393603Z","caller":"traceutil/trace.go:171","msg":"trace[1442190295] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"1.2951545s","start":"2026-02-19T18:52:28.098429Z","end":"2026-02-19T18:52:29.393583Z","steps":["trace[1442190295] 'process raft request' (duration: 1.059450912s)","trace[1442190295] 'compare' (duration: 235.431401ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:52:29.393659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006291172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-19T18:52:29.393673Z","caller":"traceutil/trace.go:171","msg":"trace[1309285237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:348; }","duration":"1.006342964s","start":"2026-02-19T18:52:28.387325Z","end":"2026-02-19T18:52:29.393668Z","steps":["trace[1309285237] 'agreement among raft nodes before linearized reading' (duration: 1.006304063s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:29.393688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:28.387302Z","time spent":"1.006383265s","remote":"127.0.0.1:57526","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-02-19T18:52:29.39369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:28.098336Z","time spent":"1.295311643s","remote":"127.0.0.1:57526","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-19T18:52:29.393764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077242609s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/coredns-5dd5756b68-6zkz7.1895ba8deec4a911\" ","response":"range_response_count:1 size:845"} {"level":"warn","ts":"2026-02-19T18:52:29.393805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.94363ms","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-19T18:52:29.393843Z","caller":"traceutil/trace.go:171","msg":"trace[1473476777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:348; }","duration":"844.995212ms","start":"2026-02-19T18:52:28.548839Z","end":"2026-02-19T18:52:29.393834Z","steps":["trace[1473476777] 'agreement among raft nodes before linearized reading' (duration: 844.905349ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:29.393841Z","caller":"traceutil/trace.go:171","msg":"trace[1434174604] range","detail":"{range_begin:/registry/events/kube-system/coredns-5dd5756b68-6zkz7.1895ba8deec4a911; range_end:; response_count:1; response_revision:348; }","duration":"1.077396593s","start":"2026-02-19T18:52:28.316424Z","end":"2026-02-19T18:52:29.393821Z","steps":["trace[1434174604] 'agreement among raft nodes before linearized reading' (duration: 1.077203439s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:29.393847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.743479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"warn","ts":"2026-02-19T18:52:29.393911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:28.548753Z","time spent":"845.142895ms","remote":"127.0.0.1:57526","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-19T18:52:29.393958Z","caller":"traceutil/trace.go:171","msg":"trace[1998206482] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:348; }","duration":"652.894543ms","start":"2026-02-19T18:52:28.741026Z","end":"2026-02-19T18:52:29.39392Z","steps":["trace[1998206482] 'agreement among raft nodes before linearized reading' (duration: 652.653887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:29.393883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:28.316404Z","time spent":"1.077469704s","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":868,"request content":"key:\"/registry/events/kube-system/coredns-5dd5756b68-6zkz7.1895ba8deec4a911\" "} {"level":"warn","ts":"2026-02-19T18:52:29.394022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:28.741007Z","time spent":"653.000105ms","remote":"127.0.0.1:57468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4283,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-02-19T18:52:29.512544Z","caller":"traceutil/trace.go:171","msg":"trace[356064489] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"110.334ms","start":"2026-02-19T18:52:29.402179Z","end":"2026-02-19T18:52:29.512513Z","steps":["trace[356064489] 'process raft request' (duration: 106.350796ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:29.627387Z","caller":"traceutil/trace.go:171","msg":"trace[1619922819] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"107.218767ms","start":"2026-02-19T18:52:29.519887Z","end":"2026-02-19T18:52:29.627106Z","steps":["trace[1619922819] 'process raft request' (duration: 107.038133ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:30.055567Z","caller":"traceutil/trace.go:171","msg":"trace[439718420] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:367; }","duration":"150.573135ms","start":"2026-02-19T18:52:29.904976Z","end":"2026-02-19T18:52:30.05555Z","steps":["trace[439718420] 'read index received' (duration: 150.492373ms)","trace[439718420] 'applied index is now lower than readState.Index' (duration: 79.952µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:52:30.055618Z","caller":"traceutil/trace.go:171","msg":"trace[616692694] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"167.933506ms","start":"2026-02-19T18:52:29.887652Z","end":"2026-02-19T18:52:30.055585Z","steps":["trace[616692694] 'process raft request' (duration: 167.722201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:30.055729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.738639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-19T18:52:30.055878Z","caller":"traceutil/trace.go:171","msg":"trace[1451231970] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:354; }","duration":"150.920743ms","start":"2026-02-19T18:52:29.904946Z","end":"2026-02-19T18:52:30.055867Z","steps":["trace[1451231970] 'agreement among raft nodes before linearized reading' (duration: 150.718879ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:30.164165Z","caller":"traceutil/trace.go:171","msg":"trace[1526784992] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"223.860462ms","start":"2026-02-19T18:52:29.940256Z","end":"2026-02-19T18:52:30.164116Z","steps":["trace[1526784992] 'process raft request' (duration: 221.351323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:30.757339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.160378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T18:52:30.757979Z","caller":"traceutil/trace.go:171","msg":"trace[2100817191] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"221.280093ms","start":"2026-02-19T18:52:30.536648Z","end":"2026-02-19T18:52:30.757929Z","steps":["trace[2100817191] 'process raft request' (duration: 48.461319ms)","trace[2100817191] 'compare' (duration: 172.032625ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:52:39.283229Z","caller":"traceutil/trace.go:171","msg":"trace[324548983] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"309.521365ms","start":"2026-02-19T18:52:38.973683Z","end":"2026-02-19T18:52:39.283204Z","steps":["trace[324548983] 'read index received' (duration: 309.382332ms)","trace[324548983] 'applied index is now lower than readState.Index' (duration: 137.543µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:52:39.283355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.653458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:52:39.283348Z","caller":"traceutil/trace.go:171","msg":"trace[1326464747] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"480.911264ms","start":"2026-02-19T18:52:38.802403Z","end":"2026-02-19T18:52:39.283314Z","steps":["trace[1326464747] 'process raft request' (duration: 480.662438ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:39.283388Z","caller":"traceutil/trace.go:171","msg":"trace[570122067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"309.73088ms","start":"2026-02-19T18:52:38.973646Z","end":"2026-02-19T18:52:39.283377Z","steps":["trace[570122067] 'agreement among raft nodes before linearized reading' (duration: 309.657218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:39.283434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:38.973631Z","time spent":"309.795501ms","remote":"127.0.0.1:57256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T18:52:39.283516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:38.802376Z","time spent":"481.051587ms","remote":"127.0.0.1:57526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:52:50.006339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.088321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T18:52:50.006467Z","caller":"traceutil/trace.go:171","msg":"trace[198006653] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"784.254362ms","start":"2026-02-19T18:52:49.222193Z","end":"2026-02-19T18:52:50.006447Z","steps":["trace[198006653] 'process raft request' (duration: 306.986727ms)","trace[198006653] 'compare' (duration: 476.911877ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:52:50.006527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:49.222182Z","time spent":"784.324375ms","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3805,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T18:52:50.007242Z","caller":"traceutil/trace.go:171","msg":"trace[32866253] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:467; }","duration":"678.302426ms","start":"2026-02-19T18:52:49.328925Z","end":"2026-02-19T18:52:50.007227Z","steps":["trace[32866253] 'read index received' (duration: 200.269683ms)","trace[32866253] 'applied index is now lower than readState.Index' (duration: 478.031803ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:52:50.007377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.441689ms","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-19T18:52:50.007413Z","caller":"traceutil/trace.go:171","msg":"trace[1001880283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:448; }","duration":"678.517621ms","start":"2026-02-19T18:52:49.328884Z","end":"2026-02-19T18:52:50.007402Z","steps":["trace[1001880283] 'agreement among raft nodes before linearized reading' (duration: 678.411679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:50.007446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:49.328866Z","time spent":"678.572652ms","remote":"127.0.0.1:57526","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-19T18:52:50.007728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.156563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-19T18:52:50.007768Z","caller":"traceutil/trace.go:171","msg":"trace[1832613145] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:448; }","duration":"356.241475ms","start":"2026-02-19T18:52:49.651516Z","end":"2026-02-19T18:52:50.007757Z","steps":["trace[1832613145] 'agreement among raft nodes before linearized reading' (duration: 356.176363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:50.007798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:49.651492Z","time spent":"356.299917ms","remote":"127.0.0.1:57526","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":"info","ts":"2026-02-19T18:52:50.007847Z","caller":"traceutil/trace.go:171","msg":"trace[210516504] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"781.497316ms","start":"2026-02-19T18:52:49.226317Z","end":"2026-02-19T18:52:50.007814Z","steps":["trace[210516504] 'process raft request' (duration: 780.851041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:50.007894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.385058ms","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-19T18:52:50.007921Z","caller":"traceutil/trace.go:171","msg":"trace[408469858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:448; }","duration":"351.42851ms","start":"2026-02-19T18:52:49.656485Z","end":"2026-02-19T18:52:50.007913Z","steps":["trace[408469858] 'agreement among raft nodes before linearized reading' (duration: 351.381949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:50.007953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:49.656475Z","time spent":"351.472761ms","remote":"127.0.0.1:57526","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":"warn","ts":"2026-02-19T18:52:50.007919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.270232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-19T18:52:50.008048Z","caller":"traceutil/trace.go:171","msg":"trace[1161513602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:448; }","duration":"350.428235ms","start":"2026-02-19T18:52:49.657575Z","end":"2026-02-19T18:52:50.008003Z","steps":["trace[1161513602] 'agreement among raft nodes before linearized reading' (duration: 350.174009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:50.008123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:49.657565Z","time spent":"350.528558ms","remote":"127.0.0.1:57526","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-02-19T18:52:50.007972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:49.226291Z","time spent":"781.626649ms","remote":"127.0.0.1:57732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10217,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T18:52:50.008584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.065502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-19T18:52:50.008641Z","caller":"traceutil/trace.go:171","msg":"trace[660958901] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:448; }","duration":"102.131553ms","start":"2026-02-19T18:52:49.906492Z","end":"2026-02-19T18:52:50.008623Z","steps":["trace[660958901] 'agreement among raft nodes before linearized reading' (duration: 101.209721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:52.523989Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603953089913379,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T18:52:52.595353Z","caller":"traceutil/trace.go:171","msg":"trace[96387039] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"571.813523ms","start":"2026-02-19T18:52:52.023513Z","end":"2026-02-19T18:52:52.595327Z","steps":["trace[96387039] 'read index received' (duration: 571.602597ms)","trace[96387039] 'applied index is now lower than readState.Index' (duration: 210.025µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:52:52.595483Z","caller":"traceutil/trace.go:171","msg":"trace[1520556788] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"600.709291ms","start":"2026-02-19T18:52:51.994731Z","end":"2026-02-19T18:52:52.59544Z","steps":["trace[1520556788] 'process raft request' (duration: 600.442485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:52.595623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.144516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-19T18:52:52.595621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.70066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-19T18:52:52.595664Z","caller":"traceutil/trace.go:171","msg":"trace[1955425683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:475; }","duration":"571.200347ms","start":"2026-02-19T18:52:52.02445Z","end":"2026-02-19T18:52:52.59565Z","steps":["trace[1955425683] 'agreement among raft nodes before linearized reading' (duration: 571.125046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:52.595667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:51.994716Z","time spent":"600.854604ms","remote":"127.0.0.1:57460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:52:52.595677Z","caller":"traceutil/trace.go:171","msg":"trace[266890680] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:475; }","duration":"571.763762ms","start":"2026-02-19T18:52:52.023896Z","end":"2026-02-19T18:52:52.59566Z","steps":["trace[266890680] 'agreement among raft nodes before linearized reading' (duration: 571.669339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:52.595699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:52.024439Z","time spent":"571.252829ms","remote":"127.0.0.1:57526","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-02-19T18:52:52.595674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.526926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-19T18:52:52.595749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:52.023893Z","time spent":"571.840953ms","remote":"127.0.0.1:57526","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":"info","ts":"2026-02-19T18:52:52.595775Z","caller":"traceutil/trace.go:171","msg":"trace[1150863701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:475; }","duration":"571.67704ms","start":"2026-02-19T18:52:52.02407Z","end":"2026-02-19T18:52:52.595747Z","steps":["trace[1150863701] 'agreement among raft nodes before linearized reading' (duration: 571.525996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:52.595837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:52.024052Z","time spent":"571.768801ms","remote":"127.0.0.1:57526","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":"warn","ts":"2026-02-19T18:52:52.595527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.989387ms","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-19T18:52:52.595981Z","caller":"traceutil/trace.go:171","msg":"trace[1748578539] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:475; }","duration":"572.485939ms","start":"2026-02-19T18:52:52.023482Z","end":"2026-02-19T18:52:52.595968Z","steps":["trace[1748578539] 'agreement among raft nodes before linearized reading' (duration: 571.985107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:52.596012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:52.023467Z","time spent":"572.53535ms","remote":"127.0.0.1:57526","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-19T18:52:59.20161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603953089913459,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T18:52:59.205251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.515791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-19T18:52:59.20534Z","caller":"traceutil/trace.go:171","msg":"trace[1573453351] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:515; }","duration":"504.640115ms","start":"2026-02-19T18:52:58.700677Z","end":"2026-02-19T18:52:59.205317Z","steps":["trace[1573453351] 'agreement among raft nodes before linearized reading' (duration: 504.144973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:59.205404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:58.700659Z","time spent":"504.733817ms","remote":"127.0.0.1:57526","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":"info","ts":"2026-02-19T18:52:59.206035Z","caller":"traceutil/trace.go:171","msg":"trace[89096215] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"504.090331ms","start":"2026-02-19T18:52:58.700716Z","end":"2026-02-19T18:52:59.204806Z","steps":["trace[89096215] 'read index received' (duration: 503.63442ms)","trace[89096215] 'applied index is now lower than readState.Index' (duration: 454.561µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:52:59.20601Z","caller":"traceutil/trace.go:171","msg":"trace[1036958986] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"638.82017ms","start":"2026-02-19T18:52:58.567158Z","end":"2026-02-19T18:52:59.205978Z","steps":["trace[1036958986] 'process raft request' (duration: 637.17966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:59.206397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:58.567123Z","time spent":"639.176998ms","remote":"127.0.0.1:57468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:52:59.206539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.40526ms","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-19T18:52:59.20658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.122033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:52:59.206574Z","caller":"traceutil/trace.go:171","msg":"trace[899831922] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:515; }","duration":"502.465992ms","start":"2026-02-19T18:52:58.704087Z","end":"2026-02-19T18:52:59.206554Z","steps":["trace[899831922] 'agreement among raft nodes before linearized reading' (duration: 502.305558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:59.206775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:58.704067Z","time spent":"502.698058ms","remote":"127.0.0.1:57526","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-19T18:52:59.206899Z","caller":"traceutil/trace.go:171","msg":"trace[616893706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"232.255166ms","start":"2026-02-19T18:52:58.974387Z","end":"2026-02-19T18:52:59.206643Z","steps":["trace[616893706] 'agreement among raft nodes before linearized reading' (duration: 232.097732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:59.207291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.426361ms","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-19T18:52:59.207342Z","caller":"traceutil/trace.go:171","msg":"trace[403384402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"502.528783ms","start":"2026-02-19T18:52:58.704796Z","end":"2026-02-19T18:52:59.207325Z","steps":["trace[403384402] 'agreement among raft nodes before linearized reading' (duration: 502.201136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:59.207624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:58.704776Z","time spent":"502.662986ms","remote":"127.0.0.1:57526","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":"warn","ts":"2026-02-19T18:52:59.207657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.486666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-19T18:52:59.207694Z","caller":"traceutil/trace.go:171","msg":"trace[2097228387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:515; }","duration":"503.527818ms","start":"2026-02-19T18:52:58.704147Z","end":"2026-02-19T18:52:59.207674Z","steps":["trace[2097228387] 'agreement among raft nodes before linearized reading' (duration: 503.130858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:52:59.207807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:58.704142Z","time spent":"503.564639ms","remote":"127.0.0.1:57526","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":"info","ts":"2026-02-19T18:52:59.380127Z","caller":"traceutil/trace.go:171","msg":"trace[107545796] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"156.505088ms","start":"2026-02-19T18:52:59.223602Z","end":"2026-02-19T18:52:59.380107Z","steps":["trace[107545796] 'process raft request' (duration: 156.466777ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:52:59.380163Z","caller":"traceutil/trace.go:171","msg":"trace[2083057577] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"161.966671ms","start":"2026-02-19T18:52:59.218157Z","end":"2026-02-19T18:52:59.380124Z","steps":["trace[2083057577] 'process raft request' (duration: 81.777395ms)","trace[2083057577] 'compare' (duration: 79.998591ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:52:59.380225Z","caller":"traceutil/trace.go:171","msg":"trace[1525858294] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"161.986921ms","start":"2026-02-19T18:52:59.218194Z","end":"2026-02-19T18:52:59.380181Z","steps":["trace[1525858294] 'process raft request' (duration: 161.849298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:53:00.561523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.128651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-19T18:53:00.561632Z","caller":"traceutil/trace.go:171","msg":"trace[2091857148] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:519; }","duration":"654.296975ms","start":"2026-02-19T18:52:59.907308Z","end":"2026-02-19T18:53:00.561604Z","steps":["trace[2091857148] 'range keys from in-memory index tree' (duration: 654.018478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:53:00.561698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:59.907284Z","time spent":"654.397757ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.140\" "} {"level":"warn","ts":"2026-02-19T18:53:00.561737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.898064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:53:00.561791Z","caller":"traceutil/trace.go:171","msg":"trace[1656079209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"588.021947ms","start":"2026-02-19T18:52:59.973755Z","end":"2026-02-19T18:53:00.561777Z","steps":["trace[1656079209] 'range keys from in-memory index tree' (duration: 587.832323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:53:00.561814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:52:59.973738Z","time spent":"588.070738ms","remote":"127.0.0.1:57256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T18:53:00.773366Z","caller":"traceutil/trace.go:171","msg":"trace[694623076] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"181.808213ms","start":"2026-02-19T18:53:00.591526Z","end":"2026-02-19T18:53:00.773334Z","steps":["trace[694623076] 'process raft request' (duration: 122.932305ms)","trace[694623076] 'compare' (duration: 58.717634ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:53:02.071026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.791489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-19T18:53:02.071117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.670447ms","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-19T18:53:02.071137Z","caller":"traceutil/trace.go:171","msg":"trace[646813335] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:520; }","duration":"686.983665ms","start":"2026-02-19T18:53:01.384127Z","end":"2026-02-19T18:53:02.071111Z","steps":["trace[646813335] 'range keys from in-memory index tree' (duration: 686.522322ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:53:02.071186Z","caller":"traceutil/trace.go:171","msg":"trace[983049059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"686.78866ms","start":"2026-02-19T18:53:01.384378Z","end":"2026-02-19T18:53:02.071167Z","steps":["trace[983049059] 'range keys from in-memory index tree' (duration: 686.452681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:53:02.071165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.094609935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T18:53:02.071203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:53:01.384097Z","time spent":"687.090908ms","remote":"127.0.0.1:57526","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-02-19T18:53:02.071237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:53:01.384352Z","time spent":"686.867573ms","remote":"127.0.0.1:57526","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-19T18:53:02.071248Z","caller":"traceutil/trace.go:171","msg":"trace[1902334222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"1.094735689s","start":"2026-02-19T18:53:00.976488Z","end":"2026-02-19T18:53:02.071224Z","steps":["trace[1902334222] 'range keys from in-memory index tree' (duration: 1.094478052s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:53:02.071289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:53:00.976466Z","time spent":"1.094814682s","remote":"127.0.0.1:57256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T18:53:02.071516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.818996ms","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-19T18:53:02.071617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.136823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-19T18:53:02.071712Z","caller":"traceutil/trace.go:171","msg":"trace[485125295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"687.290947ms","start":"2026-02-19T18:53:01.384396Z","end":"2026-02-19T18:53:02.071687Z","steps":["trace[485125295] 'range keys from in-memory index tree' (duration: 686.872635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:53:02.071763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:53:01.384374Z","time spent":"687.379259ms","remote":"127.0.0.1:57526","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":"info","ts":"2026-02-19T18:53:02.071957Z","caller":"traceutil/trace.go:171","msg":"trace[27293983] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:520; }","duration":"816.26547ms","start":"2026-02-19T18:53:01.255642Z","end":"2026-02-19T18:53:02.071907Z","steps":["trace[27293983] 'range keys from in-memory index tree' (duration: 815.631822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:53:02.072021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:53:01.255619Z","time spent":"816.384565ms","remote":"127.0.0.1:57526","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-19T18:53:02.580004Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555603953089913481,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T18:53:03.003406Z","caller":"traceutil/trace.go:171","msg":"trace[28367361] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"926.994005ms","start":"2026-02-19T18:53:02.076386Z","end":"2026-02-19T18:53:03.00338Z","steps":["trace[28367361] 'process raft request' (duration: 926.827019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:53:03.003556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:53:02.076358Z","time spent":"927.116639ms","remote":"127.0.0.1:57526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:53:03.004087Z","caller":"traceutil/trace.go:171","msg":"trace[26450900] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:544; }","duration":"924.71228ms","start":"2026-02-19T18:53:02.07935Z","end":"2026-02-19T18:53:03.004062Z","steps":["trace[26450900] 'read index received' (duration: 923.905324ms)","trace[26450900] 'applied index is now lower than readState.Index' (duration: 805.746µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:53:03.004225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.852804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:53:03.004261Z","caller":"traceutil/trace.go:171","msg":"trace[979665193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"924.901096ms","start":"2026-02-19T18:53:02.079347Z","end":"2026-02-19T18:53:03.004248Z","steps":["trace[979665193] 'agreement among raft nodes before linearized reading' (duration: 924.811613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:53:03.004293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:53:02.079324Z","time spent":"924.961228ms","remote":"127.0.0.1:57256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T18:53:03.004367Z","caller":"traceutil/trace.go:171","msg":"trace[539944163] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"927.126268ms","start":"2026-02-19T18:53:02.077227Z","end":"2026-02-19T18:53:03.004353Z","steps":["trace[539944163] 'process raft request' (duration: 926.755826ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:53:03.004369Z","caller":"traceutil/trace.go:171","msg":"trace[1398361192] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"927.881143ms","start":"2026-02-19T18:53:02.076461Z","end":"2026-02-19T18:53:03.004342Z","steps":["trace[1398361192] 'process raft request' (duration: 927.424588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:53:03.004437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:53:02.077214Z","time spent":"927.186851ms","remote":"127.0.0.1:57526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:53:03.004472Z","caller":"traceutil/trace.go:171","msg":"trace[10684] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"926.320183ms","start":"2026-02-19T18:53:02.078138Z","end":"2026-02-19T18:53:03.004458Z","steps":["trace[10684] 'process raft request' (duration: 925.883278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:53:03.004477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:53:02.076434Z","time spent":"927.985646ms","remote":"127.0.0.1:57526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:53:03.004526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:53:02.07813Z","time spent":"926.367624ms","remote":"127.0.0.1:57526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:53:05.17935Z","caller":"traceutil/trace.go:171","msg":"trace[1865020100] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"168.825023ms","start":"2026-02-19T18:53:05.010506Z","end":"2026-02-19T18:53:05.179331Z","steps":["trace[1865020100] 'process raft request' (duration: 168.696549ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:53:05.261634Z","caller":"traceutil/trace.go:171","msg":"trace[2022823872] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"249.774262ms","start":"2026-02-19T18:53:05.011838Z","end":"2026-02-19T18:53:05.261613Z","steps":["trace[2022823872] 'process raft request' (duration: 249.71568ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:53:05.261701Z","caller":"traceutil/trace.go:171","msg":"trace[1674305458] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"251.030903ms","start":"2026-02-19T18:53:05.01062Z","end":"2026-02-19T18:53:05.261651Z","steps":["trace[1674305458] 'process raft request' (duration: 250.809015ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:53:05.262066Z","caller":"traceutil/trace.go:171","msg":"trace[179461361] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"249.323298ms","start":"2026-02-19T18:53:05.012735Z","end":"2026-02-19T18:53:05.262059Z","steps":["trace[179461361] 'process raft request' (duration: 248.846762ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:53:05.818974Z","caller":"traceutil/trace.go:171","msg":"trace[2141467464] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"101.368658ms","start":"2026-02-19T18:53:05.717582Z","end":"2026-02-19T18:53:05.818951Z","steps":["trace[2141467464] 'process raft request' (duration: 101.234324ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:53:06.227009Z","caller":"traceutil/trace.go:171","msg":"trace[1914599285] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"251.728152ms","start":"2026-02-19T18:53:05.975221Z","end":"2026-02-19T18:53:06.22695Z","steps":["trace[1914599285] 'process raft request' (duration: 163.535368ms)","trace[1914599285] 'compare' (duration: 87.870393ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:53:06.386612Z","caller":"traceutil/trace.go:171","msg":"trace[1465682203] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:571; }","duration":"410.866645ms","start":"2026-02-19T18:53:05.975722Z","end":"2026-02-19T18:53:06.386588Z","steps":["trace[1465682203] 'read index received' (duration: 162.991821ms)","trace[1465682203] 'applied index is now lower than readState.Index' (duration: 247.873754ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:53:06.386704Z","caller":"traceutil/trace.go:171","msg":"trace[1382339205] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"409.977527ms","start":"2026-02-19T18:53:05.976682Z","end":"2026-02-19T18:53:06.38666Z","steps":["trace[1382339205] 'process raft request' (duration: 409.810271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:53:06.386832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.092212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:53:06.386876Z","caller":"traceutil/trace.go:171","msg":"trace[625731721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"411.205176ms","start":"2026-02-19T18:53:05.97566Z","end":"2026-02-19T18:53:06.386866Z","steps":["trace[625731721] 'agreement among raft nodes before linearized reading' (duration: 411.037011ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:53:06.386867Z","caller":"traceutil/trace.go:171","msg":"trace[187939028] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"411.497066ms","start":"2026-02-19T18:53:05.975347Z","end":"2026-02-19T18:53:06.386844Z","steps":["trace[187939028] 'process raft request' (duration: 372.628457ms)","trace[187939028] 'compare' (duration: 38.367962ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:53:06.386913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:53:05.975637Z","time spent":"411.269119ms","remote":"127.0.0.1:57244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T18:53:06.386989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:53:05.975307Z","time spent":"411.609829ms","remote":"127.0.0.1:57792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T18:53:06.38707Z","caller":"traceutil/trace.go:171","msg":"trace[372848432] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"291.399043ms","start":"2026-02-19T18:53:06.095645Z","end":"2026-02-19T18:53:06.387044Z","steps":["trace[372848432] 'process raft request' (duration: 290.901497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:53:06.387977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:53:05.97667Z","time spent":"410.129521ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-19T18:53:06.406491Z","caller":"traceutil/trace.go:171","msg":"trace[920288264] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"112.074512ms","start":"2026-02-19T18:53:06.294248Z","end":"2026-02-19T18:53:06.406323Z","steps":["trace[920288264] 'process raft request' (duration: 112.047661ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:53:06.406457Z","caller":"traceutil/trace.go:171","msg":"trace[580443915] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"173.63685ms","start":"2026-02-19T18:53:06.23278Z","end":"2026-02-19T18:53:06.406417Z","steps":["trace[580443915] 'process raft request' (duration: 173.432643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:53:08.002758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.31024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:53:08.002849Z","caller":"traceutil/trace.go:171","msg":"trace[1324997956] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:592; }","duration":"310.453175ms","start":"2026-02-19T18:53:07.692374Z","end":"2026-02-19T18:53:08.002827Z","steps":["trace[1324997956] 'range keys from in-memory index tree' (duration: 310.224317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:53:08.002897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:53:07.692354Z","time spent":"310.534267ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-19T18:54:34.155087Z","caller":"traceutil/trace.go:171","msg":"trace[338316319] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"433.741925ms","start":"2026-02-19T18:54:33.721319Z","end":"2026-02-19T18:54:34.155061Z","steps":["trace[338316319] 'process raft request' (duration: 433.601172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:54:34.155318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:54:33.721294Z","time spent":"433.932961ms","remote":"127.0.0.1:57526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:54:34.155822Z","caller":"traceutil/trace.go:171","msg":"trace[343342670] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1008; }","duration":"366.124597ms","start":"2026-02-19T18:54:33.789685Z","end":"2026-02-19T18:54:34.15581Z","steps":["trace[343342670] 'read index received' (duration: 366.119417ms)","trace[343342670] 'applied index is now lower than readState.Index' (duration: 3.2µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:54:34.155967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.89835ms","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-19T18:54:34.156Z","caller":"traceutil/trace.go:171","msg":"trace[1865203388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:962; }","duration":"358.984383ms","start":"2026-02-19T18:54:33.797003Z","end":"2026-02-19T18:54:34.155987Z","steps":["trace[1865203388] 'agreement among raft nodes before linearized reading' (duration: 358.87907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:54:34.156034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:54:33.796978Z","time spent":"359.049755ms","remote":"127.0.0.1:57526","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":"warn","ts":"2026-02-19T18:54:34.156213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.856103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:54:34.156249Z","caller":"traceutil/trace.go:171","msg":"trace[1148019726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"174.917455ms","start":"2026-02-19T18:54:33.981322Z","end":"2026-02-19T18:54:34.156239Z","steps":["trace[1148019726] 'agreement among raft nodes before linearized reading' (duration: 174.863074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:54:34.15634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.641721ms","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-19T18:54:34.156435Z","caller":"traceutil/trace.go:171","msg":"trace[223199580] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:962; }","duration":"366.793195ms","start":"2026-02-19T18:54:33.789621Z","end":"2026-02-19T18:54:34.156414Z","steps":["trace[223199580] 'agreement among raft nodes before linearized reading' (duration: 366.490627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:54:34.156485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:54:33.789592Z","time spent":"366.885118ms","remote":"127.0.0.1:57526","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-19T18:54:34.156586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.200633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-19T18:54:34.15663Z","caller":"traceutil/trace.go:171","msg":"trace[1508104081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:962; }","duration":"279.245624ms","start":"2026-02-19T18:54:33.877374Z","end":"2026-02-19T18:54:34.156619Z","steps":["trace[1508104081] 'agreement among raft nodes before linearized reading' (duration: 279.141431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:54:34.328768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.040424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T18:54:34.329041Z","caller":"traceutil/trace.go:171","msg":"trace[1444646799] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1008; }","duration":"160.669507ms","start":"2026-02-19T18:54:34.168354Z","end":"2026-02-19T18:54:34.329023Z","steps":["trace[1444646799] 'read index received' (duration: 51.233653ms)","trace[1444646799] 'applied index is now lower than readState.Index' (duration: 109.433074ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:54:34.329027Z","caller":"traceutil/trace.go:171","msg":"trace[32751503] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"165.355805ms","start":"2026-02-19T18:54:34.163623Z","end":"2026-02-19T18:54:34.328979Z","steps":["trace[32751503] 'process raft request' (duration: 55.952021ms)","trace[32751503] 'compare' (duration: 108.776417ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:54:34.329128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.76951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:54:34.329161Z","caller":"traceutil/trace.go:171","msg":"trace[634096269] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:965; }","duration":"160.819351ms","start":"2026-02-19T18:54:34.16833Z","end":"2026-02-19T18:54:34.32915Z","steps":["trace[634096269] 'agreement among raft nodes before linearized reading' (duration: 160.75093ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:54:34.329206Z","caller":"traceutil/trace.go:171","msg":"trace[1641704409] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"165.229402ms","start":"2026-02-19T18:54:34.163964Z","end":"2026-02-19T18:54:34.329193Z","steps":["trace[1641704409] 'process raft request' (duration: 164.949084ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:54:34.329331Z","caller":"traceutil/trace.go:171","msg":"trace[2012912152] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"165.308033ms","start":"2026-02-19T18:54:34.164008Z","end":"2026-02-19T18:54:34.329316Z","steps":["trace[2012912152] 'process raft request' (duration: 164.963424ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:54:35.696708Z","caller":"traceutil/trace.go:171","msg":"trace[1642140246] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"529.084067ms","start":"2026-02-19T18:54:35.16759Z","end":"2026-02-19T18:54:35.696674Z","steps":["trace[1642140246] 'read index received' (duration: 434.823147ms)","trace[1642140246] 'applied index is now lower than readState.Index' (duration: 94.25712ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:54:35.696989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.358185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:54:35.697213Z","caller":"traceutil/trace.go:171","msg":"trace[1958971471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"529.635001ms","start":"2026-02-19T18:54:35.167548Z","end":"2026-02-19T18:54:35.697183Z","steps":["trace[1958971471] 'agreement among raft nodes before linearized reading' (duration: 529.22044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:54:35.697276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:54:35.167528Z","time spent":"529.737915ms","remote":"127.0.0.1:57256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T18:54:35.697548Z","caller":"traceutil/trace.go:171","msg":"trace[1483463992] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"278.717098ms","start":"2026-02-19T18:54:35.418787Z","end":"2026-02-19T18:54:35.697504Z","steps":["trace[1483463992] 'process raft request' (duration: 277.792203ms)"],"step_count":1}