{"level":"warn","ts":"2026-02-26T21:01:37.665298Z","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-26T21:01:37.665386Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153: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.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153: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-26T21:01:37.665457Z","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-26T21:01:37.66547Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-02-26T21:01:37.665485Z","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-26T21:01:37.666079Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-02-26T21:01:37.666153Z","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.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153: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-26T21:01:37.67041Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.055616ms"} {"level":"info","ts":"2026-02-26T21:01:37.679498Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-02-26T21:01:37.679562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T21:01:37.67965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-02-26T21:01:37.6797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T21:01:37.679708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-02-26T21:01:37.679815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-02-26T21:01:37.688326Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T21:01:37.689791Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T21:01:37.690837Z","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-26T21:01:37.692733Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T21:01:37.692909Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T21:01:37.693071Z","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-26T21:01:37.693174Z","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-26T21:01:37.693185Z","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-26T21:01:37.693667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-02-26T21:01:37.693795Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-02-26T21:01:37.696847Z","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-26T21:01:37.69762Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-02-26T21:01:37.697664Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-02-26T21:01:37.697819Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T21:01:37.697867Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T21:01:38.180391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T21:01:38.180475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T21:01:38.180514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-02-26T21:01:38.180539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-02-26T21:01:38.180551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-02-26T21:01:38.180566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-02-26T21:01:38.18058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-02-26T21:01:38.181651Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T21:01:38.182168Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T21:01:38.182214Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T21:01:38.182578Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T21:01:38.182696Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T21:01:38.182804Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T21:01:38.185704Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T21:01:38.186038Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T21:01:38.186241Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T21:01:38.186324Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T21:01:38.191381Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-02-26T21:02:02.567543Z","caller":"traceutil/trace.go:171","msg":"trace[868794035] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:444; }","duration":"178.891685ms","start":"2026-02-26T21:02:02.388616Z","end":"2026-02-26T21:02:02.567507Z","steps":["trace[868794035] 'read index received' (duration: 178.633628ms)","trace[868794035] 'applied index is now lower than readState.Index' (duration: 256.346µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:02.567669Z","caller":"traceutil/trace.go:171","msg":"trace[213188031] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"203.905244ms","start":"2026-02-26T21:02:02.363715Z","end":"2026-02-26T21:02:02.567621Z","steps":["trace[213188031] 'process raft request' (duration: 203.541474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:02.567881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.100651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:02.568166Z","caller":"traceutil/trace.go:171","msg":"trace[1417458792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"179.547563ms","start":"2026-02-26T21:02:02.388537Z","end":"2026-02-26T21:02:02.568085Z","steps":["trace[1417458792] 'agreement among raft nodes before linearized reading' (duration: 179.136462ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:31.94842Z","caller":"traceutil/trace.go:171","msg":"trace[1816833641] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"374.068877ms","start":"2026-02-26T21:02:31.574321Z","end":"2026-02-26T21:02:31.94839Z","steps":["trace[1816833641] 'read index received' (duration: 373.831301ms)","trace[1816833641] 'applied index is now lower than readState.Index' (duration: 236.916µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:31.948619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.244831ms","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-26T21:02:31.94866Z","caller":"traceutil/trace.go:171","msg":"trace[532734824] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"284.338104ms","start":"2026-02-26T21:02:31.664309Z","end":"2026-02-26T21:02:31.948647Z","steps":["trace[532734824] 'agreement among raft nodes before linearized reading' (duration: 284.250052ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:31.948532Z","caller":"traceutil/trace.go:171","msg":"trace[343420307] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"431.481681ms","start":"2026-02-26T21:02:31.516991Z","end":"2026-02-26T21:02:31.948473Z","steps":["trace[343420307] 'process raft request' (duration: 431.223084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:31.948717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.360045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T21:02:31.948825Z","caller":"traceutil/trace.go:171","msg":"trace[1881025397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:564; }","duration":"374.52016ms","start":"2026-02-26T21:02:31.574277Z","end":"2026-02-26T21:02:31.948797Z","steps":["trace[1881025397] 'agreement among raft nodes before linearized reading' (duration: 374.278153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:31.948911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:31.574265Z","time spent":"374.635683ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T21:02:31.9497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:31.516973Z","time spent":"431.769118ms","remote":"127.0.0.1:43962","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-26T21:02:32.445041Z","caller":"traceutil/trace.go:171","msg":"trace[1113894174] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"490.472756ms","start":"2026-02-26T21:02:31.954533Z","end":"2026-02-26T21:02:32.445005Z","steps":["trace[1113894174] 'process raft request' (duration: 490.198029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:32.445277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:31.954504Z","time spent":"490.644371ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:02:32.461778Z","caller":"traceutil/trace.go:171","msg":"trace[1048348088] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:588; }","duration":"476.620692ms","start":"2026-02-26T21:02:31.985126Z","end":"2026-02-26T21:02:32.461747Z","steps":["trace[1048348088] 'read index received' (duration: 459.635424ms)","trace[1048348088] 'applied index is now lower than readState.Index' (duration: 16.983898ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:32.461929Z","caller":"traceutil/trace.go:171","msg":"trace[665351185] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"507.034333ms","start":"2026-02-26T21:02:31.954858Z","end":"2026-02-26T21:02:32.461893Z","steps":["trace[665351185] 'process raft request' (duration: 506.716765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:32.461983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.826937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p8rbn\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-02-26T21:02:32.462028Z","caller":"traceutil/trace.go:171","msg":"trace[406866162] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p8rbn; range_end:; response_count:1; response_revision:566; }","duration":"476.95477ms","start":"2026-02-26T21:02:31.985061Z","end":"2026-02-26T21:02:32.462016Z","steps":["trace[406866162] 'agreement among raft nodes before linearized reading' (duration: 476.829056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:32.46207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:31.985037Z","time spent":"477.024312ms","remote":"127.0.0.1:43870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-p8rbn\" "} {"level":"warn","ts":"2026-02-26T21:02:32.462119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:31.954829Z","time spent":"507.185187ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:32.842171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.300798ms","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-26T21:02:32.842283Z","caller":"traceutil/trace.go:171","msg":"trace[823946816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:567; }","duration":"126.492364ms","start":"2026-02-26T21:02:32.715766Z","end":"2026-02-26T21:02:32.842259Z","steps":["trace[823946816] 'range keys from in-memory index tree' (duration: 126.150245ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:33.956318Z","caller":"traceutil/trace.go:171","msg":"trace[1333974178] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"520.253001ms","start":"2026-02-26T21:02:33.435991Z","end":"2026-02-26T21:02:33.956245Z","steps":["trace[1333974178] 'process raft request' (duration: 429.963992ms)","trace[1333974178] 'compare' (duration: 89.995332ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:33.956534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:33.435956Z","time spent":"520.461627ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:53.110685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.113446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:53.110827Z","caller":"traceutil/trace.go:171","msg":"trace[1858570199] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:753; }","duration":"394.348292ms","start":"2026-02-26T21:02:52.716454Z","end":"2026-02-26T21:02:53.110802Z","steps":["trace[1858570199] 'count revisions from in-memory index tree' (duration: 393.950801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:53.110892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:52.716434Z","time spent":"394.446894ms","remote":"127.0.0.1:43994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:02:54.579639Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438360577807740,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:02:55.079867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438360577807740,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:02:55.580822Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438360577807740,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:02:55.598705Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.617162817s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T21:02:55.599318Z","caller":"traceutil/trace.go:171","msg":"trace[1097184727] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"1.617914227s","start":"2026-02-26T21:02:53.981366Z","end":"2026-02-26T21:02:55.59928Z","steps":["trace[1097184727] 'process raft request' (duration: 1.617705191s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:55.59953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:53.981335Z","time spent":"1.618091982s","remote":"127.0.0.1:43962","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-26T21:02:55.84995Z","caller":"traceutil/trace.go:171","msg":"trace[677306477] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:787; }","duration":"1.770807942s","start":"2026-02-26T21:02:54.079119Z","end":"2026-02-26T21:02:55.849927Z","steps":["trace[677306477] 'read index received' (duration: 1.51992356s)","trace[677306477] 'applied index is now lower than readState.Index' (duration: 250.883482ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:55.850094Z","caller":"traceutil/trace.go:171","msg":"trace[1765947271] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"1.443149038s","start":"2026-02-26T21:02:54.406886Z","end":"2026-02-26T21:02:55.850036Z","steps":["trace[1765947271] 'process raft request' (duration: 1.412715651s)","trace[1765947271] 'compare' (duration: 30.209561ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:55.850158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.756086777s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:55.850197Z","caller":"traceutil/trace.go:171","msg":"trace[390535668] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:756; }","duration":"1.756152039s","start":"2026-02-26T21:02:54.094034Z","end":"2026-02-26T21:02:55.850186Z","steps":["trace[390535668] 'agreement among raft nodes before linearized reading' (duration: 1.756026016s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:55.850226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:54.093966Z","time spent":"1.756252991s","remote":"127.0.0.1:44248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:02:55.85032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.771172133s","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-26T21:02:55.850407Z","caller":"traceutil/trace.go:171","msg":"trace[604153089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"1.771343297s","start":"2026-02-26T21:02:54.079044Z","end":"2026-02-26T21:02:55.850387Z","steps":["trace[604153089] 'agreement among raft nodes before linearized reading' (duration: 1.771012218s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:55.850455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:54.079016Z","time spent":"1.771431459s","remote":"127.0.0.1:43962","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-26T21:02:55.850533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019628873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T21:02:55.850619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243547129s","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-26T21:02:55.850662Z","caller":"traceutil/trace.go:171","msg":"trace[76613108] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:756; }","duration":"1.243597431s","start":"2026-02-26T21:02:54.607043Z","end":"2026-02-26T21:02:55.85064Z","steps":["trace[76613108] 'agreement among raft nodes before linearized reading' (duration: 1.243475368s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:55.850669Z","caller":"traceutil/trace.go:171","msg":"trace[1597052431] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:756; }","duration":"1.019784257s","start":"2026-02-26T21:02:54.830834Z","end":"2026-02-26T21:02:55.850618Z","steps":["trace[1597052431] 'agreement among raft nodes before linearized reading' (duration: 1.019574011s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:55.85069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:54.607032Z","time spent":"1.243653302s","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T21:02:55.850729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:54.830826Z","time spent":"1.019888639s","remote":"127.0.0.1:44262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:02:55.850792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.248952781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T21:02:55.85081Z","caller":"traceutil/trace.go:171","msg":"trace[657467825] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:756; }","duration":"1.249029442s","start":"2026-02-26T21:02:54.601776Z","end":"2026-02-26T21:02:55.850805Z","steps":["trace[657467825] 'agreement among raft nodes before linearized reading' (duration: 1.248968771s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:55.850827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:54.601751Z","time spent":"1.249072614s","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T21:02:55.850913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:54.406837Z","time spent":"1.443359925s","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:55.850973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.460918585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:55.851Z","caller":"traceutil/trace.go:171","msg":"trace[1947827702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:756; }","duration":"1.461009046s","start":"2026-02-26T21:02:54.389983Z","end":"2026-02-26T21:02:55.850992Z","steps":["trace[1947827702] 'agreement among raft nodes before linearized reading' (duration: 1.460966175s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:55.851019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:54.389954Z","time spent":"1.461060988s","remote":"127.0.0.1:43684","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-26T21:02:55.851011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.543216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:02:55.851048Z","caller":"traceutil/trace.go:171","msg":"trace[1404367105] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:756; }","duration":"368.633568ms","start":"2026-02-26T21:02:55.482402Z","end":"2026-02-26T21:02:55.851035Z","steps":["trace[1404367105] 'agreement among raft nodes before linearized reading' (duration: 368.571866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:55.851077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:55.48236Z","time spent":"368.708889ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:02:55.851199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.791834ms","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-26T21:02:55.851229Z","caller":"traceutil/trace.go:171","msg":"trace[70805291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:756; }","duration":"707.857595ms","start":"2026-02-26T21:02:55.143362Z","end":"2026-02-26T21:02:55.851219Z","steps":["trace[70805291] 'agreement among raft nodes before linearized reading' (duration: 707.707321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:55.851252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:55.143335Z","time spent":"707.911806ms","remote":"127.0.0.1:43962","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-26T21:02:56.105393Z","caller":"traceutil/trace.go:171","msg":"trace[1857491421] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"249.21747ms","start":"2026-02-26T21:02:55.85614Z","end":"2026-02-26T21:02:56.105357Z","steps":["trace[1857491421] 'process raft request' (duration: 234.972376ms)","trace[1857491421] 'compare' (duration: 13.656248ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:56.105675Z","caller":"traceutil/trace.go:171","msg":"trace[189797616] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:790; }","duration":"244.367652ms","start":"2026-02-26T21:02:55.86095Z","end":"2026-02-26T21:02:56.105647Z","steps":["trace[189797616] 'read index received' (duration: 46.705534ms)","trace[189797616] 'applied index is now lower than readState.Index' (duration: 197.660838ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:56.10567Z","caller":"traceutil/trace.go:171","msg":"trace[1896417305] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"247.865544ms","start":"2026-02-26T21:02:55.857765Z","end":"2026-02-26T21:02:56.105631Z","steps":["trace[1896417305] 'process raft request' (duration: 247.465013ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:56.105715Z","caller":"traceutil/trace.go:171","msg":"trace[319859442] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"247.765081ms","start":"2026-02-26T21:02:55.857929Z","end":"2026-02-26T21:02:56.105694Z","steps":["trace[319859442] 'process raft request' (duration: 247.609057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:56.105782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.840414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:56.105814Z","caller":"traceutil/trace.go:171","msg":"trace[1769962312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"244.916346ms","start":"2026-02-26T21:02:55.86089Z","end":"2026-02-26T21:02:56.105806Z","steps":["trace[1769962312] 'agreement among raft nodes before linearized reading' (duration: 244.844214ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:57.83428Z","caller":"traceutil/trace.go:171","msg":"trace[510064800] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"219.534262ms","start":"2026-02-26T21:02:57.614714Z","end":"2026-02-26T21:02:57.834248Z","steps":["trace[510064800] 'process raft request' (duration: 219.333966ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:20.104091Z","caller":"traceutil/trace.go:171","msg":"trace[1078270107] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"155.785391ms","start":"2026-02-26T21:03:19.94828Z","end":"2026-02-26T21:03:20.104065Z","steps":["trace[1078270107] 'process raft request' (duration: 155.268788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:21.964311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.577148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:03:21.964483Z","caller":"traceutil/trace.go:171","msg":"trace[1443625565] linearizableReadLoop","detail":"{readStateIndex:872; appliedIndex:871; }","duration":"562.895816ms","start":"2026-02-26T21:03:21.401562Z","end":"2026-02-26T21:03:21.964458Z","steps":["trace[1443625565] 'read index received' (duration: 299.749683ms)","trace[1443625565] 'applied index is now lower than readState.Index' (duration: 263.144723ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:21.964787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.204615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:21.964936Z","caller":"traceutil/trace.go:171","msg":"trace[242217861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:832; }","duration":"563.4197ms","start":"2026-02-26T21:03:21.401495Z","end":"2026-02-26T21:03:21.964915Z","steps":["trace[242217861] 'agreement among raft nodes before linearized reading' (duration: 563.059951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:21.964986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:21.401462Z","time spent":"563.513093ms","remote":"127.0.0.1:43692","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-26T21:03:21.965215Z","caller":"traceutil/trace.go:171","msg":"trace[490978344] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"563.372269ms","start":"2026-02-26T21:03:21.401135Z","end":"2026-02-26T21:03:21.964507Z","steps":["trace[490978344] 'process raft request' (duration: 300.281698ms)","trace[490978344] 'compare' (duration: 262.393663ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:21.96546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:21.401116Z","time spent":"564.234811ms","remote":"127.0.0.1:43724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:22.28981Z","caller":"traceutil/trace.go:171","msg":"trace[1294243984] linearizableReadLoop","detail":"{readStateIndex:873; appliedIndex:872; }","duration":"123.167777ms","start":"2026-02-26T21:03:22.166607Z","end":"2026-02-26T21:03:22.289775Z","steps":["trace[1294243984] 'read index received' (duration: 122.89921ms)","trace[1294243984] 'applied index is now lower than readState.Index' (duration: 267.357µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:22.290209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.553497ms","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-26T21:03:22.290297Z","caller":"traceutil/trace.go:171","msg":"trace[617836453] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:833; }","duration":"123.752012ms","start":"2026-02-26T21:03:22.16653Z","end":"2026-02-26T21:03:22.290282Z","steps":["trace[617836453] 'agreement among raft nodes before linearized reading' (duration: 123.417174ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:22.290486Z","caller":"traceutil/trace.go:171","msg":"trace[2054557026] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"171.706118ms","start":"2026-02-26T21:03:22.118159Z","end":"2026-02-26T21:03:22.289865Z","steps":["trace[2054557026] 'process raft request' (duration: 171.427151ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:24.773483Z","caller":"traceutil/trace.go:171","msg":"trace[912181238] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:878; }","duration":"462.63517ms","start":"2026-02-26T21:03:24.310815Z","end":"2026-02-26T21:03:24.773451Z","steps":["trace[912181238] 'read index received' (duration: 462.374653ms)","trace[912181238] 'applied index is now lower than readState.Index' (duration: 259.497µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:24.773966Z","caller":"traceutil/trace.go:171","msg":"trace[997668187] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"473.688689ms","start":"2026-02-26T21:03:24.300227Z","end":"2026-02-26T21:03:24.773916Z","steps":["trace[997668187] 'process raft request' (duration: 473.079683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:24.774034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.203755ms","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-26T21:03:24.77406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.565562ms","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-26T21:03:24.774095Z","caller":"traceutil/trace.go:171","msg":"trace[1611307251] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:839; }","duration":"463.311268ms","start":"2026-02-26T21:03:24.310766Z","end":"2026-02-26T21:03:24.774077Z","steps":["trace[1611307251] 'agreement among raft nodes before linearized reading' (duration: 463.078952ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:24.774148Z","caller":"traceutil/trace.go:171","msg":"trace[1764674283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:839; }","duration":"377.694465ms","start":"2026-02-26T21:03:24.396436Z","end":"2026-02-26T21:03:24.77413Z","steps":["trace[1764674283] 'agreement among raft nodes before linearized reading' (duration: 377.50561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:24.774167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:24.310755Z","time spent":"463.397661ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T21:03:24.7742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:24.396396Z","time spent":"377.791998ms","remote":"127.0.0.1:43962","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-26T21:03:24.77424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.548613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-26T21:03:24.774332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:24.300193Z","time spent":"473.878435ms","remote":"127.0.0.1:43962","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-26T21:03:24.774423Z","caller":"traceutil/trace.go:171","msg":"trace[1222708746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:839; }","duration":"400.841651ms","start":"2026-02-26T21:03:24.373505Z","end":"2026-02-26T21:03:24.774347Z","steps":["trace[1222708746] 'agreement among raft nodes before linearized reading' (duration: 400.466781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:24.774646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:24.37346Z","time spent":"401.122419ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T21:03:24.77489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.74198ms","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-26T21:03:24.77496Z","caller":"traceutil/trace.go:171","msg":"trace[1501224084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:839; }","duration":"410.841553ms","start":"2026-02-26T21:03:24.364102Z","end":"2026-02-26T21:03:24.774944Z","steps":["trace[1501224084] 'agreement among raft nodes before linearized reading' (duration: 409.748875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:24.775017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:24.364089Z","time spent":"410.908485ms","remote":"127.0.0.1:43962","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-26T21:03:25.634127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.406364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:03:25.634431Z","caller":"traceutil/trace.go:171","msg":"trace[39618035] linearizableReadLoop","detail":"{readStateIndex:883; appliedIndex:879; }","duration":"616.520402ms","start":"2026-02-26T21:03:25.017888Z","end":"2026-02-26T21:03:25.634408Z","steps":["trace[39618035] 'read index received' (duration: 161.722568ms)","trace[39618035] 'applied index is now lower than readState.Index' (duration: 454.796674ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:25.634723Z","caller":"traceutil/trace.go:171","msg":"trace[423331727] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"854.088635ms","start":"2026-02-26T21:03:24.780596Z","end":"2026-02-26T21:03:25.634685Z","steps":["trace[423331727] 'process raft request' (duration: 853.773997ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:25.634747Z","caller":"traceutil/trace.go:171","msg":"trace[766891407] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"854.747113ms","start":"2026-02-26T21:03:24.779966Z","end":"2026-02-26T21:03:25.634713Z","steps":["trace[766891407] 'process raft request' (duration: 854.362083ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:25.634772Z","caller":"traceutil/trace.go:171","msg":"trace[2089936435] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"854.977449ms","start":"2026-02-26T21:03:24.779734Z","end":"2026-02-26T21:03:25.634712Z","steps":["trace[2089936435] 'process raft request' (duration: 854.536397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:25.634804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.046127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:25.63487Z","caller":"traceutil/trace.go:171","msg":"trace[776636619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:843; }","duration":"243.177651ms","start":"2026-02-26T21:03:25.391672Z","end":"2026-02-26T21:03:25.63485Z","steps":["trace[776636619] 'agreement among raft nodes before linearized reading' (duration: 243.027437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:25.634874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.952363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T21:03:25.634906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:24.77995Z","time spent":"854.879056ms","remote":"127.0.0.1:43962","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-26T21:03:25.634935Z","caller":"traceutil/trace.go:171","msg":"trace[1368913199] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:843; }","duration":"617.086766ms","start":"2026-02-26T21:03:25.017832Z","end":"2026-02-26T21:03:25.634919Z","steps":["trace[1368913199] 'agreement among raft nodes before linearized reading' (duration: 616.940942ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:25.634908Z","caller":"traceutil/trace.go:171","msg":"trace[11356041] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"856.421087ms","start":"2026-02-26T21:03:24.778407Z","end":"2026-02-26T21:03:25.634828Z","steps":["trace[11356041] 'process raft request' (duration: 401.191211ms)","trace[11356041] 'compare' (duration: 454.25464ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:25.634988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:25.017811Z","time spent":"617.166829ms","remote":"127.0.0.1:43850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:03:25.63502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:24.779708Z","time spent":"855.143803ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:25.635062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:24.780568Z","time spent":"854.2606ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:25.63511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:24.778378Z","time spent":"856.621782ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:26.506903Z","caller":"traceutil/trace.go:171","msg":"trace[182660230] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"125.510289ms","start":"2026-02-26T21:03:26.381367Z","end":"2026-02-26T21:03:26.506877Z","steps":["trace[182660230] 'process raft request' (duration: 125.333234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:41.892978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438360577808211,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:03:42.393709Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432438360577808211,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:03:42.528253Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.206990802s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T21:03:42.528627Z","caller":"traceutil/trace.go:171","msg":"trace[125968134] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"1.136638638s","start":"2026-02-26T21:03:41.391957Z","end":"2026-02-26T21:03:42.528596Z","steps":["trace[125968134] 'read index received' (duration: 1.136435923s)","trace[125968134] 'applied index is now lower than readState.Index' (duration: 201.455µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:42.528675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:41.321047Z","time spent":"1.207607218s","remote":"127.0.0.1:43724","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-26T21:03:42.528768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136791693s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:42.528811Z","caller":"traceutil/trace.go:171","msg":"trace[1600392103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:944; }","duration":"1.136913646s","start":"2026-02-26T21:03:41.391887Z","end":"2026-02-26T21:03:42.5288Z","steps":["trace[1600392103] 'agreement among raft nodes before linearized reading' (duration: 1.136811503s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:42.528881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:41.391857Z","time spent":"1.137013598s","remote":"127.0.0.1:43684","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-26T21:03:42.529037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.656833ms","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-26T21:03:42.529131Z","caller":"traceutil/trace.go:171","msg":"trace[781484558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:944; }","duration":"788.797076ms","start":"2026-02-26T21:03:41.740311Z","end":"2026-02-26T21:03:42.529108Z","steps":["trace[781484558] 'agreement among raft nodes before linearized reading' (duration: 788.497898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:42.529135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.425238ms","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-26T21:03:42.52923Z","caller":"traceutil/trace.go:171","msg":"trace[279030676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:944; }","duration":"790.585382ms","start":"2026-02-26T21:03:41.738623Z","end":"2026-02-26T21:03:42.529208Z","steps":["trace[279030676] 'agreement among raft nodes before linearized reading' (duration: 790.271404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:42.529173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:41.740301Z","time spent":"788.861747ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T21:03:42.529273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:41.738593Z","time spent":"790.672095ms","remote":"127.0.0.1:43962","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-26T21:03:42.529038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.168765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:03:42.529341Z","caller":"traceutil/trace.go:171","msg":"trace[1071462179] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:944; }","duration":"776.571955ms","start":"2026-02-26T21:03:41.752758Z","end":"2026-02-26T21:03:42.52933Z","steps":["trace[1071462179] 'agreement among raft nodes before linearized reading' (duration: 776.116664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:42.529364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:41.752735Z","time spent":"776.619266ms","remote":"127.0.0.1:44228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:03:42.529461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.634954ms","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-26T21:03:42.529491Z","caller":"traceutil/trace.go:171","msg":"trace[1114505621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:944; }","duration":"790.691565ms","start":"2026-02-26T21:03:41.738789Z","end":"2026-02-26T21:03:42.529481Z","steps":["trace[1114505621] 'agreement among raft nodes before linearized reading' (duration: 790.041788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:42.529535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:41.738782Z","time spent":"790.748227ms","remote":"127.0.0.1:43962","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-26T21:03:42.529638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.423329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T21:03:42.529694Z","caller":"traceutil/trace.go:171","msg":"trace[181188959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:944; }","duration":"795.533382ms","start":"2026-02-26T21:03:41.734147Z","end":"2026-02-26T21:03:42.52968Z","steps":["trace[181188959] 'agreement among raft nodes before linearized reading' (duration: 794.627418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:42.529749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:41.734088Z","time spent":"795.653925ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T21:03:43.327813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.680269ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:03:43.327935Z","caller":"traceutil/trace.go:171","msg":"trace[1646301076] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"797.303577ms","start":"2026-02-26T21:03:42.530608Z","end":"2026-02-26T21:03:43.327912Z","steps":["trace[1646301076] 'process raft request' (duration: 352.404472ms)","trace[1646301076] 'compare' (duration: 444.480334ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:43.328002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:42.530537Z","time spent":"797.435131ms","remote":"127.0.0.1:43724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:43.340063Z","caller":"traceutil/trace.go:171","msg":"trace[1549473527] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"805.926473ms","start":"2026-02-26T21:03:42.53412Z","end":"2026-02-26T21:03:43.340046Z","steps":["trace[1549473527] 'process raft request' (duration: 805.747779ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:43.340083Z","caller":"traceutil/trace.go:171","msg":"trace[1836003015] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"805.938824ms","start":"2026-02-26T21:03:42.53412Z","end":"2026-02-26T21:03:43.340059Z","steps":["trace[1836003015] 'process raft request' (duration: 805.873512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:43.340131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:42.5341Z","time spent":"805.997866ms","remote":"127.0.0.1:43962","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-26T21:03:43.340224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:42.534099Z","time spent":"806.045687ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:03:43.340444Z","caller":"traceutil/trace.go:171","msg":"trace[2019504686] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"805.80892ms","start":"2026-02-26T21:03:42.534601Z","end":"2026-02-26T21:03:43.34041Z","steps":["trace[2019504686] 'process raft request' (duration: 805.42593ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:43.340567Z","caller":"traceutil/trace.go:171","msg":"trace[495067917] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"803.245604ms","start":"2026-02-26T21:03:42.537312Z","end":"2026-02-26T21:03:43.340557Z","steps":["trace[495067917] 'process raft request' (duration: 803.009348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:43.340617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:42.537279Z","time spent":"803.314016ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:43.340616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:42.53458Z","time spent":"805.953084ms","remote":"127.0.0.1:43962","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-26T21:03:43.341066Z","caller":"traceutil/trace.go:171","msg":"trace[409138546] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:989; }","duration":"801.769435ms","start":"2026-02-26T21:03:42.539284Z","end":"2026-02-26T21:03:43.341054Z","steps":["trace[409138546] 'read index received' (duration: 343.749366ms)","trace[409138546] 'applied index is now lower than readState.Index' (duration: 458.019059ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:43.341246Z","caller":"traceutil/trace.go:171","msg":"trace[774938250] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"494.779992ms","start":"2026-02-26T21:03:42.846457Z","end":"2026-02-26T21:03:43.341237Z","steps":["trace[774938250] 'process raft request' (duration: 494.044152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:43.341292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:42.846417Z","time spent":"494.846353ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:43.34143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.151664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:43.341462Z","caller":"traceutil/trace.go:171","msg":"trace[1582738974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"802.190836ms","start":"2026-02-26T21:03:42.539256Z","end":"2026-02-26T21:03:43.341446Z","steps":["trace[1582738974] 'agreement among raft nodes before linearized reading' (duration: 802.146935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:43.341482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:42.539245Z","time spent":"802.232997ms","remote":"127.0.0.1:43684","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-26T21:03:43.341644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.423941ms","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-26T21:03:43.341662Z","caller":"traceutil/trace.go:171","msg":"trace[1127705291] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:950; }","duration":"476.443692ms","start":"2026-02-26T21:03:42.865213Z","end":"2026-02-26T21:03:43.341657Z","steps":["trace[1127705291] 'agreement among raft nodes before linearized reading' (duration: 476.365889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:43.34168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:42.865136Z","time spent":"476.539654ms","remote":"127.0.0.1:43962","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-26T21:03:49.635261Z","caller":"traceutil/trace.go:171","msg":"trace[347257897] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"107.414944ms","start":"2026-02-26T21:03:49.527807Z","end":"2026-02-26T21:03:49.635222Z","steps":["trace[347257897] 'process raft request' (duration: 107.373983ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:49.635279Z","caller":"traceutil/trace.go:171","msg":"trace[431268510] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"107.867856ms","start":"2026-02-26T21:03:49.527376Z","end":"2026-02-26T21:03:49.635243Z","steps":["trace[431268510] 'process raft request' (duration: 105.051792ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:49.635322Z","caller":"traceutil/trace.go:171","msg":"trace[1492004103] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"107.565748ms","start":"2026-02-26T21:03:49.527707Z","end":"2026-02-26T21:03:49.635272Z","steps":["trace[1492004103] 'process raft request' (duration: 107.439745ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:51.636642Z","caller":"traceutil/trace.go:171","msg":"trace[2062197957] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"170.739854ms","start":"2026-02-26T21:03:51.465891Z","end":"2026-02-26T21:03:51.636631Z","steps":["trace[2062197957] 'process raft request' (duration: 122.588912ms)","trace[2062197957] 'compare' (duration: 47.883395ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:51.636604Z","caller":"traceutil/trace.go:171","msg":"trace[2140487209] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"169.059659ms","start":"2026-02-26T21:03:51.467511Z","end":"2026-02-26T21:03:51.636571Z","steps":["trace[2140487209] 'read index received' (duration: 120.872307ms)","trace[2140487209] 'applied index is now lower than readState.Index' (duration: 48.186052ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:51.636734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.210094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-26T21:03:51.63677Z","caller":"traceutil/trace.go:171","msg":"trace[296889466] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:970; }","duration":"169.312526ms","start":"2026-02-26T21:03:51.467447Z","end":"2026-02-26T21:03:51.636759Z","steps":["trace[296889466] 'agreement among raft nodes before linearized reading' (duration: 169.217914ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:51.808105Z","caller":"traceutil/trace.go:171","msg":"trace[753936631] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"163.424501ms","start":"2026-02-26T21:03:51.644652Z","end":"2026-02-26T21:03:51.808076Z","steps":["trace[753936631] 'process raft request' (duration: 163.331959ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:51.808165Z","caller":"traceutil/trace.go:171","msg":"trace[1076964413] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"164.793117ms","start":"2026-02-26T21:03:51.643314Z","end":"2026-02-26T21:03:51.808107Z","steps":["trace[1076964413] 'process raft request' (duration: 104.577599ms)","trace[1076964413] 'compare' (duration: 59.984212ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:51.808318Z","caller":"traceutil/trace.go:171","msg":"trace[335182015] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"162.697762ms","start":"2026-02-26T21:03:51.645609Z","end":"2026-02-26T21:03:51.808307Z","steps":["trace[335182015] 'process raft request' (duration: 162.427045ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:56.578329Z","caller":"traceutil/trace.go:171","msg":"trace[582357332] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"206.785278ms","start":"2026-02-26T21:03:56.371519Z","end":"2026-02-26T21:03:56.578305Z","steps":["trace[582357332] 'process raft request' (duration: 206.606693ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:57.221673Z","caller":"traceutil/trace.go:171","msg":"trace[1726604535] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"122.383476ms","start":"2026-02-26T21:03:57.099262Z","end":"2026-02-26T21:03:57.221645Z","steps":["trace[1726604535] 'process raft request' (duration: 122.296434ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:57.222309Z","caller":"traceutil/trace.go:171","msg":"trace[692166775] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"142.76107ms","start":"2026-02-26T21:03:57.079512Z","end":"2026-02-26T21:03:57.222273Z","steps":["trace[692166775] 'process raft request' (duration: 47.361761ms)","trace[692166775] 'compare' (duration: 94.540777ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:58.348314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.545478ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-26T21:03:58.348686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.466937ms","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-26T21:03:58.348806Z","caller":"traceutil/trace.go:171","msg":"trace[687135931] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1007; }","duration":"117.585777ms","start":"2026-02-26T21:03:58.231196Z","end":"2026-02-26T21:03:58.348781Z","steps":["trace[687135931] 'range keys from in-memory index tree' (duration: 117.452007ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:59.985253Z","caller":"traceutil/trace.go:171","msg":"trace[1911109810] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"237.855873ms","start":"2026-02-26T21:03:59.74736Z","end":"2026-02-26T21:03:59.985216Z","steps":["trace[1911109810] 'read index received' (duration: 237.548263ms)","trace[1911109810] 'applied index is now lower than readState.Index' (duration: 305.88µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:59.985491Z","caller":"traceutil/trace.go:171","msg":"trace[1447519230] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"307.410262ms","start":"2026-02-26T21:03:59.678058Z","end":"2026-02-26T21:03:59.985468Z","steps":["trace[1447519230] 'process raft request' (duration: 306.869823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:59.985677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:59.678032Z","time spent":"307.506092ms","remote":"127.0.0.1:43962","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-26T21:03:59.985853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.427442ms","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-26T21:03:59.985973Z","caller":"traceutil/trace.go:171","msg":"trace[163362479] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1012; }","duration":"238.629972ms","start":"2026-02-26T21:03:59.747308Z","end":"2026-02-26T21:03:59.985938Z","steps":["trace[163362479] 'agreement among raft nodes before linearized reading' (duration: 238.203532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:59.986224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.005499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-26T21:03:59.986285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.570492ms","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-26T21:03:59.986319Z","caller":"traceutil/trace.go:171","msg":"trace[2011036191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1012; }","duration":"142.156769ms","start":"2026-02-26T21:03:59.844138Z","end":"2026-02-26T21:03:59.986295Z","steps":["trace[2011036191] 'agreement among raft nodes before linearized reading' (duration: 141.920639ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:59.986327Z","caller":"traceutil/trace.go:171","msg":"trace[904684845] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1012; }","duration":"132.636852ms","start":"2026-02-26T21:03:59.853679Z","end":"2026-02-26T21:03:59.986316Z","steps":["trace[904684845] 'agreement among raft nodes before linearized reading' (duration: 132.496852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:59.986409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.34142ms","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-26T21:03:59.98738Z","caller":"traceutil/trace.go:171","msg":"trace[1228103421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1012; }","duration":"141.32212ms","start":"2026-02-26T21:03:59.845997Z","end":"2026-02-26T21:03:59.987319Z","steps":["trace[1228103421] 'agreement among raft nodes before linearized reading' (duration: 140.25139ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:00.173123Z","caller":"traceutil/trace.go:171","msg":"trace[736729186] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"180.676889ms","start":"2026-02-26T21:03:59.992413Z","end":"2026-02-26T21:04:00.17309Z","steps":["trace[736729186] 'process raft request' (duration: 180.471619ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:00.226748Z","caller":"traceutil/trace.go:171","msg":"trace[245382678] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"233.968443ms","start":"2026-02-26T21:03:59.992758Z","end":"2026-02-26T21:04:00.226726Z","steps":["trace[245382678] 'process raft request' (duration: 233.768983ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:00.226759Z","caller":"traceutil/trace.go:171","msg":"trace[114218950] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"233.917313ms","start":"2026-02-26T21:03:59.992817Z","end":"2026-02-26T21:04:00.226735Z","steps":["trace[114218950] 'process raft request' (duration: 233.841663ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:00.226969Z","caller":"traceutil/trace.go:171","msg":"trace[2104151321] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"232.519503ms","start":"2026-02-26T21:03:59.994411Z","end":"2026-02-26T21:04:00.226931Z","steps":["trace[2104151321] 'process raft request' (duration: 232.283383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:01.657563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:04:01.323165Z","time spent":"334.391644ms","remote":"127.0.0.1:43724","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-26T21:04:02.093839Z","caller":"traceutil/trace.go:171","msg":"trace[1441079655] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1066; }","duration":"100.879681ms","start":"2026-02-26T21:04:01.992927Z","end":"2026-02-26T21:04:02.093807Z","steps":["trace[1441079655] 'read index received' (duration: 79.531677ms)","trace[1441079655] 'applied index is now lower than readState.Index' (duration: 21.347204ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:04:02.093904Z","caller":"traceutil/trace.go:171","msg":"trace[724669740] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"434.987465ms","start":"2026-02-26T21:04:01.658874Z","end":"2026-02-26T21:04:02.093861Z","steps":["trace[724669740] 'process raft request' (duration: 413.657161ms)","trace[724669740] 'compare' (duration: 21.170874ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:04:02.094047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.106151ms","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-26T21:04:02.094076Z","caller":"traceutil/trace.go:171","msg":"trace[2111938311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1017; }","duration":"101.243191ms","start":"2026-02-26T21:04:01.992825Z","end":"2026-02-26T21:04:02.094068Z","steps":["trace[2111938311] 'agreement among raft nodes before linearized reading' (duration: 101.087301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:02.094102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:04:01.658839Z","time spent":"435.146195ms","remote":"127.0.0.1:43724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:04:02.392681Z","caller":"traceutil/trace.go:171","msg":"trace[607825304] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"161.232284ms","start":"2026-02-26T21:04:02.231424Z","end":"2026-02-26T21:04:02.392657Z","steps":["trace[607825304] 'read index received' (duration: 161.029534ms)","trace[607825304] 'applied index is now lower than readState.Index' (duration: 201.18µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:04:02.392873Z","caller":"traceutil/trace.go:171","msg":"trace[1676820371] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"209.34117ms","start":"2026-02-26T21:04:02.183517Z","end":"2026-02-26T21:04:02.392859Z","steps":["trace[1676820371] 'process raft request' (duration: 208.97656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:02.393238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.931764ms","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-26T21:04:02.393366Z","caller":"traceutil/trace.go:171","msg":"trace[1498754313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1019; }","duration":"161.111844ms","start":"2026-02-26T21:04:02.232231Z","end":"2026-02-26T21:04:02.393343Z","steps":["trace[1498754313] 'agreement among raft nodes before linearized reading' (duration: 160.850474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:02.393573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.119834ms","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-26T21:04:02.393629Z","caller":"traceutil/trace.go:171","msg":"trace[1377513745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1019; }","duration":"162.244544ms","start":"2026-02-26T21:04:02.231369Z","end":"2026-02-26T21:04:02.393613Z","steps":["trace[1377513745] 'agreement among raft nodes before linearized reading' (duration: 162.071014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:02.393738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.838653ms","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-26T21:04:02.393814Z","caller":"traceutil/trace.go:171","msg":"trace[701991530] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1019; }","duration":"160.936623ms","start":"2026-02-26T21:04:02.232865Z","end":"2026-02-26T21:04:02.393802Z","steps":["trace[701991530] 'agreement among raft nodes before linearized reading' (duration: 160.733794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:02.550475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.586679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:04:02.550736Z","caller":"traceutil/trace.go:171","msg":"trace[399445282] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"149.913747ms","start":"2026-02-26T21:04:02.400802Z","end":"2026-02-26T21:04:02.550716Z","steps":["trace[399445282] 'process raft request' (duration: 149.868157ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:02.550739Z","caller":"traceutil/trace.go:171","msg":"trace[874839854] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"150.463906ms","start":"2026-02-26T21:04:02.400248Z","end":"2026-02-26T21:04:02.550712Z","steps":["trace[874839854] 'process raft request' (duration: 150.353066ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:02.552038Z","caller":"traceutil/trace.go:171","msg":"trace[1047750269] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"152.481466ms","start":"2026-02-26T21:04:02.398184Z","end":"2026-02-26T21:04:02.550666Z","steps":["trace[1047750269] 'process raft request' (duration: 46.577467ms)","trace[1047750269] 'compare' (duration: 105.443099ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:04:04.031886Z","caller":"traceutil/trace.go:171","msg":"trace[353880206] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"120.213906ms","start":"2026-02-26T21:04:03.911644Z","end":"2026-02-26T21:04:04.031858Z","steps":["trace[353880206] 'process raft request' (duration: 120.025756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:04.83596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.011949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-26T21:04:04.836029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.248821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-26T21:04:04.836111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.175275ms","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-26T21:04:04.836139Z","caller":"traceutil/trace.go:171","msg":"trace[321261892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1023; }","duration":"269.447171ms","start":"2026-02-26T21:04:04.566668Z","end":"2026-02-26T21:04:04.836115Z","steps":["trace[321261892] 'range keys from in-memory index tree' (duration: 268.973662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:04.83616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.071258ms","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-26T21:04:04.836195Z","caller":"traceutil/trace.go:171","msg":"trace[353524852] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1023; }","duration":"281.233298ms","start":"2026-02-26T21:04:04.554951Z","end":"2026-02-26T21:04:04.836184Z","steps":["trace[353524852] 'range keys from in-memory index tree' (duration: 280.905228ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:04.836191Z","caller":"traceutil/trace.go:171","msg":"trace[1578226403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1023; }","duration":"709.318895ms","start":"2026-02-26T21:04:04.12685Z","end":"2026-02-26T21:04:04.836169Z","steps":["trace[1578226403] 'range keys from in-memory index tree' (duration: 708.978006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:04.836238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:04:04.126825Z","time spent":"709.405055ms","remote":"127.0.0.1:43962","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-26T21:04:04.836313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.869291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:04:04.836395Z","caller":"traceutil/trace.go:171","msg":"trace[92845596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"445.015781ms","start":"2026-02-26T21:04:04.391359Z","end":"2026-02-26T21:04:04.836374Z","steps":["trace[92845596] 'range keys from in-memory index tree' (duration: 444.734061ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:04.836075Z","caller":"traceutil/trace.go:171","msg":"trace[1401120826] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1023; }","duration":"280.179668ms","start":"2026-02-26T21:04:04.55587Z","end":"2026-02-26T21:04:04.83605Z","steps":["trace[1401120826] 'range keys from in-memory index tree' (duration: 279.781909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:04.836445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:04:04.39133Z","time spent":"445.105951ms","remote":"127.0.0.1:43684","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-26T21:04:04.836885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.210383ms","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-26T21:04:04.836931Z","caller":"traceutil/trace.go:171","msg":"trace[131444143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1023; }","duration":"438.287973ms","start":"2026-02-26T21:04:04.398629Z","end":"2026-02-26T21:04:04.836917Z","steps":["trace[131444143] 'range keys from in-memory index tree' (duration: 436.858754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:04.836971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:04:04.398606Z","time spent":"438.358233ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T21:04:05.017323Z","caller":"traceutil/trace.go:171","msg":"trace[1138869998] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"175.07916ms","start":"2026-02-26T21:04:04.842219Z","end":"2026-02-26T21:04:05.017298Z","steps":["trace[1138869998] 'process raft request' (duration: 175.02108ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:05.017364Z","caller":"traceutil/trace.go:171","msg":"trace[1477467469] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"175.50379ms","start":"2026-02-26T21:04:04.841829Z","end":"2026-02-26T21:04:05.017333Z","steps":["trace[1477467469] 'process raft request' (duration: 122.692475ms)","trace[1477467469] 'compare' (duration: 52.474685ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:04:05.01755Z","caller":"traceutil/trace.go:171","msg":"trace[955962190] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"175.63779ms","start":"2026-02-26T21:04:04.841892Z","end":"2026-02-26T21:04:05.017529Z","steps":["trace[955962190] 'process raft request' (duration: 175.24102ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:05.017698Z","caller":"traceutil/trace.go:171","msg":"trace[1202241014] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"175.602909ms","start":"2026-02-26T21:04:04.842007Z","end":"2026-02-26T21:04:05.01761Z","steps":["trace[1202241014] 'process raft request' (duration: 175.17203ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:07.318346Z","caller":"traceutil/trace.go:171","msg":"trace[1812638195] linearizableReadLoop","detail":"{readStateIndex:1079; appliedIndex:1078; }","duration":"427.055217ms","start":"2026-02-26T21:04:06.891245Z","end":"2026-02-26T21:04:07.3183Z","steps":["trace[1812638195] 'read index received' (duration: 337.751523ms)","trace[1812638195] 'applied index is now lower than readState.Index' (duration: 89.301474ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:04:07.318687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.974515ms","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-26T21:04:07.318682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.405227ms","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-26T21:04:07.318731Z","caller":"traceutil/trace.go:171","msg":"trace[889127216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1028; }","duration":"295.088315ms","start":"2026-02-26T21:04:07.023632Z","end":"2026-02-26T21:04:07.31872Z","steps":["trace[889127216] 'agreement among raft nodes before linearized reading' (duration: 294.886705ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:07.318766Z","caller":"traceutil/trace.go:171","msg":"trace[447578234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1028; }","duration":"427.568577ms","start":"2026-02-26T21:04:06.891176Z","end":"2026-02-26T21:04:07.318745Z","steps":["trace[447578234] 'agreement among raft nodes before linearized reading' (duration: 427.259647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:07.318817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:04:06.891149Z","time spent":"427.659047ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T21:04:07.319035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.225105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T21:04:07.319074Z","caller":"traceutil/trace.go:171","msg":"trace[532454545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1028; }","duration":"295.300275ms","start":"2026-02-26T21:04:07.023764Z","end":"2026-02-26T21:04:07.319064Z","steps":["trace[532454545] 'agreement among raft nodes before linearized reading' (duration: 295.200005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:07.319149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.414306ms","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-26T21:04:07.319182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.517836ms","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-26T21:04:07.319246Z","caller":"traceutil/trace.go:171","msg":"trace[484694474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1028; }","duration":"294.541266ms","start":"2026-02-26T21:04:07.024683Z","end":"2026-02-26T21:04:07.319224Z","steps":["trace[484694474] 'agreement among raft nodes before linearized reading' (duration: 294.261456ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:07.319247Z","caller":"traceutil/trace.go:171","msg":"trace[283903664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1028; }","duration":"294.599566ms","start":"2026-02-26T21:04:07.024632Z","end":"2026-02-26T21:04:07.319232Z","steps":["trace[283903664] 'agreement among raft nodes before linearized reading' (duration: 294.400736ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:07.429383Z","caller":"traceutil/trace.go:171","msg":"trace[428508914] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"104.747909ms","start":"2026-02-26T21:04:07.324609Z","end":"2026-02-26T21:04:07.429357Z","steps":["trace[428508914] 'process raft request' (duration: 104.571309ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:07.515318Z","caller":"traceutil/trace.go:171","msg":"trace[1936300289] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"190.542446ms","start":"2026-02-26T21:04:07.324723Z","end":"2026-02-26T21:04:07.515265Z","steps":["trace[1936300289] 'process raft request' (duration: 190.195206ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:07.515353Z","caller":"traceutil/trace.go:171","msg":"trace[1594772485] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"189.687856ms","start":"2026-02-26T21:04:07.325631Z","end":"2026-02-26T21:04:07.515319Z","steps":["trace[1594772485] 'process raft request' (duration: 189.460516ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:07.515398Z","caller":"traceutil/trace.go:171","msg":"trace[976284612] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"185.264567ms","start":"2026-02-26T21:04:07.330109Z","end":"2026-02-26T21:04:07.515373Z","steps":["trace[976284612] 'process raft request' (duration: 185.048417ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:07.515699Z","caller":"traceutil/trace.go:171","msg":"trace[1822493227] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"185.462767ms","start":"2026-02-26T21:04:07.330223Z","end":"2026-02-26T21:04:07.515686Z","steps":["trace[1822493227] 'process raft request' (duration: 184.968157ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:07.516087Z","caller":"traceutil/trace.go:171","msg":"trace[1625360998] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1079; }","duration":"122.927834ms","start":"2026-02-26T21:04:07.393136Z","end":"2026-02-26T21:04:07.516064Z","steps":["trace[1625360998] 'read index received' (duration: 36.177969ms)","trace[1625360998] 'applied index is now lower than readState.Index' (duration: 86.748655ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:04:07.516212Z","caller":"traceutil/trace.go:171","msg":"trace[103982042] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"185.962826ms","start":"2026-02-26T21:04:07.330217Z","end":"2026-02-26T21:04:07.51618Z","steps":["trace[103982042] 'process raft request' (duration: 185.005977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:07.516246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.071804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:04:07.516293Z","caller":"traceutil/trace.go:171","msg":"trace[1161378100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1034; }","duration":"123.195794ms","start":"2026-02-26T21:04:07.393086Z","end":"2026-02-26T21:04:07.516281Z","steps":["trace[1161378100] 'agreement among raft nodes before linearized reading' (duration: 123.081794ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:08.388359Z","caller":"traceutil/trace.go:171","msg":"trace[767358658] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"289.537076ms","start":"2026-02-26T21:04:08.098796Z","end":"2026-02-26T21:04:08.388333Z","steps":["trace[767358658] 'read index received' (duration: 289.389836ms)","trace[767358658] 'applied index is now lower than readState.Index' (duration: 145.89µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:04:08.388619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.725966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:04:08.388734Z","caller":"traceutil/trace.go:171","msg":"trace[911907962] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1035; }","duration":"289.980246ms","start":"2026-02-26T21:04:08.09873Z","end":"2026-02-26T21:04:08.38871Z","steps":["trace[911907962] 'agreement among raft nodes before linearized reading' (duration: 289.692236ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:04:08.389008Z","caller":"traceutil/trace.go:171","msg":"trace[259265220] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"632.112797ms","start":"2026-02-26T21:04:07.756259Z","end":"2026-02-26T21:04:08.388372Z","steps":["trace[259265220] 'process raft request' (duration: 631.930777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:08.389153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:04:07.756236Z","time spent":"632.823967ms","remote":"127.0.0.1:43778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":888,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T21:04:13.236868Z","caller":"traceutil/trace.go:171","msg":"trace[29605782] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"437.635684ms","start":"2026-02-26T21:04:12.799174Z","end":"2026-02-26T21:04:13.23681Z","steps":["trace[29605782] 'process raft request' (duration: 437.478154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:13.237072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:04:12.799154Z","time spent":"437.811124ms","remote":"127.0.0.1:43804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:04:13.638094Z","caller":"traceutil/trace.go:171","msg":"trace[665107977] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"246.501302ms","start":"2026-02-26T21:04:13.391563Z","end":"2026-02-26T21:04:13.638064Z","steps":["trace[665107977] 'read index received' (duration: 193.922701ms)","trace[665107977] 'applied index is now lower than readState.Index' (duration: 52.576871ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:04:13.638245Z","caller":"traceutil/trace.go:171","msg":"trace[1340671169] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"392.425508ms","start":"2026-02-26T21:04:13.245806Z","end":"2026-02-26T21:04:13.638231Z","steps":["trace[1340671169] 'process raft request' (duration: 339.834627ms)","trace[1340671169] 'compare' (duration: 52.245672ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:04:13.638373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:04:13.245781Z","time spent":"392.493649ms","remote":"127.0.0.1:43962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:04:13.63847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.631766ms","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-26T21:04:13.638577Z","caller":"traceutil/trace.go:171","msg":"trace[998601874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1083; }","duration":"178.846301ms","start":"2026-02-26T21:04:13.459695Z","end":"2026-02-26T21:04:13.638542Z","steps":["trace[998601874] 'agreement among raft nodes before linearized reading' (duration: 178.576824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:04:13.638787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.206671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:04:13.638941Z","caller":"traceutil/trace.go:171","msg":"trace[405686079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1083; }","duration":"247.371075ms","start":"2026-02-26T21:04:13.391487Z","end":"2026-02-26T21:04:13.638858Z","steps":["trace[405686079] 'agreement among raft nodes before linearized reading' (duration: 247.239521ms)"],"step_count":1}