{"level":"warn","ts":"2026-04-01T14:55:50.941549Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T14:55:50.941669Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.225:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.225:2380","--initial-cluster=instance=https://199.19.213.225:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.225:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.225:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-01T14:55:50.941766Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T14:55:50.941781Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.225:2380"]} {"level":"info","ts":"2026-04-01T14:55:50.941799Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-01T14:55:50.942648Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.225:2379"]} {"level":"info","ts":"2026-04-01T14:55:50.942741Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.225:2380"],"listen-peer-urls":["https://199.19.213.225:2380"],"advertise-client-urls":["https://199.19.213.225:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.225:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.225:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-01T14:55:50.948077Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.076245ms"} {"level":"info","ts":"2026-04-01T14:55:50.957629Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b7b89c171c5fe95c","cluster-id":"5746414c29a21eae"} {"level":"info","ts":"2026-04-01T14:55:50.957749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c switched to configuration voters=()"} {"level":"info","ts":"2026-04-01T14:55:50.957782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became follower at term 0"} {"level":"info","ts":"2026-04-01T14:55:50.9578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b7b89c171c5fe95c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-01T14:55:50.957817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became follower at term 1"} {"level":"info","ts":"2026-04-01T14:55:50.957865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c switched to configuration voters=(13238502727729801564)"} {"level":"warn","ts":"2026-04-01T14:55:50.966767Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-01T14:55:50.969804Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-01T14:55:50.970907Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-01T14:55:50.973272Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b7b89c171c5fe95c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-01T14:55:50.973398Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b7b89c171c5fe95c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-01T14:55:50.973674Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-01T14:55:50.97379Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-01T14:55:50.973815Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-01T14:55:50.976764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c switched to configuration voters=(13238502727729801564)"} {"level":"info","ts":"2026-04-01T14:55:50.976974Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5746414c29a21eae","local-member-id":"b7b89c171c5fe95c","added-peer-id":"b7b89c171c5fe95c","added-peer-peer-urls":["https://199.19.213.225:2380"]} {"level":"info","ts":"2026-04-01T14:55:50.978146Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-01T14:55:50.978481Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b7b89c171c5fe95c","initial-advertise-peer-urls":["https://199.19.213.225:2380"],"listen-peer-urls":["https://199.19.213.225:2380"],"advertise-client-urls":["https://199.19.213.225:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.225:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-01T14:55:50.978566Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.225:2380"} {"level":"info","ts":"2026-04-01T14:55:50.978636Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.225:2380"} {"level":"info","ts":"2026-04-01T14:55:50.97852Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-01T14:55:51.558794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c is starting a new election at term 1"} {"level":"info","ts":"2026-04-01T14:55:51.558871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-01T14:55:51.558938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c received MsgPreVoteResp from b7b89c171c5fe95c at term 1"} {"level":"info","ts":"2026-04-01T14:55:51.558962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became candidate at term 2"} {"level":"info","ts":"2026-04-01T14:55:51.558977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c received MsgVoteResp from b7b89c171c5fe95c at term 2"} {"level":"info","ts":"2026-04-01T14:55:51.559118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b7b89c171c5fe95c became leader at term 2"} {"level":"info","ts":"2026-04-01T14:55:51.55914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b7b89c171c5fe95c elected leader b7b89c171c5fe95c at term 2"} {"level":"info","ts":"2026-04-01T14:55:51.560144Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T14:55:51.561212Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b7b89c171c5fe95c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.225:2379]}","request-path":"/0/members/b7b89c171c5fe95c/attributes","cluster-id":"5746414c29a21eae","publish-timeout":"7s"} {"level":"info","ts":"2026-04-01T14:55:51.561284Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T14:55:51.561308Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T14:55:51.561726Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-01T14:55:51.561802Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-01T14:55:51.561828Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5746414c29a21eae","local-member-id":"b7b89c171c5fe95c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T14:55:51.561976Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T14:55:51.562021Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T14:55:51.564709Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-01T14:55:51.565331Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.225:2379"} {"level":"info","ts":"2026-04-01T14:55:55.848503Z","caller":"traceutil/trace.go:171","msg":"trace[629556412] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"127.836568ms","start":"2026-04-01T14:55:55.720639Z","end":"2026-04-01T14:55:55.848476Z","steps":["trace[629556412] 'process raft request' (duration: 127.684504ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:55:56.585179Z","caller":"traceutil/trace.go:171","msg":"trace[1820811762] linearizableReadLoop","detail":"{readStateIndex:239; appliedIndex:238; }","duration":"217.548977ms","start":"2026-04-01T14:55:56.367599Z","end":"2026-04-01T14:55:56.585148Z","steps":["trace[1820811762] 'read index received' (duration: 140.902652ms)","trace[1820811762] 'applied index is now lower than readState.Index' (duration: 76.644795ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T14:55:56.58532Z","caller":"traceutil/trace.go:171","msg":"trace[168140278] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"218.050909ms","start":"2026-04-01T14:55:56.36723Z","end":"2026-04-01T14:55:56.585281Z","steps":["trace[168140278] 'process raft request' (duration: 141.260431ms)","trace[168140278] 'compare' (duration: 76.488921ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T14:55:56.585406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.774183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-01T14:55:56.585486Z","caller":"traceutil/trace.go:171","msg":"trace[491202556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:233; }","duration":"217.906256ms","start":"2026-04-01T14:55:56.367565Z","end":"2026-04-01T14:55:56.585471Z","steps":["trace[491202556] 'agreement among raft nodes before linearized reading' (duration: 217.721342ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:55:56.631347Z","caller":"traceutil/trace.go:171","msg":"trace[632090090] transaction","detail":"{read_only:false; number_of_response:0; response_revision:235; }","duration":"127.130792ms","start":"2026-04-01T14:55:56.504202Z","end":"2026-04-01T14:55:56.631333Z","steps":["trace[632090090] 'process raft request' (duration: 127.100251ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:55:56.63161Z","caller":"traceutil/trace.go:171","msg":"trace[527797702] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"228.770729ms","start":"2026-04-01T14:55:56.402789Z","end":"2026-04-01T14:55:56.63156Z","steps":["trace[527797702] 'process raft request' (duration: 228.468302ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:55:56.631541Z","caller":"traceutil/trace.go:171","msg":"trace[2123880541] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"230.915181ms","start":"2026-04-01T14:55:56.400599Z","end":"2026-04-01T14:55:56.631515Z","steps":["trace[2123880541] 'process raft request' (duration: 230.577883ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:55:56.694866Z","caller":"traceutil/trace.go:171","msg":"trace[1911323855] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"183.658271ms","start":"2026-04-01T14:55:56.511176Z","end":"2026-04-01T14:55:56.694834Z","steps":["trace[1911323855] 'process raft request' (duration: 183.519808ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:55:56.694895Z","caller":"traceutil/trace.go:171","msg":"trace[79749997] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"182.975525ms","start":"2026-04-01T14:55:56.511903Z","end":"2026-04-01T14:55:56.694878Z","steps":["trace[79749997] 'process raft request' (duration: 182.880253ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:55:56.69501Z","caller":"traceutil/trace.go:171","msg":"trace[231234719] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"183.53199ms","start":"2026-04-01T14:55:56.511468Z","end":"2026-04-01T14:55:56.695Z","steps":["trace[231234719] 'process raft request' (duration: 183.287943ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:55:56.694993Z","caller":"traceutil/trace.go:171","msg":"trace[1220446692] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"105.558891ms","start":"2026-04-01T14:55:56.589399Z","end":"2026-04-01T14:55:56.694958Z","steps":["trace[1220446692] 'process raft request' (duration: 105.421898ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:55:56.695128Z","caller":"traceutil/trace.go:171","msg":"trace[65269933] transaction","detail":"{read_only:false; number_of_response:0; response_revision:235; }","duration":"184.123074ms","start":"2026-04-01T14:55:56.510937Z","end":"2026-04-01T14:55:56.69506Z","steps":["trace[65269933] 'process raft request' (duration: 183.708353ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:55:56.69536Z","caller":"traceutil/trace.go:171","msg":"trace[1163918914] linearizableReadLoop","detail":"{readStateIndex:247; appliedIndex:240; }","duration":"110.090221ms","start":"2026-04-01T14:55:56.585254Z","end":"2026-04-01T14:55:56.695344Z","steps":["trace[1163918914] 'read index received' (duration: 46.015378ms)","trace[1163918914] 'applied index is now lower than readState.Index' (duration: 64.073743ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T14:55:56.695394Z","caller":"traceutil/trace.go:171","msg":"trace[1851241592] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"104.366994ms","start":"2026-04-01T14:55:56.591014Z","end":"2026-04-01T14:55:56.695381Z","steps":["trace[1851241592] 'process raft request' (duration: 103.844111ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:55:56.695454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.153478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-01T14:55:56.695481Z","caller":"traceutil/trace.go:171","msg":"trace[459955908] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:241; }","duration":"295.203788ms","start":"2026-04-01T14:55:56.400267Z","end":"2026-04-01T14:55:56.695471Z","steps":["trace[459955908] 'agreement among raft nodes before linearized reading' (duration: 295.140107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:55:56.695775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.792872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T14:55:56.695821Z","caller":"traceutil/trace.go:171","msg":"trace[799757712] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:241; }","duration":"295.865975ms","start":"2026-04-01T14:55:56.399949Z","end":"2026-04-01T14:55:56.695815Z","steps":["trace[799757712] 'agreement among raft nodes before linearized reading' (duration: 295.808063ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:55:56.696025Z","caller":"traceutil/trace.go:171","msg":"trace[198681374] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"182.966075ms","start":"2026-04-01T14:55:56.51195Z","end":"2026-04-01T14:55:56.694916Z","steps":["trace[198681374] 'process raft request' (duration: 182.853793ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:55:57.602037Z","caller":"traceutil/trace.go:171","msg":"trace[2052836642] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"298.620449ms","start":"2026-04-01T14:55:57.303396Z","end":"2026-04-01T14:55:57.602016Z","steps":["trace[2052836642] 'process raft request' (duration: 298.506626ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:55:57.602352Z","caller":"traceutil/trace.go:171","msg":"trace[80720586] linearizableReadLoop","detail":"{readStateIndex:277; appliedIndex:277; }","duration":"176.859459ms","start":"2026-04-01T14:55:57.425458Z","end":"2026-04-01T14:55:57.602318Z","steps":["trace[80720586] 'read index received' (duration: 176.823778ms)","trace[80720586] 'applied index is now lower than readState.Index' (duration: 9.35µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T14:55:57.602643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.153216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-04-01T14:55:57.602903Z","caller":"traceutil/trace.go:171","msg":"trace[1291604526] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:269; }","duration":"177.463085ms","start":"2026-04-01T14:55:57.425421Z","end":"2026-04-01T14:55:57.602884Z","steps":["trace[1291604526] 'agreement among raft nodes before linearized reading' (duration: 177.039033ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:55:57.621444Z","caller":"traceutil/trace.go:171","msg":"trace[882488618] transaction","detail":"{read_only:false; number_of_response:0; response_revision:269; }","duration":"195.861721ms","start":"2026-04-01T14:55:57.42556Z","end":"2026-04-01T14:55:57.621422Z","steps":["trace[882488618] 'process raft request' (duration: 195.224386ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:55:57.621549Z","caller":"traceutil/trace.go:171","msg":"trace[1759262718] transaction","detail":"{read_only:false; number_of_response:0; response_revision:269; }","duration":"195.909692ms","start":"2026-04-01T14:55:57.425635Z","end":"2026-04-01T14:55:57.621545Z","steps":["trace[1759262718] 'process raft request' (duration: 195.540453ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:55:57.621613Z","caller":"traceutil/trace.go:171","msg":"trace[1688387626] transaction","detail":"{read_only:false; number_of_response:0; response_revision:269; }","duration":"195.919483ms","start":"2026-04-01T14:55:57.425689Z","end":"2026-04-01T14:55:57.621608Z","steps":["trace[1688387626] 'process raft request' (duration: 195.501043ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:55:57.621691Z","caller":"traceutil/trace.go:171","msg":"trace[999149053] transaction","detail":"{read_only:false; number_of_response:0; response_revision:269; }","duration":"195.633736ms","start":"2026-04-01T14:55:57.42603Z","end":"2026-04-01T14:55:57.621664Z","steps":["trace[999149053] 'process raft request' (duration: 195.179405ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:01.350323Z","caller":"traceutil/trace.go:171","msg":"trace[443976252] linearizableReadLoop","detail":"{readStateIndex:342; appliedIndex:339; }","duration":"197.228669ms","start":"2026-04-01T14:56:01.153078Z","end":"2026-04-01T14:56:01.350306Z","steps":["trace[443976252] 'read index received' (duration: 63.605462ms)","trace[443976252] 'applied index is now lower than readState.Index' (duration: 133.620247ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T14:56:01.350468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.624103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-01T14:56:01.350484Z","caller":"traceutil/trace.go:171","msg":"trace[572352972] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"215.732486ms","start":"2026-04-01T14:56:01.13462Z","end":"2026-04-01T14:56:01.350352Z","steps":["trace[572352972] 'process raft request' (duration: 179.138194ms)","trace[572352972] 'compare' (duration: 36.368996ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T14:56:01.350569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.661907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-bf45c\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-04-01T14:56:01.350664Z","caller":"traceutil/trace.go:171","msg":"trace[1560633408] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"127.751189ms","start":"2026-04-01T14:56:01.222678Z","end":"2026-04-01T14:56:01.35043Z","steps":["trace[1560633408] 'process raft request' (duration: 127.599935ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:01.350702Z","caller":"traceutil/trace.go:171","msg":"trace[258812485] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-bf45c; range_end:; response_count:1; response_revision:331; }","duration":"129.820101ms","start":"2026-04-01T14:56:01.220872Z","end":"2026-04-01T14:56:01.350692Z","steps":["trace[258812485] 'agreement among raft nodes before linearized reading' (duration: 129.623256ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:01.350548Z","caller":"traceutil/trace.go:171","msg":"trace[1478334051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:331; }","duration":"140.750056ms","start":"2026-04-01T14:56:01.209783Z","end":"2026-04-01T14:56:01.350533Z","steps":["trace[1478334051] 'agreement among raft nodes before linearized reading' (duration: 140.641374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:01.350469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.381453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-01T14:56:01.350876Z","caller":"traceutil/trace.go:171","msg":"trace[2006722888] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:331; }","duration":"197.841946ms","start":"2026-04-01T14:56:01.153028Z","end":"2026-04-01T14:56:01.35087Z","steps":["trace[2006722888] 'agreement among raft nodes before linearized reading' (duration: 197.369933ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:01.498573Z","caller":"traceutil/trace.go:171","msg":"trace[700359579] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"133.765211ms","start":"2026-04-01T14:56:01.36478Z","end":"2026-04-01T14:56:01.498546Z","steps":["trace[700359579] 'process raft request' (duration: 106.434642ms)","trace[700359579] 'compare' (duration: 27.044232ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T14:56:01.498592Z","caller":"traceutil/trace.go:171","msg":"trace[968849249] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"132.18505ms","start":"2026-04-01T14:56:01.366377Z","end":"2026-04-01T14:56:01.498562Z","steps":["trace[968849249] 'process raft request' (duration: 132.071527ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:02.079725Z","caller":"traceutil/trace.go:171","msg":"trace[699001750] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:356; }","duration":"183.91086ms","start":"2026-04-01T14:56:01.895788Z","end":"2026-04-01T14:56:02.079699Z","steps":["trace[699001750] 'read index received' (duration: 133.804797ms)","trace[699001750] 'applied index is now lower than readState.Index' (duration: 50.104983ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T14:56:02.079907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.119626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-01T14:56:02.079993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.241751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-04-01T14:56:02.08002Z","caller":"traceutil/trace.go:171","msg":"trace[2015122896] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:345; }","duration":"142.295742ms","start":"2026-04-01T14:56:01.937717Z","end":"2026-04-01T14:56:02.080013Z","steps":["trace[2015122896] 'agreement among raft nodes before linearized reading' (duration: 142.234361ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:02.080024Z","caller":"traceutil/trace.go:171","msg":"trace[286048219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:345; }","duration":"184.278449ms","start":"2026-04-01T14:56:01.895699Z","end":"2026-04-01T14:56:02.079978Z","steps":["trace[286048219] 'agreement among raft nodes before linearized reading' (duration: 184.130596ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:02.080226Z","caller":"traceutil/trace.go:171","msg":"trace[900659736] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"393.743127ms","start":"2026-04-01T14:56:01.686017Z","end":"2026-04-01T14:56:02.07976Z","steps":["trace[900659736] 'process raft request' (duration: 343.668304ms)","trace[900659736] 'compare' (duration: 49.888538ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T14:56:02.081118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:01.685996Z","time spent":"394.420494ms","remote":"127.0.0.1:48982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T14:56:03.218585Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16815488047887724837,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T14:56:03.719065Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16815488047887724837,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T14:56:04.219262Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16815488047887724837,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T14:56:04.719987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16815488047887724837,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T14:56:04.721686Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.637387514s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-01T14:56:05.162519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.340704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T14:56:05.162635Z","caller":"traceutil/trace.go:171","msg":"trace[1637175625] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"3.078430147s","start":"2026-04-01T14:56:02.08419Z","end":"2026-04-01T14:56:05.16262Z","steps":["trace[1637175625] 'process raft request' (duration: 2.637726843s)","trace[1637175625] 'compare' (duration: 438.737232ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T14:56:05.162705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:02.084164Z","time spent":"3.078501619s","remote":"127.0.0.1:48786","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-04-01T14:56:05.734894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.494968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T14:56:05.735047Z","caller":"traceutil/trace.go:171","msg":"trace[1594348271] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:357; }","duration":"3.017248748s","start":"2026-04-01T14:56:02.717783Z","end":"2026-04-01T14:56:05.735032Z","steps":["trace[1594348271] 'read index received' (duration: 2.004223436s)","trace[1594348271] 'applied index is now lower than readState.Index' (duration: 1.013024542s)"],"step_count":2} {"level":"info","ts":"2026-04-01T14:56:05.735129Z","caller":"traceutil/trace.go:171","msg":"trace[1442019792] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"2.50756673s","start":"2026-04-01T14:56:03.227553Z","end":"2026-04-01T14:56:05.735119Z","steps":["trace[1442019792] 'process raft request' (duration: 2.507412525s)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:05.735185Z","caller":"traceutil/trace.go:171","msg":"trace[2017251764] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"3.646044786s","start":"2026-04-01T14:56:02.089123Z","end":"2026-04-01T14:56:05.735168Z","steps":["trace[2017251764] 'process raft request' (duration: 3.538214078s)","trace[2017251764] 'compare' (duration: 107.374205ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T14:56:05.735211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:03.227529Z","time spent":"2.507646322s","remote":"127.0.0.1:48786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T14:56:05.735264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:02.089102Z","time spent":"3.646123647s","remote":"127.0.0.1:48692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T14:56:05.735396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.889169703s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-01T14:56:05.735429Z","caller":"traceutil/trace.go:171","msg":"trace[1917835439] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:349; }","duration":"2.889231225s","start":"2026-04-01T14:56:02.846187Z","end":"2026-04-01T14:56:05.735418Z","steps":["trace[1917835439] 'agreement among raft nodes before linearized reading' (duration: 2.889170253s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:05.735456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:02.846171Z","time spent":"2.889277846s","remote":"127.0.0.1:48786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-01T14:56:05.735531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.017744481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-01T14:56:05.735539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.362542349s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.225\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-01T14:56:05.735568Z","caller":"traceutil/trace.go:171","msg":"trace[122464491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:349; }","duration":"3.017814543s","start":"2026-04-01T14:56:02.717744Z","end":"2026-04-01T14:56:05.735559Z","steps":["trace[122464491] 'agreement among raft nodes before linearized reading' (duration: 3.017754981s)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:05.735579Z","caller":"traceutil/trace.go:171","msg":"trace[969720639] range","detail":"{range_begin:/registry/masterleases/199.19.213.225; range_end:; response_count:1; response_revision:349; }","duration":"1.362637341s","start":"2026-04-01T14:56:04.372934Z","end":"2026-04-01T14:56:05.735571Z","steps":["trace[969720639] 'agreement among raft nodes before linearized reading' (duration: 1.362580019s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:05.735595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:02.717726Z","time spent":"3.017861533s","remote":"127.0.0.1:48786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T14:56:05.735607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:04.372911Z","time spent":"1.362689773s","remote":"127.0.0.1:48542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.225\" "} {"level":"warn","ts":"2026-04-01T14:56:05.735624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.336273272s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-01T14:56:05.735665Z","caller":"traceutil/trace.go:171","msg":"trace[125330376] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"2.418083677s","start":"2026-04-01T14:56:03.317572Z","end":"2026-04-01T14:56:05.735656Z","steps":["trace[125330376] 'process raft request' (duration: 2.417427609s)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:05.735705Z","caller":"traceutil/trace.go:171","msg":"trace[192609726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:349; }","duration":"2.336400845s","start":"2026-04-01T14:56:03.399284Z","end":"2026-04-01T14:56:05.735685Z","steps":["trace[192609726] 'agreement among raft nodes before linearized reading' (duration: 2.336262371s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:05.735719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:03.317543Z","time spent":"2.418149399s","remote":"127.0.0.1:48578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":777,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T14:56:05.735752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:03.399266Z","time spent":"2.336476456s","remote":"127.0.0.1:48786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-01T14:56:06.081848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:05.738035Z","time spent":"343.808274ms","remote":"127.0.0.1:48542","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-01T14:56:06.082119Z","caller":"traceutil/trace.go:171","msg":"trace[1710358921] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:361; }","duration":"341.897735ms","start":"2026-04-01T14:56:05.740201Z","end":"2026-04-01T14:56:06.082099Z","steps":["trace[1710358921] 'read index received' (duration: 331.848482ms)","trace[1710358921] 'applied index is now lower than readState.Index' (duration: 10.048342ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T14:56:06.082204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.984417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-04-01T14:56:06.082233Z","caller":"traceutil/trace.go:171","msg":"trace[279886544] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:352; }","duration":"342.027948ms","start":"2026-04-01T14:56:05.740197Z","end":"2026-04-01T14:56:06.082225Z","steps":["trace[279886544] 'agreement among raft nodes before linearized reading' (duration: 341.962666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:06.082262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:05.740167Z","time spent":"342.08847ms","remote":"127.0.0.1:48692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6988,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-04-01T14:56:06.082266Z","caller":"traceutil/trace.go:171","msg":"trace[1760009562] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"341.537594ms","start":"2026-04-01T14:56:05.740708Z","end":"2026-04-01T14:56:06.082245Z","steps":["trace[1760009562] 'process raft request' (duration: 341.295598ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:06.082348Z","caller":"traceutil/trace.go:171","msg":"trace[1879572622] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"342.411458ms","start":"2026-04-01T14:56:05.739922Z","end":"2026-04-01T14:56:06.082334Z","steps":["trace[1879572622] 'process raft request' (duration: 341.815332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:06.082406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:05.740698Z","time spent":"341.611947ms","remote":"127.0.0.1:48786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T14:56:06.082422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:05.739903Z","time spent":"342.47554ms","remote":"127.0.0.1:48786","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-04-01T14:56:06.082416Z","caller":"traceutil/trace.go:171","msg":"trace[2077230911] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"341.059403ms","start":"2026-04-01T14:56:05.741348Z","end":"2026-04-01T14:56:06.082407Z","steps":["trace[2077230911] 'process raft request' (duration: 340.722274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:06.082542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:05.741325Z","time spent":"341.190057ms","remote":"127.0.0.1:48786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T14:56:06.368154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.927706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-04-01T14:56:06.368792Z","caller":"traceutil/trace.go:171","msg":"trace[1255367864] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:355; }","duration":"133.03444ms","start":"2026-04-01T14:56:06.235178Z","end":"2026-04-01T14:56:06.368213Z","steps":["trace[1255367864] 'range keys from in-memory index tree' (duration: 132.864114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:26.680382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16815488047887725124,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T14:56:27.018494Z","caller":"traceutil/trace.go:171","msg":"trace[262637450] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"874.103529ms","start":"2026-04-01T14:56:26.143865Z","end":"2026-04-01T14:56:27.017969Z","steps":["trace[262637450] 'process raft request' (duration: 864.618036ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:27.018515Z","caller":"traceutil/trace.go:171","msg":"trace[1282319630] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"858.560029ms","start":"2026-04-01T14:56:26.159415Z","end":"2026-04-01T14:56:27.017975Z","steps":["trace[1282319630] 'process raft request' (duration: 858.446096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:27.018753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:26.159328Z","time spent":"859.27356ms","remote":"127.0.0.1:48550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T14:56:27.019157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:26.143845Z","time spent":"874.738307ms","remote":"127.0.0.1:48550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T14:56:27.020292Z","caller":"traceutil/trace.go:171","msg":"trace[1050206118] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:522; }","duration":"840.03116ms","start":"2026-04-01T14:56:26.180181Z","end":"2026-04-01T14:56:27.020212Z","steps":["trace[1050206118] 'read index received' (duration: 828.396023ms)","trace[1050206118] 'applied index is now lower than readState.Index' (duration: 11.632887ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T14:56:27.020554Z","caller":"traceutil/trace.go:171","msg":"trace[1282248952] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"804.767875ms","start":"2026-04-01T14:56:26.215759Z","end":"2026-04-01T14:56:27.020527Z","steps":["trace[1282248952] 'process raft request' (duration: 802.187138ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:27.021307Z","caller":"traceutil/trace.go:171","msg":"trace[2087957944] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"801.751915ms","start":"2026-04-01T14:56:26.219518Z","end":"2026-04-01T14:56:27.02127Z","steps":["trace[2087957944] 'process raft request' (duration: 800.275251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:27.022192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.468564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-01T14:56:27.022406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.262768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T14:56:27.022634Z","caller":"traceutil/trace.go:171","msg":"trace[1390249809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:503; }","duration":"419.511945ms","start":"2026-04-01T14:56:26.603085Z","end":"2026-04-01T14:56:27.022597Z","steps":["trace[1390249809] 'agreement among raft nodes before linearized reading' (duration: 418.929208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:27.022983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.457454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-01T14:56:27.023051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.874204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-01T14:56:27.023053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:26.219484Z","time spent":"801.881799ms","remote":"127.0.0.1:48786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T14:56:27.023121Z","caller":"traceutil/trace.go:171","msg":"trace[276184836] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:503; }","duration":"842.967057ms","start":"2026-04-01T14:56:26.180125Z","end":"2026-04-01T14:56:27.023092Z","steps":["trace[276184836] 'agreement among raft nodes before linearized reading' (duration: 840.529134ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:27.023117Z","caller":"traceutil/trace.go:171","msg":"trace[1887499423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:503; }","duration":"838.556407ms","start":"2026-04-01T14:56:26.184478Z","end":"2026-04-01T14:56:27.023034Z","steps":["trace[1887499423] 'agreement among raft nodes before linearized reading' (duration: 838.056181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:27.023335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:26.18447Z","time spent":"838.799594ms","remote":"127.0.0.1:48786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-01T14:56:27.023379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:26.603065Z","time spent":"419.599348ms","remote":"127.0.0.1:48500","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-04-01T14:56:27.023165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:26.180106Z","time spent":"843.04918ms","remote":"127.0.0.1:48786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-01T14:56:27.025007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.772931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T14:56:27.025013Z","caller":"traceutil/trace.go:171","msg":"trace[155898575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:503; }","duration":"838.890926ms","start":"2026-04-01T14:56:26.183447Z","end":"2026-04-01T14:56:27.022338Z","steps":["trace[155898575] 'agreement among raft nodes before linearized reading' (duration: 837.395642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:27.020779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:26.215737Z","time spent":"804.895719ms","remote":"127.0.0.1:48550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T14:56:27.026379Z","caller":"traceutil/trace.go:171","msg":"trace[1721522477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:503; }","duration":"260.886365ms","start":"2026-04-01T14:56:26.764181Z","end":"2026-04-01T14:56:27.025068Z","steps":["trace[1721522477] 'agreement among raft nodes before linearized reading' (duration: 258.37071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:27.026525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:26.183439Z","time spent":"842.38549ms","remote":"127.0.0.1:48786","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-04-01T14:56:27.640283Z","caller":"traceutil/trace.go:171","msg":"trace[767843864] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"105.137431ms","start":"2026-04-01T14:56:27.535125Z","end":"2026-04-01T14:56:27.640263Z","steps":["trace[767843864] 'process raft request' (duration: 66.076348ms)","trace[767843864] 'compare' (duration: 38.93792ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T14:56:28.102749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.413097ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T14:56:28.102849Z","caller":"traceutil/trace.go:171","msg":"trace[138386193] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"414.54005ms","start":"2026-04-01T14:56:27.688291Z","end":"2026-04-01T14:56:28.102831Z","steps":["trace[138386193] 'process raft request' (duration: 234.970338ms)","trace[138386193] 'compare' (duration: 179.283424ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T14:56:28.102905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:27.688274Z","time spent":"414.605293ms","remote":"127.0.0.1:48550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18032,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T14:56:28.12214Z","caller":"traceutil/trace.go:171","msg":"trace[1006458429] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"395.282299ms","start":"2026-04-01T14:56:27.726821Z","end":"2026-04-01T14:56:28.122103Z","steps":["trace[1006458429] 'process raft request' (duration: 394.948989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:28.122347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:27.726796Z","time spent":"395.447354ms","remote":"127.0.0.1:48550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T14:56:28.12283Z","caller":"traceutil/trace.go:171","msg":"trace[395931830] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:553; }","duration":"359.986738ms","start":"2026-04-01T14:56:27.76282Z","end":"2026-04-01T14:56:28.122807Z","steps":["trace[395931830] 'read index received' (duration: 160.445058ms)","trace[395931830] 'applied index is now lower than readState.Index' (duration: 199.54072ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T14:56:28.123438Z","caller":"traceutil/trace.go:171","msg":"trace[1775972468] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"336.581862ms","start":"2026-04-01T14:56:27.786604Z","end":"2026-04-01T14:56:28.123186Z","steps":["trace[1775972468] 'process raft request' (duration: 335.887821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:28.123544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:27.78658Z","time spent":"336.900491ms","remote":"127.0.0.1:48550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T14:56:28.12371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.877575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T14:56:28.123795Z","caller":"traceutil/trace.go:171","msg":"trace[1548626663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"360.973338ms","start":"2026-04-01T14:56:27.762784Z","end":"2026-04-01T14:56:28.123757Z","steps":["trace[1548626663] 'agreement among raft nodes before linearized reading' (duration: 360.112721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:28.123834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:27.762765Z","time spent":"361.060471ms","remote":"127.0.0.1:48500","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-04-01T14:56:28.332987Z","caller":"traceutil/trace.go:171","msg":"trace[1891500045] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"203.368155ms","start":"2026-04-01T14:56:28.129591Z","end":"2026-04-01T14:56:28.332959Z","steps":["trace[1891500045] 'process raft request' (duration: 136.924717ms)","trace[1891500045] 'compare' (duration: 66.285394ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T14:56:28.340135Z","caller":"traceutil/trace.go:171","msg":"trace[1583700830] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"101.127436ms","start":"2026-04-01T14:56:28.238984Z","end":"2026-04-01T14:56:28.340111Z","steps":["trace[1583700830] 'process raft request' (duration: 101.072614ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:28.340195Z","caller":"traceutil/trace.go:171","msg":"trace[1631625904] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"202.374115ms","start":"2026-04-01T14:56:28.137784Z","end":"2026-04-01T14:56:28.340158Z","steps":["trace[1631625904] 'process raft request' (duration: 202.127818ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:28.501834Z","caller":"traceutil/trace.go:171","msg":"trace[86852434] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"135.608838ms","start":"2026-04-01T14:56:28.366198Z","end":"2026-04-01T14:56:28.501807Z","steps":["trace[86852434] 'process raft request' (duration: 75.922422ms)","trace[86852434] 'compare' (duration: 58.894832ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T14:56:28.754262Z","caller":"traceutil/trace.go:171","msg":"trace[803112665] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:563; }","duration":"168.222555ms","start":"2026-04-01T14:56:28.586013Z","end":"2026-04-01T14:56:28.754236Z","steps":["trace[803112665] 'read index received' (duration: 155.548805ms)","trace[803112665] 'applied index is now lower than readState.Index' (duration: 12.67291ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T14:56:28.754434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.40932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-04-01T14:56:28.754469Z","caller":"traceutil/trace.go:171","msg":"trace[1082750603] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:542; }","duration":"168.489852ms","start":"2026-04-01T14:56:28.585967Z","end":"2026-04-01T14:56:28.754456Z","steps":["trace[1082750603] 'agreement among raft nodes before linearized reading' (duration: 168.356828ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:28.754484Z","caller":"traceutil/trace.go:171","msg":"trace[465932943] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"237.347653ms","start":"2026-04-01T14:56:28.517098Z","end":"2026-04-01T14:56:28.754445Z","steps":["trace[465932943] 'process raft request' (duration: 224.443846ms)","trace[465932943] 'compare' (duration: 12.417361ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T14:56:28.754583Z","caller":"traceutil/trace.go:171","msg":"trace[2007249892] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"229.54862ms","start":"2026-04-01T14:56:28.525026Z","end":"2026-04-01T14:56:28.754574Z","steps":["trace[2007249892] 'process raft request' (duration: 229.143658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:35.825324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.534636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T14:56:35.825774Z","caller":"traceutil/trace.go:171","msg":"trace[1375467400] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"588.815958ms","start":"2026-04-01T14:56:35.236935Z","end":"2026-04-01T14:56:35.825751Z","steps":["trace[1375467400] 'read index received' (duration: 262.943153ms)","trace[1375467400] 'applied index is now lower than readState.Index' (duration: 325.870745ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T14:56:35.825809Z","caller":"traceutil/trace.go:171","msg":"trace[1165761373] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"596.551897ms","start":"2026-04-01T14:56:35.229225Z","end":"2026-04-01T14:56:35.825777Z","steps":["trace[1165761373] 'process raft request' (duration: 270.464494ms)","trace[1165761373] 'compare' (duration: 324.595706ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T14:56:35.826148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:35.229211Z","time spent":"596.667981ms","remote":"127.0.0.1:48786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T14:56:35.826176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.227671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-01T14:56:35.826228Z","caller":"traceutil/trace.go:171","msg":"trace[990669696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"589.295433ms","start":"2026-04-01T14:56:35.236916Z","end":"2026-04-01T14:56:35.826212Z","steps":["trace[990669696] 'agreement among raft nodes before linearized reading' (duration: 588.928082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:35.826272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:35.236907Z","time spent":"589.353214ms","remote":"127.0.0.1:48786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-01T14:56:36.235203Z","caller":"traceutil/trace.go:171","msg":"trace[1415705937] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"403.932192ms","start":"2026-04-01T14:56:35.831249Z","end":"2026-04-01T14:56:36.235181Z","steps":["trace[1415705937] 'process raft request' (duration: 403.87951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:36.235351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:35.831228Z","time spent":"404.046396ms","remote":"127.0.0.1:48786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T14:56:36.235458Z","caller":"traceutil/trace.go:171","msg":"trace[1663860509] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"908.243933ms","start":"2026-04-01T14:56:35.327166Z","end":"2026-04-01T14:56:36.23541Z","steps":["trace[1663860509] 'process raft request' (duration: 881.841315ms)","trace[1663860509] 'compare' (duration: 25.805539ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T14:56:36.235523Z","caller":"traceutil/trace.go:171","msg":"trace[1608911190] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"409.671319ms","start":"2026-04-01T14:56:35.825812Z","end":"2026-04-01T14:56:36.235484Z","steps":["trace[1608911190] 'read index received' (duration: 383.045835ms)","trace[1608911190] 'applied index is now lower than readState.Index' (duration: 26.620454ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T14:56:36.235629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.361063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T14:56:36.235692Z","caller":"traceutil/trace.go:171","msg":"trace[1024639279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"471.459866ms","start":"2026-04-01T14:56:35.764207Z","end":"2026-04-01T14:56:36.235667Z","steps":["trace[1024639279] 'agreement among raft nodes before linearized reading' (duration: 471.374163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:36.235703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:35.327102Z","time spent":"908.454679ms","remote":"127.0.0.1:48598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T14:56:36.235728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:35.764184Z","time spent":"471.535868ms","remote":"127.0.0.1:48512","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-04-01T14:56:36.235756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.703749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-01T14:56:36.235804Z","caller":"traceutil/trace.go:171","msg":"trace[939108670] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"828.849863ms","start":"2026-04-01T14:56:35.406939Z","end":"2026-04-01T14:56:36.235789Z","steps":["trace[939108670] 'agreement among raft nodes before linearized reading' (duration: 828.646717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:36.235828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.541159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6kk7l\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-01T14:56:36.235852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:35.406908Z","time spent":"828.933186ms","remote":"127.0.0.1:48786","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-04-01T14:56:36.235883Z","caller":"traceutil/trace.go:171","msg":"trace[1555907704] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6kk7l; range_end:; response_count:1; response_revision:580; }","duration":"718.629982ms","start":"2026-04-01T14:56:35.517235Z","end":"2026-04-01T14:56:36.235865Z","steps":["trace[1555907704] 'agreement among raft nodes before linearized reading' (duration: 718.472707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:36.235925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:35.517217Z","time spent":"718.696054ms","remote":"127.0.0.1:48692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-6kk7l\" "} {"level":"info","ts":"2026-04-01T14:56:36.446843Z","caller":"traceutil/trace.go:171","msg":"trace[1652544407] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"201.357478ms","start":"2026-04-01T14:56:36.24546Z","end":"2026-04-01T14:56:36.446817Z","steps":["trace[1652544407] 'process raft request' (duration: 197.822668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:38.196185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.393750519s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T14:56:38.196353Z","caller":"traceutil/trace.go:171","msg":"trace[2066847277] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.10298052s","start":"2026-04-01T14:56:37.093359Z","end":"2026-04-01T14:56:38.19634Z","steps":["trace[2066847277] 'process raft request' (duration: 1.102945369s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:38.196399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:37.093323Z","time spent":"1.103054602s","remote":"127.0.0.1:48786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T14:56:38.197132Z","caller":"traceutil/trace.go:171","msg":"trace[533868424] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.908664771s","start":"2026-04-01T14:56:36.288455Z","end":"2026-04-01T14:56:38.19712Z","steps":["trace[533868424] 'process raft request' (duration: 1.907806594s)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:38.197154Z","caller":"traceutil/trace.go:171","msg":"trace[1387438226] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.942333364s","start":"2026-04-01T14:56:36.254797Z","end":"2026-04-01T14:56:38.19713Z","steps":["trace[1387438226] 'process raft request' (duration: 547.552373ms)","trace[1387438226] 'compare' (duration: 1.393648636s)"],"step_count":2} {"level":"warn","ts":"2026-04-01T14:56:38.197207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:36.288428Z","time spent":"1.908730353s","remote":"127.0.0.1:48862","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T14:56:38.197278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:36.254776Z","time spent":"1.942441837s","remote":"127.0.0.1:48692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T14:56:38.196769Z","caller":"traceutil/trace.go:171","msg":"trace[651649978] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:606; }","duration":"1.5926668s","start":"2026-04-01T14:56:36.604054Z","end":"2026-04-01T14:56:38.196721Z","steps":["trace[651649978] 'read index received' (duration: 198.201979ms)","trace[651649978] 'applied index is now lower than readState.Index' (duration: 1.394460471s)"],"step_count":2} {"level":"warn","ts":"2026-04-01T14:56:38.198159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.594085236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T14:56:38.1982Z","caller":"traceutil/trace.go:171","msg":"trace[2027359641] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"1.594182879s","start":"2026-04-01T14:56:36.604005Z","end":"2026-04-01T14:56:38.198188Z","steps":["trace[2027359641] 'agreement among raft nodes before linearized reading' (duration: 1.594080676s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:38.19823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:36.603985Z","time spent":"1.59423854s","remote":"127.0.0.1:48500","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-04-01T14:56:38.201416Z","caller":"traceutil/trace.go:171","msg":"trace[671571003] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"656.149472ms","start":"2026-04-01T14:56:37.545241Z","end":"2026-04-01T14:56:38.201391Z","steps":["trace[671571003] 'process raft request' (duration: 656.016518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:38.201496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.585443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-01T14:56:38.201535Z","caller":"traceutil/trace.go:171","msg":"trace[1043514113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"971.672066ms","start":"2026-04-01T14:56:37.229853Z","end":"2026-04-01T14:56:38.201525Z","steps":["trace[1043514113] 'agreement among raft nodes before linearized reading' (duration: 971.510171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:38.201559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:37.229834Z","time spent":"971.718938ms","remote":"127.0.0.1:48786","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-04-01T14:56:38.201594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:37.545216Z","time spent":"656.297356ms","remote":"127.0.0.1:48786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T14:56:38.201733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.082668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T14:56:38.201752Z","caller":"traceutil/trace.go:171","msg":"trace[2131176527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"436.15275ms","start":"2026-04-01T14:56:37.765592Z","end":"2026-04-01T14:56:38.201745Z","steps":["trace[2131176527] 'agreement among raft nodes before linearized reading' (duration: 436.120259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:38.201772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:37.765563Z","time spent":"436.201611ms","remote":"127.0.0.1:48500","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-04-01T14:56:38.201751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.986331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-01T14:56:38.201825Z","caller":"traceutil/trace.go:171","msg":"trace[641933779] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"369.181926ms","start":"2026-04-01T14:56:37.832623Z","end":"2026-04-01T14:56:38.201804Z","steps":["trace[641933779] 'agreement among raft nodes before linearized reading' (duration: 368.842926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:38.201875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:37.832536Z","time spent":"369.329381ms","remote":"127.0.0.1:48786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-01T14:56:40.004747Z","caller":"traceutil/trace.go:171","msg":"trace[1000256460] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"240.072944ms","start":"2026-04-01T14:56:39.764646Z","end":"2026-04-01T14:56:40.004719Z","steps":["trace[1000256460] 'read index received' (duration: 239.968141ms)","trace[1000256460] 'applied index is now lower than readState.Index' (duration: 103.863µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T14:56:40.004995Z","caller":"traceutil/trace.go:171","msg":"trace[484786790] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"277.54594ms","start":"2026-04-01T14:56:39.727405Z","end":"2026-04-01T14:56:40.004951Z","steps":["trace[484786790] 'process raft request' (duration: 277.123267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:40.005063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.24638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T14:56:40.005312Z","caller":"traceutil/trace.go:171","msg":"trace[338308060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"240.527639ms","start":"2026-04-01T14:56:39.764589Z","end":"2026-04-01T14:56:40.005116Z","steps":["trace[338308060] 'agreement among raft nodes before linearized reading' (duration: 240.23758ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:40.023818Z","caller":"traceutil/trace.go:171","msg":"trace[717391671] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"226.047177ms","start":"2026-04-01T14:56:39.797751Z","end":"2026-04-01T14:56:40.023798Z","steps":["trace[717391671] 'process raft request' (duration: 225.520911ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:56:53.964895Z","caller":"traceutil/trace.go:171","msg":"trace[523077793] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"199.292222ms","start":"2026-04-01T14:56:53.765577Z","end":"2026-04-01T14:56:53.964869Z","steps":["trace[523077793] 'read index received' (duration: 199.023923ms)","trace[523077793] 'applied index is now lower than readState.Index' (duration: 267.029µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T14:56:53.96492Z","caller":"traceutil/trace.go:171","msg":"trace[2129512253] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"329.417785ms","start":"2026-04-01T14:56:53.635478Z","end":"2026-04-01T14:56:53.964895Z","steps":["trace[2129512253] 'process raft request' (duration: 329.211988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T14:56:53.965027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.427217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T14:56:53.965051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T14:56:53.635453Z","time spent":"329.535039ms","remote":"127.0.0.1:48750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T14:56:53.965062Z","caller":"traceutil/trace.go:171","msg":"trace[1382252813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"199.51553ms","start":"2026-04-01T14:56:53.765534Z","end":"2026-04-01T14:56:53.965049Z","steps":["trace[1382252813] 'agreement among raft nodes before linearized reading' (duration: 199.439747ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:58:44.993544Z","caller":"traceutil/trace.go:171","msg":"trace[1049128977] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"120.321868ms","start":"2026-04-01T14:58:44.873201Z","end":"2026-04-01T14:58:44.993523Z","steps":["trace[1049128977] 'process raft request' (duration: 120.201654ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:58:44.994413Z","caller":"traceutil/trace.go:171","msg":"trace[482049422] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"118.659955ms","start":"2026-04-01T14:58:44.87573Z","end":"2026-04-01T14:58:44.99439Z","steps":["trace[482049422] 'process raft request' (duration: 118.512529ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T14:58:44.994696Z","caller":"traceutil/trace.go:171","msg":"trace[1654157554] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"117.890696ms","start":"2026-04-01T14:58:44.876781Z","end":"2026-04-01T14:58:44.994672Z","steps":["trace[1654157554] 'process raft request' (duration: 117.564104ms)"],"step_count":1}