{"level":"warn","ts":"2026-05-14T07:39:56.733963Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T07:39:56.734052Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-14T07:39:56.734296Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-14T07:39:56.734306Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-14T07:39:56.734324Z","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-05-14T07:39:56.73529Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-05-14T07:39:56.735417Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-14T07:39:56.739854Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.084379ms"} {"level":"info","ts":"2026-05-14T07:39:56.747394Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-05-14T07:39:56.747556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-05-14T07:39:56.747596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-05-14T07:39:56.747621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-14T07:39:56.74765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-05-14T07:39:56.747749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-05-14T07:39:56.755869Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-14T07:39:56.757239Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-14T07:39:56.758216Z","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-05-14T07:39:56.76071Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-14T07:39:56.760819Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-14T07:39:56.760976Z","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-05-14T07:39:56.761079Z","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-05-14T07:39:56.761102Z","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-05-14T07:39:56.762672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-05-14T07:39:56.762836Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-14T07:39:56.765543Z","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-05-14T07:39:56.76565Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-14T07:39:56.765681Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-14T07:39:56.765934Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-14T07:39:56.765976Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-14T07:39:57.74819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-05-14T07:39:57.748248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-14T07:39:57.748295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-05-14T07:39:57.748315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-05-14T07:39:57.748447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-14T07:39:57.748469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-05-14T07:39:57.748488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-14T07:39:57.749921Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T07:39:57.749908Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T07:39:57.749921Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-05-14T07:39:57.750033Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T07:39:57.750537Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-14T07:39:57.750607Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-14T07:39:57.75086Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T07:39:57.750967Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T07:39:57.751013Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T07:39:57.753944Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-14T07:39:57.75399Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"warn","ts":"2026-05-14T07:40:19.710211Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699232184258269,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T07:40:19.755932Z","caller":"traceutil/trace.go:171","msg":"trace[781242966] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"565.718558ms","start":"2026-05-14T07:40:19.190171Z","end":"2026-05-14T07:40:19.755889Z","steps":["trace[781242966] 'process raft request' (duration: 565.597285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:19.757302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:19.190149Z","time spent":"565.846361ms","remote":"127.0.0.1:40994","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-05-14T07:40:19.790548Z","caller":"traceutil/trace.go:171","msg":"trace[191200126] linearizableReadLoop","detail":"{readStateIndex:470; appliedIndex:468; }","duration":"580.736619ms","start":"2026-05-14T07:40:19.209786Z","end":"2026-05-14T07:40:19.790523Z","steps":["trace[191200126] 'read index received' (duration: 545.983613ms)","trace[191200126] 'applied index is now lower than readState.Index' (duration: 34.751786ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:40:19.790577Z","caller":"traceutil/trace.go:171","msg":"trace[1700524031] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"142.152842ms","start":"2026-05-14T07:40:19.648405Z","end":"2026-05-14T07:40:19.790558Z","steps":["trace[1700524031] 'process raft request' (duration: 141.965348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:19.790738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.941344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-14T07:40:19.790832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.233346ms","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-05-14T07:40:19.790845Z","caller":"traceutil/trace.go:171","msg":"trace[1765523900] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:456; }","duration":"581.083527ms","start":"2026-05-14T07:40:19.209733Z","end":"2026-05-14T07:40:19.790817Z","steps":["trace[1765523900] 'agreement among raft nodes before linearized reading' (duration: 580.922934ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:19.790886Z","caller":"traceutil/trace.go:171","msg":"trace[126866684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:456; }","duration":"577.320998ms","start":"2026-05-14T07:40:19.213555Z","end":"2026-05-14T07:40:19.790876Z","steps":["trace[126866684] 'agreement among raft nodes before linearized reading' (duration: 577.233456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:19.790897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:19.209713Z","time spent":"581.174249ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T07:40:19.790918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:19.21354Z","time spent":"577.370859ms","remote":"127.0.0.1:40994","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-05-14T07:40:19.790942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.854684ms","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-05-14T07:40:19.791034Z","caller":"traceutil/trace.go:171","msg":"trace[1559789885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:456; }","duration":"406.998197ms","start":"2026-05-14T07:40:19.384016Z","end":"2026-05-14T07:40:19.791015Z","steps":["trace[1559789885] 'agreement among raft nodes before linearized reading' (duration: 406.809043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:19.791085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:19.383998Z","time spent":"407.077729ms","remote":"127.0.0.1:40994","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-05-14T07:40:19.955706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.341475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:40:19.955979Z","caller":"traceutil/trace.go:171","msg":"trace[147869192] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"160.879163ms","start":"2026-05-14T07:40:19.795077Z","end":"2026-05-14T07:40:19.955956Z","steps":["trace[147869192] 'process raft request' (duration: 38.009935ms)","trace[147869192] 'compare' (duration: 122.190792ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:40:19.955991Z","caller":"traceutil/trace.go:171","msg":"trace[1194039112] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"160.309259ms","start":"2026-05-14T07:40:19.795649Z","end":"2026-05-14T07:40:19.955958Z","steps":["trace[1194039112] 'process raft request' (duration: 160.171606ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:19.95606Z","caller":"traceutil/trace.go:171","msg":"trace[427127439] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"160.285778ms","start":"2026-05-14T07:40:19.795735Z","end":"2026-05-14T07:40:19.956021Z","steps":["trace[427127439] 'process raft request' (duration: 160.142895ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:25.939806Z","caller":"traceutil/trace.go:171","msg":"trace[778143432] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"153.376862ms","start":"2026-05-14T07:40:25.786407Z","end":"2026-05-14T07:40:25.939784Z","steps":["trace[778143432] 'process raft request' (duration: 153.157137ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:29.839269Z","caller":"traceutil/trace.go:171","msg":"trace[1497612518] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"145.884701ms","start":"2026-05-14T07:40:29.693355Z","end":"2026-05-14T07:40:29.83924Z","steps":["trace[1497612518] 'process raft request' (duration: 145.532403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:30.415959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699232184258501,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T07:40:30.916987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699232184258501,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T07:40:31.247859Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.128607829s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T07:40:31.249277Z","caller":"traceutil/trace.go:171","msg":"trace[586229501] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.481363261s","start":"2026-05-14T07:40:29.767889Z","end":"2026-05-14T07:40:31.249252Z","steps":["trace[586229501] 'process raft request' (duration: 1.480858648s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:31.249687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:29.767856Z","time spent":"1.481621217s","remote":"127.0.0.1:40812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:40:31.254781Z","caller":"traceutil/trace.go:171","msg":"trace[1732750936] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.346958984s","start":"2026-05-14T07:40:29.907771Z","end":"2026-05-14T07:40:31.25473Z","steps":["trace[1732750936] 'process raft request' (duration: 1.346714428s)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:31.254866Z","caller":"traceutil/trace.go:171","msg":"trace[1721769601] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.290708371s","start":"2026-05-14T07:40:29.964144Z","end":"2026-05-14T07:40:31.254853Z","steps":["trace[1721769601] 'process raft request' (duration: 1.290497385s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:31.254962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:29.964127Z","time spent":"1.290805633s","remote":"127.0.0.1:40812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:40:31.254968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:29.907747Z","time spent":"1.347117679s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:40:31.255059Z","caller":"traceutil/trace.go:171","msg":"trace[177293345] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"1.26655616s","start":"2026-05-14T07:40:29.988495Z","end":"2026-05-14T07:40:31.255051Z","steps":["trace[177293345] 'process raft request' (duration: 1.266381656s)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:31.255104Z","caller":"traceutil/trace.go:171","msg":"trace[676882507] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:620; }","duration":"1.339703731s","start":"2026-05-14T07:40:29.915382Z","end":"2026-05-14T07:40:31.255086Z","steps":["trace[676882507] 'read index received' (duration: 1.333271075s)","trace[676882507] 'applied index is now lower than readState.Index' (duration: 6.431956ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:40:31.255149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:29.988467Z","time spent":"1.266629782s","remote":"127.0.0.1:40812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T07:40:31.255207Z","caller":"traceutil/trace.go:171","msg":"trace[1787923122] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"688.705889ms","start":"2026-05-14T07:40:30.566478Z","end":"2026-05-14T07:40:31.255184Z","steps":["trace[1787923122] 'process raft request' (duration: 688.554926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:31.255287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:30.566459Z","time spent":"688.795631ms","remote":"127.0.0.1:40812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:40:31.255352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299669356s","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-05-14T07:40:31.25537Z","caller":"traceutil/trace.go:171","msg":"trace[501770055] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.091665649s","start":"2026-05-14T07:40:30.163691Z","end":"2026-05-14T07:40:31.255357Z","steps":["trace[501770055] 'process raft request' (duration: 1.09128382s)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:31.255421Z","caller":"traceutil/trace.go:171","msg":"trace[699321755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"1.299722528s","start":"2026-05-14T07:40:29.955652Z","end":"2026-05-14T07:40:31.255375Z","steps":["trace[699321755] 'agreement among raft nodes before linearized reading' (duration: 1.299625056s)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:31.255442Z","caller":"traceutil/trace.go:171","msg":"trace[1611836200] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"886.150082ms","start":"2026-05-14T07:40:30.36928Z","end":"2026-05-14T07:40:31.25543Z","steps":["trace[1611836200] 'process raft request' (duration: 885.725962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:31.255452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:30.163657Z","time spent":"1.091745502s","remote":"127.0.0.1:40812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:40:31.255499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:30.369256Z","time spent":"886.219934ms","remote":"127.0.0.1:40812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:40:31.255518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340135251s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:40:31.255542Z","caller":"traceutil/trace.go:171","msg":"trace[1653356235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"1.340158432s","start":"2026-05-14T07:40:29.915378Z","end":"2026-05-14T07:40:31.255536Z","steps":["trace[1653356235] 'agreement among raft nodes before linearized reading' (duration: 1.340107741s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:31.255563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:29.915337Z","time spent":"1.340222163s","remote":"127.0.0.1:40760","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-05-14T07:40:31.255452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:29.955641Z","time spent":"1.29980512s","remote":"127.0.0.1:40994","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-05-14T07:40:31.255638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.719827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-14T07:40:31.25568Z","caller":"traceutil/trace.go:171","msg":"trace[436937548] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:609; }","duration":"594.801899ms","start":"2026-05-14T07:40:30.660867Z","end":"2026-05-14T07:40:31.255669Z","steps":["trace[436937548] 'agreement among raft nodes before linearized reading' (duration: 594.720917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:31.25571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:30.660847Z","time spent":"594.857431ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.240\" "} {"level":"warn","ts":"2026-05-14T07:40:31.255753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253019083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-14T07:40:31.255736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.078331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:40:31.25579Z","caller":"traceutil/trace.go:171","msg":"trace[1644613162] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:609; }","duration":"292.170663ms","start":"2026-05-14T07:40:30.963607Z","end":"2026-05-14T07:40:31.255778Z","steps":["trace[1644613162] 'agreement among raft nodes before linearized reading' (duration: 292.096242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:31.255847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256021026s","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-05-14T07:40:31.255868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.366332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:40:31.255909Z","caller":"traceutil/trace.go:171","msg":"trace[1134625904] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:609; }","duration":"492.450994ms","start":"2026-05-14T07:40:30.763448Z","end":"2026-05-14T07:40:31.255899Z","steps":["trace[1134625904] 'agreement among raft nodes before linearized reading' (duration: 492.389013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:31.255939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:30.763429Z","time spent":"492.503016ms","remote":"127.0.0.1:40812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-14T07:40:31.255993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2561827s","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-05-14T07:40:31.256017Z","caller":"traceutil/trace.go:171","msg":"trace[1726987723] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"1.256207831s","start":"2026-05-14T07:40:29.999803Z","end":"2026-05-14T07:40:31.256011Z","steps":["trace[1726987723] 'agreement among raft nodes before linearized reading' (duration: 1.2561504s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:31.25606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:29.999773Z","time spent":"1.256263242s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-14T07:40:31.25587Z","caller":"traceutil/trace.go:171","msg":"trace[1296650245] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:609; }","duration":"1.256085348s","start":"2026-05-14T07:40:29.999779Z","end":"2026-05-14T07:40:31.255864Z","steps":["trace[1296650245] 'agreement among raft nodes before linearized reading' (duration: 1.256029956s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:31.256114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:29.999757Z","time spent":"1.256345054s","remote":"127.0.0.1:40994","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-05-14T07:40:31.255779Z","caller":"traceutil/trace.go:171","msg":"trace[465701002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"1.253044943s","start":"2026-05-14T07:40:30.002727Z","end":"2026-05-14T07:40:31.255772Z","steps":["trace[465701002] 'agreement among raft nodes before linearized reading' (duration: 1.252997792s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:31.256211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:30.002693Z","time spent":"1.253495374s","remote":"127.0.0.1:40994","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-05-14T07:40:37.793869Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699232184258753,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T07:40:38.295052Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699232184258753,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T07:40:38.348434Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.091076386s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T07:40:38.349243Z","caller":"traceutil/trace.go:171","msg":"trace[1754731572] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:684; }","duration":"1.056040532s","start":"2026-05-14T07:40:37.293182Z","end":"2026-05-14T07:40:38.349222Z","steps":["trace[1754731572] 'read index received' (duration: 1.055383396s)","trace[1754731572] 'applied index is now lower than readState.Index' (duration: 656.246µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:40:38.349388Z","caller":"traceutil/trace.go:171","msg":"trace[46615233] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.093141695s","start":"2026-05-14T07:40:37.25621Z","end":"2026-05-14T07:40:38.349351Z","steps":["trace[46615233] 'process raft request' (duration: 1.092597282s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:38.3495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.854759ms","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-05-14T07:40:38.349528Z","caller":"traceutil/trace.go:171","msg":"trace[1778042203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:664; }","duration":"945.903151ms","start":"2026-05-14T07:40:37.403617Z","end":"2026-05-14T07:40:38.34952Z","steps":["trace[1778042203] 'agreement among raft nodes before linearized reading' (duration: 945.845119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:38.349554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:37.403605Z","time spent":"945.942891ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T07:40:38.349546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054995197s","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-05-14T07:40:38.349577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:37.256186Z","time spent":"1.093291388s","remote":"127.0.0.1:40846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:40:38.349598Z","caller":"traceutil/trace.go:171","msg":"trace[641344610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:664; }","duration":"1.055052678s","start":"2026-05-14T07:40:37.29453Z","end":"2026-05-14T07:40:38.349583Z","steps":["trace[641344610] 'agreement among raft nodes before linearized reading' (duration: 1.054904805s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:38.349634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:37.294499Z","time spent":"1.05512719s","remote":"127.0.0.1:40994","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-05-14T07:40:38.349765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.89303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:40:38.349785Z","caller":"traceutil/trace.go:171","msg":"trace[19205005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"432.953642ms","start":"2026-05-14T07:40:37.916825Z","end":"2026-05-14T07:40:38.349779Z","steps":["trace[19205005] 'agreement among raft nodes before linearized reading' (duration: 432.921711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:38.349807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:37.916796Z","time spent":"433.008384ms","remote":"127.0.0.1:40750","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-05-14T07:40:38.349794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047424874s","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-05-14T07:40:38.34984Z","caller":"traceutil/trace.go:171","msg":"trace[631432993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:664; }","duration":"1.047490626s","start":"2026-05-14T07:40:37.30234Z","end":"2026-05-14T07:40:38.349831Z","steps":["trace[631432993] 'agreement among raft nodes before linearized reading' (duration: 1.047374963s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:38.349441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056253386s","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-05-14T07:40:38.349901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.344099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b2gt5\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-14T07:40:38.349918Z","caller":"traceutil/trace.go:171","msg":"trace[1135576508] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b2gt5; range_end:; response_count:1; response_revision:664; }","duration":"830.4265ms","start":"2026-05-14T07:40:37.519487Z","end":"2026-05-14T07:40:38.349913Z","steps":["trace[1135576508] 'agreement among raft nodes before linearized reading' (duration: 830.391409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:38.349936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:37.519464Z","time spent":"830.468032ms","remote":"127.0.0.1:40914","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-b2gt5\" "} {"level":"info","ts":"2026-05-14T07:40:38.349931Z","caller":"traceutil/trace.go:171","msg":"trace[999517558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:664; }","duration":"1.05681211s","start":"2026-05-14T07:40:37.293101Z","end":"2026-05-14T07:40:38.349914Z","steps":["trace[999517558] 'agreement among raft nodes before linearized reading' (duration: 1.056221116s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:38.349946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047836405s","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-05-14T07:40:38.349976Z","caller":"traceutil/trace.go:171","msg":"trace[677918889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:664; }","duration":"1.047904095s","start":"2026-05-14T07:40:37.302064Z","end":"2026-05-14T07:40:38.349968Z","steps":["trace[677918889] 'agreement among raft nodes before linearized reading' (duration: 1.047829414s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:38.349989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:37.29308Z","time spent":"1.056893212s","remote":"127.0.0.1:40994","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-05-14T07:40:38.350009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:37.302043Z","time spent":"1.047960477s","remote":"127.0.0.1:40994","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-05-14T07:40:38.349876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:37.302328Z","time spent":"1.047541457s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T07:40:38.538149Z","caller":"traceutil/trace.go:171","msg":"trace[1352438136] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"182.785339ms","start":"2026-05-14T07:40:38.355335Z","end":"2026-05-14T07:40:38.538121Z","steps":["trace[1352438136] 'process raft request' (duration: 182.623036ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:38.538401Z","caller":"traceutil/trace.go:171","msg":"trace[1871508748] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"182.736689ms","start":"2026-05-14T07:40:38.355643Z","end":"2026-05-14T07:40:38.53838Z","steps":["trace[1871508748] 'process raft request' (duration: 182.435171ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:38.538541Z","caller":"traceutil/trace.go:171","msg":"trace[757138390] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"180.591318ms","start":"2026-05-14T07:40:38.357931Z","end":"2026-05-14T07:40:38.538523Z","steps":["trace[757138390] 'process raft request' (duration: 180.442293ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:38.538528Z","caller":"traceutil/trace.go:171","msg":"trace[741972142] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"174.559992ms","start":"2026-05-14T07:40:38.363945Z","end":"2026-05-14T07:40:38.538505Z","steps":["trace[741972142] 'process raft request' (duration: 174.467449ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:38.538752Z","caller":"traceutil/trace.go:171","msg":"trace[1041732015] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"183.039717ms","start":"2026-05-14T07:40:38.355701Z","end":"2026-05-14T07:40:38.538741Z","steps":["trace[1041732015] 'process raft request' (duration: 182.575755ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:38.543491Z","caller":"traceutil/trace.go:171","msg":"trace[1827568382] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"139.945109ms","start":"2026-05-14T07:40:38.403519Z","end":"2026-05-14T07:40:38.543464Z","steps":["trace[1827568382] 'process raft request' (duration: 139.840697ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:39.14265Z","caller":"traceutil/trace.go:171","msg":"trace[1752892980] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"200.257691ms","start":"2026-05-14T07:40:38.94236Z","end":"2026-05-14T07:40:39.142618Z","steps":["trace[1752892980] 'process raft request' (duration: 199.628676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:40.629078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33804439s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:40:40.629458Z","caller":"traceutil/trace.go:171","msg":"trace[1704736862] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"711.315553ms","start":"2026-05-14T07:40:39.918124Z","end":"2026-05-14T07:40:40.629439Z","steps":["trace[1704736862] 'read index received' (duration: 59.091µs)","trace[1704736862] 'applied index is now lower than readState.Index' (duration: 711.255042ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:40:40.629494Z","caller":"traceutil/trace.go:171","msg":"trace[2064721182] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"1.429957283s","start":"2026-05-14T07:40:39.199498Z","end":"2026-05-14T07:40:40.629455Z","steps":["trace[2064721182] 'process raft request' (duration: 91.467953ms)","trace[2064721182] 'compare' (duration: 1.337419695s)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:40:40.629683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.493857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:40:40.629752Z","caller":"traceutil/trace.go:171","msg":"trace[2000631998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:673; }","duration":"711.670581ms","start":"2026-05-14T07:40:39.918068Z","end":"2026-05-14T07:40:40.629739Z","steps":["trace[2000631998] 'agreement among raft nodes before linearized reading' (duration: 711.477917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:40.629885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.46107ms","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-05-14T07:40:40.629981Z","caller":"traceutil/trace.go:171","msg":"trace[1388108336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:673; }","duration":"174.600732ms","start":"2026-05-14T07:40:40.455363Z","end":"2026-05-14T07:40:40.629964Z","steps":["trace[1388108336] 'agreement among raft nodes before linearized reading' (duration: 174.262144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:40.629924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:39.918027Z","time spent":"711.882866ms","remote":"127.0.0.1:40750","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-05-14T07:40:40.629809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:39.199477Z","time spent":"1.4302443s","remote":"127.0.0.1:41070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:40:41.478191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.915539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:40:41.478531Z","caller":"traceutil/trace.go:171","msg":"trace[275331562] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"842.686835ms","start":"2026-05-14T07:40:40.635827Z","end":"2026-05-14T07:40:41.478514Z","steps":["trace[275331562] 'process raft request' (duration: 842.541112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:41.478597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:40.635805Z","time spent":"842.760147ms","remote":"127.0.0.1:40994","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-05-14T07:40:41.478593Z","caller":"traceutil/trace.go:171","msg":"trace[462342265] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"843.346941ms","start":"2026-05-14T07:40:40.635213Z","end":"2026-05-14T07:40:41.47856Z","steps":["trace[462342265] 'process raft request' (duration: 843.075525ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:41.47863Z","caller":"traceutil/trace.go:171","msg":"trace[1454933172] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"843.531575ms","start":"2026-05-14T07:40:40.635069Z","end":"2026-05-14T07:40:41.478601Z","steps":["trace[1454933172] 'process raft request' (duration: 101.119064ms)","trace[1454933172] 'compare' (duration: 741.413168ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:40:41.478762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:40.635205Z","time spent":"843.473674ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:40:41.478795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:40.635033Z","time spent":"843.658858ms","remote":"127.0.0.1:40994","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-05-14T07:40:41.478934Z","caller":"traceutil/trace.go:171","msg":"trace[1042852508] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"842.735797ms","start":"2026-05-14T07:40:40.636184Z","end":"2026-05-14T07:40:41.47892Z","steps":["trace[1042852508] 'process raft request' (duration: 842.260606ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:41.479004Z","caller":"traceutil/trace.go:171","msg":"trace[239340706] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:694; }","duration":"838.013233ms","start":"2026-05-14T07:40:40.640984Z","end":"2026-05-14T07:40:41.478997Z","steps":["trace[239340706] 'read index received' (duration: 95.180211ms)","trace[239340706] 'applied index is now lower than readState.Index' (duration: 742.832412ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:40:41.479012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:40.636167Z","time spent":"842.804828ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:40:41.479094Z","caller":"traceutil/trace.go:171","msg":"trace[209571560] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"842.264675ms","start":"2026-05-14T07:40:40.636824Z","end":"2026-05-14T07:40:41.479089Z","steps":["trace[209571560] 'process raft request' (duration: 842.04875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:41.479123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:40.636817Z","time spent":"842.287765ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:40:41.479184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.204437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:40:41.479202Z","caller":"traceutil/trace.go:171","msg":"trace[386434385] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:678; }","duration":"838.236558ms","start":"2026-05-14T07:40:40.640959Z","end":"2026-05-14T07:40:41.479195Z","steps":["trace[386434385] 'agreement among raft nodes before linearized reading' (duration: 838.204368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:41.479216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:40.640951Z","time spent":"838.261679ms","remote":"127.0.0.1:41072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" "} {"level":"warn","ts":"2026-05-14T07:40:41.479243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.54531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-14T07:40:41.479283Z","caller":"traceutil/trace.go:171","msg":"trace[1989883161] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:678; }","duration":"817.628872ms","start":"2026-05-14T07:40:40.661641Z","end":"2026-05-14T07:40:41.47927Z","steps":["trace[1989883161] 'agreement among raft nodes before linearized reading' (duration: 817.493659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:41.479317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:40.661618Z","time spent":"817.691494ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.240\" "} {"level":"info","ts":"2026-05-14T07:40:46.789337Z","caller":"traceutil/trace.go:171","msg":"trace[2048230009] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"197.088583ms","start":"2026-05-14T07:40:46.592221Z","end":"2026-05-14T07:40:46.78931Z","steps":["trace[2048230009] 'process raft request' (duration: 195.196163ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:49.980816Z","caller":"traceutil/trace.go:171","msg":"trace[1254944455] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"321.989972ms","start":"2026-05-14T07:40:49.658793Z","end":"2026-05-14T07:40:49.980783Z","steps":["trace[1254944455] 'process raft request' (duration: 321.726484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:49.981038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:49.658765Z","time spent":"322.174968ms","remote":"127.0.0.1:33818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2142,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:40:50.936527Z","caller":"traceutil/trace.go:171","msg":"trace[1863556475] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"218.979194ms","start":"2026-05-14T07:40:50.717501Z","end":"2026-05-14T07:40:50.93648Z","steps":["trace[1863556475] 'process raft request' (duration: 145.15414ms)","trace[1863556475] 'compare' (duration: 73.538555ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:40:53.719801Z","caller":"traceutil/trace.go:171","msg":"trace[1346469268] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"101.791509ms","start":"2026-05-14T07:40:53.617989Z","end":"2026-05-14T07:40:53.719781Z","steps":["trace[1346469268] 'process raft request' (duration: 101.633454ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:53.720162Z","caller":"traceutil/trace.go:171","msg":"trace[1957451019] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"102.056677ms","start":"2026-05-14T07:40:53.618082Z","end":"2026-05-14T07:40:53.720139Z","steps":["trace[1957451019] 'process raft request' (duration: 101.662235ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:40:53.720195Z","caller":"traceutil/trace.go:171","msg":"trace[1751194075] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"101.872671ms","start":"2026-05-14T07:40:53.618304Z","end":"2026-05-14T07:40:53.720177Z","steps":["trace[1751194075] 'process raft request' (duration: 101.665415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:59.418781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.363067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9nmtf\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-14T07:40:59.418881Z","caller":"traceutil/trace.go:171","msg":"trace[61716366] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9nmtf; range_end:; response_count:1; response_revision:803; }","duration":"786.511532ms","start":"2026-05-14T07:40:58.632344Z","end":"2026-05-14T07:40:59.418855Z","steps":["trace[61716366] 'range keys from in-memory index tree' (duration: 786.178831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:59.418929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:58.632323Z","time spent":"786.596225ms","remote":"127.0.0.1:40914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9nmtf\" "} {"level":"warn","ts":"2026-05-14T07:40:59.41917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.308514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:40:59.419401Z","caller":"traceutil/trace.go:171","msg":"trace[1794600341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"502.594733ms","start":"2026-05-14T07:40:58.916702Z","end":"2026-05-14T07:40:59.419297Z","steps":["trace[1794600341] 'range keys from in-memory index tree' (duration: 502.20272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:40:59.419533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:40:58.916616Z","time spent":"502.900233ms","remote":"127.0.0.1:40750","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-05-14T07:41:17.983925Z","caller":"traceutil/trace.go:171","msg":"trace[77245376] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"146.201053ms","start":"2026-05-14T07:41:17.837679Z","end":"2026-05-14T07:41:17.98388Z","steps":["trace[77245376] 'process raft request' (duration: 109.075039ms)","trace[77245376] 'compare' (duration: 36.917928ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:41:18.030368Z","caller":"traceutil/trace.go:171","msg":"trace[211029281] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:992; }","duration":"113.641074ms","start":"2026-05-14T07:41:17.916704Z","end":"2026-05-14T07:41:18.030345Z","steps":["trace[211029281] 'read index received' (duration: 30.060921ms)","trace[211029281] 'applied index is now lower than readState.Index' (duration: 83.579293ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:41:18.030437Z","caller":"traceutil/trace.go:171","msg":"trace[1873327454] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"191.819316ms","start":"2026-05-14T07:41:17.838605Z","end":"2026-05-14T07:41:18.030424Z","steps":["trace[1873327454] 'process raft request' (duration: 191.703723ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:18.03052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.8148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:41:18.030558Z","caller":"traceutil/trace.go:171","msg":"trace[940631586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"113.904642ms","start":"2026-05-14T07:41:17.916643Z","end":"2026-05-14T07:41:18.030548Z","steps":["trace[940631586] 'agreement among raft nodes before linearized reading' (duration: 113.81967ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:41:18.030372Z","caller":"traceutil/trace.go:171","msg":"trace[1976046411] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"191.839127ms","start":"2026-05-14T07:41:17.838517Z","end":"2026-05-14T07:41:18.030356Z","steps":["trace[1976046411] 'process raft request' (duration: 191.682962ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:41:28.004967Z","caller":"traceutil/trace.go:171","msg":"trace[1236610955] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"146.556824ms","start":"2026-05-14T07:41:27.858388Z","end":"2026-05-14T07:41:28.004945Z","steps":["trace[1236610955] 'process raft request' (duration: 146.410989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:28.21021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.790816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:41:28.210308Z","caller":"traceutil/trace.go:171","msg":"trace[1390420348] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1024; }","duration":"298.538591ms","start":"2026-05-14T07:41:27.911754Z","end":"2026-05-14T07:41:28.210293Z","steps":["trace[1390420348] 'read index received' (duration: 93.057843ms)","trace[1390420348] 'applied index is now lower than readState.Index' (duration: 205.479698ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:41:28.210361Z","caller":"traceutil/trace.go:171","msg":"trace[2017808457] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"325.70775ms","start":"2026-05-14T07:41:27.884617Z","end":"2026-05-14T07:41:28.210325Z","steps":["trace[2017808457] 'process raft request' (duration: 176.729868ms)","trace[2017808457] 'compare' (duration: 148.689403ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:41:28.210468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.104353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T07:41:28.210501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.742357ms","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-05-14T07:41:28.210539Z","caller":"traceutil/trace.go:171","msg":"trace[1724831244] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:993; }","duration":"298.837601ms","start":"2026-05-14T07:41:27.911693Z","end":"2026-05-14T07:41:28.210531Z","steps":["trace[1724831244] 'agreement among raft nodes before linearized reading' (duration: 298.675976ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:41:28.210512Z","caller":"traceutil/trace.go:171","msg":"trace[1123805682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:993; }","duration":"295.165865ms","start":"2026-05-14T07:41:27.915333Z","end":"2026-05-14T07:41:28.210499Z","steps":["trace[1123805682] 'agreement among raft nodes before linearized reading' (duration: 295.085662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:28.210569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:27.884601Z","time spent":"325.857325ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:41:28.210584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.130567ms","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-05-14T07:41:28.21063Z","caller":"traceutil/trace.go:171","msg":"trace[1429115069] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:993; }","duration":"131.191048ms","start":"2026-05-14T07:41:28.079427Z","end":"2026-05-14T07:41:28.210618Z","steps":["trace[1429115069] 'agreement among raft nodes before linearized reading' (duration: 131.102186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:28.210751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.749051ms","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-05-14T07:41:28.210769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.956793ms","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-05-14T07:41:28.210813Z","caller":"traceutil/trace.go:171","msg":"trace[1571988787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:993; }","duration":"140.861714ms","start":"2026-05-14T07:41:28.069938Z","end":"2026-05-14T07:41:28.2108Z","steps":["trace[1571988787] 'agreement among raft nodes before linearized reading' (duration: 140.652348ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:41:28.210826Z","caller":"traceutil/trace.go:171","msg":"trace[1788477752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:993; }","duration":"188.053116ms","start":"2026-05-14T07:41:28.022758Z","end":"2026-05-14T07:41:28.210811Z","steps":["trace[1788477752] 'agreement among raft nodes before linearized reading' (duration: 187.85709ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:41:34.403213Z","caller":"traceutil/trace.go:171","msg":"trace[1122751633] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"356.329293ms","start":"2026-05-14T07:41:34.046842Z","end":"2026-05-14T07:41:34.403171Z","steps":["trace[1122751633] 'process raft request' (duration: 356.18922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:34.403336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:34.046831Z","time spent":"356.438416ms","remote":"127.0.0.1:40994","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-05-14T07:41:36.211435Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.68530815s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T07:41:36.21187Z","caller":"traceutil/trace.go:171","msg":"trace[2132244512] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1050; }","duration":"1.978061022s","start":"2026-05-14T07:41:34.233783Z","end":"2026-05-14T07:41:36.211844Z","steps":["trace[2132244512] 'read index received' (duration: 169.250792ms)","trace[2132244512] 'applied index is now lower than readState.Index' (duration: 1.80880927s)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:41:36.211955Z","caller":"traceutil/trace.go:171","msg":"trace[1720938708] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"1.984292198s","start":"2026-05-14T07:41:34.227616Z","end":"2026-05-14T07:41:36.211908Z","steps":["trace[1720938708] 'process raft request' (duration: 1.984056412s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:36.2121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:34.22759Z","time spent":"1.984443952s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:41:36.212134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.978341768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-14T07:41:36.212194Z","caller":"traceutil/trace.go:171","msg":"trace[1031539133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1017; }","duration":"1.978469142s","start":"2026-05-14T07:41:34.23371Z","end":"2026-05-14T07:41:36.212179Z","steps":["trace[1031539133] 'agreement among raft nodes before linearized reading' (duration: 1.978202305s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:36.21224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:34.233695Z","time spent":"1.978538313s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-14T07:41:36.248251Z","caller":"traceutil/trace.go:171","msg":"trace[1974209293] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"1.502879776s","start":"2026-05-14T07:41:34.745354Z","end":"2026-05-14T07:41:36.248234Z","steps":["trace[1974209293] 'process raft request' (duration: 1.502664241s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:36.248341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:34.74533Z","time spent":"1.502969669s","remote":"127.0.0.1:40994","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-05-14T07:41:36.251243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333957652s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T07:41:36.25129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.011028322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-14T07:41:36.251304Z","caller":"traceutil/trace.go:171","msg":"trace[927740592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1019; }","duration":"1.334072265s","start":"2026-05-14T07:41:34.917217Z","end":"2026-05-14T07:41:36.25129Z","steps":["trace[927740592] 'agreement among raft nodes before linearized reading' (duration: 1.333965062s)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:41:36.251353Z","caller":"traceutil/trace.go:171","msg":"trace[1496071543] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1019; }","duration":"2.011110224s","start":"2026-05-14T07:41:34.240225Z","end":"2026-05-14T07:41:36.251335Z","steps":["trace[1496071543] 'agreement among raft nodes before linearized reading' (duration: 2.01093015s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:36.251363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:34.917198Z","time spent":"1.334155767s","remote":"127.0.0.1:40760","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-05-14T07:41:36.251385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:34.240219Z","time spent":"2.011159525s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T07:41:36.25141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.008788516s","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-05-14T07:41:36.251472Z","caller":"traceutil/trace.go:171","msg":"trace[1349027863] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1019; }","duration":"2.008857617s","start":"2026-05-14T07:41:34.242594Z","end":"2026-05-14T07:41:36.251451Z","steps":["trace[1349027863] 'agreement among raft nodes before linearized reading' (duration: 2.008703913s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:36.251518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:34.242587Z","time spent":"2.008915489s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T07:41:36.25158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.315697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:41:36.251625Z","caller":"traceutil/trace.go:171","msg":"trace[126880452] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1019; }","duration":"268.403869ms","start":"2026-05-14T07:41:35.983211Z","end":"2026-05-14T07:41:36.251615Z","steps":["trace[126880452] 'agreement among raft nodes before linearized reading' (duration: 268.323717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:36.251643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.21551957s","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-05-14T07:41:36.251669Z","caller":"traceutil/trace.go:171","msg":"trace[1407051700] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1019; }","duration":"1.215577151s","start":"2026-05-14T07:41:35.036081Z","end":"2026-05-14T07:41:36.251658Z","steps":["trace[1407051700] 'agreement among raft nodes before linearized reading' (duration: 1.21554489s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:36.251694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:35.036062Z","time spent":"1.215626462s","remote":"127.0.0.1:41190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-05-14T07:41:36.251598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.011214137s","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-05-14T07:41:36.251818Z","caller":"traceutil/trace.go:171","msg":"trace[547952338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1019; }","duration":"2.011445403s","start":"2026-05-14T07:41:34.240357Z","end":"2026-05-14T07:41:36.251802Z","steps":["trace[547952338] 'agreement among raft nodes before linearized reading' (duration: 2.011193736s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:36.251857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:34.240352Z","time spent":"2.011493574s","remote":"127.0.0.1:40994","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-05-14T07:41:40.581122Z","caller":"traceutil/trace.go:171","msg":"trace[524644684] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"115.170071ms","start":"2026-05-14T07:41:40.465932Z","end":"2026-05-14T07:41:40.581102Z","steps":["trace[524644684] 'process raft request' (duration: 115.056098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:44.722005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.971205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T07:41:44.722489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.853432ms","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-05-14T07:41:44.722583Z","caller":"traceutil/trace.go:171","msg":"trace[581804901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1043; }","duration":"804.591021ms","start":"2026-05-14T07:41:43.917978Z","end":"2026-05-14T07:41:44.722569Z","steps":["trace[581804901] 'range keys from in-memory index tree' (duration: 803.892014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:44.722616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:43.917948Z","time spent":"804.657423ms","remote":"127.0.0.1:40750","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-05-14T07:41:44.722611Z","caller":"traceutil/trace.go:171","msg":"trace[727851825] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1043; }","duration":"995.035997ms","start":"2026-05-14T07:41:43.727542Z","end":"2026-05-14T07:41:44.722578Z","steps":["trace[727851825] 'count revisions from in-memory index tree' (duration: 994.781411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:44.722667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:43.727526Z","time spent":"995.123499ms","remote":"127.0.0.1:41198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-14T07:41:44.724101Z","caller":"traceutil/trace.go:171","msg":"trace[424845380] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"360.496217ms","start":"2026-05-14T07:41:44.363583Z","end":"2026-05-14T07:41:44.724079Z","steps":["trace[424845380] 'read index received' (duration: 360.348974ms)","trace[424845380] 'applied index is now lower than readState.Index' (duration: 146.393µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:41:44.724349Z","caller":"traceutil/trace.go:171","msg":"trace[2107722268] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"470.819986ms","start":"2026-05-14T07:41:44.253511Z","end":"2026-05-14T07:41:44.724331Z","steps":["trace[2107722268] 'process raft request' (duration: 470.455066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:44.72433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.706853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T07:41:44.724408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.34585ms","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-05-14T07:41:44.724435Z","caller":"traceutil/trace.go:171","msg":"trace[115854232] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1044; }","duration":"338.431803ms","start":"2026-05-14T07:41:44.385996Z","end":"2026-05-14T07:41:44.724428Z","steps":["trace[115854232] 'agreement among raft nodes before linearized reading' (duration: 338.331911ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:41:44.724434Z","caller":"traceutil/trace.go:171","msg":"trace[1088399219] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1044; }","duration":"360.885248ms","start":"2026-05-14T07:41:44.363528Z","end":"2026-05-14T07:41:44.724414Z","steps":["trace[1088399219] 'agreement among raft nodes before linearized reading' (duration: 360.686683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:44.724443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:44.253479Z","time spent":"470.908939ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:41:44.724463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:44.385977Z","time spent":"338.480854ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T07:41:44.72449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:44.363508Z","time spent":"360.96602ms","remote":"127.0.0.1:40886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-05-14T07:41:44.724541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.863314ms","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-05-14T07:41:44.724569Z","caller":"traceutil/trace.go:171","msg":"trace[2097987927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1044; }","duration":"338.941565ms","start":"2026-05-14T07:41:44.385617Z","end":"2026-05-14T07:41:44.724558Z","steps":["trace[2097987927] 'agreement among raft nodes before linearized reading' (duration: 338.862233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:44.724595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:44.38561Z","time spent":"338.980446ms","remote":"127.0.0.1:40994","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-05-14T07:41:44.72446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.575329ms","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-05-14T07:41:44.724658Z","caller":"traceutil/trace.go:171","msg":"trace[594737630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1044; }","duration":"354.838846ms","start":"2026-05-14T07:41:44.369809Z","end":"2026-05-14T07:41:44.724648Z","steps":["trace[594737630] 'agreement among raft nodes before linearized reading' (duration: 354.477607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:44.724689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:44.36979Z","time spent":"354.891177ms","remote":"127.0.0.1:40994","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-05-14T07:41:44.724775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.248113ms","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-05-14T07:41:44.724816Z","caller":"traceutil/trace.go:171","msg":"trace[373237913] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1044; }","duration":"339.308044ms","start":"2026-05-14T07:41:44.385497Z","end":"2026-05-14T07:41:44.724805Z","steps":["trace[373237913] 'agreement among raft nodes before linearized reading' (duration: 339.13454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:44.724845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:44.385488Z","time spent":"339.346965ms","remote":"127.0.0.1:40994","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-05-14T07:41:59.343522Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699232184259649,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T07:41:59.844259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699232184259649,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T07:41:59.867603Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.050657197s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T07:41:59.867971Z","caller":"traceutil/trace.go:171","msg":"trace[18284166] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"1.025071608s","start":"2026-05-14T07:41:58.842875Z","end":"2026-05-14T07:41:59.867946Z","steps":["trace[18284166] 'read index received' (duration: 1.024816861s)","trace[18284166] 'applied index is now lower than readState.Index' (duration: 253.647µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:41:59.86801Z","caller":"traceutil/trace.go:171","msg":"trace[381295289] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"1.051140698s","start":"2026-05-14T07:41:58.816838Z","end":"2026-05-14T07:41:59.867979Z","steps":["trace[381295289] 'process raft request' (duration: 1.050980954s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:59.868134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:58.81681Z","time spent":"1.051244261s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:41:59.868192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025304583s","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-05-14T07:41:59.868236Z","caller":"traceutil/trace.go:171","msg":"trace[1136580098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1090; }","duration":"1.025397936s","start":"2026-05-14T07:41:58.842825Z","end":"2026-05-14T07:41:59.868223Z","steps":["trace[1136580098] 'agreement among raft nodes before linearized reading' (duration: 1.025250222s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:59.86828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:58.842804Z","time spent":"1.025467148s","remote":"127.0.0.1:40994","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-05-14T07:41:59.868353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019327832s","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-05-14T07:41:59.868392Z","caller":"traceutil/trace.go:171","msg":"trace[1815959028] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1090; }","duration":"1.019403015s","start":"2026-05-14T07:41:58.848979Z","end":"2026-05-14T07:41:59.868382Z","steps":["trace[1815959028] 'agreement among raft nodes before linearized reading' (duration: 1.01928055s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:59.868421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:58.848962Z","time spent":"1.019452455s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T07:41:59.868503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.598783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:41:59.868527Z","caller":"traceutil/trace.go:171","msg":"trace[522746864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"520.656445ms","start":"2026-05-14T07:41:59.347864Z","end":"2026-05-14T07:41:59.86852Z","steps":["trace[522746864] 'agreement among raft nodes before linearized reading' (duration: 520.618184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:59.86855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:59.347845Z","time spent":"520.700636ms","remote":"127.0.0.1:40750","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-05-14T07:41:59.868758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017859476s","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-05-14T07:41:59.868861Z","caller":"traceutil/trace.go:171","msg":"trace[1259311336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1090; }","duration":"1.017966138s","start":"2026-05-14T07:41:58.850832Z","end":"2026-05-14T07:41:59.868798Z","steps":["trace[1259311336] 'agreement among raft nodes before linearized reading' (duration: 1.017821314s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:59.868949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:58.850812Z","time spent":"1.018085151s","remote":"127.0.0.1:40994","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-05-14T07:41:59.868883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005948012s","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-05-14T07:41:59.869701Z","caller":"traceutil/trace.go:171","msg":"trace[1276727185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1090; }","duration":"1.006809144s","start":"2026-05-14T07:41:58.86286Z","end":"2026-05-14T07:41:59.869669Z","steps":["trace[1276727185] 'agreement among raft nodes before linearized reading' (duration: 1.005755607s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:59.869806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:58.86284Z","time spent":"1.006934847s","remote":"127.0.0.1:40994","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-05-14T07:41:59.86917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00742589s","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-05-14T07:41:59.86995Z","caller":"traceutil/trace.go:171","msg":"trace[2090080898] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1090; }","duration":"1.008166819s","start":"2026-05-14T07:41:58.86171Z","end":"2026-05-14T07:41:59.869876Z","steps":["trace[2090080898] 'agreement among raft nodes before linearized reading' (duration: 1.006777264s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:41:59.870075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:58.861697Z","time spent":"1.008358363s","remote":"127.0.0.1:40994","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-05-14T07:42:00.376393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.189344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:42:00.376511Z","caller":"traceutil/trace.go:171","msg":"trace[1909594090] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"503.435609ms","start":"2026-05-14T07:41:59.873054Z","end":"2026-05-14T07:42:00.37649Z","steps":["trace[1909594090] 'process raft request' (duration: 63.07002ms)","trace[1909594090] 'compare' (duration: 440.044661ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:42:00.376572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:59.873034Z","time spent":"503.50948ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:42:00.665172Z","caller":"traceutil/trace.go:171","msg":"trace[166663226] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"791.712609ms","start":"2026-05-14T07:41:59.87343Z","end":"2026-05-14T07:42:00.665143Z","steps":["trace[166663226] 'process raft request' (duration: 791.453653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:00.66541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:59.873413Z","time spent":"791.844533ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:42:00.665459Z","caller":"traceutil/trace.go:171","msg":"trace[1268529649] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"791.157075ms","start":"2026-05-14T07:41:59.87428Z","end":"2026-05-14T07:42:00.665437Z","steps":["trace[1268529649] 'process raft request' (duration: 790.809876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:00.665585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:59.874264Z","time spent":"791.253157ms","remote":"127.0.0.1:40994","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-05-14T07:42:00.665902Z","caller":"traceutil/trace.go:171","msg":"trace[404881984] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"791.379071ms","start":"2026-05-14T07:41:59.874498Z","end":"2026-05-14T07:42:00.665877Z","steps":["trace[404881984] 'process raft request' (duration: 790.852858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:00.666042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:59.874442Z","time spent":"791.526734ms","remote":"127.0.0.1:40994","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-05-14T07:42:00.666405Z","caller":"traceutil/trace.go:171","msg":"trace[1296931515] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1130; }","duration":"749.243052ms","start":"2026-05-14T07:41:59.917151Z","end":"2026-05-14T07:42:00.666394Z","steps":["trace[1296931515] 'read index received' (duration: 18.973091ms)","trace[1296931515] 'applied index is now lower than readState.Index' (duration: 730.269081ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:42:00.666652Z","caller":"traceutil/trace.go:171","msg":"trace[229127378] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"792.058168ms","start":"2026-05-14T07:41:59.874579Z","end":"2026-05-14T07:42:00.666638Z","steps":["trace[229127378] 'process raft request' (duration: 791.169756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:00.666728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:59.874553Z","time spent":"792.139309ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:42:00.667008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.862648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:42:00.66705Z","caller":"traceutil/trace.go:171","msg":"trace[725942862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"749.94175ms","start":"2026-05-14T07:41:59.917098Z","end":"2026-05-14T07:42:00.66704Z","steps":["trace[725942862] 'agreement among raft nodes before linearized reading' (duration: 749.872198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:00.667084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:41:59.917077Z","time spent":"750.000472ms","remote":"127.0.0.1:40760","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-05-14T07:42:00.862594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.032792ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:42:00.862709Z","caller":"traceutil/trace.go:171","msg":"trace[595392528] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"177.646635ms","start":"2026-05-14T07:42:00.685043Z","end":"2026-05-14T07:42:00.862689Z","steps":["trace[595392528] 'process raft request' (duration: 63.441039ms)","trace[595392528] 'compare' (duration: 113.849847ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:42:02.417635Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699232184259667,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T07:42:02.691384Z","caller":"traceutil/trace.go:171","msg":"trace[684737950] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"817.307729ms","start":"2026-05-14T07:42:01.87405Z","end":"2026-05-14T07:42:02.691358Z","steps":["trace[684737950] 'process raft request' (duration: 817.111384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:02.691521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:01.87403Z","time spent":"817.421101ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:42:02.691558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.78494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:42:02.691633Z","caller":"traceutil/trace.go:171","msg":"trace[919137050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1097; }","duration":"774.899822ms","start":"2026-05-14T07:42:01.916715Z","end":"2026-05-14T07:42:02.691615Z","steps":["trace[919137050] 'agreement among raft nodes before linearized reading' (duration: 774.761719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:02.691666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:01.916703Z","time spent":"774.956554ms","remote":"127.0.0.1:40750","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-05-14T07:42:02.691855Z","caller":"traceutil/trace.go:171","msg":"trace[484123996] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"774.944834ms","start":"2026-05-14T07:42:01.916896Z","end":"2026-05-14T07:42:02.691841Z","steps":["trace[484123996] 'read index received' (duration: 774.263657ms)","trace[484123996] 'applied index is now lower than readState.Index' (duration: 170.194µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:42:02.692008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.923602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:42:02.69205Z","caller":"traceutil/trace.go:171","msg":"trace[1649113369] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1097; }","duration":"773.011434ms","start":"2026-05-14T07:42:01.919027Z","end":"2026-05-14T07:42:02.692039Z","steps":["trace[1649113369] 'agreement among raft nodes before linearized reading' (duration: 772.909811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:02.692082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:01.919006Z","time spent":"773.067716ms","remote":"127.0.0.1:33954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-14T07:42:02.692317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.080826ms","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-05-14T07:42:02.69235Z","caller":"traceutil/trace.go:171","msg":"trace[651547749] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1097; }","duration":"312.152797ms","start":"2026-05-14T07:42:02.380189Z","end":"2026-05-14T07:42:02.692342Z","steps":["trace[651547749] 'agreement among raft nodes before linearized reading' (duration: 312.082316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:02.69237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:02.380169Z","time spent":"312.196458ms","remote":"127.0.0.1:40994","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-05-14T07:42:02.692352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.604691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T07:42:02.692434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.991422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:42:02.692491Z","caller":"traceutil/trace.go:171","msg":"trace[871364567] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1097; }","duration":"472.080154ms","start":"2026-05-14T07:42:02.220402Z","end":"2026-05-14T07:42:02.692482Z","steps":["trace[871364567] 'agreement among raft nodes before linearized reading' (duration: 472.004102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:02.692526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:02.220388Z","time spent":"472.130305ms","remote":"127.0.0.1:41076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-05-14T07:42:02.692437Z","caller":"traceutil/trace.go:171","msg":"trace[1822026028] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1097; }","duration":"275.736584ms","start":"2026-05-14T07:42:02.416681Z","end":"2026-05-14T07:42:02.692417Z","steps":["trace[1822026028] 'agreement among raft nodes before linearized reading' (duration: 275.621091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:03.006962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.678207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:42:03.007102Z","caller":"traceutil/trace.go:171","msg":"trace[631571687] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"309.621253ms","start":"2026-05-14T07:42:02.697453Z","end":"2026-05-14T07:42:03.007075Z","steps":["trace[631571687] 'process raft request' (duration: 159.727071ms)","trace[631571687] 'compare' (duration: 149.544323ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:42:03.007198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:02.697434Z","time spent":"309.719685ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:42:03.949534Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.038646761s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T07:42:03.950073Z","caller":"traceutil/trace.go:171","msg":"trace[895486932] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"1.252544207s","start":"2026-05-14T07:42:02.697506Z","end":"2026-05-14T07:42:03.95005Z","steps":["trace[895486932] 'process raft request' (duration: 1.25230135s)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:03.950145Z","caller":"traceutil/trace.go:171","msg":"trace[1245846661] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"1.252572608s","start":"2026-05-14T07:42:02.697526Z","end":"2026-05-14T07:42:03.950099Z","steps":["trace[1245846661] 'process raft request' (duration: 1.252415233s)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:03.95026Z","caller":"traceutil/trace.go:171","msg":"trace[1705587708] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"1.252673001s","start":"2026-05-14T07:42:02.697576Z","end":"2026-05-14T07:42:03.950249Z","steps":["trace[1705587708] 'process raft request' (duration: 1.252443595s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:03.950342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:02.697549Z","time spent":"1.252748862s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:42:03.950222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:02.697492Z","time spent":"1.25265006s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:42:03.950431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:02.697506Z","time spent":"1.252795453s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:42:03.950071Z","caller":"traceutil/trace.go:171","msg":"trace[618692351] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"1.252502806s","start":"2026-05-14T07:42:02.697549Z","end":"2026-05-14T07:42:03.950051Z","steps":["trace[618692351] 'process raft request' (duration: 1.252430843s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:03.95055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:02.697539Z","time spent":"1.252980518s","remote":"127.0.0.1:40994","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-05-14T07:42:04.477488Z","caller":"traceutil/trace.go:171","msg":"trace[909644583] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"982.644072ms","start":"2026-05-14T07:42:03.494817Z","end":"2026-05-14T07:42:04.477461Z","steps":["trace[909644583] 'process raft request' (duration: 960.87768ms)","trace[909644583] 'compare' (duration: 21.459154ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:42:04.477468Z","caller":"traceutil/trace.go:171","msg":"trace[983514364] linearizableReadLoop","detail":"{readStateIndex:1144; appliedIndex:1143; }","duration":"560.353831ms","start":"2026-05-14T07:42:03.917091Z","end":"2026-05-14T07:42:04.477444Z","steps":["trace[983514364] 'read index received' (duration: 33.468599ms)","trace[983514364] 'applied index is now lower than readState.Index' (duration: 526.884072ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:42:04.477646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:03.494784Z","time spent":"982.784506ms","remote":"127.0.0.1:40994","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-05-14T07:42:04.477695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.595097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T07:42:04.477686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.684949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:42:04.477772Z","caller":"traceutil/trace.go:171","msg":"trace[1863494863] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1103; }","duration":"241.817812ms","start":"2026-05-14T07:42:04.235937Z","end":"2026-05-14T07:42:04.477755Z","steps":["trace[1863494863] 'agreement among raft nodes before linearized reading' (duration: 241.696339ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:04.47777Z","caller":"traceutil/trace.go:171","msg":"trace[185538908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"560.71414ms","start":"2026-05-14T07:42:03.917034Z","end":"2026-05-14T07:42:04.477748Z","steps":["trace[185538908] 'agreement among raft nodes before linearized reading' (duration: 560.584707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:04.477755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.674008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T07:42:04.477826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:03.917002Z","time spent":"560.813543ms","remote":"127.0.0.1:40750","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-05-14T07:42:04.477851Z","caller":"traceutil/trace.go:171","msg":"trace[891035520] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1103; }","duration":"194.808871ms","start":"2026-05-14T07:42:04.283017Z","end":"2026-05-14T07:42:04.477825Z","steps":["trace[891035520] 'agreement among raft nodes before linearized reading' (duration: 194.623266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:06.28812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.007128ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-14T07:42:06.288665Z","caller":"traceutil/trace.go:171","msg":"trace[1119864484] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1146; }","duration":"372.442166ms","start":"2026-05-14T07:42:05.916203Z","end":"2026-05-14T07:42:06.288645Z","steps":["trace[1119864484] 'read index received' (duration: 155.872984ms)","trace[1119864484] 'applied index is now lower than readState.Index' (duration: 216.567032ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:42:06.28885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.62511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:42:06.288926Z","caller":"traceutil/trace.go:171","msg":"trace[331430988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1105; }","duration":"372.745233ms","start":"2026-05-14T07:42:05.916162Z","end":"2026-05-14T07:42:06.288907Z","steps":["trace[331430988] 'agreement among raft nodes before linearized reading' (duration: 372.60589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:06.289008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:05.916147Z","time spent":"372.815105ms","remote":"127.0.0.1:40750","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-05-14T07:42:06.289026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.282178ms","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-05-14T07:42:06.289096Z","caller":"traceutil/trace.go:171","msg":"trace[486916978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1105; }","duration":"334.37857ms","start":"2026-05-14T07:42:05.954698Z","end":"2026-05-14T07:42:06.289077Z","steps":["trace[486916978] 'agreement among raft nodes before linearized reading' (duration: 334.134564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:06.289149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:05.954691Z","time spent":"334.447932ms","remote":"127.0.0.1:40994","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-05-14T07:42:06.289108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.416181ms","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-05-14T07:42:06.289243Z","caller":"traceutil/trace.go:171","msg":"trace[2007757449] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1105; }","duration":"334.671068ms","start":"2026-05-14T07:42:05.954558Z","end":"2026-05-14T07:42:06.289229Z","steps":["trace[2007757449] 'agreement among raft nodes before linearized reading' (duration: 334.242217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:06.289326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:05.954539Z","time spent":"334.772611ms","remote":"127.0.0.1:40994","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-05-14T07:42:06.289169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.349039ms","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-05-14T07:42:06.290185Z","caller":"traceutil/trace.go:171","msg":"trace[1395932039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1105; }","duration":"335.420577ms","start":"2026-05-14T07:42:05.954746Z","end":"2026-05-14T07:42:06.290167Z","steps":["trace[1395932039] 'agreement among raft nodes before linearized reading' (duration: 334.064112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:06.290236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:05.954724Z","time spent":"335.496689ms","remote":"127.0.0.1:40994","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-05-14T07:42:06.289272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.504163ms","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-05-14T07:42:06.290401Z","caller":"traceutil/trace.go:171","msg":"trace[1822534129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1105; }","duration":"335.778475ms","start":"2026-05-14T07:42:05.954611Z","end":"2026-05-14T07:42:06.290389Z","steps":["trace[1822534129] 'agreement among raft nodes before linearized reading' (duration: 334.232266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:06.290467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:05.954552Z","time spent":"335.876688ms","remote":"127.0.0.1:40994","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-05-14T07:42:06.289743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.599643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:42:06.290577Z","caller":"traceutil/trace.go:171","msg":"trace[1333363303] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1105; }","duration":"106.05485ms","start":"2026-05-14T07:42:06.184508Z","end":"2026-05-14T07:42:06.290563Z","steps":["trace[1333363303] 'agreement among raft nodes before linearized reading' (duration: 104.422198ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:06.547888Z","caller":"traceutil/trace.go:171","msg":"trace[2088613922] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"253.164292ms","start":"2026-05-14T07:42:06.294694Z","end":"2026-05-14T07:42:06.547859Z","steps":["trace[2088613922] 'process raft request' (duration: 233.970295ms)","trace[2088613922] 'compare' (duration: 19.007992ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:42:06.547908Z","caller":"traceutil/trace.go:171","msg":"trace[870603822] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"253.080388ms","start":"2026-05-14T07:42:06.294803Z","end":"2026-05-14T07:42:06.547884Z","steps":["trace[870603822] 'process raft request' (duration: 253.006246ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:06.548085Z","caller":"traceutil/trace.go:171","msg":"trace[82821366] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"253.264573ms","start":"2026-05-14T07:42:06.294811Z","end":"2026-05-14T07:42:06.548075Z","steps":["trace[82821366] 'process raft request' (duration: 253.037957ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:06.547998Z","caller":"traceutil/trace.go:171","msg":"trace[456779446] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1109; }","duration":"100.011997ms","start":"2026-05-14T07:42:06.447951Z","end":"2026-05-14T07:42:06.547963Z","steps":["trace[456779446] 'agreement among raft nodes before linearized reading' (duration: 99.975406ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:06.913075Z","caller":"traceutil/trace.go:171","msg":"trace[756840248] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"185.257788ms","start":"2026-05-14T07:42:06.727796Z","end":"2026-05-14T07:42:06.913053Z","steps":["trace[756840248] 'process raft request' (duration: 185.133695ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:07.247699Z","caller":"traceutil/trace.go:171","msg":"trace[767401158] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"185.972957ms","start":"2026-05-14T07:42:07.0617Z","end":"2026-05-14T07:42:07.247673Z","steps":["trace[767401158] 'read index received' (duration: 185.717181ms)","trace[767401158] 'applied index is now lower than readState.Index' (duration: 254.586µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:42:07.247788Z","caller":"traceutil/trace.go:171","msg":"trace[1880628956] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"337.352046ms","start":"2026-05-14T07:42:06.910397Z","end":"2026-05-14T07:42:07.247749Z","steps":["trace[1880628956] 'process raft request' (duration: 337.136121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:07.247978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.255654ms","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-05-14T07:42:07.248078Z","caller":"traceutil/trace.go:171","msg":"trace[1584281843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1111; }","duration":"186.397618ms","start":"2026-05-14T07:42:07.061668Z","end":"2026-05-14T07:42:07.248066Z","steps":["trace[1584281843] 'agreement among raft nodes before linearized reading' (duration: 186.100961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:07.248182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:06.910367Z","time spent":"337.524361ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:42:07.473648Z","caller":"traceutil/trace.go:171","msg":"trace[949234510] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"220.247565ms","start":"2026-05-14T07:42:07.253384Z","end":"2026-05-14T07:42:07.473632Z","steps":["trace[949234510] 'process raft request' (duration: 178.412184ms)","trace[949234510] 'compare' (duration: 41.180515ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:42:07.473808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.121401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:42:07.473876Z","caller":"traceutil/trace.go:171","msg":"trace[225330616] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1112; }","duration":"149.264485ms","start":"2026-05-14T07:42:07.324598Z","end":"2026-05-14T07:42:07.473863Z","steps":["trace[225330616] 'agreement among raft nodes before linearized reading' (duration: 149.094581ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:07.473487Z","caller":"traceutil/trace.go:171","msg":"trace[754832858] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1153; }","duration":"148.845335ms","start":"2026-05-14T07:42:07.324617Z","end":"2026-05-14T07:42:07.473462Z","steps":["trace[754832858] 'read index received' (duration: 107.152268ms)","trace[754832858] 'applied index is now lower than readState.Index' (duration: 41.691837ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:42:07.474408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.889061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:42:07.474594Z","caller":"traceutil/trace.go:171","msg":"trace[561782518] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1112; }","duration":"107.034755ms","start":"2026-05-14T07:42:07.367468Z","end":"2026-05-14T07:42:07.474503Z","steps":["trace[561782518] 'agreement among raft nodes before linearized reading' (duration: 106.902112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:18.417714Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699232184259880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T07:42:18.918596Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699232184259880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T07:42:18.978312Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.455841933s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T07:42:18.978786Z","caller":"traceutil/trace.go:171","msg":"trace[2031931529] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"1.456372216s","start":"2026-05-14T07:42:17.522386Z","end":"2026-05-14T07:42:18.978758Z","steps":["trace[2031931529] 'process raft request' (duration: 1.456186891s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:18.978928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:17.522375Z","time spent":"1.456479229s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:42:19.917367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00024988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-14T07:42:19.917485Z","caller":"traceutil/trace.go:171","msg":"trace[1319034568] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000421124s","start":"2026-05-14T07:42:17.91704Z","end":"2026-05-14T07:42:19.917461Z","steps":["trace[1319034568] 'agreement among raft nodes before linearized reading' (duration: 2.000283071s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:19.917539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:17.917019Z","time spent":"2.000507637s","remote":"127.0.0.1:40750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/14 07:42:19 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-14T07:42:20.583773Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.532880546s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-14T07:42:20.584795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.533862142s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:42:20.584945Z","caller":"traceutil/trace.go:171","msg":"trace[1186629030] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"1.545514597s","start":"2026-05-14T07:42:19.039418Z","end":"2026-05-14T07:42:20.584933Z","steps":["trace[1186629030] 'process raft request' (duration: 1.545454656s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:20.584986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:19.039395Z","time spent":"1.545573989s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:42:20.585101Z","caller":"traceutil/trace.go:171","msg":"trace[994038980] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"3.005645228s","start":"2026-05-14T07:42:17.579449Z","end":"2026-05-14T07:42:20.585095Z","steps":["trace[994038980] 'process raft request' (duration: 1.471420387s)","trace[994038980] 'compare' (duration: 1.533693027s)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:42:20.585125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:17.57943Z","time spent":"3.00568257s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:42:20.585189Z","caller":"traceutil/trace.go:171","msg":"trace[194837599] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1240; }","duration":"2.668092078s","start":"2026-05-14T07:42:17.917092Z","end":"2026-05-14T07:42:20.585184Z","steps":["trace[194837599] 'read index received' (duration: 1.06143596s)","trace[194837599] 'applied index is now lower than readState.Index' (duration: 1.606655568s)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:42:20.585388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.214661668s","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-05-14T07:42:20.585411Z","caller":"traceutil/trace.go:171","msg":"trace[1850174093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1197; }","duration":"2.2147035s","start":"2026-05-14T07:42:18.370694Z","end":"2026-05-14T07:42:20.585398Z","steps":["trace[1850174093] 'agreement among raft nodes before linearized reading' (duration: 2.214637588s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:20.585425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:18.370679Z","time spent":"2.214743s","remote":"127.0.0.1:40994","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-05-14T07:42:20.585512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.602221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:42:20.585536Z","caller":"traceutil/trace.go:171","msg":"trace[1709770175] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1197; }","duration":"570.668504ms","start":"2026-05-14T07:42:20.014862Z","end":"2026-05-14T07:42:20.58553Z","steps":["trace[1709770175] 'agreement among raft nodes before linearized reading' (duration: 570.637113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:20.585552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:20.014842Z","time spent":"570.706044ms","remote":"127.0.0.1:33868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-14T07:42:20.58567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.830478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:42:20.585684Z","caller":"traceutil/trace.go:171","msg":"trace[1802342371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1197; }","duration":"667.924841ms","start":"2026-05-14T07:42:19.917755Z","end":"2026-05-14T07:42:20.58568Z","steps":["trace[1802342371] 'agreement among raft nodes before linearized reading' (duration: 667.853669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:20.585696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:19.917739Z","time spent":"667.953711ms","remote":"127.0.0.1:40760","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-05-14T07:42:20.585795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.981451663s","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-05-14T07:42:20.585815Z","caller":"traceutil/trace.go:171","msg":"trace[305442739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1197; }","duration":"1.981490724s","start":"2026-05-14T07:42:18.604312Z","end":"2026-05-14T07:42:20.585803Z","steps":["trace[305442739] 'agreement among raft nodes before linearized reading' (duration: 1.981448533s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:20.585827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:18.604301Z","time spent":"1.981522764s","remote":"127.0.0.1:40994","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-05-14T07:42:20.585916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.987354553s","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-05-14T07:42:20.585929Z","caller":"traceutil/trace.go:171","msg":"trace[142016808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1197; }","duration":"1.987440514s","start":"2026-05-14T07:42:18.598484Z","end":"2026-05-14T07:42:20.585925Z","steps":["trace[142016808] 'agreement among raft nodes before linearized reading' (duration: 1.987391683s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:20.585941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:18.598432Z","time spent":"1.987505966s","remote":"127.0.0.1:40994","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-05-14T07:42:20.586227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.988501192s","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-05-14T07:42:20.58629Z","caller":"traceutil/trace.go:171","msg":"trace[618724548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1197; }","duration":"1.988619655s","start":"2026-05-14T07:42:18.597654Z","end":"2026-05-14T07:42:20.586274Z","steps":["trace[618724548] 'agreement among raft nodes before linearized reading' (duration: 1.9884254s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:20.586318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:18.597615Z","time spent":"1.988696997s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T07:42:32.889081Z","caller":"traceutil/trace.go:171","msg":"trace[25373537] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"169.955101ms","start":"2026-05-14T07:42:32.719097Z","end":"2026-05-14T07:42:32.889052Z","steps":["trace[25373537] 'process raft request' (duration: 169.842328ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:32.933947Z","caller":"traceutil/trace.go:171","msg":"trace[265919466] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"214.087841ms","start":"2026-05-14T07:42:32.719843Z","end":"2026-05-14T07:42:32.933931Z","steps":["trace[265919466] 'process raft request' (duration: 214.04715ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:32.93403Z","caller":"traceutil/trace.go:171","msg":"trace[1881294233] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"214.236904ms","start":"2026-05-14T07:42:32.719759Z","end":"2026-05-14T07:42:32.933996Z","steps":["trace[1881294233] 'process raft request' (duration: 214.030169ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:32.934551Z","caller":"traceutil/trace.go:171","msg":"trace[1441056] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"214.666535ms","start":"2026-05-14T07:42:32.719865Z","end":"2026-05-14T07:42:32.934531Z","steps":["trace[1441056] 'process raft request' (duration: 214.047789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:33.172948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.323559ms","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-05-14T07:42:33.173035Z","caller":"traceutil/trace.go:171","msg":"trace[97682295] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1242; }","duration":"129.431512ms","start":"2026-05-14T07:42:33.043585Z","end":"2026-05-14T07:42:33.173016Z","steps":["trace[97682295] 'range keys from in-memory index tree' (duration: 129.132564ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:35.121839Z","caller":"traceutil/trace.go:171","msg":"trace[1363998273] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"153.257187ms","start":"2026-05-14T07:42:34.968554Z","end":"2026-05-14T07:42:35.121811Z","steps":["trace[1363998273] 'process raft request' (duration: 100.285984ms)","trace[1363998273] 'compare' (duration: 52.732207ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:42:35.12211Z","caller":"traceutil/trace.go:171","msg":"trace[1104658766] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"153.451572ms","start":"2026-05-14T07:42:34.968648Z","end":"2026-05-14T07:42:35.122099Z","steps":["trace[1104658766] 'process raft request' (duration: 153.114854ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:35.122109Z","caller":"traceutil/trace.go:171","msg":"trace[1131927342] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"152.630331ms","start":"2026-05-14T07:42:34.969458Z","end":"2026-05-14T07:42:35.122088Z","steps":["trace[1131927342] 'process raft request' (duration: 152.543599ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:35.531935Z","caller":"traceutil/trace.go:171","msg":"trace[881311498] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1297; }","duration":"268.062068ms","start":"2026-05-14T07:42:35.263845Z","end":"2026-05-14T07:42:35.531907Z","steps":["trace[881311498] 'read index received' (duration: 266.530339ms)","trace[881311498] 'applied index is now lower than readState.Index' (duration: 1.530619ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:42:35.531952Z","caller":"traceutil/trace.go:171","msg":"trace[1979855810] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"334.646096ms","start":"2026-05-14T07:42:35.197285Z","end":"2026-05-14T07:42:35.531931Z","steps":["trace[1979855810] 'process raft request' (duration: 333.072877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:35.532102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:35.197262Z","time spent":"334.75686ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:42:35.532189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.311334ms","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-05-14T07:42:35.532466Z","caller":"traceutil/trace.go:171","msg":"trace[1470796546] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1249; }","duration":"268.616211ms","start":"2026-05-14T07:42:35.26383Z","end":"2026-05-14T07:42:35.532447Z","steps":["trace[1470796546] 'agreement among raft nodes before linearized reading' (duration: 268.15656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:37.417542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699232184260015,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T07:42:37.71243Z","caller":"traceutil/trace.go:171","msg":"trace[370572573] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"985.955735ms","start":"2026-05-14T07:42:36.726425Z","end":"2026-05-14T07:42:37.71238Z","steps":["trace[370572573] 'process raft request' (duration: 985.78025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:37.712649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:36.726402Z","time spent":"986.11788ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:42:37.884719Z","caller":"traceutil/trace.go:171","msg":"trace[199228331] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1300; }","duration":"967.754524ms","start":"2026-05-14T07:42:36.916945Z","end":"2026-05-14T07:42:37.884699Z","steps":["trace[199228331] 'read index received' (duration: 795.231198ms)","trace[199228331] 'applied index is now lower than readState.Index' (duration: 172.522536ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:42:37.884814Z","caller":"traceutil/trace.go:171","msg":"trace[383189969] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"765.249357ms","start":"2026-05-14T07:42:37.119556Z","end":"2026-05-14T07:42:37.884805Z","steps":["trace[383189969] 'process raft request' (duration: 667.212691ms)","trace[383189969] 'compare' (duration: 97.824711ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:42:37.88489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:37.119534Z","time spent":"765.298289ms","remote":"127.0.0.1:40824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":872,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-14T07:42:37.884976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.811117ms","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-05-14T07:42:37.885008Z","caller":"traceutil/trace.go:171","msg":"trace[801928591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1252; }","duration":"848.885629ms","start":"2026-05-14T07:42:37.036115Z","end":"2026-05-14T07:42:37.885Z","steps":["trace[801928591] 'agreement among raft nodes before linearized reading' (duration: 848.738406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:37.885029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:37.036097Z","time spent":"848.92719ms","remote":"127.0.0.1:40994","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-05-14T07:42:37.885091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.838835ms","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-05-14T07:42:37.885135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.194974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:42:37.885141Z","caller":"traceutil/trace.go:171","msg":"trace[2010104433] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1252; }","duration":"758.902926ms","start":"2026-05-14T07:42:37.126225Z","end":"2026-05-14T07:42:37.885128Z","steps":["trace[2010104433] 'agreement among raft nodes before linearized reading' (duration: 758.746673ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:37.885157Z","caller":"traceutil/trace.go:171","msg":"trace[305815828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1252; }","duration":"968.244776ms","start":"2026-05-14T07:42:36.916905Z","end":"2026-05-14T07:42:37.88515Z","steps":["trace[305815828] 'agreement among raft nodes before linearized reading' (duration: 968.210405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:37.885178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:36.916888Z","time spent":"968.285077ms","remote":"127.0.0.1:40750","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-05-14T07:42:37.885177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:37.126218Z","time spent":"758.950308ms","remote":"127.0.0.1:40994","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-05-14T07:42:37.885277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.782697ms","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-05-14T07:42:37.885299Z","caller":"traceutil/trace.go:171","msg":"trace[1236286225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1252; }","duration":"339.850289ms","start":"2026-05-14T07:42:37.545442Z","end":"2026-05-14T07:42:37.885293Z","steps":["trace[1236286225] 'agreement among raft nodes before linearized reading' (duration: 339.786297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:37.885283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.601935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-14T07:42:37.885319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:37.545419Z","time spent":"339.89607ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-14T07:42:37.88535Z","caller":"traceutil/trace.go:171","msg":"trace[569498702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1252; }","duration":"759.702926ms","start":"2026-05-14T07:42:37.125626Z","end":"2026-05-14T07:42:37.885329Z","steps":["trace[569498702] 'agreement among raft nodes before linearized reading' (duration: 759.507962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:37.885395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.668926ms","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-05-14T07:42:37.885398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:37.125617Z","time spent":"759.772319ms","remote":"127.0.0.1:40994","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":"info","ts":"2026-05-14T07:42:37.885411Z","caller":"traceutil/trace.go:171","msg":"trace[1321457667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1252; }","duration":"759.694406ms","start":"2026-05-14T07:42:37.125711Z","end":"2026-05-14T07:42:37.885405Z","steps":["trace[1321457667] 'agreement among raft nodes before linearized reading' (duration: 759.649435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:37.885427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:37.125707Z","time spent":"759.716717ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-14T07:42:38.082278Z","caller":"traceutil/trace.go:171","msg":"trace[485725457] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"191.858247ms","start":"2026-05-14T07:42:37.890401Z","end":"2026-05-14T07:42:38.082259Z","steps":["trace[485725457] 'process raft request' (duration: 191.830696ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:38.082397Z","caller":"traceutil/trace.go:171","msg":"trace[910522172] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"193.450237ms","start":"2026-05-14T07:42:37.88891Z","end":"2026-05-14T07:42:38.08236Z","steps":["trace[910522172] 'process raft request' (duration: 193.165469ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:38.082575Z","caller":"traceutil/trace.go:171","msg":"trace[571918034] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"192.828971ms","start":"2026-05-14T07:42:37.889709Z","end":"2026-05-14T07:42:38.082538Z","steps":["trace[571918034] 'process raft request' (duration: 192.498723ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:38.082614Z","caller":"traceutil/trace.go:171","msg":"trace[610865205] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"193.418766ms","start":"2026-05-14T07:42:37.889185Z","end":"2026-05-14T07:42:38.082604Z","steps":["trace[610865205] 'process raft request' (duration: 192.990855ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:39.858426Z","caller":"traceutil/trace.go:171","msg":"trace[206827782] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"140.548766ms","start":"2026-05-14T07:42:39.717853Z","end":"2026-05-14T07:42:39.858401Z","steps":["trace[206827782] 'process raft request' (duration: 140.405482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:40.509015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.327791ms","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-05-14T07:42:40.509107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.087175ms","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-05-14T07:42:40.509017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.055207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T07:42:40.50916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.624885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:42:40.509166Z","caller":"traceutil/trace.go:171","msg":"trace[1072582297] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1258; }","duration":"197.259314ms","start":"2026-05-14T07:42:40.311894Z","end":"2026-05-14T07:42:40.509154Z","steps":["trace[1072582297] 'count revisions from in-memory index tree' (duration: 196.983706ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:40.509176Z","caller":"traceutil/trace.go:171","msg":"trace[512120639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1258; }","duration":"422.168147ms","start":"2026-05-14T07:42:40.086986Z","end":"2026-05-14T07:42:40.509154Z","steps":["trace[512120639] 'range keys from in-memory index tree' (duration: 421.922801ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:40.509199Z","caller":"traceutil/trace.go:171","msg":"trace[1183204291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1258; }","duration":"591.707146ms","start":"2026-05-14T07:42:39.917477Z","end":"2026-05-14T07:42:40.509184Z","steps":["trace[1183204291] 'range keys from in-memory index tree' (duration: 591.518911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:40.509227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:40.08694Z","time spent":"422.27693ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T07:42:40.509233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:39.917459Z","time spent":"591.765177ms","remote":"127.0.0.1:40750","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-05-14T07:42:40.509433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.859903ms","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-05-14T07:42:40.50946Z","caller":"traceutil/trace.go:171","msg":"trace[646131527] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1258; }","duration":"571.906474ms","start":"2026-05-14T07:42:39.937544Z","end":"2026-05-14T07:42:40.509451Z","steps":["trace[646131527] 'range keys from in-memory index tree' (duration: 571.7291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:40.50949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:39.937533Z","time spent":"571.949385ms","remote":"127.0.0.1:40994","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-05-14T07:42:40.509099Z","caller":"traceutil/trace.go:171","msg":"trace[312761971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1258; }","duration":"422.468205ms","start":"2026-05-14T07:42:40.086613Z","end":"2026-05-14T07:42:40.509081Z","steps":["trace[312761971] 'range keys from in-memory index tree' (duration: 422.127226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:40.509532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.943677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-14T07:42:40.509564Z","caller":"traceutil/trace.go:171","msg":"trace[995795593] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1258; }","duration":"423.005338ms","start":"2026-05-14T07:42:40.08655Z","end":"2026-05-14T07:42:40.509555Z","steps":["trace[995795593] 'range keys from in-memory index tree' (duration: 422.849014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:40.509567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:40.086592Z","time spent":"422.963167ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-14T07:42:40.509594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:40.086533Z","time spent":"423.051119ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T07:42:40.509718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.010239ms","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-05-14T07:42:40.509742Z","caller":"traceutil/trace.go:171","msg":"trace[1467596650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1258; }","duration":"423.032899ms","start":"2026-05-14T07:42:40.086702Z","end":"2026-05-14T07:42:40.509734Z","steps":["trace[1467596650] 'range keys from in-memory index tree' (duration: 422.901125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:40.509766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:40.086662Z","time spent":"423.097871ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-14T07:42:40.628233Z","caller":"traceutil/trace.go:171","msg":"trace[1860828873] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"114.060242ms","start":"2026-05-14T07:42:40.514153Z","end":"2026-05-14T07:42:40.628213Z","steps":["trace[1860828873] 'process raft request' (duration: 98.289282ms)","trace[1860828873] 'compare' (duration: 15.649387ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:42:40.628544Z","caller":"traceutil/trace.go:171","msg":"trace[156699538] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"114.284239ms","start":"2026-05-14T07:42:40.514224Z","end":"2026-05-14T07:42:40.628509Z","steps":["trace[156699538] 'process raft request' (duration: 113.96202ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:40.628644Z","caller":"traceutil/trace.go:171","msg":"trace[300762629] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"114.208947ms","start":"2026-05-14T07:42:40.514407Z","end":"2026-05-14T07:42:40.628616Z","steps":["trace[300762629] 'process raft request' (duration: 114.023511ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:40.628743Z","caller":"traceutil/trace.go:171","msg":"trace[717092926] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"114.150856ms","start":"2026-05-14T07:42:40.514577Z","end":"2026-05-14T07:42:40.628728Z","steps":["trace[717092926] 'process raft request' (duration: 113.945371ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:40.80625Z","caller":"traceutil/trace.go:171","msg":"trace[1101305463] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"119.549873ms","start":"2026-05-14T07:42:40.686667Z","end":"2026-05-14T07:42:40.806217Z","steps":["trace[1101305463] 'process raft request' (duration: 75.743091ms)","trace[1101305463] 'compare' (duration: 43.484694ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:42:41.878579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.88893ms","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-05-14T07:42:41.878696Z","caller":"traceutil/trace.go:171","msg":"trace[1641598909] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1268; }","duration":"142.016913ms","start":"2026-05-14T07:42:41.736664Z","end":"2026-05-14T07:42:41.878681Z","steps":["trace[1641598909] 'range keys from in-memory index tree' (duration: 141.875958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:41.878822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.302475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:42:41.878895Z","caller":"traceutil/trace.go:171","msg":"trace[343284301] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1319; }","duration":"505.522602ms","start":"2026-05-14T07:42:41.373359Z","end":"2026-05-14T07:42:41.878882Z","steps":["trace[343284301] 'read index received' (duration: 308.017082ms)","trace[343284301] 'applied index is now lower than readState.Index' (duration: 197.50437ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:42:41.878977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.621394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:42:41.878996Z","caller":"traceutil/trace.go:171","msg":"trace[1304274194] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1269; }","duration":"505.684285ms","start":"2026-05-14T07:42:41.373305Z","end":"2026-05-14T07:42:41.878989Z","steps":["trace[1304274194] 'agreement among raft nodes before linearized reading' (duration: 505.618114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:41.879019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:41.373283Z","time spent":"505.730187ms","remote":"127.0.0.1:34054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-14T07:42:41.879176Z","caller":"traceutil/trace.go:171","msg":"trace[689451677] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"587.336886ms","start":"2026-05-14T07:42:41.2918Z","end":"2026-05-14T07:42:41.879137Z","steps":["trace[689451677] 'process raft request' (duration: 389.637521ms)","trace[689451677] 'compare' (duration: 197.09918ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:42:41.879385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:41.291771Z","time spent":"587.505991ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:42:42.106696Z","caller":"traceutil/trace.go:171","msg":"trace[1651274482] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"189.623629ms","start":"2026-05-14T07:42:41.917045Z","end":"2026-05-14T07:42:42.106669Z","steps":["trace[1651274482] 'read index received' (duration: 153.935814ms)","trace[1651274482] 'applied index is now lower than readState.Index' (duration: 35.686365ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:42:42.106835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.777893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:42:42.106868Z","caller":"traceutil/trace.go:171","msg":"trace[1777687058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"189.864355ms","start":"2026-05-14T07:42:41.916994Z","end":"2026-05-14T07:42:42.106858Z","steps":["trace[1777687058] 'agreement among raft nodes before linearized reading' (duration: 189.764353ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:42.107183Z","caller":"traceutil/trace.go:171","msg":"trace[698398505] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"243.403254ms","start":"2026-05-14T07:42:41.863755Z","end":"2026-05-14T07:42:42.107158Z","steps":["trace[698398505] 'process raft request' (duration: 207.250727ms)","trace[698398505] 'compare' (duration: 35.424188ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:42:46.286934Z","caller":"traceutil/trace.go:171","msg":"trace[439841405] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"167.311653ms","start":"2026-05-14T07:42:46.119598Z","end":"2026-05-14T07:42:46.28691Z","steps":["trace[439841405] 'process raft request' (duration: 167.126818ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:50.564399Z","caller":"traceutil/trace.go:171","msg":"trace[1576809773] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1402; }","duration":"228.420112ms","start":"2026-05-14T07:42:50.33595Z","end":"2026-05-14T07:42:50.56437Z","steps":["trace[1576809773] 'read index received' (duration: 228.279049ms)","trace[1576809773] 'applied index is now lower than readState.Index' (duration: 139.993µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:42:50.564515Z","caller":"traceutil/trace.go:171","msg":"trace[630085853] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"264.57489ms","start":"2026-05-14T07:42:50.299908Z","end":"2026-05-14T07:42:50.564483Z","steps":["trace[630085853] 'process raft request' (duration: 264.259162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:50.56468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.70804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-14T07:42:50.564719Z","caller":"traceutil/trace.go:171","msg":"trace[1779981004] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1351; }","duration":"228.802112ms","start":"2026-05-14T07:42:50.335905Z","end":"2026-05-14T07:42:50.564707Z","steps":["trace[1779981004] 'agreement among raft nodes before linearized reading' (duration: 228.564926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:51.805923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174217981s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T07:42:51.806071Z","caller":"traceutil/trace.go:171","msg":"trace[713714876] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1403; }","duration":"1.211740092s","start":"2026-05-14T07:42:50.594307Z","end":"2026-05-14T07:42:51.806047Z","steps":["trace[713714876] 'read index received' (duration: 37.041519ms)","trace[713714876] 'applied index is now lower than readState.Index' (duration: 1.174696353s)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:42:51.806225Z","caller":"traceutil/trace.go:171","msg":"trace[660687545] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"1.236585633s","start":"2026-05-14T07:42:50.56962Z","end":"2026-05-14T07:42:51.806206Z","steps":["trace[660687545] 'process raft request' (duration: 61.87247ms)","trace[660687545] 'compare' (duration: 1.17381252s)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:42:51.80631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:50.56959Z","time spent":"1.236680075s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:42:51.8066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166533266s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-14T07:42:51.806696Z","caller":"traceutil/trace.go:171","msg":"trace[1742964191] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1352; }","duration":"1.166705961s","start":"2026-05-14T07:42:50.639967Z","end":"2026-05-14T07:42:51.806673Z","steps":["trace[1742964191] 'agreement among raft nodes before linearized reading' (duration: 1.166432904s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:51.806747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:50.63994Z","time spent":"1.166797443s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-14T07:42:51.806881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086416504s","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-05-14T07:42:51.806946Z","caller":"traceutil/trace.go:171","msg":"trace[696055862] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1352; }","duration":"1.086533066s","start":"2026-05-14T07:42:50.720396Z","end":"2026-05-14T07:42:51.806929Z","steps":["trace[696055862] 'agreement among raft nodes before linearized reading' (duration: 1.086392573s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:51.807029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:50.720388Z","time spent":"1.086628399s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T07:42:51.807035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087435559s","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-05-14T07:42:51.807067Z","caller":"traceutil/trace.go:171","msg":"trace[373561817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1352; }","duration":"1.087491271s","start":"2026-05-14T07:42:50.719567Z","end":"2026-05-14T07:42:51.807058Z","steps":["trace[373561817] 'agreement among raft nodes before linearized reading' (duration: 1.087367768s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:51.807101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:50.719549Z","time spent":"1.087545852s","remote":"127.0.0.1:40994","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-05-14T07:42:51.807213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087985133s","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-05-14T07:42:51.807243Z","caller":"traceutil/trace.go:171","msg":"trace[345797013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1352; }","duration":"1.088060935s","start":"2026-05-14T07:42:50.719174Z","end":"2026-05-14T07:42:51.807235Z","steps":["trace[345797013] 'agreement among raft nodes before linearized reading' (duration: 1.087987723s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:51.807262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212961354s","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-05-14T07:42:51.807272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:50.719143Z","time spent":"1.088124227s","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T07:42:51.807291Z","caller":"traceutil/trace.go:171","msg":"trace[456189328] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1352; }","duration":"1.213031195s","start":"2026-05-14T07:42:50.594251Z","end":"2026-05-14T07:42:51.807282Z","steps":["trace[456189328] 'agreement among raft nodes before linearized reading' (duration: 1.212946723s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:51.807319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:50.59423Z","time spent":"1.213081966s","remote":"127.0.0.1:40994","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-05-14T07:42:51.807445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13950776s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.240\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-14T07:42:51.807436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086934217s","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-05-14T07:42:51.807479Z","caller":"traceutil/trace.go:171","msg":"trace[1370117548] range","detail":"{range_begin:/registry/masterleases/199.204.45.240; range_end:; response_count:1; response_revision:1352; }","duration":"1.139593203s","start":"2026-05-14T07:42:50.667877Z","end":"2026-05-14T07:42:51.80747Z","steps":["trace[1370117548] 'agreement among raft nodes before linearized reading' (duration: 1.139504611s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:51.807477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.418203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:42:51.807491Z","caller":"traceutil/trace.go:171","msg":"trace[1679021831] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1352; }","duration":"1.087007748s","start":"2026-05-14T07:42:50.720471Z","end":"2026-05-14T07:42:51.807478Z","steps":["trace[1679021831] 'agreement among raft nodes before linearized reading' (duration: 1.086783823s)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:51.807512Z","caller":"traceutil/trace.go:171","msg":"trace[1603552992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1352; }","duration":"890.482735ms","start":"2026-05-14T07:42:50.917019Z","end":"2026-05-14T07:42:51.807502Z","steps":["trace[1603552992] 'agreement among raft nodes before linearized reading' (duration: 890.430203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:51.807505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:50.667857Z","time spent":"1.139642994s","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.240\" "} {"level":"warn","ts":"2026-05-14T07:42:51.80754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:50.916998Z","time spent":"890.537126ms","remote":"127.0.0.1:40750","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-05-14T07:42:51.807549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:50.720136Z","time spent":"1.087398269s","remote":"127.0.0.1:40994","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-05-14T07:42:52.224366Z","caller":"traceutil/trace.go:171","msg":"trace[788273274] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"411.572648ms","start":"2026-05-14T07:42:51.812759Z","end":"2026-05-14T07:42:52.224331Z","steps":["trace[788273274] 'process raft request' (duration: 364.756301ms)","trace[788273274] 'compare' (duration: 45.572805ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:42:52.224533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:51.812738Z","time spent":"411.711042ms","remote":"127.0.0.1:40994","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-05-14T07:42:52.224668Z","caller":"traceutil/trace.go:171","msg":"trace[894961793] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"411.407254ms","start":"2026-05-14T07:42:51.81324Z","end":"2026-05-14T07:42:52.224648Z","steps":["trace[894961793] 'process raft request' (duration: 411.089526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:52.224776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:51.813231Z","time spent":"411.493356ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:42:52.224406Z","caller":"traceutil/trace.go:171","msg":"trace[1261981661] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"411.444736ms","start":"2026-05-14T07:42:51.812935Z","end":"2026-05-14T07:42:52.22438Z","steps":["trace[1261981661] 'process raft request' (duration: 411.356433ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:52.225139Z","caller":"traceutil/trace.go:171","msg":"trace[1416549226] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"410.933742ms","start":"2026-05-14T07:42:51.81419Z","end":"2026-05-14T07:42:52.225123Z","steps":["trace[1416549226] 'process raft request' (duration: 410.385959ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:52.225358Z","caller":"traceutil/trace.go:171","msg":"trace[1703987520] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"411.060166ms","start":"2026-05-14T07:42:51.814272Z","end":"2026-05-14T07:42:52.225332Z","steps":["trace[1703987520] 'process raft request' (duration: 410.796339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:52.225851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:51.814168Z","time spent":"411.564399ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:42:52.225931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:51.814264Z","time spent":"411.595069ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:42:52.224829Z","caller":"traceutil/trace.go:171","msg":"trace[438094136] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"412.03451ms","start":"2026-05-14T07:42:51.812771Z","end":"2026-05-14T07:42:52.224806Z","steps":["trace[438094136] 'process raft request' (duration: 411.427025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:52.22606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:51.812752Z","time spent":"413.25574ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:42:52.226047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:51.812879Z","time spent":"412.753488ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T07:42:52.228572Z","caller":"traceutil/trace.go:171","msg":"trace[1736281590] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1405; }","duration":"310.001351ms","start":"2026-05-14T07:42:51.918559Z","end":"2026-05-14T07:42:52.22856Z","steps":["trace[1736281590] 'read index received' (duration: 258.858364ms)","trace[1736281590] 'applied index is now lower than readState.Index' (duration: 51.141987ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:42:52.228655Z","caller":"traceutil/trace.go:171","msg":"trace[1909808702] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"166.375229ms","start":"2026-05-14T07:42:52.062257Z","end":"2026-05-14T07:42:52.228632Z","steps":["trace[1909808702] 'process raft request' (duration: 166.275226ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:42:52.228787Z","caller":"traceutil/trace.go:171","msg":"trace[656741882] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"369.700546ms","start":"2026-05-14T07:42:51.859075Z","end":"2026-05-14T07:42:52.228776Z","steps":["trace[656741882] 'process raft request' (duration: 369.399719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:52.228862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:51.859044Z","time spent":"369.770937ms","remote":"127.0.0.1:40800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T07:42:52.229076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.413868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-14T07:42:52.229118Z","caller":"traceutil/trace.go:171","msg":"trace[1583698855] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1360; }","duration":"266.455069ms","start":"2026-05-14T07:42:51.962651Z","end":"2026-05-14T07:42:52.229106Z","steps":["trace[1583698855] 'agreement among raft nodes before linearized reading' (duration: 266.226962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:52.229539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.975967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:42:52.229601Z","caller":"traceutil/trace.go:171","msg":"trace[1669715498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1360; }","duration":"311.07646ms","start":"2026-05-14T07:42:51.91851Z","end":"2026-05-14T07:42:52.229586Z","steps":["trace[1669715498] 'agreement among raft nodes before linearized reading' (duration: 310.271019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:42:52.229644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:42:51.918489Z","time spent":"311.144231ms","remote":"127.0.0.1:40750","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-05-14T07:43:51.053046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.57683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:43:51.053147Z","caller":"traceutil/trace.go:171","msg":"trace[1170342011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1629; }","duration":"135.747174ms","start":"2026-05-14T07:43:50.917377Z","end":"2026-05-14T07:43:51.053124Z","steps":["trace[1170342011] 'range keys from in-memory index tree' (duration: 135.479867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:43:51.053102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.881811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:43:51.053354Z","caller":"traceutil/trace.go:171","msg":"trace[295908608] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1629; }","duration":"137.21807ms","start":"2026-05-14T07:43:50.916114Z","end":"2026-05-14T07:43:51.053332Z","steps":["trace[295908608] 'count revisions from in-memory index tree' (duration: 136.719477ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:44:03.615744Z","caller":"traceutil/trace.go:171","msg":"trace[1066776741] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"110.574642ms","start":"2026-05-14T07:44:03.505106Z","end":"2026-05-14T07:44:03.61568Z","steps":["trace[1066776741] 'process raft request' (duration: 110.416328ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:44:08.874786Z","caller":"traceutil/trace.go:171","msg":"trace[898428319] transaction","detail":"{read_only:false; response_revision:1707; number_of_response:1; }","duration":"167.596246ms","start":"2026-05-14T07:44:08.707161Z","end":"2026-05-14T07:44:08.874758Z","steps":["trace[898428319] 'process raft request' (duration: 167.414661ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:44:08.913922Z","caller":"traceutil/trace.go:171","msg":"trace[1374325444] linearizableReadLoop","detail":"{readStateIndex:1776; appliedIndex:1774; }","duration":"193.400521ms","start":"2026-05-14T07:44:08.720498Z","end":"2026-05-14T07:44:08.913899Z","steps":["trace[1374325444] 'read index received' (duration: 154.106558ms)","trace[1374325444] 'applied index is now lower than readState.Index' (duration: 39.293153ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:44:08.913998Z","caller":"traceutil/trace.go:171","msg":"trace[389480392] transaction","detail":"{read_only:false; response_revision:1708; number_of_response:1; }","duration":"204.757977ms","start":"2026-05-14T07:44:08.709217Z","end":"2026-05-14T07:44:08.913975Z","steps":["trace[389480392] 'process raft request' (duration: 204.578322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:44:08.914175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.659769ms","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-05-14T07:44:08.914239Z","caller":"traceutil/trace.go:171","msg":"trace[1485550962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1708; }","duration":"193.73516ms","start":"2026-05-14T07:44:08.720473Z","end":"2026-05-14T07:44:08.914208Z","steps":["trace[1485550962] 'agreement among raft nodes before linearized reading' (duration: 193.577386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:44:08.914603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.598193ms","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-05-14T07:44:08.914695Z","caller":"traceutil/trace.go:171","msg":"trace[1234403065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1708; }","duration":"185.700225ms","start":"2026-05-14T07:44:08.728981Z","end":"2026-05-14T07:44:08.914681Z","steps":["trace[1234403065] 'agreement among raft nodes before linearized reading' (duration: 185.527981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:44:08.914885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.870959ms","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-05-14T07:44:08.914977Z","caller":"traceutil/trace.go:171","msg":"trace[1647144232] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1708; }","duration":"185.991972ms","start":"2026-05-14T07:44:08.728962Z","end":"2026-05-14T07:44:08.914954Z","steps":["trace[1647144232] 'agreement among raft nodes before linearized reading' (duration: 185.668034ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:44:12.515742Z","caller":"traceutil/trace.go:171","msg":"trace[805082356] transaction","detail":"{read_only:false; response_revision:1722; number_of_response:1; }","duration":"116.375602ms","start":"2026-05-14T07:44:12.399348Z","end":"2026-05-14T07:44:12.515723Z","steps":["trace[805082356] 'process raft request' (duration: 116.26505ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:44:12.516921Z","caller":"traceutil/trace.go:171","msg":"trace[844775675] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"114.19612ms","start":"2026-05-14T07:44:12.40263Z","end":"2026-05-14T07:44:12.516826Z","steps":["trace[844775675] 'process raft request' (duration: 113.972704ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:44:13.850308Z","caller":"traceutil/trace.go:171","msg":"trace[1020638060] transaction","detail":"{read_only:false; response_revision:1731; number_of_response:1; }","duration":"148.869162ms","start":"2026-05-14T07:44:13.701417Z","end":"2026-05-14T07:44:13.850286Z","steps":["trace[1020638060] 'process raft request' (duration: 148.726109ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:44:17.071725Z","caller":"traceutil/trace.go:171","msg":"trace[1764056238] linearizableReadLoop","detail":"{readStateIndex:1812; appliedIndex:1811; }","duration":"115.600625ms","start":"2026-05-14T07:44:16.9561Z","end":"2026-05-14T07:44:17.071701Z","steps":["trace[1764056238] 'read index received' (duration: 115.488762ms)","trace[1764056238] 'applied index is now lower than readState.Index' (duration: 110.763µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:44:17.071783Z","caller":"traceutil/trace.go:171","msg":"trace[454001718] transaction","detail":"{read_only:false; response_revision:1742; number_of_response:1; }","duration":"153.76051ms","start":"2026-05-14T07:44:16.917986Z","end":"2026-05-14T07:44:17.071747Z","steps":["trace[454001718] 'process raft request' (duration: 153.552915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:44:17.072244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.113797ms","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-05-14T07:44:17.072304Z","caller":"traceutil/trace.go:171","msg":"trace[608139510] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1742; }","duration":"116.2149ms","start":"2026-05-14T07:44:16.956074Z","end":"2026-05-14T07:44:17.072289Z","steps":["trace[608139510] 'agreement among raft nodes before linearized reading' (duration: 115.741239ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:44:19.305857Z","caller":"traceutil/trace.go:171","msg":"trace[1286152114] transaction","detail":"{read_only:false; response_revision:1759; number_of_response:1; }","duration":"191.060914ms","start":"2026-05-14T07:44:19.114752Z","end":"2026-05-14T07:44:19.305813Z","steps":["trace[1286152114] 'process raft request' (duration: 191.008673ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:44:19.306232Z","caller":"traceutil/trace.go:171","msg":"trace[1877261246] transaction","detail":"{read_only:false; response_revision:1756; number_of_response:1; }","duration":"191.911575ms","start":"2026-05-14T07:44:19.114276Z","end":"2026-05-14T07:44:19.306187Z","steps":["trace[1877261246] 'process raft request' (duration: 190.756308ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:44:19.306508Z","caller":"traceutil/trace.go:171","msg":"trace[1509325576] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"191.787772ms","start":"2026-05-14T07:44:19.114646Z","end":"2026-05-14T07:44:19.306433Z","steps":["trace[1509325576] 'process raft request' (duration: 191.009904ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:44:19.306577Z","caller":"traceutil/trace.go:171","msg":"trace[14912050] transaction","detail":"{read_only:false; response_revision:1758; number_of_response:1; }","duration":"191.831093ms","start":"2026-05-14T07:44:19.114703Z","end":"2026-05-14T07:44:19.306535Z","steps":["trace[14912050] 'process raft request' (duration: 191.013333ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:44:25.36948Z","caller":"traceutil/trace.go:171","msg":"trace[1143186265] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"191.194158ms","start":"2026-05-14T07:44:25.178252Z","end":"2026-05-14T07:44:25.369446Z","steps":["trace[1143186265] 'process raft request' (duration: 190.508911ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:44:27.66906Z","caller":"traceutil/trace.go:171","msg":"trace[1076566203] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"192.988151ms","start":"2026-05-14T07:44:27.47604Z","end":"2026-05-14T07:44:27.669028Z","steps":["trace[1076566203] 'process raft request' (duration: 182.303642ms)","trace[1076566203] 'compare' (duration: 10.400692ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T07:44:27.669189Z","caller":"traceutil/trace.go:171","msg":"trace[2082820578] linearizableReadLoop","detail":"{readStateIndex:1866; appliedIndex:1864; }","duration":"101.734148ms","start":"2026-05-14T07:44:27.56743Z","end":"2026-05-14T07:44:27.669164Z","steps":["trace[2082820578] 'read index received' (duration: 90.900615ms)","trace[2082820578] 'applied index is now lower than readState.Index' (duration: 10.832503ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T07:44:27.669504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.060606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-14T07:44:27.669559Z","caller":"traceutil/trace.go:171","msg":"trace[1076287807] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1794; }","duration":"102.149308ms","start":"2026-05-14T07:44:27.567391Z","end":"2026-05-14T07:44:27.66954Z","steps":["trace[1076287807] 'agreement among raft nodes before linearized reading' (duration: 101.972874ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:44:27.669633Z","caller":"traceutil/trace.go:171","msg":"trace[982709272] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"191.808413ms","start":"2026-05-14T07:44:27.477809Z","end":"2026-05-14T07:44:27.669617Z","steps":["trace[982709272] 'process raft request' (duration: 191.170167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:46:02.262327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:46:01.960527Z","time spent":"301.789094ms","remote":"127.0.0.1:50328","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-14T07:46:02.262415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.48464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-14T07:46:02.262655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.158143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-14T07:46:02.263545Z","caller":"traceutil/trace.go:171","msg":"trace[939126836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2248; }","duration":"377.63962ms","start":"2026-05-14T07:46:01.885864Z","end":"2026-05-14T07:46:02.263504Z","steps":["trace[939126836] 'range keys from in-memory index tree' (duration: 376.278024ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:46:02.263623Z","caller":"traceutil/trace.go:171","msg":"trace[1408843391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2248; }","duration":"293.172981ms","start":"2026-05-14T07:46:01.970418Z","end":"2026-05-14T07:46:02.263591Z","steps":["trace[1408843391] 'range keys from in-memory index tree' (duration: 291.983579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:46:02.263802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:46:01.885844Z","time spent":"377.939109ms","remote":"127.0.0.1:40994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T07:46:02.262531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.988534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T07:46:02.26408Z","caller":"traceutil/trace.go:171","msg":"trace[188553494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2248; }","duration":"346.590806ms","start":"2026-05-14T07:46:01.917474Z","end":"2026-05-14T07:46:02.264065Z","steps":["trace[188553494] 'range keys from in-memory index tree' (duration: 344.818899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T07:46:02.264156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T07:46:01.917429Z","time spent":"346.715499ms","remote":"127.0.0.1:40750","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-05-14T07:46:07.886601Z","caller":"traceutil/trace.go:171","msg":"trace[464450052] transaction","detail":"{read_only:false; response_revision:2278; number_of_response:1; }","duration":"228.864313ms","start":"2026-05-14T07:46:07.657708Z","end":"2026-05-14T07:46:07.886572Z","steps":["trace[464450052] 'process raft request' (duration: 228.598077ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T07:46:07.896116Z","caller":"traceutil/trace.go:171","msg":"trace[389967489] transaction","detail":"{read_only:false; response_revision:2279; number_of_response:1; }","duration":"226.023179ms","start":"2026-05-14T07:46:07.670066Z","end":"2026-05-14T07:46:07.896089Z","steps":["trace[389967489] 'process raft request' (duration: 225.934067ms)"],"step_count":1}