{"level":"warn","ts":"2026-02-24T04:35:41.208127Z","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-24T04:35:41.208221Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-24T04:35:41.208303Z","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-24T04:35:41.208314Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-02-24T04:35:41.208339Z","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-24T04:35:41.209156Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-02-24T04:35:41.20924Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-24T04:35:41.214784Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.268758ms"} {"level":"info","ts":"2026-02-24T04:35:41.222032Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-02-24T04:35:41.22217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T04:35:41.222211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-02-24T04:35:41.222236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T04:35:41.222259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-02-24T04:35:41.222329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-02-24T04:35:41.23462Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T04:35:41.239611Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T04:35:41.240627Z","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-24T04:35:41.242826Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T04:35:41.243132Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T04:35:41.243155Z","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-24T04:35:41.247692Z","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-24T04:35:41.247783Z","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-24T04:35:41.248682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-02-24T04:35:41.249596Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-02-24T04:35:41.253603Z","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-24T04:35:41.253717Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-02-24T04:35:41.253773Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-02-24T04:35:41.254384Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T04:35:41.254479Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T04:35:41.723179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T04:35:41.723226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T04:35:41.723243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-02-24T04:35:41.723258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-02-24T04:35:41.723266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-02-24T04:35:41.723277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-02-24T04:35:41.723287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-02-24T04:35:41.833547Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T04:35:41.83368Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T04:35:41.833756Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T04:35:41.833929Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:35:41.835403Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:35:41.835537Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:35:41.835565Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:35:41.83591Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T04:35:41.836595Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T04:35:41.836664Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T04:35:41.836827Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"info","ts":"2026-02-24T04:35:44.234755Z","caller":"traceutil/trace.go:171","msg":"trace[252804644] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"133.320231ms","start":"2026-02-24T04:35:44.101403Z","end":"2026-02-24T04:35:44.234723Z","steps":["trace[252804644] 'process raft request' (duration: 72.145591ms)","trace[252804644] 'compare' (duration: 60.955825ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:35:44.57427Z","caller":"traceutil/trace.go:171","msg":"trace[397696416] transaction","detail":"{read_only:false; response_revision:128; number_of_response:1; }","duration":"127.545069ms","start":"2026-02-24T04:35:44.446693Z","end":"2026-02-24T04:35:44.574238Z","steps":["trace[397696416] 'process raft request' (duration: 127.346005ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:44.907322Z","caller":"traceutil/trace.go:171","msg":"trace[1253388371] linearizableReadLoop","detail":"{readStateIndex:146; appliedIndex:145; }","duration":"112.729479ms","start":"2026-02-24T04:35:44.794559Z","end":"2026-02-24T04:35:44.907289Z","steps":["trace[1253388371] 'read index received' (duration: 73.678036ms)","trace[1253388371] 'applied index is now lower than readState.Index' (duration: 39.050603ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:35:44.907396Z","caller":"traceutil/trace.go:171","msg":"trace[673646573] transaction","detail":"{read_only:false; response_revision:141; number_of_response:1; }","duration":"126.154436ms","start":"2026-02-24T04:35:44.781222Z","end":"2026-02-24T04:35:44.907377Z","steps":["trace[673646573] 'process raft request' (duration: 87.062302ms)","trace[673646573] 'compare' (duration: 38.88154ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:35:44.907502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.900183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:35:44.907561Z","caller":"traceutil/trace.go:171","msg":"trace[80420646] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver; range_end:; response_count:0; response_revision:141; }","duration":"113.026246ms","start":"2026-02-24T04:35:44.794521Z","end":"2026-02-24T04:35:44.907548Z","steps":["trace[80420646] 'agreement among raft nodes before linearized reading' (duration: 112.881203ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:45.109323Z","caller":"traceutil/trace.go:171","msg":"trace[169034084] transaction","detail":"{read_only:false; response_revision:145; number_of_response:1; }","duration":"119.686159ms","start":"2026-02-24T04:35:44.989612Z","end":"2026-02-24T04:35:45.109298Z","steps":["trace[169034084] 'process raft request' (duration: 117.479678ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:45.71326Z","caller":"traceutil/trace.go:171","msg":"trace[1246372262] transaction","detail":"{read_only:false; response_revision:158; number_of_response:1; }","duration":"171.623477ms","start":"2026-02-24T04:35:45.541609Z","end":"2026-02-24T04:35:45.713233Z","steps":["trace[1246372262] 'process raft request' (duration: 94.538893ms)","trace[1246372262] 'compare' (duration: 76.975741ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:35:45.879791Z","caller":"traceutil/trace.go:171","msg":"trace[1842160280] transaction","detail":"{read_only:false; response_revision:160; number_of_response:1; }","duration":"122.968254ms","start":"2026-02-24T04:35:45.756793Z","end":"2026-02-24T04:35:45.879762Z","steps":["trace[1842160280] 'process raft request' (duration: 84.535504ms)","trace[1842160280] 'compare' (duration: 38.284377ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:35:46.691534Z","caller":"traceutil/trace.go:171","msg":"trace[1592014497] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"249.09843ms","start":"2026-02-24T04:35:46.442404Z","end":"2026-02-24T04:35:46.691502Z","steps":["trace[1592014497] 'process raft request' (duration: 150.088494ms)","trace[1592014497] 'compare' (duration: 98.867063ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:35:47.395383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.777937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:35:47.395716Z","caller":"traceutil/trace.go:171","msg":"trace[2100141028] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"157.599265ms","start":"2026-02-24T04:35:47.238098Z","end":"2026-02-24T04:35:47.395697Z","steps":["trace[2100141028] 'process raft request' (duration: 157.410781ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:47.39586Z","caller":"traceutil/trace.go:171","msg":"trace[357551388] linearizableReadLoop","detail":"{readStateIndex:239; appliedIndex:238; }","duration":"158.212369ms","start":"2026-02-24T04:35:47.237618Z","end":"2026-02-24T04:35:47.395831Z","steps":["trace[357551388] 'read index received' (duration: 35.223585ms)","trace[357551388] 'applied index is now lower than readState.Index' (duration: 122.985724ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:35:47.396049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.427674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/system:controller:bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:35:47.396349Z","caller":"traceutil/trace.go:171","msg":"trace[2065791967] range","detail":"{range_begin:/registry/rolebindings/kube-public/system:controller:bootstrap-signer; range_end:; response_count:0; response_revision:235; }","duration":"158.746361ms","start":"2026-02-24T04:35:47.237566Z","end":"2026-02-24T04:35:47.396313Z","steps":["trace[2065791967] 'agreement among raft nodes before linearized reading' (duration: 158.446895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:47.396265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.534173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:35:47.396006Z","caller":"traceutil/trace.go:171","msg":"trace[1975677292] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"159.566871ms","start":"2026-02-24T04:35:47.236415Z","end":"2026-02-24T04:35:47.395982Z","steps":["trace[1975677292] 'process raft request' (duration: 36.418523ms)","trace[1975677292] 'compare' (duration: 121.508091ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:35:47.396658Z","caller":"traceutil/trace.go:171","msg":"trace[1536074238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:235; }","duration":"101.912562ms","start":"2026-02-24T04:35:47.294696Z","end":"2026-02-24T04:35:47.396608Z","steps":["trace[1536074238] 'agreement among raft nodes before linearized reading' (duration: 101.547174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:47.79234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.543487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:35:47.792566Z","caller":"traceutil/trace.go:171","msg":"trace[1684731261] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"192.508625ms","start":"2026-02-24T04:35:47.600011Z","end":"2026-02-24T04:35:47.79252Z","steps":["trace[1684731261] 'process raft request' (duration: 54.695802ms)","trace[1684731261] 'compare' (duration: 137.20087ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:35:48.235653Z","caller":"traceutil/trace.go:171","msg":"trace[1257472196] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"125.259537ms","start":"2026-02-24T04:35:48.11037Z","end":"2026-02-24T04:35:48.23563Z","steps":["trace[1257472196] 'process raft request' (duration: 116.931536ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:48.23594Z","caller":"traceutil/trace.go:171","msg":"trace[444988392] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"125.387119ms","start":"2026-02-24T04:35:48.110525Z","end":"2026-02-24T04:35:48.235912Z","steps":["trace[444988392] 'process raft request' (duration: 125.056202ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:48.447374Z","caller":"traceutil/trace.go:171","msg":"trace[755256157] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"108.877071ms","start":"2026-02-24T04:35:48.338464Z","end":"2026-02-24T04:35:48.447341Z","steps":["trace[755256157] 'process raft request' (duration: 108.814009ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:48.447463Z","caller":"traceutil/trace.go:171","msg":"trace[1231346796] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"179.598711ms","start":"2026-02-24T04:35:48.267835Z","end":"2026-02-24T04:35:48.447434Z","steps":["trace[1231346796] 'process raft request' (duration: 179.375515ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:48.447371Z","caller":"traceutil/trace.go:171","msg":"trace[1068034190] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"179.838115ms","start":"2026-02-24T04:35:48.267478Z","end":"2026-02-24T04:35:48.447316Z","steps":["trace[1068034190] 'process raft request' (duration: 101.543905ms)","trace[1068034190] 'compare' (duration: 77.979003ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:35:48.649189Z","caller":"traceutil/trace.go:171","msg":"trace[1485269745] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"169.636052ms","start":"2026-02-24T04:35:48.479506Z","end":"2026-02-24T04:35:48.649142Z","steps":["trace[1485269745] 'process raft request' (duration: 89.57502ms)","trace[1485269745] 'compare' (duration: 79.531939ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:35:48.670386Z","caller":"traceutil/trace.go:171","msg":"trace[987756595] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"155.229432ms","start":"2026-02-24T04:35:48.51513Z","end":"2026-02-24T04:35:48.670359Z","steps":["trace[987756595] 'process raft request' (duration: 154.915305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:49.222404Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697482000443479,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:35:49.723478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697482000443479,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:35:50.223693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697482000443479,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:35:50.723934Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697482000443479,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:35:50.725775Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.008011529s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T04:35:50.726088Z","caller":"traceutil/trace.go:171","msg":"trace[1619522694] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"2.01505006s","start":"2026-02-24T04:35:48.711012Z","end":"2026-02-24T04:35:50.726062Z","steps":["trace[1619522694] 'process raft request' (duration: 2.014922717s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:50.726371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:48.71097Z","time spent":"2.015161742s","remote":"127.0.0.1:58792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6883,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:35:52.637739Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.911815087s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T04:35:52.643679Z","caller":"traceutil/trace.go:171","msg":"trace[1932931929] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"3.460511896s","start":"2026-02-24T04:35:49.183142Z","end":"2026-02-24T04:35:52.643654Z","steps":["trace[1932931929] 'process raft request' (duration: 3.454784415s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:52.643783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:49.183106Z","time spent":"3.460626819s","remote":"127.0.0.1:58792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5860,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T04:35:52.643857Z","caller":"traceutil/trace.go:171","msg":"trace[238590846] linearizableReadLoop","detail":"{readStateIndex:267; appliedIndex:265; }","duration":"3.921725011s","start":"2026-02-24T04:35:48.721873Z","end":"2026-02-24T04:35:52.643598Z","steps":["trace[238590846] 'read index received' (duration: 2.004046099s)","trace[238590846] 'applied index is now lower than readState.Index' (duration: 1.917677662s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:35:52.643901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.921994477s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:35:52.644001Z","caller":"traceutil/trace.go:171","msg":"trace[1504306009] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:0; response_revision:261; }","duration":"3.922141991s","start":"2026-02-24T04:35:48.721826Z","end":"2026-02-24T04:35:52.643968Z","steps":["trace[1504306009] 'agreement among raft nodes before linearized reading' (duration: 3.921977946s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:52.644045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.124116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:35:52.644067Z","caller":"traceutil/trace.go:171","msg":"trace[1472161430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:261; }","duration":"518.173167ms","start":"2026-02-24T04:35:52.125885Z","end":"2026-02-24T04:35:52.644059Z","steps":["trace[1472161430] 'agreement among raft nodes before linearized reading' (duration: 518.147356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:52.644063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:48.721793Z","time spent":"3.922254113s","remote":"127.0.0.1:58816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-02-24T04:35:52.644087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:52.125868Z","time spent":"518.215088ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T04:35:52.644092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.404214516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-24T04:35:52.644142Z","caller":"traceutil/trace.go:171","msg":"trace[1582099058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:261; }","duration":"2.404297327s","start":"2026-02-24T04:35:50.239828Z","end":"2026-02-24T04:35:52.644125Z","steps":["trace[1582099058] 'agreement among raft nodes before linearized reading' (duration: 2.404229936s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:52.644193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.91537165s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2898"} {"level":"warn","ts":"2026-02-24T04:35:52.644186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:50.239807Z","time spent":"2.40436713s","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T04:35:52.644211Z","caller":"traceutil/trace.go:171","msg":"trace[864928961] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:261; }","duration":"1.91540826s","start":"2026-02-24T04:35:50.728798Z","end":"2026-02-24T04:35:52.644206Z","steps":["trace[864928961] 'agreement among raft nodes before linearized reading' (duration: 1.915372839s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:52.644229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:50.728785Z","time spent":"1.915439631s","remote":"127.0.0.1:58792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2921,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-24T04:35:52.644034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.665965068s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3182"} {"level":"warn","ts":"2026-02-24T04:35:52.644012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.40308325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-24T04:35:52.644306Z","caller":"traceutil/trace.go:171","msg":"trace[699362163] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:261; }","duration":"3.666291265s","start":"2026-02-24T04:35:48.978008Z","end":"2026-02-24T04:35:52.6443Z","steps":["trace[699362163] 'agreement among raft nodes before linearized reading' (duration: 3.665956098s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:52.644326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:48.977986Z","time spent":"3.666333986s","remote":"127.0.0.1:58784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3205,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-24T04:35:52.644336Z","caller":"traceutil/trace.go:171","msg":"trace[1972783031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:261; }","duration":"2.403430107s","start":"2026-02-24T04:35:50.240886Z","end":"2026-02-24T04:35:52.644316Z","steps":["trace[1972783031] 'agreement among raft nodes before linearized reading' (duration: 2.403086339s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:52.644418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:50.240822Z","time spent":"2.403578491s","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T04:35:52.64443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.244315119s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-24T04:35:52.645849Z","caller":"traceutil/trace.go:171","msg":"trace[1837021884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:261; }","duration":"3.245754481s","start":"2026-02-24T04:35:49.400058Z","end":"2026-02-24T04:35:52.645813Z","steps":["trace[1837021884] 'agreement among raft nodes before linearized reading' (duration: 3.244331099s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:52.645949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:49.40003Z","time spent":"3.245883815s","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T04:35:53.310973Z","caller":"traceutil/trace.go:171","msg":"trace[395929500] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"162.836796ms","start":"2026-02-24T04:35:53.1481Z","end":"2026-02-24T04:35:53.310937Z","steps":["trace[395929500] 'process raft request' (duration: 69.307285ms)","trace[395929500] 'compare' (duration: 93.157191ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:35:54.04318Z","caller":"traceutil/trace.go:171","msg":"trace[953227041] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"186.544029ms","start":"2026-02-24T04:35:53.856595Z","end":"2026-02-24T04:35:54.043139Z","steps":["trace[953227041] 'process raft request' (duration: 120.768635ms)","trace[953227041] 'compare' (duration: 65.516669ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:35:54.043328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.271451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-24T04:35:54.043183Z","caller":"traceutil/trace.go:171","msg":"trace[410051821] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:313; }","duration":"125.85582ms","start":"2026-02-24T04:35:53.917279Z","end":"2026-02-24T04:35:54.043135Z","steps":["trace[410051821] 'read index received' (duration: 60.173498ms)","trace[410051821] 'applied index is now lower than readState.Index' (duration: 65.680622ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:35:54.04339Z","caller":"traceutil/trace.go:171","msg":"trace[725971362] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:308; }","duration":"192.368083ms","start":"2026-02-24T04:35:53.851006Z","end":"2026-02-24T04:35:54.043374Z","steps":["trace[725971362] 'agreement among raft nodes before linearized reading' (duration: 192.184029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:54.043395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.953437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"warn","ts":"2026-02-24T04:35:54.043432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.809195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-24T04:35:54.04354Z","caller":"traceutil/trace.go:171","msg":"trace[2116638976] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:308; }","duration":"145.128212ms","start":"2026-02-24T04:35:53.898377Z","end":"2026-02-24T04:35:54.043505Z","steps":["trace[2116638976] 'agreement among raft nodes before linearized reading' (duration: 144.827885ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:54.043616Z","caller":"traceutil/trace.go:171","msg":"trace[2019026311] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:308; }","duration":"187.02558ms","start":"2026-02-24T04:35:53.856544Z","end":"2026-02-24T04:35:54.043569Z","steps":["trace[2019026311] 'agreement among raft nodes before linearized reading' (duration: 186.711423ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:54.052571Z","caller":"traceutil/trace.go:171","msg":"trace[1378502630] transaction","detail":"{read_only:false; number_of_response:0; response_revision:308; }","duration":"102.788442ms","start":"2026-02-24T04:35:53.949762Z","end":"2026-02-24T04:35:54.052551Z","steps":["trace[1378502630] 'process raft request' (duration: 102.70685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:54.052784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.228075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-02-24T04:35:54.052779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.170436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-02-24T04:35:54.052848Z","caller":"traceutil/trace.go:171","msg":"trace[694504686] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:308; }","duration":"104.355058ms","start":"2026-02-24T04:35:53.948475Z","end":"2026-02-24T04:35:54.05283Z","steps":["trace[694504686] 'agreement among raft nodes before linearized reading' (duration: 104.185754ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:54.052861Z","caller":"traceutil/trace.go:171","msg":"trace[1190999953] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:308; }","duration":"129.319639ms","start":"2026-02-24T04:35:53.923523Z","end":"2026-02-24T04:35:54.052843Z","steps":["trace[1190999953] 'agreement among raft nodes before linearized reading' (duration: 129.128875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:54.25292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.060648ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:35:54.253073Z","caller":"traceutil/trace.go:171","msg":"trace[66250853] linearizableReadLoop","detail":"{readStateIndex:321; appliedIndex:319; }","duration":"112.123286ms","start":"2026-02-24T04:35:54.140935Z","end":"2026-02-24T04:35:54.253058Z","steps":["trace[66250853] 'read index received' (duration: 8.841173ms)","trace[66250853] 'applied index is now lower than readState.Index' (duration: 103.281093ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:35:54.253192Z","caller":"traceutil/trace.go:171","msg":"trace[1067285068] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"113.21919ms","start":"2026-02-24T04:35:54.139949Z","end":"2026-02-24T04:35:54.253169Z","steps":["trace[1067285068] 'process raft request' (duration: 113.058187ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:54.253266Z","caller":"traceutil/trace.go:171","msg":"trace[186994313] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"161.074845ms","start":"2026-02-24T04:35:54.092179Z","end":"2026-02-24T04:35:54.253254Z","steps":["trace[186994313] 'process raft request' (duration: 57.609398ms)","trace[186994313] 'compare' (duration: 102.951016ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:35:54.25322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.287419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3688"} {"level":"info","ts":"2026-02-24T04:35:54.253569Z","caller":"traceutil/trace.go:171","msg":"trace[120292042] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:314; }","duration":"112.76328ms","start":"2026-02-24T04:35:54.140737Z","end":"2026-02-24T04:35:54.2535Z","steps":["trace[120292042] 'agreement among raft nodes before linearized reading' (duration: 112.406812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:54.254775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.930334ms","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-24T04:35:54.254881Z","caller":"traceutil/trace.go:171","msg":"trace[1737120603] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:314; }","duration":"113.035778ms","start":"2026-02-24T04:35:54.141831Z","end":"2026-02-24T04:35:54.254866Z","steps":["trace[1737120603] 'agreement among raft nodes before linearized reading' (duration: 112.896765ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:54.988025Z","caller":"traceutil/trace.go:171","msg":"trace[1013820008] transaction","detail":"{read_only:false; number_of_response:0; response_revision:332; }","duration":"107.491469ms","start":"2026-02-24T04:35:54.880507Z","end":"2026-02-24T04:35:54.987999Z","steps":["trace[1013820008] 'process raft request' (duration: 36.224409ms)","trace[1013820008] 'compare' (duration: 71.198508ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:35:54.98912Z","caller":"traceutil/trace.go:171","msg":"trace[1641382821] transaction","detail":"{read_only:false; number_of_response:0; response_revision:332; }","duration":"108.580245ms","start":"2026-02-24T04:35:54.880532Z","end":"2026-02-24T04:35:54.989113Z","steps":["trace[1641382821] 'process raft request' (duration: 108.254798ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:54.989199Z","caller":"traceutil/trace.go:171","msg":"trace[286272982] transaction","detail":"{read_only:false; number_of_response:0; response_revision:332; }","duration":"108.638976ms","start":"2026-02-24T04:35:54.880555Z","end":"2026-02-24T04:35:54.989194Z","steps":["trace[286272982] 'process raft request' (duration: 108.271027ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:54.989282Z","caller":"traceutil/trace.go:171","msg":"trace[562806082] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"105.782691ms","start":"2026-02-24T04:35:54.883491Z","end":"2026-02-24T04:35:54.989274Z","steps":["trace[562806082] 'process raft request' (duration: 105.357181ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:55.134793Z","caller":"traceutil/trace.go:171","msg":"trace[767227159] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"135.824369ms","start":"2026-02-24T04:35:54.998948Z","end":"2026-02-24T04:35:55.134772Z","steps":["trace[767227159] 'process raft request' (duration: 131.009569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:57.549472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.568241ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:35:57.549608Z","caller":"traceutil/trace.go:171","msg":"trace[755331475] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:369; }","duration":"367.713415ms","start":"2026-02-24T04:35:57.181875Z","end":"2026-02-24T04:35:57.549588Z","steps":["trace[755331475] 'range keys from in-memory index tree' (duration: 367.555841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:57.549937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.196565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:35:57.550083Z","caller":"traceutil/trace.go:171","msg":"trace[1820753229] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:384; }","duration":"351.948614ms","start":"2026-02-24T04:35:57.198086Z","end":"2026-02-24T04:35:57.550035Z","steps":["trace[1820753229] 'read index received' (duration: 108.325619ms)","trace[1820753229] 'applied index is now lower than readState.Index' (duration: 243.619675ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:35:57.550286Z","caller":"traceutil/trace.go:171","msg":"trace[111749260] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"393.580507ms","start":"2026-02-24T04:35:57.156636Z","end":"2026-02-24T04:35:57.550217Z","steps":["trace[111749260] 'process raft request' (duration: 149.845629ms)","trace[111749260] 'compare' (duration: 242.872358ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:35:57.550487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.361913ms","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-24T04:35:57.550576Z","caller":"traceutil/trace.go:171","msg":"trace[723311831] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:370; }","duration":"352.528667ms","start":"2026-02-24T04:35:57.198032Z","end":"2026-02-24T04:35:57.55056Z","steps":["trace[723311831] 'agreement among raft nodes before linearized reading' (duration: 352.304432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:57.550675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:57.198003Z","time spent":"352.65978ms","remote":"127.0.0.1:58816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-24T04:35:57.550574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:57.156608Z","time spent":"393.857042ms","remote":"127.0.0.1:58780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T04:35:57.55114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.029602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-24T04:35:57.551244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.595937ms","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-02-24T04:35:57.551324Z","caller":"traceutil/trace.go:171","msg":"trace[1310340445] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:370; }","duration":"147.731339ms","start":"2026-02-24T04:35:57.40358Z","end":"2026-02-24T04:35:57.551312Z","steps":["trace[1310340445] 'agreement among raft nodes before linearized reading' (duration: 147.588226ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:57.551335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.772317ms","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-24T04:35:57.551372Z","caller":"traceutil/trace.go:171","msg":"trace[979652349] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:370; }","duration":"201.812278ms","start":"2026-02-24T04:35:57.349549Z","end":"2026-02-24T04:35:57.551361Z","steps":["trace[979652349] 'agreement among raft nodes before linearized reading' (duration: 201.742266ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:57.551246Z","caller":"traceutil/trace.go:171","msg":"trace[2113955757] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:370; }","duration":"302.183874ms","start":"2026-02-24T04:35:57.24904Z","end":"2026-02-24T04:35:57.551224Z","steps":["trace[2113955757] 'agreement among raft nodes before linearized reading' (duration: 301.951069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:57.551515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:35:57.249023Z","time spent":"302.482612ms","remote":"127.0.0.1:58816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-02-24T04:35:57.551785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.362444ms","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-24T04:35:57.552021Z","caller":"traceutil/trace.go:171","msg":"trace[2125834516] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:370; }","duration":"252.593181ms","start":"2026-02-24T04:35:57.299349Z","end":"2026-02-24T04:35:57.551942Z","steps":["trace[2125834516] 'agreement among raft nodes before linearized reading' (duration: 252.117869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:03.297334Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697482000443831,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T04:36:03.381806Z","caller":"traceutil/trace.go:171","msg":"trace[1263631471] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"644.993509ms","start":"2026-02-24T04:36:02.736497Z","end":"2026-02-24T04:36:03.38149Z","steps":["trace[1263631471] 'process raft request' (duration: 644.759093ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:03.381903Z","caller":"traceutil/trace.go:171","msg":"trace[501155638] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"645.193424ms","start":"2026-02-24T04:36:02.736495Z","end":"2026-02-24T04:36:03.381688Z","steps":["trace[501155638] 'process raft request' (duration: 644.941368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:03.382002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:02.736486Z","time spent":"645.400539ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:36:03.382059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:02.736475Z","time spent":"645.499432ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:36:03.382882Z","caller":"traceutil/trace.go:171","msg":"trace[1375420880] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"646.089645ms","start":"2026-02-24T04:36:02.73677Z","end":"2026-02-24T04:36:03.38286Z","steps":["trace[1375420880] 'process raft request' (duration: 645.821159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:03.382962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:02.736738Z","time spent":"646.183277ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:36:03.390263Z","caller":"traceutil/trace.go:171","msg":"trace[325571099] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:439; }","duration":"601.934674ms","start":"2026-02-24T04:36:02.788313Z","end":"2026-02-24T04:36:03.390248Z","steps":["trace[325571099] 'read index received' (duration: 592.997589ms)","trace[325571099] 'applied index is now lower than readState.Index' (duration: 8.934445ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:03.390345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.045667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-24T04:36:03.390377Z","caller":"traceutil/trace.go:171","msg":"trace[170357659] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:425; }","duration":"602.158679ms","start":"2026-02-24T04:36:02.78821Z","end":"2026-02-24T04:36:03.390369Z","steps":["trace[170357659] 'agreement among raft nodes before linearized reading' (duration: 602.094597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:03.390407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:02.78815Z","time spent":"602.249241ms","remote":"127.0.0.1:58860","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-02-24T04:36:23.744648Z","caller":"traceutil/trace.go:171","msg":"trace[329318144] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"207.799764ms","start":"2026-02-24T04:36:23.536811Z","end":"2026-02-24T04:36:23.74461Z","steps":["trace[329318144] 'read index received' (duration: 123.320999ms)","trace[329318144] 'applied index is now lower than readState.Index' (duration: 84.476845ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:23.744887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.055451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-24T04:36:23.744923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.183798ms","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-24T04:36:23.744923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.430503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-24T04:36:23.74497Z","caller":"traceutil/trace.go:171","msg":"trace[1856074191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"193.25964ms","start":"2026-02-24T04:36:23.551696Z","end":"2026-02-24T04:36:23.744955Z","steps":["trace[1856074191] 'agreement among raft nodes before linearized reading' (duration: 193.115937ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:23.745005Z","caller":"traceutil/trace.go:171","msg":"trace[918812728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:534; }","duration":"199.556465ms","start":"2026-02-24T04:36:23.545434Z","end":"2026-02-24T04:36:23.744991Z","steps":["trace[918812728] 'agreement among raft nodes before linearized reading' (duration: 199.374011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:23.745028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.30469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T04:36:23.744951Z","caller":"traceutil/trace.go:171","msg":"trace[1347192219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:534; }","duration":"208.176553ms","start":"2026-02-24T04:36:23.536757Z","end":"2026-02-24T04:36:23.744933Z","steps":["trace[1347192219] 'agreement among raft nodes before linearized reading' (duration: 207.974919ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:23.745105Z","caller":"traceutil/trace.go:171","msg":"trace[170208529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:534; }","duration":"199.472873ms","start":"2026-02-24T04:36:23.545611Z","end":"2026-02-24T04:36:23.745084Z","steps":["trace[170208529] 'agreement among raft nodes before linearized reading' (duration: 199.240828ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:26.119912Z","caller":"traceutil/trace.go:171","msg":"trace[2004449195] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"139.42753ms","start":"2026-02-24T04:36:25.980457Z","end":"2026-02-24T04:36:26.119884Z","steps":["trace[2004449195] 'process raft request' (duration: 139.234965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:27.110641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.899828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:36:27.110722Z","caller":"traceutil/trace.go:171","msg":"trace[357828755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:548; }","duration":"659.058932ms","start":"2026-02-24T04:36:26.451647Z","end":"2026-02-24T04:36:27.110705Z","steps":["trace[357828755] 'range keys from in-memory index tree' (duration: 658.823757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:27.11076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:26.451623Z","time spent":"659.129184ms","remote":"127.0.0.1:58608","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-24T04:36:28.398881Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697482000444092,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T04:36:28.402499Z","caller":"traceutil/trace.go:171","msg":"trace[1519771038] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"637.158578ms","start":"2026-02-24T04:36:27.765317Z","end":"2026-02-24T04:36:28.402476Z","steps":["trace[1519771038] 'process raft request' (duration: 636.991314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:28.402627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:27.7653Z","time spent":"637.26035ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:36:28.503404Z","caller":"traceutil/trace.go:171","msg":"trace[1332676606] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:571; }","duration":"605.599622ms","start":"2026-02-24T04:36:27.897777Z","end":"2026-02-24T04:36:28.503376Z","steps":["trace[1332676606] 'read index received' (duration: 504.526655ms)","trace[1332676606] 'applied index is now lower than readState.Index' (duration: 101.071717ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:28.50345Z","caller":"traceutil/trace.go:171","msg":"trace[1512017746] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"736.019094ms","start":"2026-02-24T04:36:27.767401Z","end":"2026-02-24T04:36:28.50342Z","steps":["trace[1512017746] 'process raft request' (duration: 735.578834ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:28.503549Z","caller":"traceutil/trace.go:171","msg":"trace[355005348] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"734.875947ms","start":"2026-02-24T04:36:27.768662Z","end":"2026-02-24T04:36:28.503538Z","steps":["trace[355005348] 'process raft request' (duration: 734.629272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:28.50359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.807996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-24T04:36:28.503636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:27.767387Z","time spent":"736.149477ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:36:28.503653Z","caller":"traceutil/trace.go:171","msg":"trace[673535440] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:552; }","duration":"605.884338ms","start":"2026-02-24T04:36:27.897754Z","end":"2026-02-24T04:36:28.503638Z","steps":["trace[673535440] 'agreement among raft nodes before linearized reading' (duration: 605.779145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:28.503695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:27.897729Z","time spent":"605.95727ms","remote":"127.0.0.1:58646","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.240\" "} {"level":"warn","ts":"2026-02-24T04:36:28.503677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:27.768646Z","time spent":"734.935618ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:36:28.503128Z","caller":"traceutil/trace.go:171","msg":"trace[378565045] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"737.259942ms","start":"2026-02-24T04:36:27.765826Z","end":"2026-02-24T04:36:28.503086Z","steps":["trace[378565045] 'process raft request' (duration: 734.403947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:28.504182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:27.765805Z","time spent":"738.228405ms","remote":"127.0.0.1:58860","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-24T04:36:42.632454Z","caller":"traceutil/trace.go:171","msg":"trace[951449068] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"118.340255ms","start":"2026-02-24T04:36:42.514093Z","end":"2026-02-24T04:36:42.632433Z","steps":["trace[951449068] 'process raft request' (duration: 91.823104ms)","trace[951449068] 'compare' (duration: 26.407178ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:42.920904Z","caller":"traceutil/trace.go:171","msg":"trace[749871705] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:714; }","duration":"272.459822ms","start":"2026-02-24T04:36:42.64842Z","end":"2026-02-24T04:36:42.92088Z","steps":["trace[749871705] 'read index received' (duration: 269.380881ms)","trace[749871705] 'applied index is now lower than readState.Index' (duration: 3.077141ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:42.920968Z","caller":"traceutil/trace.go:171","msg":"trace[242465853] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"283.69393ms","start":"2026-02-24T04:36:42.637239Z","end":"2026-02-24T04:36:42.920932Z","steps":["trace[242465853] 'process raft request' (duration: 280.578259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:42.92115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.714459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-7r52l\" ","response":"range_response_count:1 size:4697"} {"level":"info","ts":"2026-02-24T04:36:42.921182Z","caller":"traceutil/trace.go:171","msg":"trace[816696500] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-7r52l; range_end:; response_count:1; response_revision:688; }","duration":"272.79996ms","start":"2026-02-24T04:36:42.648373Z","end":"2026-02-24T04:36:42.921173Z","steps":["trace[816696500] 'agreement among raft nodes before linearized reading' (duration: 272.605345ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:48.820388Z","caller":"traceutil/trace.go:171","msg":"trace[1948747094] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"160.827073ms","start":"2026-02-24T04:36:48.659529Z","end":"2026-02-24T04:36:48.820356Z","steps":["trace[1948747094] 'process raft request' (duration: 160.664719ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:12.043755Z","caller":"traceutil/trace.go:171","msg":"trace[916904583] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"129.554663ms","start":"2026-02-24T04:37:11.914174Z","end":"2026-02-24T04:37:12.043729Z","steps":["trace[916904583] 'process raft request' (duration: 128.919828ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:12.043927Z","caller":"traceutil/trace.go:171","msg":"trace[507140844] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"116.558684ms","start":"2026-02-24T04:37:11.927338Z","end":"2026-02-24T04:37:12.043897Z","steps":["trace[507140844] 'process raft request' (duration: 116.178405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:16.562079Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697482000444704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:37:17.062186Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697482000444704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:37:17.186269Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.128609441s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-24T04:37:17.375466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.193945ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:17.375598Z","caller":"traceutil/trace.go:171","msg":"trace[1940518658] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:821; }","duration":"192.341478ms","start":"2026-02-24T04:37:17.183238Z","end":"2026-02-24T04:37:17.37558Z","steps":["trace[1940518658] 'range keys from in-memory index tree' (duration: 192.174124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:17.37647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.88345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:37:17.37652Z","caller":"traceutil/trace.go:171","msg":"trace[2058225066] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"1.318964364s","start":"2026-02-24T04:37:16.057546Z","end":"2026-02-24T04:37:17.376511Z","steps":["trace[2058225066] 'process raft request' (duration: 1.128969851s)","trace[2058225066] 'compare' (duration: 189.454521ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:17.376558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:16.057522Z","time spent":"1.319016676s","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:37:17.425502Z","caller":"traceutil/trace.go:171","msg":"trace[1901751689] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:856; }","duration":"1.363900369s","start":"2026-02-24T04:37:16.061578Z","end":"2026-02-24T04:37:17.425479Z","steps":["trace[1901751689] 'read index received' (duration: 1.124951378s)","trace[1901751689] 'applied index is now lower than readState.Index' (duration: 238.947651ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:17.425651Z","caller":"traceutil/trace.go:171","msg":"trace[464142303] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"1.344743198s","start":"2026-02-24T04:37:16.080854Z","end":"2026-02-24T04:37:17.425597Z","steps":["trace[464142303] 'process raft request' (duration: 1.344505202s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:17.425735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.409786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-24T04:37:17.425769Z","caller":"traceutil/trace.go:171","msg":"trace[940671369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:823; }","duration":"363.476018ms","start":"2026-02-24T04:37:17.062282Z","end":"2026-02-24T04:37:17.425758Z","steps":["trace[940671369] 'agreement among raft nodes before linearized reading' (duration: 363.322015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:17.425796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:17.062265Z","time spent":"363.523549ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T04:37:17.425766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.364180905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-24T04:37:17.4259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:16.08083Z","time spent":"1.344940123s","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:37:17.425947Z","caller":"traceutil/trace.go:171","msg":"trace[318523219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:823; }","duration":"1.36436983s","start":"2026-02-24T04:37:16.061543Z","end":"2026-02-24T04:37:17.425913Z","steps":["trace[318523219] 'agreement among raft nodes before linearized reading' (duration: 1.364051323s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:17.425995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:16.061529Z","time spent":"1.364453161s","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T04:37:18.51939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.456866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:37:18.51961Z","caller":"traceutil/trace.go:171","msg":"trace[1091449605] linearizableReadLoop","detail":"{readStateIndex:864; appliedIndex:863; }","duration":"477.458491ms","start":"2026-02-24T04:37:18.042128Z","end":"2026-02-24T04:37:18.519587Z","steps":["trace[1091449605] 'read index received' (duration: 153.605576ms)","trace[1091449605] 'applied index is now lower than readState.Index' (duration: 323.834524ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:18.51964Z","caller":"traceutil/trace.go:171","msg":"trace[703573795] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"477.649385ms","start":"2026-02-24T04:37:18.041952Z","end":"2026-02-24T04:37:18.519601Z","steps":["trace[703573795] 'process raft request' (duration: 153.869642ms)","trace[703573795] 'compare' (duration: 323.20097ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:18.519749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:18.041936Z","time spent":"477.757858ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:37:18.519865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.729508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T04:37:18.519911Z","caller":"traceutil/trace.go:171","msg":"trace[742755067] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:828; }","duration":"477.819149ms","start":"2026-02-24T04:37:18.042078Z","end":"2026-02-24T04:37:18.519897Z","steps":["trace[742755067] 'agreement among raft nodes before linearized reading' (duration: 477.623694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:18.519954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:18.042063Z","time spent":"477.878211ms","remote":"127.0.0.1:58646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-02-24T04:37:19.616031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.283181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-24T04:37:19.616046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.898751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-24T04:37:19.616162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.708558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-24T04:37:19.616185Z","caller":"traceutil/trace.go:171","msg":"trace[1566994982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:828; }","duration":"140.76767ms","start":"2026-02-24T04:37:19.475411Z","end":"2026-02-24T04:37:19.616179Z","steps":["trace[1566994982] 'range keys from in-memory index tree' (duration: 140.605657ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:19.616165Z","caller":"traceutil/trace.go:171","msg":"trace[1162843550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:828; }","duration":"236.080974ms","start":"2026-02-24T04:37:19.380056Z","end":"2026-02-24T04:37:19.616137Z","steps":["trace[1162843550] 'range keys from in-memory index tree' (duration: 235.653605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:19.616284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.584837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:19.616383Z","caller":"traceutil/trace.go:171","msg":"trace[1907830552] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:828; }","duration":"471.70908ms","start":"2026-02-24T04:37:19.144649Z","end":"2026-02-24T04:37:19.616358Z","steps":["trace[1907830552] 'count revisions from in-memory index tree' (duration: 471.502125ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:19.616106Z","caller":"traceutil/trace.go:171","msg":"trace[621526652] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:828; }","duration":"157.402384ms","start":"2026-02-24T04:37:19.458683Z","end":"2026-02-24T04:37:19.616085Z","steps":["trace[621526652] 'range keys from in-memory index tree' (duration: 157.029905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:19.616434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:19.144585Z","time spent":"471.836473ms","remote":"127.0.0.1:58768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-24T04:37:20.191804Z","caller":"traceutil/trace.go:171","msg":"trace[602254492] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"570.586215ms","start":"2026-02-24T04:37:19.621192Z","end":"2026-02-24T04:37:20.191778Z","steps":["trace[602254492] 'process raft request' (duration: 570.418451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:20.191996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:19.621173Z","time spent":"570.717298ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:37:20.216178Z","caller":"traceutil/trace.go:171","msg":"trace[902293625] linearizableReadLoop","detail":"{readStateIndex:867; appliedIndex:864; }","duration":"306.456065ms","start":"2026-02-24T04:37:19.909693Z","end":"2026-02-24T04:37:20.216149Z","steps":["trace[902293625] 'read index received' (duration: 281.94381ms)","trace[902293625] 'applied index is now lower than readState.Index' (duration: 24.510965ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:20.216292Z","caller":"traceutil/trace.go:171","msg":"trace[1145322346] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"594.365773ms","start":"2026-02-24T04:37:19.621886Z","end":"2026-02-24T04:37:20.216252Z","steps":["trace[1145322346] 'process raft request' (duration: 594.087657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:20.216378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.268753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T04:37:20.216426Z","caller":"traceutil/trace.go:171","msg":"trace[840232799] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:831; }","duration":"181.401667ms","start":"2026-02-24T04:37:20.035008Z","end":"2026-02-24T04:37:20.21641Z","steps":["trace[840232799] 'agreement among raft nodes before linearized reading' (duration: 181.282984ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:20.216411Z","caller":"traceutil/trace.go:171","msg":"trace[1313450924] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"592.971421ms","start":"2026-02-24T04:37:19.623411Z","end":"2026-02-24T04:37:20.216383Z","steps":["trace[1313450924] 'process raft request' (duration: 592.693345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:20.216455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.763052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-24T04:37:20.216489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:19.621871Z","time spent":"594.513836ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:37:20.21653Z","caller":"traceutil/trace.go:171","msg":"trace[1587404726] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:831; }","duration":"306.916455ms","start":"2026-02-24T04:37:19.909596Z","end":"2026-02-24T04:37:20.216512Z","steps":["trace[1587404726] 'agreement among raft nodes before linearized reading' (duration: 306.70168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:20.216548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:19.623381Z","time spent":"593.095524ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:37:20.216573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:19.909559Z","time spent":"307.003517ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T04:37:20.764048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.768071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:37:20.764225Z","caller":"traceutil/trace.go:171","msg":"trace[247405769] linearizableReadLoop","detail":"{readStateIndex:868; appliedIndex:867; }","duration":"311.683826ms","start":"2026-02-24T04:37:20.452495Z","end":"2026-02-24T04:37:20.764179Z","steps":["trace[247405769] 'read index received' (duration: 53.261µs)","trace[247405769] 'applied index is now lower than readState.Index' (duration: 311.628585ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:20.764215Z","caller":"traceutil/trace.go:171","msg":"trace[2039553454] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"543.468321ms","start":"2026-02-24T04:37:20.220711Z","end":"2026-02-24T04:37:20.764179Z","steps":["trace[2039553454] 'process raft request' (duration: 164.413394ms)","trace[2039553454] 'compare' (duration: 378.436732ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:20.764387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:20.220655Z","time spent":"543.630745ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:37:20.764398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.907851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:20.764433Z","caller":"traceutil/trace.go:171","msg":"trace[1209485216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:832; }","duration":"311.939122ms","start":"2026-02-24T04:37:20.452479Z","end":"2026-02-24T04:37:20.764418Z","steps":["trace[1209485216] 'agreement among raft nodes before linearized reading' (duration: 311.85344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:20.764481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:20.452419Z","time spent":"312.053174ms","remote":"127.0.0.1:58608","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-24T04:37:20.764905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.288714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T04:37:20.764978Z","caller":"traceutil/trace.go:171","msg":"trace[1295837450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:832; }","duration":"241.414987ms","start":"2026-02-24T04:37:20.523545Z","end":"2026-02-24T04:37:20.76496Z","steps":["trace[1295837450] 'agreement among raft nodes before linearized reading' (duration: 241.194802ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:24.434765Z","caller":"traceutil/trace.go:171","msg":"trace[545207582] linearizableReadLoop","detail":"{readStateIndex:876; appliedIndex:875; }","duration":"151.363895ms","start":"2026-02-24T04:37:24.283375Z","end":"2026-02-24T04:37:24.434738Z","steps":["trace[545207582] 'read index received' (duration: 151.14024ms)","trace[545207582] 'applied index is now lower than readState.Index' (duration: 222.285µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:24.434969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.577279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-24T04:37:24.435012Z","caller":"traceutil/trace.go:171","msg":"trace[1479834209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:839; }","duration":"151.702292ms","start":"2026-02-24T04:37:24.283303Z","end":"2026-02-24T04:37:24.435005Z","steps":["trace[1479834209] 'agreement among raft nodes before linearized reading' (duration: 151.528618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:24.435269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.840785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-24T04:37:24.435312Z","caller":"traceutil/trace.go:171","msg":"trace[1941020968] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:839; }","duration":"151.960667ms","start":"2026-02-24T04:37:24.28334Z","end":"2026-02-24T04:37:24.435301Z","steps":["trace[1941020968] 'agreement among raft nodes before linearized reading' (duration: 151.553708ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:24.434806Z","caller":"traceutil/trace.go:171","msg":"trace[504026242] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"195.326665ms","start":"2026-02-24T04:37:24.239452Z","end":"2026-02-24T04:37:24.434778Z","steps":["trace[504026242] 'process raft request' (duration: 195.057879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:24.882656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.278709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:37:24.882747Z","caller":"traceutil/trace.go:171","msg":"trace[144960585] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"442.975368ms","start":"2026-02-24T04:37:24.439756Z","end":"2026-02-24T04:37:24.882732Z","steps":["trace[144960585] 'process raft request' (duration: 187.528925ms)","trace[144960585] 'compare' (duration: 255.003093ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:24.882792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:24.439733Z","time spent":"443.037089ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:37:25.365408Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697482000444760,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T04:37:25.390553Z","caller":"traceutil/trace.go:171","msg":"trace[2064999117] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"949.75582ms","start":"2026-02-24T04:37:24.440764Z","end":"2026-02-24T04:37:25.39052Z","steps":["trace[2064999117] 'process raft request' (duration: 949.552686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:25.390751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:24.440752Z","time spent":"949.887933ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:37:25.403021Z","caller":"traceutil/trace.go:171","msg":"trace[1220634893] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:877; }","duration":"538.165274ms","start":"2026-02-24T04:37:24.864825Z","end":"2026-02-24T04:37:25.40299Z","steps":["trace[1220634893] 'read index received' (duration: 525.553194ms)","trace[1220634893] 'applied index is now lower than readState.Index' (duration: 12.6115ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:25.403184Z","caller":"traceutil/trace.go:171","msg":"trace[883344599] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"146.876932ms","start":"2026-02-24T04:37:25.256284Z","end":"2026-02-24T04:37:25.403161Z","steps":["trace[883344599] 'process raft request' (duration: 146.612315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:25.403357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.568578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T04:37:25.403419Z","caller":"traceutil/trace.go:171","msg":"trace[1823271643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:842; }","duration":"506.686241ms","start":"2026-02-24T04:37:24.896723Z","end":"2026-02-24T04:37:25.40341Z","steps":["trace[1823271643] 'agreement among raft nodes before linearized reading' (duration: 506.493756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:25.403462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:24.896705Z","time spent":"506.748282ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T04:37:25.403625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.798699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T04:37:25.403663Z","caller":"traceutil/trace.go:171","msg":"trace[898220886] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:842; }","duration":"538.87285ms","start":"2026-02-24T04:37:24.864782Z","end":"2026-02-24T04:37:25.403655Z","steps":["trace[898220886] 'agreement among raft nodes before linearized reading' (duration: 538.384819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:25.403698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:24.864763Z","time spent":"538.928171ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T04:37:25.515237Z","caller":"traceutil/trace.go:171","msg":"trace[574636254] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"106.600387ms","start":"2026-02-24T04:37:25.408581Z","end":"2026-02-24T04:37:25.515181Z","steps":["trace[574636254] 'process raft request' (duration: 27.284196ms)","trace[574636254] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-svcs-lock; req_size:419; } (duration: 78.812748ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:25.515296Z","caller":"traceutil/trace.go:171","msg":"trace[984455682] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"106.316381ms","start":"2026-02-24T04:37:25.408929Z","end":"2026-02-24T04:37:25.515245Z","steps":["trace[984455682] 'process raft request' (duration: 105.997072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:26.111277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:25.777646Z","time spent":"333.624517ms","remote":"127.0.0.1:48298","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-24T04:37:26.641376Z","caller":"traceutil/trace.go:171","msg":"trace[20880304] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"195.619801ms","start":"2026-02-24T04:37:26.445734Z","end":"2026-02-24T04:37:26.641353Z","steps":["trace[20880304] 'process raft request' (duration: 195.481287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:27.04974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.061455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-24T04:37:27.049821Z","caller":"traceutil/trace.go:171","msg":"trace[1701608842] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:845; }","duration":"164.187898ms","start":"2026-02-24T04:37:26.885613Z","end":"2026-02-24T04:37:27.049801Z","steps":["trace[1701608842] 'range keys from in-memory index tree' (duration: 163.858891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:27.89544Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697482000444772,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T04:37:27.995962Z","caller":"traceutil/trace.go:171","msg":"trace[258784538] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"942.282279ms","start":"2026-02-24T04:37:27.053647Z","end":"2026-02-24T04:37:27.995929Z","steps":["trace[258784538] 'process raft request' (duration: 942.052534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:27.996296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:27.053626Z","time spent":"942.554115ms","remote":"127.0.0.1:58860","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-24T04:37:27.999852Z","caller":"traceutil/trace.go:171","msg":"trace[650369805] linearizableReadLoop","detail":"{readStateIndex:884; appliedIndex:882; }","duration":"604.544367ms","start":"2026-02-24T04:37:27.395291Z","end":"2026-02-24T04:37:27.999835Z","steps":["trace[650369805] 'read index received' (duration: 600.477213ms)","trace[650369805] 'applied index is now lower than readState.Index' (duration: 4.066584ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:28.000012Z","caller":"traceutil/trace.go:171","msg":"trace[1331319815] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"212.819695ms","start":"2026-02-24T04:37:27.787136Z","end":"2026-02-24T04:37:27.999956Z","steps":["trace[1331319815] 'process raft request' (duration: 212.597899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:28.0002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.319743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:28.000269Z","caller":"traceutil/trace.go:171","msg":"trace[1097886288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:847; }","duration":"550.412165ms","start":"2026-02-24T04:37:27.44984Z","end":"2026-02-24T04:37:28.000252Z","steps":["trace[1097886288] 'agreement among raft nodes before linearized reading' (duration: 550.312522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:28.000304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:27.449824Z","time spent":"550.472047ms","remote":"127.0.0.1:58608","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-24T04:37:28.000325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.973597ms","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-24T04:37:28.00041Z","caller":"traceutil/trace.go:171","msg":"trace[1729775614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:847; }","duration":"605.10416ms","start":"2026-02-24T04:37:27.395248Z","end":"2026-02-24T04:37:28.000352Z","steps":["trace[1729775614] 'agreement among raft nodes before linearized reading' (duration: 604.68646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:28.000544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:27.395229Z","time spent":"605.303664ms","remote":"127.0.0.1:58860","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-24T04:37:28.000627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.636003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T04:37:28.000668Z","caller":"traceutil/trace.go:171","msg":"trace[1193928609] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:847; }","duration":"480.714665ms","start":"2026-02-24T04:37:27.519942Z","end":"2026-02-24T04:37:28.000656Z","steps":["trace[1193928609] 'agreement among raft nodes before linearized reading' (duration: 480.571012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:28.00065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.303366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-24T04:37:28.000702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:27.519922Z","time spent":"480.772797ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T04:37:28.000749Z","caller":"traceutil/trace.go:171","msg":"trace[1384098975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:847; }","duration":"480.437168ms","start":"2026-02-24T04:37:27.520284Z","end":"2026-02-24T04:37:28.000721Z","steps":["trace[1384098975] 'agreement among raft nodes before linearized reading' (duration: 480.110911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:28.000814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:27.520271Z","time spent":"480.530001ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T04:37:40.651463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.83716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:40.651667Z","caller":"traceutil/trace.go:171","msg":"trace[642827262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"201.077646ms","start":"2026-02-24T04:37:40.450568Z","end":"2026-02-24T04:37:40.651646Z","steps":["trace[642827262] 'range keys from in-memory index tree' (duration: 200.768678ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:41.224255Z","caller":"traceutil/trace.go:171","msg":"trace[1961260612] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"376.543443ms","start":"2026-02-24T04:37:40.847692Z","end":"2026-02-24T04:37:41.224236Z","steps":["trace[1961260612] 'process raft request' (duration: 376.430641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:41.224375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:40.847681Z","time spent":"376.627115ms","remote":"127.0.0.1:58860","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-02-24T04:37:51.968893Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697482000445050,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:37:52.46996Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153697482000445050,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:37:52.938783Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.635408109s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T04:37:52.939226Z","caller":"traceutil/trace.go:171","msg":"trace[1422618964] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"1.470552055s","start":"2026-02-24T04:37:51.468649Z","end":"2026-02-24T04:37:52.939201Z","steps":["trace[1422618964] 'read index received' (duration: 1.470259278s)","trace[1422618964] 'applied index is now lower than readState.Index' (duration: 291.657µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:52.939333Z","caller":"traceutil/trace.go:171","msg":"trace[2098514891] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"1.636020423s","start":"2026-02-24T04:37:51.303275Z","end":"2026-02-24T04:37:52.939296Z","steps":["trace[2098514891] 'process raft request' (duration: 1.635754727s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:52.939491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.58512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-24T04:37:52.939495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:51.303258Z","time spent":"1.636128875s","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:37:52.939537Z","caller":"traceutil/trace.go:171","msg":"trace[517332278] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:964; }","duration":"825.708284ms","start":"2026-02-24T04:37:52.113816Z","end":"2026-02-24T04:37:52.939525Z","steps":["trace[517332278] 'agreement among raft nodes before linearized reading' (duration: 825.53874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:52.939562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.480182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:37:52.939582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:52.113786Z","time spent":"825.787365ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T04:37:52.939471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.47079946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:52.939622Z","caller":"traceutil/trace.go:171","msg":"trace[1033505652] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:964; }","duration":"444.534003ms","start":"2026-02-24T04:37:52.495067Z","end":"2026-02-24T04:37:52.939601Z","steps":["trace[1033505652] 'agreement among raft nodes before linearized reading' (duration: 444.465461ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:52.939662Z","caller":"traceutil/trace.go:171","msg":"trace[2106873342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"1.471071347s","start":"2026-02-24T04:37:51.468579Z","end":"2026-02-24T04:37:52.93965Z","steps":["trace[2106873342] 'agreement among raft nodes before linearized reading' (duration: 1.47077076s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:52.939668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:52.494979Z","time spent":"444.680077ms","remote":"127.0.0.1:58920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:37:52.939702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:51.468555Z","time spent":"1.471136718s","remote":"127.0.0.1:58608","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-24T04:37:52.939755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.476771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-24T04:37:52.939791Z","caller":"traceutil/trace.go:171","msg":"trace[288514203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:964; }","duration":"701.530693ms","start":"2026-02-24T04:37:52.238249Z","end":"2026-02-24T04:37:52.93978Z","steps":["trace[288514203] 'agreement among raft nodes before linearized reading' (duration: 701.447851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:52.939827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:52.238239Z","time spent":"701.579384ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T04:37:52.939954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.710982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-24T04:37:52.939986Z","caller":"traceutil/trace.go:171","msg":"trace[1960531788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:964; }","duration":"713.769953ms","start":"2026-02-24T04:37:52.226207Z","end":"2026-02-24T04:37:52.939977Z","steps":["trace[1960531788] 'agreement among raft nodes before linearized reading' (duration: 713.691901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:52.939999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.067024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:37:52.940017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:52.226194Z","time spent":"713.817254ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T04:37:52.940036Z","caller":"traceutil/trace.go:171","msg":"trace[453869177] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:964; }","duration":"204.117735ms","start":"2026-02-24T04:37:52.735904Z","end":"2026-02-24T04:37:52.940022Z","steps":["trace[453869177] 'agreement among raft nodes before linearized reading' (duration: 204.064164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:52.940238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.790347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-24T04:37:52.940293Z","caller":"traceutil/trace.go:171","msg":"trace[807321252] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:964; }","duration":"714.894899ms","start":"2026-02-24T04:37:52.225386Z","end":"2026-02-24T04:37:52.94028Z","steps":["trace[807321252] 'agreement among raft nodes before linearized reading' (duration: 714.719075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:52.940332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:52.225359Z","time spent":"714.965551ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T04:37:53.566142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.097303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:37:53.566289Z","caller":"traceutil/trace.go:171","msg":"trace[1339447320] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"620.279278ms","start":"2026-02-24T04:37:52.945983Z","end":"2026-02-24T04:37:53.566262Z","steps":["trace[1339447320] 'process raft request' (duration: 260.924849ms)","trace[1339447320] 'compare' (duration: 358.732115ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:53.566357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:52.94595Z","time spent":"620.37606ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:37:54.274756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.397685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:37:54.27516Z","caller":"traceutil/trace.go:171","msg":"trace[2114591601] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"1.32833004s","start":"2026-02-24T04:37:52.946782Z","end":"2026-02-24T04:37:54.275112Z","steps":["trace[2114591601] 'process raft request' (duration: 983.450103ms)","trace[2114591601] 'compare' (duration: 344.197161ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:54.275241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:52.946762Z","time spent":"1.328442543s","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:37:54.275226Z","caller":"traceutil/trace.go:171","msg":"trace[513849179] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"1.327641674s","start":"2026-02-24T04:37:52.947525Z","end":"2026-02-24T04:37:54.275167Z","steps":["trace[513849179] 'process raft request' (duration: 1.327358467s)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:54.275413Z","caller":"traceutil/trace.go:171","msg":"trace[2056519431] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"1.325078346s","start":"2026-02-24T04:37:52.950296Z","end":"2026-02-24T04:37:54.275375Z","steps":["trace[2056519431] 'process raft request' (duration: 1.324657926s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:54.275444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:52.947514Z","time spent":"1.327803428s","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:37:54.275587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:52.950282Z","time spent":"1.325213558s","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:37:54.307105Z","caller":"traceutil/trace.go:171","msg":"trace[585865385] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1007; }","duration":"1.357501938s","start":"2026-02-24T04:37:52.949562Z","end":"2026-02-24T04:37:54.307063Z","steps":["trace[585865385] 'read index received' (duration: 257.365107ms)","trace[585865385] 'applied index is now lower than readState.Index' (duration: 1.100134331s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:54.307432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.357824507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:37:54.307574Z","caller":"traceutil/trace.go:171","msg":"trace[168609609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"1.358025391s","start":"2026-02-24T04:37:52.949515Z","end":"2026-02-24T04:37:54.307541Z","steps":["trace[168609609] 'agreement among raft nodes before linearized reading' (duration: 1.357682882s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:54.307657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:52.9495Z","time spent":"1.358145215s","remote":"127.0.0.1:58608","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-24T04:37:59.734584Z","caller":"traceutil/trace.go:171","msg":"trace[122054751] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"118.406298ms","start":"2026-02-24T04:37:59.616157Z","end":"2026-02-24T04:37:59.734563Z","steps":["trace[122054751] 'process raft request' (duration: 118.283625ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:38:02.522105Z","caller":"traceutil/trace.go:171","msg":"trace[1235134637] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"122.533923ms","start":"2026-02-24T04:38:02.39948Z","end":"2026-02-24T04:38:02.522014Z","steps":["trace[1235134637] 'process raft request' (duration: 30.52727ms)","trace[1235134637] 'compare' (duration: 91.499821ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:38:02.523279Z","caller":"traceutil/trace.go:171","msg":"trace[780188757] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"123.069005ms","start":"2026-02-24T04:38:02.400127Z","end":"2026-02-24T04:38:02.523196Z","steps":["trace[780188757] 'process raft request' (duration: 121.996301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:38:08.1141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.909466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T04:38:08.114377Z","caller":"traceutil/trace.go:171","msg":"trace[753210005] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:1004; }","duration":"209.09573ms","start":"2026-02-24T04:38:07.90509Z","end":"2026-02-24T04:38:08.114185Z","steps":["trace[753210005] 'range keys from in-memory index tree' (duration: 208.575658ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:38:14.841153Z","caller":"traceutil/trace.go:171","msg":"trace[1955147922] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"360.114126ms","start":"2026-02-24T04:38:14.481Z","end":"2026-02-24T04:38:14.841114Z","steps":["trace[1955147922] 'process raft request' (duration: 359.8653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:38:14.841422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:38:14.480961Z","time spent":"360.331881ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:38:14.8422Z","caller":"traceutil/trace.go:171","msg":"trace[30529738] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1091; }","duration":"266.385634ms","start":"2026-02-24T04:38:14.575796Z","end":"2026-02-24T04:38:14.842182Z","steps":["trace[30529738] 'read index received' (duration: 266.379014ms)","trace[30529738] 'applied index is now lower than readState.Index' (duration: 4.96µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:38:14.842391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.385354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-24T04:38:14.842426Z","caller":"traceutil/trace.go:171","msg":"trace[1026583242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1042; }","duration":"266.438925ms","start":"2026-02-24T04:38:14.575975Z","end":"2026-02-24T04:38:14.842413Z","steps":["trace[1026583242] 'agreement among raft nodes before linearized reading' (duration: 266.289032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:38:14.842593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.799824ms","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-24T04:38:14.842617Z","caller":"traceutil/trace.go:171","msg":"trace[263100942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1042; }","duration":"266.916407ms","start":"2026-02-24T04:38:14.575693Z","end":"2026-02-24T04:38:14.842609Z","steps":["trace[263100942] 'agreement among raft nodes before linearized reading' (duration: 266.836175ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:38:15.203677Z","caller":"traceutil/trace.go:171","msg":"trace[1051816958] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"356.020942ms","start":"2026-02-24T04:38:14.847624Z","end":"2026-02-24T04:38:15.203645Z","steps":["trace[1051816958] 'process raft request' (duration: 319.829771ms)","trace[1051816958] 'compare' (duration: 35.349001ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:38:15.203956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:38:14.847572Z","time spent":"356.299668ms","remote":"127.0.0.1:58860","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-24T04:38:16.654048Z","caller":"traceutil/trace.go:171","msg":"trace[1840283603] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1095; }","duration":"132.615474ms","start":"2026-02-24T04:38:16.521407Z","end":"2026-02-24T04:38:16.654023Z","steps":["trace[1840283603] 'read index received' (duration: 132.389179ms)","trace[1840283603] 'applied index is now lower than readState.Index' (duration: 225.235µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:38:16.654136Z","caller":"traceutil/trace.go:171","msg":"trace[69201457] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"200.80332ms","start":"2026-02-24T04:38:16.45329Z","end":"2026-02-24T04:38:16.654094Z","steps":["trace[69201457] 'process raft request' (duration: 200.586294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:38:16.654184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.755058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:38:16.654227Z","caller":"traceutil/trace.go:171","msg":"trace[169841956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1047; }","duration":"132.84108ms","start":"2026-02-24T04:38:16.521376Z","end":"2026-02-24T04:38:16.654218Z","steps":["trace[169841956] 'agreement among raft nodes before linearized reading' (duration: 132.748317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:38:17.526982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.200875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T04:38:17.527062Z","caller":"traceutil/trace.go:171","msg":"trace[336447983] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1047; }","duration":"728.264606ms","start":"2026-02-24T04:38:16.798764Z","end":"2026-02-24T04:38:17.527029Z","steps":["trace[336447983] 'count revisions from in-memory index tree' (duration: 728.075102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:38:17.527093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:38:16.798734Z","time spent":"728.351429ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:38:17.527156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.185233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T04:38:17.527213Z","caller":"traceutil/trace.go:171","msg":"trace[2032299565] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1047; }","duration":"680.308656ms","start":"2026-02-24T04:38:16.846888Z","end":"2026-02-24T04:38:17.527196Z","steps":["trace[2032299565] 'range keys from in-memory index tree' (duration: 679.895916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:38:17.527266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:38:16.846863Z","time spent":"680.390187ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T04:38:17.527347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.327755ms","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-24T04:38:17.527594Z","caller":"traceutil/trace.go:171","msg":"trace[220910754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1047; }","duration":"666.405077ms","start":"2026-02-24T04:38:16.861004Z","end":"2026-02-24T04:38:17.527409Z","steps":["trace[220910754] 'range keys from in-memory index tree' (duration: 666.166902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:38:17.527648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:38:16.86095Z","time spent":"666.683673ms","remote":"127.0.0.1:58860","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-24T04:38:17.527729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.991973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-24T04:38:17.526964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.45032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T04:38:17.527894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.437912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-24T04:38:17.527927Z","caller":"traceutil/trace.go:171","msg":"trace[1908598890] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1047; }","duration":"292.504893ms","start":"2026-02-24T04:38:17.235412Z","end":"2026-02-24T04:38:17.527917Z","steps":["trace[1908598890] 'range keys from in-memory index tree' (duration: 292.307159ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:38:17.527938Z","caller":"traceutil/trace.go:171","msg":"trace[105424236] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1047; }","duration":"732.434642ms","start":"2026-02-24T04:38:16.795437Z","end":"2026-02-24T04:38:17.527872Z","steps":["trace[105424236] 'count revisions from in-memory index tree' (duration: 731.367738ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:38:17.527804Z","caller":"traceutil/trace.go:171","msg":"trace[49253749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1047; }","duration":"272.118316ms","start":"2026-02-24T04:38:17.255664Z","end":"2026-02-24T04:38:17.527782Z","steps":["trace[49253749] 'range keys from in-memory index tree' (duration: 271.733217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:38:17.528094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:38:16.795417Z","time spent":"732.610646ms","remote":"127.0.0.1:58726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:38:17.528014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.530201ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:38:17.528535Z","caller":"traceutil/trace.go:171","msg":"trace[340748413] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1047; }","duration":"345.736776ms","start":"2026-02-24T04:38:17.182408Z","end":"2026-02-24T04:38:17.528145Z","steps":["trace[340748413] 'range keys from in-memory index tree' (duration: 344.869736ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:38:19.371496Z","caller":"traceutil/trace.go:171","msg":"trace[138736757] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"377.034185ms","start":"2026-02-24T04:38:18.994437Z","end":"2026-02-24T04:38:19.371471Z","steps":["trace[138736757] 'process raft request' (duration: 376.790818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:38:19.371641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:38:18.994412Z","time spent":"377.160568ms","remote":"127.0.0.1:58712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":872,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T04:38:20.427247Z","caller":"traceutil/trace.go:171","msg":"trace[1244540798] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"794.604298ms","start":"2026-02-24T04:38:19.632612Z","end":"2026-02-24T04:38:20.427216Z","steps":["trace[1244540798] 'process raft request' (duration: 794.380183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:38:20.427416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:38:19.632585Z","time spent":"794.741802ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:38:20.502301Z","caller":"traceutil/trace.go:171","msg":"trace[1018005466] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1106; }","duration":"439.852226ms","start":"2026-02-24T04:38:20.062429Z","end":"2026-02-24T04:38:20.502281Z","steps":["trace[1018005466] 'read index received' (duration: 364.505687ms)","trace[1018005466] 'applied index is now lower than readState.Index' (duration: 75.345559ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:38:20.502576Z","caller":"traceutil/trace.go:171","msg":"trace[1648080936] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"868.776942ms","start":"2026-02-24T04:38:19.633787Z","end":"2026-02-24T04:38:20.502564Z","steps":["trace[1648080936] 'process raft request' (duration: 826.456591ms)","trace[1648080936] 'compare' (duration: 41.85806ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:38:20.502667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:38:19.63376Z","time spent":"868.847883ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:38:20.502814Z","caller":"traceutil/trace.go:171","msg":"trace[1780408523] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"867.159794ms","start":"2026-02-24T04:38:19.635647Z","end":"2026-02-24T04:38:20.502807Z","steps":["trace[1780408523] 'process raft request' (duration: 866.587271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:38:20.502919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:38:19.635634Z","time spent":"867.204825ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:38:20.503136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.696425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-24T04:38:20.503209Z","caller":"traceutil/trace.go:171","msg":"trace[764237552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1059; }","duration":"440.810109ms","start":"2026-02-24T04:38:20.062382Z","end":"2026-02-24T04:38:20.503192Z","steps":["trace[764237552] 'agreement among raft nodes before linearized reading' (duration: 440.575273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:38:20.503255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:38:20.062343Z","time spent":"440.899891ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T04:38:20.812751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.936697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:38:20.812948Z","caller":"traceutil/trace.go:171","msg":"trace[7825363] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1061; }","duration":"199.198513ms","start":"2026-02-24T04:38:20.613697Z","end":"2026-02-24T04:38:20.812896Z","steps":["trace[7825363] 'range keys from in-memory index tree' (duration: 198.786494ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:38:28.387603Z","caller":"traceutil/trace.go:171","msg":"trace[1744942380] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"100.084558ms","start":"2026-02-24T04:38:28.287492Z","end":"2026-02-24T04:38:28.387576Z","steps":["trace[1744942380] 'process raft request' (duration: 97.307054ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:38:35.959155Z","caller":"traceutil/trace.go:171","msg":"trace[149916197] linearizableReadLoop","detail":"{readStateIndex:1203; appliedIndex:1202; }","duration":"181.955202ms","start":"2026-02-24T04:38:35.777174Z","end":"2026-02-24T04:38:35.959129Z","steps":["trace[149916197] 'read index received' (duration: 181.743137ms)","trace[149916197] 'applied index is now lower than readState.Index' (duration: 211.045µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:38:35.959232Z","caller":"traceutil/trace.go:171","msg":"trace[1070760589] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"253.02205ms","start":"2026-02-24T04:38:35.706181Z","end":"2026-02-24T04:38:35.959203Z","steps":["trace[1070760589] 'process raft request' (duration: 252.720253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:38:35.959814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.201287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:38:35.959974Z","caller":"traceutil/trace.go:171","msg":"trace[502312678] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1150; }","duration":"182.819622ms","start":"2026-02-24T04:38:35.777082Z","end":"2026-02-24T04:38:35.959902Z","steps":["trace[502312678] 'agreement among raft nodes before linearized reading' (duration: 182.173367ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:38:48.117754Z","caller":"traceutil/trace.go:171","msg":"trace[1149543239] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"146.559386ms","start":"2026-02-24T04:38:47.97116Z","end":"2026-02-24T04:38:48.11772Z","steps":["trace[1149543239] 'process raft request' (duration: 121.638502ms)","trace[1149543239] 'compare' (duration: 24.74072ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:38:48.117741Z","caller":"traceutil/trace.go:171","msg":"trace[1036600154] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"102.404249ms","start":"2026-02-24T04:38:48.015302Z","end":"2026-02-24T04:38:48.117706Z","steps":["trace[1036600154] 'read index received' (duration: 77.447474ms)","trace[1036600154] 'applied index is now lower than readState.Index' (duration: 24.955275ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:38:48.118135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.806539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-24T04:38:48.118791Z","caller":"traceutil/trace.go:171","msg":"trace[1578666360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1201; }","duration":"102.904961ms","start":"2026-02-24T04:38:48.015281Z","end":"2026-02-24T04:38:48.118186Z","steps":["trace[1578666360] 'agreement among raft nodes before linearized reading' (duration: 102.533602ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:41:31.413469Z","caller":"traceutil/trace.go:171","msg":"trace[1295473324] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"398.83019ms","start":"2026-02-24T04:41:31.014611Z","end":"2026-02-24T04:41:31.413441Z","steps":["trace[1295473324] 'process raft request' (duration: 398.671886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:41:31.413632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:41:31.014586Z","time spent":"398.965012ms","remote":"127.0.0.1:58860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:41:31.591618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.010259ms","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-24T04:41:31.591692Z","caller":"traceutil/trace.go:171","msg":"trace[1294251763] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1897; }","duration":"106.121802ms","start":"2026-02-24T04:41:31.485553Z","end":"2026-02-24T04:41:31.591675Z","steps":["trace[1294251763] 'range keys from in-memory index tree' (duration: 105.863546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:41:31.591814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.204816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:41:31.591899Z","caller":"traceutil/trace.go:171","msg":"trace[1531203588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1897; }","duration":"140.34577ms","start":"2026-02-24T04:41:31.451539Z","end":"2026-02-24T04:41:31.591885Z","steps":["trace[1531203588] 'range keys from in-memory index tree' (duration: 140.034572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:41:31.592065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.350159ms","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-24T04:41:31.592137Z","caller":"traceutil/trace.go:171","msg":"trace[998142808] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1897; }","duration":"111.473732ms","start":"2026-02-24T04:41:31.48065Z","end":"2026-02-24T04:41:31.592124Z","steps":["trace[998142808] 'range keys from in-memory index tree' (duration: 111.259738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:41:31.59238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.632473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-24T04:41:31.593092Z","caller":"traceutil/trace.go:171","msg":"trace[2004628905] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1897; }","duration":"147.775127ms","start":"2026-02-24T04:41:31.444673Z","end":"2026-02-24T04:41:31.592449Z","steps":["trace[2004628905] 'range keys from in-memory index tree' (duration: 147.438579ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:41:35.838587Z","caller":"traceutil/trace.go:171","msg":"trace[1032539701] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"158.355704ms","start":"2026-02-24T04:41:35.680186Z","end":"2026-02-24T04:41:35.838541Z","steps":["trace[1032539701] 'process raft request' (duration: 86.789827ms)","trace[1032539701] 'compare' (duration: 71.368382ms)"],"step_count":2}