{"level":"warn","ts":"2026-04-22T16:27:10.802822Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T16:27:10.80294Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.242:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.242:2380","--initial-cluster=instance=https://199.19.213.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.242:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-22T16:27:10.80302Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T16:27:10.803037Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.242:2380"]} {"level":"info","ts":"2026-04-22T16:27:10.803064Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T16:27:10.803973Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.242:2379"]} {"level":"info","ts":"2026-04-22T16:27:10.80407Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.242:2380"],"listen-peer-urls":["https://199.19.213.242:2380"],"advertise-client-urls":["https://199.19.213.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.242:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-22T16:27:10.80862Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.233855ms"} {"level":"info","ts":"2026-04-22T16:27:10.816994Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ad427454a89beddc","cluster-id":"edc6037504b39883"} {"level":"info","ts":"2026-04-22T16:27:10.817062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T16:27:10.817082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became follower at term 0"} {"level":"info","ts":"2026-04-22T16:27:10.817099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ad427454a89beddc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T16:27:10.81711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became follower at term 1"} {"level":"info","ts":"2026-04-22T16:27:10.817144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc switched to configuration voters=(12484669023979302364)"} {"level":"warn","ts":"2026-04-22T16:27:10.829959Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T16:27:10.832165Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T16:27:10.8335Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-22T16:27:10.83559Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ad427454a89beddc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T16:27:10.836943Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ad427454a89beddc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T16:27:10.837099Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T16:27:10.837768Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T16:27:10.83787Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T16:27:10.838961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc switched to configuration voters=(12484669023979302364)"} {"level":"info","ts":"2026-04-22T16:27:10.839119Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"edc6037504b39883","local-member-id":"ad427454a89beddc","added-peer-id":"ad427454a89beddc","added-peer-peer-urls":["https://199.19.213.242:2380"]} {"level":"info","ts":"2026-04-22T16:27:10.839971Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T16:27:10.840101Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.242:2380"} {"level":"info","ts":"2026-04-22T16:27:10.840142Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.242:2380"} {"level":"info","ts":"2026-04-22T16:27:10.84021Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ad427454a89beddc","initial-advertise-peer-urls":["https://199.19.213.242:2380"],"listen-peer-urls":["https://199.19.213.242:2380"],"advertise-client-urls":["https://199.19.213.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T16:27:10.840241Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T16:27:11.218054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T16:27:11.218095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T16:27:11.21812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc received MsgPreVoteResp from ad427454a89beddc at term 1"} {"level":"info","ts":"2026-04-22T16:27:11.218132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became candidate at term 2"} {"level":"info","ts":"2026-04-22T16:27:11.218138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc received MsgVoteResp from ad427454a89beddc at term 2"} {"level":"info","ts":"2026-04-22T16:27:11.218146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ad427454a89beddc became leader at term 2"} {"level":"info","ts":"2026-04-22T16:27:11.218154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ad427454a89beddc elected leader ad427454a89beddc at term 2"} {"level":"info","ts":"2026-04-22T16:27:11.218787Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ad427454a89beddc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.242:2379]}","request-path":"/0/members/ad427454a89beddc/attributes","cluster-id":"edc6037504b39883","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T16:27:11.218816Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T16:27:11.218828Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:27:11.219093Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T16:27:11.219254Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T16:27:11.219015Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T16:27:11.219689Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"edc6037504b39883","local-member-id":"ad427454a89beddc","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:27:11.219863Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:27:11.219917Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:27:11.221819Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.242:2379"} {"level":"info","ts":"2026-04-22T16:27:11.223761Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T16:27:40.36213Z","caller":"traceutil/trace.go:171","msg":"trace[1797903309] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"114.124277ms","start":"2026-04-22T16:27:40.247975Z","end":"2026-04-22T16:27:40.362099Z","steps":["trace[1797903309] 'process raft request' (duration: 113.925282ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:45.199713Z","caller":"traceutil/trace.go:171","msg":"trace[1967819601] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"130.478285ms","start":"2026-04-22T16:27:45.069217Z","end":"2026-04-22T16:27:45.199695Z","steps":["trace[1967819601] 'process raft request' (duration: 130.340351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:55.411025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17139747686947644789,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:27:55.933086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.591543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:27:55.933252Z","caller":"traceutil/trace.go:171","msg":"trace[309535157] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.069092583s","start":"2026-04-22T16:27:54.864146Z","end":"2026-04-22T16:27:55.933238Z","steps":["trace[309535157] 'process raft request' (duration: 1.069034571s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:55.933301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:54.86414Z","time spent":"1.069143504s","remote":"127.0.0.1:44458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:27:55.93369Z","caller":"traceutil/trace.go:171","msg":"trace[891458957] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.069702849s","start":"2026-04-22T16:27:54.863975Z","end":"2026-04-22T16:27:55.933678Z","steps":["trace[891458957] 'process raft request' (duration: 931.165463ms)","trace[891458957] 'compare' (duration: 137.080098ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:27:55.933733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:54.863942Z","time spent":"1.069774001s","remote":"127.0.0.1:44010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:27:55.943468Z","caller":"traceutil/trace.go:171","msg":"trace[394116534] transaction","detail":"{read_only:false; number_of_response:1; response_revision:518; }","duration":"1.055887468s","start":"2026-04-22T16:27:54.887563Z","end":"2026-04-22T16:27:55.94345Z","steps":["trace[394116534] 'process raft request' (duration: 1.055809776s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:55.943625Z","caller":"traceutil/trace.go:171","msg":"trace[631756199] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.055859106s","start":"2026-04-22T16:27:54.887753Z","end":"2026-04-22T16:27:55.943612Z","steps":["trace[631756199] 'process raft request' (duration: 1.055584049s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:55.943669Z","caller":"traceutil/trace.go:171","msg":"trace[660144325] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.071624179s","start":"2026-04-22T16:27:54.872019Z","end":"2026-04-22T16:27:55.943643Z","steps":["trace[660144325] 'process raft request' (duration: 1.07129133s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:55.943693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:54.887735Z","time spent":"1.055929909s","remote":"127.0.0.1:44458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:27:55.943688Z","caller":"traceutil/trace.go:171","msg":"trace[1668891920] transaction","detail":"{read_only:false; number_of_response:0; response_revision:514; }","duration":"1.072164184s","start":"2026-04-22T16:27:54.871478Z","end":"2026-04-22T16:27:55.943642Z","steps":["trace[1668891920] 'process raft request' (duration: 1.071710652s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:55.94384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:54.871462Z","time spent":"1.072299537s","remote":"127.0.0.1:44458","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:27:55.943881Z","caller":"traceutil/trace.go:171","msg":"trace[1154413814] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.054306906s","start":"2026-04-22T16:27:54.889566Z","end":"2026-04-22T16:27:55.943873Z","steps":["trace[1154413814] 'process raft request' (duration: 1.053837194s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:55.943816Z","caller":"traceutil/trace.go:171","msg":"trace[775658325] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.072188013s","start":"2026-04-22T16:27:54.871554Z","end":"2026-04-22T16:27:55.943741Z","steps":["trace[775658325] 'process raft request' (duration: 1.071703671s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:55.943959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:54.889552Z","time spent":"1.054356357s","remote":"127.0.0.1:44010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:27:55.944012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:54.872012Z","time spent":"1.071694481s","remote":"127.0.0.1:44010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:27:55.943952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:54.887546Z","time spent":"1.05601275s","remote":"127.0.0.1:44010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10113,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:27:55.944158Z","caller":"traceutil/trace.go:171","msg":"trace[2076654577] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.056318209s","start":"2026-04-22T16:27:54.887828Z","end":"2026-04-22T16:27:55.944146Z","steps":["trace[2076654577] 'process raft request' (duration: 1.055527088s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:55.944134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:54.87155Z","time spent":"1.072398189s","remote":"127.0.0.1:44458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:27:55.944192Z","caller":"traceutil/trace.go:171","msg":"trace[849000722] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.052998613s","start":"2026-04-22T16:27:54.891185Z","end":"2026-04-22T16:27:55.944184Z","steps":["trace[849000722] 'process raft request' (duration: 1.052618153s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:55.944239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:54.887815Z","time spent":"1.056380451s","remote":"127.0.0.1:44458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:27:55.944254Z","caller":"traceutil/trace.go:171","msg":"trace[1599562928] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:536; }","duration":"1.033490721s","start":"2026-04-22T16:27:54.910753Z","end":"2026-04-22T16:27:55.944243Z","steps":["trace[1599562928] 'read index received' (duration: 884.244244ms)","trace[1599562928] 'applied index is now lower than readState.Index' (duration: 149.245707ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:27:55.944272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:54.891151Z","time spent":"1.053062384s","remote":"127.0.0.1:44010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:27:55.944309Z","caller":"traceutil/trace.go:171","msg":"trace[1506999800] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.046195744s","start":"2026-04-22T16:27:54.898107Z","end":"2026-04-22T16:27:55.944303Z","steps":["trace[1506999800] 'process raft request' (duration: 1.045904716s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:55.944354Z","caller":"traceutil/trace.go:171","msg":"trace[843758355] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"945.630082ms","start":"2026-04-22T16:27:54.998714Z","end":"2026-04-22T16:27:55.944344Z","steps":["trace[843758355] 'process raft request' (duration: 945.401365ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:55.944385Z","caller":"traceutil/trace.go:171","msg":"trace[1979381265] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.0388102s","start":"2026-04-22T16:27:54.905568Z","end":"2026-04-22T16:27:55.944378Z","steps":["trace[1979381265] 'process raft request' (duration: 1.038499262s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:55.944401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:54.998703Z","time spent":"945.677422ms","remote":"127.0.0.1:44010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:27:55.944415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033664535s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-22T16:27:55.944432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:54.905543Z","time spent":"1.038870892s","remote":"127.0.0.1:44010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:27:55.944441Z","caller":"traceutil/trace.go:171","msg":"trace[544473376] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"1.033730107s","start":"2026-04-22T16:27:54.910705Z","end":"2026-04-22T16:27:55.944435Z","steps":["trace[544473376] 'agreement among raft nodes before linearized reading' (duration: 1.033668805s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:55.944357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:54.898094Z","time spent":"1.046235635s","remote":"127.0.0.1:44010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:27:55.944462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:54.910692Z","time spent":"1.033765698s","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T16:27:55.944158Z","caller":"traceutil/trace.go:171","msg":"trace[2125825370] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.051366369s","start":"2026-04-22T16:27:54.892779Z","end":"2026-04-22T16:27:55.944145Z","steps":["trace[2125825370] 'process raft request' (duration: 1.051179065s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:55.944547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:54.892765Z","time spent":"1.051755489s","remote":"127.0.0.1:44010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:27:55.944805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.22069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-22T16:27:55.94484Z","caller":"traceutil/trace.go:171","msg":"trace[586805215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:524; }","duration":"668.290702ms","start":"2026-04-22T16:27:55.276539Z","end":"2026-04-22T16:27:55.944829Z","steps":["trace[586805215] 'agreement among raft nodes before linearized reading' (duration: 668.168559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:55.944858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:55.276516Z","time spent":"668.338334ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T16:27:55.944939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.373101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:27:55.944967Z","caller":"traceutil/trace.go:171","msg":"trace[1685644890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"282.446232ms","start":"2026-04-22T16:27:55.662514Z","end":"2026-04-22T16:27:55.94496Z","steps":["trace[1685644890] 'agreement among raft nodes before linearized reading' (duration: 282.405041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:58.72345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.047644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T16:27:58.72355Z","caller":"traceutil/trace.go:171","msg":"trace[1833620191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:584; }","duration":"203.205859ms","start":"2026-04-22T16:27:58.520323Z","end":"2026-04-22T16:27:58.723528Z","steps":["trace[1833620191] 'range keys from in-memory index tree' (duration: 202.826248ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:02.592664Z","caller":"traceutil/trace.go:171","msg":"trace[1762259913] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"336.142148ms","start":"2026-04-22T16:28:02.25649Z","end":"2026-04-22T16:28:02.592632Z","steps":["trace[1762259913] 'process raft request' (duration: 335.971233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:02.592807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:02.256468Z","time spent":"336.269972ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:28:02.857571Z","caller":"traceutil/trace.go:171","msg":"trace[1486486241] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"194.736946ms","start":"2026-04-22T16:28:02.662796Z","end":"2026-04-22T16:28:02.857533Z","steps":["trace[1486486241] 'read index received' (duration: 193.632818ms)","trace[1486486241] 'applied index is now lower than readState.Index' (duration: 1.102538ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:02.857615Z","caller":"traceutil/trace.go:171","msg":"trace[718136905] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"285.253367ms","start":"2026-04-22T16:28:02.57231Z","end":"2026-04-22T16:28:02.857563Z","steps":["trace[718136905] 'process raft request' (duration: 284.456095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:02.857748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.926412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:02.857844Z","caller":"traceutil/trace.go:171","msg":"trace[1156319573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"195.083006ms","start":"2026-04-22T16:28:02.662738Z","end":"2026-04-22T16:28:02.857821Z","steps":["trace[1156319573] 'agreement among raft nodes before linearized reading' (duration: 194.912332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:02.858357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.588332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T16:28:02.858621Z","caller":"traceutil/trace.go:171","msg":"trace[1023692097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:612; }","duration":"116.91678ms","start":"2026-04-22T16:28:02.741683Z","end":"2026-04-22T16:28:02.8586Z","steps":["trace[1023692097] 'agreement among raft nodes before linearized reading' (duration: 116.108189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:03.215055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.375601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:28:03.21517Z","caller":"traceutil/trace.go:171","msg":"trace[1425567464] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"296.103381ms","start":"2026-04-22T16:28:02.919046Z","end":"2026-04-22T16:28:03.215149Z","steps":["trace[1425567464] 'process raft request' (duration: 21.548164ms)","trace[1425567464] 'compare' (duration: 274.061163ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:03.459582Z","caller":"traceutil/trace.go:171","msg":"trace[1075601905] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"190.130037ms","start":"2026-04-22T16:28:03.269406Z","end":"2026-04-22T16:28:03.459536Z","steps":["trace[1075601905] 'read index received' (duration: 158.331905ms)","trace[1075601905] 'applied index is now lower than readState.Index' (duration: 31.797342ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:03.459677Z","caller":"traceutil/trace.go:171","msg":"trace[95192233] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"232.608728ms","start":"2026-04-22T16:28:03.227028Z","end":"2026-04-22T16:28:03.459636Z","steps":["trace[95192233] 'process raft request' (duration: 200.736384ms)","trace[95192233] 'compare' (duration: 31.667559ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:03.459761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.335822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:03.459832Z","caller":"traceutil/trace.go:171","msg":"trace[484175878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"190.461985ms","start":"2026-04-22T16:28:03.269353Z","end":"2026-04-22T16:28:03.459815Z","steps":["trace[484175878] 'agreement among raft nodes before linearized reading' (duration: 190.304831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:04.164523Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17139747686947644986,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T16:28:04.174482Z","caller":"traceutil/trace.go:171","msg":"trace[1941585814] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"708.982068ms","start":"2026-04-22T16:28:03.46545Z","end":"2026-04-22T16:28:04.174432Z","steps":["trace[1941585814] 'process raft request' (duration: 708.721071ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:04.174561Z","caller":"traceutil/trace.go:171","msg":"trace[184480610] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:648; }","duration":"510.80507ms","start":"2026-04-22T16:28:03.663732Z","end":"2026-04-22T16:28:04.174537Z","steps":["trace[184480610] 'read index received' (duration: 510.385379ms)","trace[184480610] 'applied index is now lower than readState.Index' (duration: 418.381µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:04.174709Z","caller":"traceutil/trace.go:171","msg":"trace[1084076207] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"700.495165ms","start":"2026-04-22T16:28:03.474203Z","end":"2026-04-22T16:28:04.174698Z","steps":["trace[1084076207] 'process raft request' (duration: 700.285789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:04.174708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:03.465431Z","time spent":"709.168642ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:28:04.17484Z","caller":"traceutil/trace.go:171","msg":"trace[674959954] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"707.292403ms","start":"2026-04-22T16:28:03.467264Z","end":"2026-04-22T16:28:04.174556Z","steps":["trace[674959954] 'process raft request' (duration: 707.16091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:04.175055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:03.467256Z","time spent":"707.717164ms","remote":"127.0.0.1:44154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:28:04.175131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.393846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:04.175266Z","caller":"traceutil/trace.go:171","msg":"trace[1267209415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"511.5534ms","start":"2026-04-22T16:28:03.663693Z","end":"2026-04-22T16:28:04.175246Z","steps":["trace[1267209415] 'agreement among raft nodes before linearized reading' (duration: 511.389166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:04.175309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:03.663675Z","time spent":"511.624471ms","remote":"127.0.0.1:43988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T16:28:04.174574Z","caller":"traceutil/trace.go:171","msg":"trace[153687284] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"708.868624ms","start":"2026-04-22T16:28:03.465641Z","end":"2026-04-22T16:28:04.174509Z","steps":["trace[153687284] 'process raft request' (duration: 708.681549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:04.175138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.551245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T16:28:04.175988Z","caller":"traceutil/trace.go:171","msg":"trace[1997966802] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"121.460929ms","start":"2026-04-22T16:28:04.054511Z","end":"2026-04-22T16:28:04.175972Z","steps":["trace[1997966802] 'agreement among raft nodes before linearized reading' (duration: 120.512114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:04.175045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.772317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T16:28:04.176247Z","caller":"traceutil/trace.go:171","msg":"trace[155614014] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"123.035761ms","start":"2026-04-22T16:28:04.053189Z","end":"2026-04-22T16:28:04.176225Z","steps":["trace[155614014] 'agreement among raft nodes before linearized reading' (duration: 121.654415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:04.174863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:03.474192Z","time spent":"700.553567ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:28:04.175782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:03.465632Z","time spent":"710.045024ms","remote":"127.0.0.1:44120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:28:04.480757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.630492ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-04-22T16:28:04.481367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:04.178412Z","time spent":"302.94676ms","remote":"127.0.0.1:44000","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-22T16:28:04.77434Z","caller":"traceutil/trace.go:171","msg":"trace[1884421684] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"593.906495ms","start":"2026-04-22T16:28:04.180406Z","end":"2026-04-22T16:28:04.774313Z","steps":["trace[1884421684] 'process raft request' (duration: 539.897991ms)","trace[1884421684] 'compare' (duration: 53.808158ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:04.774529Z","caller":"traceutil/trace.go:171","msg":"trace[1195241790] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"593.242427ms","start":"2026-04-22T16:28:04.181257Z","end":"2026-04-22T16:28:04.7745Z","steps":["trace[1195241790] 'process raft request' (duration: 593.005542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:04.774671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:04.181229Z","time spent":"593.364011ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:28:04.774803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:04.180378Z","time spent":"594.124761ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:28:04.775789Z","caller":"traceutil/trace.go:171","msg":"trace[1316817629] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:653; }","duration":"178.595854ms","start":"2026-04-22T16:28:04.597178Z","end":"2026-04-22T16:28:04.775774Z","steps":["trace[1316817629] 'read index received' (duration: 123.06046ms)","trace[1316817629] 'applied index is now lower than readState.Index' (duration: 55.534144ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:04.77599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.80276ms","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-04-22T16:28:04.775977Z","caller":"traceutil/trace.go:171","msg":"trace[150349481] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"293.102662ms","start":"2026-04-22T16:28:04.482832Z","end":"2026-04-22T16:28:04.775935Z","steps":["trace[150349481] 'process raft request' (duration: 292.870167ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:04.776044Z","caller":"traceutil/trace.go:171","msg":"trace[1760613655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"178.903012ms","start":"2026-04-22T16:28:04.597129Z","end":"2026-04-22T16:28:04.776032Z","steps":["trace[1760613655] 'agreement among raft nodes before linearized reading' (duration: 178.757029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:04.776905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.337896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:04.777046Z","caller":"traceutil/trace.go:171","msg":"trace[730833977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"114.121227ms","start":"2026-04-22T16:28:04.662911Z","end":"2026-04-22T16:28:04.777032Z","steps":["trace[730833977] 'agreement among raft nodes before linearized reading' (duration: 113.291335ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:06.176098Z","caller":"traceutil/trace.go:171","msg":"trace[709854100] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"229.636641ms","start":"2026-04-22T16:28:05.946441Z","end":"2026-04-22T16:28:06.176078Z","steps":["trace[709854100] 'read index received' (duration: 20.779414ms)","trace[709854100] 'applied index is now lower than readState.Index' (duration: 208.856297ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:06.176222Z","caller":"traceutil/trace.go:171","msg":"trace[1655585076] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"229.734503ms","start":"2026-04-22T16:28:05.94648Z","end":"2026-04-22T16:28:06.176215Z","steps":["trace[1655585076] 'process raft request' (duration: 229.569919ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:06.176252Z","caller":"traceutil/trace.go:171","msg":"trace[1004707453] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"229.893127ms","start":"2026-04-22T16:28:05.94633Z","end":"2026-04-22T16:28:06.176223Z","steps":["trace[1004707453] 'process raft request' (duration: 229.584039ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:06.176299Z","caller":"traceutil/trace.go:171","msg":"trace[966561747] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"229.726593ms","start":"2026-04-22T16:28:05.946418Z","end":"2026-04-22T16:28:06.176144Z","steps":["trace[966561747] 'process raft request' (duration: 229.606429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:06.17648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.021411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:06.176526Z","caller":"traceutil/trace.go:171","msg":"trace[1968463178] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:642; }","duration":"230.094422ms","start":"2026-04-22T16:28:05.946416Z","end":"2026-04-22T16:28:06.176511Z","steps":["trace[1968463178] 'agreement among raft nodes before linearized reading' (duration: 230.006769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:06.176532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.97354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:28:06.176559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.041782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:06.176646Z","caller":"traceutil/trace.go:171","msg":"trace[109656448] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:642; }","duration":"230.116823ms","start":"2026-04-22T16:28:05.946502Z","end":"2026-04-22T16:28:06.176619Z","steps":["trace[109656448] 'agreement among raft nodes before linearized reading' (duration: 229.907797ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:06.176688Z","caller":"traceutil/trace.go:171","msg":"trace[1506332281] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:642; }","duration":"230.163994ms","start":"2026-04-22T16:28:05.946481Z","end":"2026-04-22T16:28:06.176645Z","steps":["trace[1506332281] 'agreement among raft nodes before linearized reading' (duration: 229.785214ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:15.080877Z","caller":"traceutil/trace.go:171","msg":"trace[1245098906] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"135.146197ms","start":"2026-04-22T16:28:14.945704Z","end":"2026-04-22T16:28:15.080851Z","steps":["trace[1245098906] 'read index received' (duration: 134.603083ms)","trace[1245098906] 'applied index is now lower than readState.Index' (duration: 542.164µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:15.081102Z","caller":"traceutil/trace.go:171","msg":"trace[1731585433] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"176.989102ms","start":"2026-04-22T16:28:14.904076Z","end":"2026-04-22T16:28:15.081065Z","steps":["trace[1731585433] 'process raft request' (duration: 176.200612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:15.081265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.531257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T16:28:15.081331Z","caller":"traceutil/trace.go:171","msg":"trace[1199238375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:744; }","duration":"135.65207ms","start":"2026-04-22T16:28:14.945668Z","end":"2026-04-22T16:28:15.08132Z","steps":["trace[1199238375] 'agreement among raft nodes before linearized reading' (duration: 135.440724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:19.671153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17139747686947645299,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T16:28:19.811603Z","caller":"traceutil/trace.go:171","msg":"trace[1146667865] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"642.687843ms","start":"2026-04-22T16:28:19.168897Z","end":"2026-04-22T16:28:19.811585Z","steps":["trace[1146667865] 'process raft request' (duration: 642.549569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:19.811726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:19.16888Z","time spent":"642.771775ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:28:20.15411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.04801ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-22T16:28:20.154237Z","caller":"traceutil/trace.go:171","msg":"trace[2135038579] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"983.39263ms","start":"2026-04-22T16:28:19.170814Z","end":"2026-04-22T16:28:20.154206Z","steps":["trace[2135038579] 'read index received' (duration: 640.819024ms)","trace[2135038579] 'applied index is now lower than readState.Index' (duration: 342.572246ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:20.154492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.654377ms","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-04-22T16:28:20.154633Z","caller":"traceutil/trace.go:171","msg":"trace[794991781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:777; }","duration":"983.846573ms","start":"2026-04-22T16:28:19.170765Z","end":"2026-04-22T16:28:20.154612Z","steps":["trace[794991781] 'agreement among raft nodes before linearized reading' (duration: 983.530854ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:20.154692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:19.170746Z","time spent":"983.934084ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T16:28:20.155479Z","caller":"traceutil/trace.go:171","msg":"trace[859540818] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"225.117823ms","start":"2026-04-22T16:28:19.930349Z","end":"2026-04-22T16:28:20.155467Z","steps":["trace[859540818] 'process raft request' (duration: 224.894756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:20.155535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.868314ms","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-04-22T16:28:20.155622Z","caller":"traceutil/trace.go:171","msg":"trace[1531389226] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:778; }","duration":"128.994037ms","start":"2026-04-22T16:28:20.026608Z","end":"2026-04-22T16:28:20.155602Z","steps":["trace[1531389226] 'agreement among raft nodes before linearized reading' (duration: 128.800482ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:37.1927Z","caller":"traceutil/trace.go:171","msg":"trace[499486359] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"161.990097ms","start":"2026-04-22T16:28:37.030683Z","end":"2026-04-22T16:28:37.192673Z","steps":["trace[499486359] 'process raft request' (duration: 154.092649ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:37.230467Z","caller":"traceutil/trace.go:171","msg":"trace[1085564381] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"184.804425ms","start":"2026-04-22T16:28:37.045646Z","end":"2026-04-22T16:28:37.230451Z","steps":["trace[1085564381] 'process raft request' (duration: 184.708103ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:54.597666Z","caller":"traceutil/trace.go:171","msg":"trace[1826175103] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"123.919638ms","start":"2026-04-22T16:28:54.473715Z","end":"2026-04-22T16:28:54.597634Z","steps":["trace[1826175103] 'process raft request' (duration: 79.455723ms)","trace[1826175103] 'compare' (duration: 44.317031ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:55.39515Z","caller":"traceutil/trace.go:171","msg":"trace[416181753] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"182.378771ms","start":"2026-04-22T16:28:55.212747Z","end":"2026-04-22T16:28:55.395126Z","steps":["trace[416181753] 'read index received' (duration: 182.194445ms)","trace[416181753] 'applied index is now lower than readState.Index' (duration: 183.086µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:55.395193Z","caller":"traceutil/trace.go:171","msg":"trace[252123203] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"226.99714ms","start":"2026-04-22T16:28:55.168169Z","end":"2026-04-22T16:28:55.395166Z","steps":["trace[252123203] 'process raft request' (duration: 226.777844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:55.395466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.69418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-22T16:28:55.395546Z","caller":"traceutil/trace.go:171","msg":"trace[185385456] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:992; }","duration":"182.834743ms","start":"2026-04-22T16:28:55.212692Z","end":"2026-04-22T16:28:55.395527Z","steps":["trace[185385456] 'agreement among raft nodes before linearized reading' (duration: 182.571556ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:55.737946Z","caller":"traceutil/trace.go:171","msg":"trace[1795204369] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"481.712077ms","start":"2026-04-22T16:28:55.256203Z","end":"2026-04-22T16:28:55.737915Z","steps":["trace[1795204369] 'process raft request' (duration: 463.401678ms)","trace[1795204369] 'compare' (duration: 17.773885ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:55.737983Z","caller":"traceutil/trace.go:171","msg":"trace[559784] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"342.670914ms","start":"2026-04-22T16:28:55.395249Z","end":"2026-04-22T16:28:55.73792Z","steps":["trace[559784] 'read index received' (duration: 324.366694ms)","trace[559784] 'applied index is now lower than readState.Index' (duration: 18.30193ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:55.737698Z","caller":"traceutil/trace.go:171","msg":"trace[927520633] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"337.559669ms","start":"2026-04-22T16:28:55.400113Z","end":"2026-04-22T16:28:55.737673Z","steps":["trace[927520633] 'process raft request' (duration: 337.501517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:55.738105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:55.25618Z","time spent":"481.843021ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:28:55.738165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:55.400089Z","time spent":"337.99899ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:28:55.73817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.188907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T16:28:55.738262Z","caller":"traceutil/trace.go:171","msg":"trace[2037241989] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:994; }","duration":"438.306969ms","start":"2026-04-22T16:28:55.299931Z","end":"2026-04-22T16:28:55.738238Z","steps":["trace[2037241989] 'agreement among raft nodes before linearized reading' (duration: 438.074463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:55.738301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:55.299921Z","time spent":"438.371741ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T16:29:18.617783Z","caller":"traceutil/trace.go:171","msg":"trace[1341588435] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"364.466074ms","start":"2026-04-22T16:29:18.253295Z","end":"2026-04-22T16:29:18.617761Z","steps":["trace[1341588435] 'process raft request' (duration: 364.30318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:18.617936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:18.25327Z","time spent":"364.587648ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:29:18.851088Z","caller":"traceutil/trace.go:171","msg":"trace[1283793331] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"404.302477ms","start":"2026-04-22T16:29:18.446766Z","end":"2026-04-22T16:29:18.851068Z","steps":["trace[1283793331] 'process raft request' (duration: 336.418388ms)","trace[1283793331] 'compare' (duration: 67.376006ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:18.851225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:18.446728Z","time spent":"404.414771ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:29:18.851444Z","caller":"traceutil/trace.go:171","msg":"trace[1079698792] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1116; }","duration":"270.785038ms","start":"2026-04-22T16:29:18.580106Z","end":"2026-04-22T16:29:18.850892Z","steps":["trace[1079698792] 'read index received' (duration: 37.487413ms)","trace[1079698792] 'applied index is now lower than readState.Index' (duration: 233.296635ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:18.851502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.364254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T16:29:18.851599Z","caller":"traceutil/trace.go:171","msg":"trace[1843962846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1069; }","duration":"271.515627ms","start":"2026-04-22T16:29:18.580065Z","end":"2026-04-22T16:29:18.85158Z","steps":["trace[1843962846] 'agreement among raft nodes before linearized reading' (duration: 271.228899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:18.851963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.601061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-22T16:29:18.852078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.92129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T16:29:18.852156Z","caller":"traceutil/trace.go:171","msg":"trace[259938018] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1069; }","duration":"216.092253ms","start":"2026-04-22T16:29:18.636048Z","end":"2026-04-22T16:29:18.852141Z","steps":["trace[259938018] 'agreement among raft nodes before linearized reading' (duration: 215.908969ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:18.852128Z","caller":"traceutil/trace.go:171","msg":"trace[727621031] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1069; }","duration":"149.810568ms","start":"2026-04-22T16:29:18.702298Z","end":"2026-04-22T16:29:18.852108Z","steps":["trace[727621031] 'agreement among raft nodes before linearized reading' (duration: 149.409317ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:19.157358Z","caller":"traceutil/trace.go:171","msg":"trace[390158868] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"301.450001ms","start":"2026-04-22T16:29:18.85588Z","end":"2026-04-22T16:29:19.15733Z","steps":["trace[390158868] 'process raft request' (duration: 301.294656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:19.157509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:18.855859Z","time spent":"301.570724ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:29:19.162089Z","caller":"traceutil/trace.go:171","msg":"trace[1721211091] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"306.077663ms","start":"2026-04-22T16:29:18.855985Z","end":"2026-04-22T16:29:19.162063Z","steps":["trace[1721211091] 'process raft request' (duration: 305.996181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:19.162207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:18.855963Z","time spent":"306.171214ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:29:20.817889Z","caller":"traceutil/trace.go:171","msg":"trace[1094005664] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"190.913534ms","start":"2026-04-22T16:29:20.626953Z","end":"2026-04-22T16:29:20.817867Z","steps":["trace[1094005664] 'process raft request' (duration: 190.77462ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:21.461412Z","caller":"traceutil/trace.go:171","msg":"trace[1951745289] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"295.437004ms","start":"2026-04-22T16:29:21.165951Z","end":"2026-04-22T16:29:21.461388Z","steps":["trace[1951745289] 'process raft request' (duration: 295.226759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:21.461631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.399874ms","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-04-22T16:29:21.461775Z","caller":"traceutil/trace.go:171","msg":"trace[2039823958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1076; }","duration":"295.649129ms","start":"2026-04-22T16:29:21.166081Z","end":"2026-04-22T16:29:21.46173Z","steps":["trace[2039823958] 'agreement among raft nodes before linearized reading' (duration: 295.381812ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:21.461399Z","caller":"traceutil/trace.go:171","msg":"trace[391179457] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1125; }","duration":"295.125416ms","start":"2026-04-22T16:29:21.166221Z","end":"2026-04-22T16:29:21.461346Z","steps":["trace[391179457] 'read index received' (duration: 294.90148ms)","trace[391179457] 'applied index is now lower than readState.Index' (duration: 219.946µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:21.462177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.183995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:29:21.462322Z","caller":"traceutil/trace.go:171","msg":"trace[834101366] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1076; }","duration":"148.37636ms","start":"2026-04-22T16:29:21.313925Z","end":"2026-04-22T16:29:21.462301Z","steps":["trace[834101366] 'agreement among raft nodes before linearized reading' (duration: 148.182085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:21.462206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.746402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T16:29:21.46248Z","caller":"traceutil/trace.go:171","msg":"trace[748405136] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1076; }","duration":"201.019509ms","start":"2026-04-22T16:29:21.261421Z","end":"2026-04-22T16:29:21.46244Z","steps":["trace[748405136] 'agreement among raft nodes before linearized reading' (duration: 200.66511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:22.315233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.131211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:29:22.315401Z","caller":"traceutil/trace.go:171","msg":"trace[1897911104] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1126; }","duration":"652.396142ms","start":"2026-04-22T16:29:21.662987Z","end":"2026-04-22T16:29:22.315383Z","steps":["trace[1897911104] 'read index received' (duration: 36.371µs)","trace[1897911104] 'applied index is now lower than readState.Index' (duration: 652.358531ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:29:22.315446Z","caller":"traceutil/trace.go:171","msg":"trace[1986297723] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"848.058461ms","start":"2026-04-22T16:29:21.467353Z","end":"2026-04-22T16:29:22.315411Z","steps":["trace[1986297723] 'process raft request' (duration: 112.625402ms)","trace[1986297723] 'compare' (duration: 735.020238ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:22.315606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:21.467323Z","time spent":"848.188294ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:29:22.315634Z","caller":"traceutil/trace.go:171","msg":"trace[1193950010] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"449.874243ms","start":"2026-04-22T16:29:21.865743Z","end":"2026-04-22T16:29:22.315617Z","steps":["trace[1193950010] 'process raft request' (duration: 449.598796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:22.315727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.713839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:29:22.315757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.319144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T16:29:22.315804Z","caller":"traceutil/trace.go:171","msg":"trace[1776278512] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1078; }","duration":"652.855163ms","start":"2026-04-22T16:29:21.662929Z","end":"2026-04-22T16:29:22.315785Z","steps":["trace[1776278512] 'agreement among raft nodes before linearized reading' (duration: 652.518064ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:22.315831Z","caller":"traceutil/trace.go:171","msg":"trace[1789002000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1078; }","duration":"442.470979ms","start":"2026-04-22T16:29:21.873344Z","end":"2026-04-22T16:29:22.315815Z","steps":["trace[1789002000] 'agreement among raft nodes before linearized reading' (duration: 442.256423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:22.315834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.732548ms","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-04-22T16:29:22.315846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:21.662907Z","time spent":"652.931287ms","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T16:29:22.315764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:21.865701Z","time spent":"450.020177ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:29:22.31588Z","caller":"traceutil/trace.go:171","msg":"trace[1742704513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1078; }","duration":"436.83402ms","start":"2026-04-22T16:29:21.879035Z","end":"2026-04-22T16:29:22.315869Z","steps":["trace[1742704513] 'agreement among raft nodes before linearized reading' (duration: 436.673306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:22.315932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:21.878999Z","time spent":"436.923934ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T16:29:22.315878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:21.873314Z","time spent":"442.555741ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T16:29:22.781926Z","caller":"traceutil/trace.go:171","msg":"trace[659338157] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"460.807649ms","start":"2026-04-22T16:29:22.321058Z","end":"2026-04-22T16:29:22.781866Z","steps":["trace[659338157] 'process raft request' (duration: 403.899477ms)","trace[659338157] 'compare' (duration: 56.640575ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:22.782131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:22.321042Z","time spent":"460.976374ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:29:22.791583Z","caller":"traceutil/trace.go:171","msg":"trace[1486650523] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1128; }","duration":"356.082233ms","start":"2026-04-22T16:29:22.435478Z","end":"2026-04-22T16:29:22.79156Z","steps":["trace[1486650523] 'read index received' (duration: 289.407555ms)","trace[1486650523] 'applied index is now lower than readState.Index' (duration: 66.673438ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:22.791732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.214138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:29:22.791776Z","caller":"traceutil/trace.go:171","msg":"trace[1291986942] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1080; }","duration":"356.370431ms","start":"2026-04-22T16:29:22.435394Z","end":"2026-04-22T16:29:22.791764Z","steps":["trace[1291986942] 'agreement among raft nodes before linearized reading' (duration: 356.254858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:22.79181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:22.435364Z","time spent":"356.436593ms","remote":"127.0.0.1:44206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-04-22T16:29:22.791757Z","caller":"traceutil/trace.go:171","msg":"trace[497777883] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"469.538889ms","start":"2026-04-22T16:29:22.322134Z","end":"2026-04-22T16:29:22.791673Z","steps":["trace[497777883] 'process raft request' (duration: 469.307922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:22.792089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:22.322109Z","time spent":"469.816936ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:29:23.063645Z","caller":"traceutil/trace.go:171","msg":"trace[2088320797] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"238.301976ms","start":"2026-04-22T16:29:22.825321Z","end":"2026-04-22T16:29:23.063623Z","steps":["trace[2088320797] 'process raft request' (duration: 238.168273ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:35.343304Z","caller":"traceutil/trace.go:171","msg":"trace[1004352752] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"499.289339ms","start":"2026-04-22T16:29:34.843995Z","end":"2026-04-22T16:29:35.343285Z","steps":["trace[1004352752] 'process raft request' (duration: 499.154585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:35.343409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:34.843956Z","time spent":"499.401562ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:29:35.764906Z","caller":"traceutil/trace.go:171","msg":"trace[598687282] linearizableReadLoop","detail":"{readStateIndex:1226; appliedIndex:1224; }","duration":"650.995797ms","start":"2026-04-22T16:29:35.113892Z","end":"2026-04-22T16:29:35.764888Z","steps":["trace[598687282] 'read index received' (duration: 229.273876ms)","trace[598687282] 'applied index is now lower than readState.Index' (duration: 421.721371ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:29:35.764997Z","caller":"traceutil/trace.go:171","msg":"trace[224621762] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"919.989652ms","start":"2026-04-22T16:29:34.844999Z","end":"2026-04-22T16:29:35.764989Z","steps":["trace[224621762] 'process raft request' (duration: 919.760487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:35.765077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:34.844989Z","time spent":"920.024553ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:29:35.765247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.529685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T16:29:35.76532Z","caller":"traceutil/trace.go:171","msg":"trace[722163749] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1171; }","duration":"236.615617ms","start":"2026-04-22T16:29:35.528683Z","end":"2026-04-22T16:29:35.765299Z","steps":["trace[722163749] 'agreement among raft nodes before linearized reading' (duration: 236.494824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:35.765368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.598671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:29:35.76539Z","caller":"traceutil/trace.go:171","msg":"trace[465759445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1171; }","duration":"101.648892ms","start":"2026-04-22T16:29:35.663734Z","end":"2026-04-22T16:29:35.765383Z","steps":["trace[465759445] 'agreement among raft nodes before linearized reading' (duration: 101.611911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:35.76552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.628973ms","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-04-22T16:29:35.765533Z","caller":"traceutil/trace.go:171","msg":"trace[95621048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1171; }","duration":"651.678054ms","start":"2026-04-22T16:29:35.11385Z","end":"2026-04-22T16:29:35.765528Z","steps":["trace[95621048] 'agreement among raft nodes before linearized reading' (duration: 651.616022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:35.765544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:35.113834Z","time spent":"651.707144ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T16:29:35.765584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.606936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T16:29:35.765614Z","caller":"traceutil/trace.go:171","msg":"trace[1701507097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1171; }","duration":"219.671448ms","start":"2026-04-22T16:29:35.545934Z","end":"2026-04-22T16:29:35.765606Z","steps":["trace[1701507097] 'agreement among raft nodes before linearized reading' (duration: 219.562695ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:47.940083Z","caller":"traceutil/trace.go:171","msg":"trace[770134374] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"116.298371ms","start":"2026-04-22T16:29:47.823769Z","end":"2026-04-22T16:29:47.940067Z","steps":["trace[770134374] 'process raft request' (duration: 113.679904ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:50.378943Z","caller":"traceutil/trace.go:171","msg":"trace[1126684258] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"141.877564ms","start":"2026-04-22T16:29:50.237041Z","end":"2026-04-22T16:29:50.378919Z","steps":["trace[1126684258] 'process raft request' (duration: 141.633498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:51.948265Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17139747686947646416,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:29:52.44669Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.043220303s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T16:29:52.447117Z","caller":"traceutil/trace.go:171","msg":"trace[1246130771] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1300; }","duration":"999.751267ms","start":"2026-04-22T16:29:51.447338Z","end":"2026-04-22T16:29:52.44709Z","steps":["trace[1246130771] 'read index received' (duration: 999.481159ms)","trace[1246130771] 'applied index is now lower than readState.Index' (duration: 269.288µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:52.447259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.913951ms","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":"info","ts":"2026-04-22T16:29:52.447317Z","caller":"traceutil/trace.go:171","msg":"trace[387196546] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1243; }","duration":"999.999903ms","start":"2026-04-22T16:29:51.447297Z","end":"2026-04-22T16:29:52.447297Z","steps":["trace[387196546] 'agreement among raft nodes before linearized reading' (duration: 999.920361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:52.447348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:51.447283Z","time spent":"1.000058296s","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-04-22T16:29:52.447477Z","caller":"traceutil/trace.go:171","msg":"trace[698128492] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"1.043755377s","start":"2026-04-22T16:29:51.403374Z","end":"2026-04-22T16:29:52.447129Z","steps":["trace[698128492] 'process raft request' (duration: 1.043512721s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:52.447551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.867964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:29:52.447568Z","caller":"traceutil/trace.go:171","msg":"trace[1738448105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1243; }","duration":"784.916724ms","start":"2026-04-22T16:29:51.662646Z","end":"2026-04-22T16:29:52.447563Z","steps":["trace[1738448105] 'agreement among raft nodes before linearized reading' (duration: 784.889703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:52.447586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:51.662627Z","time spent":"784.955905ms","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T16:29:52.447597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:51.403344Z","time spent":"1.044192227s","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:29:52.447704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.544108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T16:29:52.447724Z","caller":"traceutil/trace.go:171","msg":"trace[75411798] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1243; }","duration":"542.59838ms","start":"2026-04-22T16:29:51.90512Z","end":"2026-04-22T16:29:52.447719Z","steps":["trace[75411798] 'agreement among raft nodes before linearized reading' (duration: 542.526088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:52.447739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:51.905101Z","time spent":"542.635001ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T16:29:52.447724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.362721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-22T16:29:52.447805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.655324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T16:29:52.447854Z","caller":"traceutil/trace.go:171","msg":"trace[1163060200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1243; }","duration":"461.739506ms","start":"2026-04-22T16:29:51.986102Z","end":"2026-04-22T16:29:52.447842Z","steps":["trace[1163060200] 'agreement among raft nodes before linearized reading' (duration: 461.648043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:52.44789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:51.98608Z","time spent":"461.803699ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T16:29:52.447812Z","caller":"traceutil/trace.go:171","msg":"trace[656554245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1243; }","duration":"484.496385ms","start":"2026-04-22T16:29:51.963307Z","end":"2026-04-22T16:29:52.447804Z","steps":["trace[656554245] 'agreement among raft nodes before linearized reading' (duration: 484.314739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:52.44794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:51.96329Z","time spent":"484.642089ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T16:29:52.623962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.509215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:29:52.624197Z","caller":"traceutil/trace.go:171","msg":"trace[1112372846] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1302; }","duration":"103.718286ms","start":"2026-04-22T16:29:52.52046Z","end":"2026-04-22T16:29:52.624178Z","steps":["trace[1112372846] 'read index received' (duration: 36.408713ms)","trace[1112372846] 'applied index is now lower than readState.Index' (duration: 67.308253ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:29:52.624255Z","caller":"traceutil/trace.go:171","msg":"trace[1076423671] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"172.706821ms","start":"2026-04-22T16:29:52.451517Z","end":"2026-04-22T16:29:52.624224Z","steps":["trace[1076423671] 'process raft request' (duration: 67.836476ms)","trace[1076423671] 'compare' (duration: 104.393473ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:52.624351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.884811ms","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-04-22T16:29:52.624413Z","caller":"traceutil/trace.go:171","msg":"trace[735569113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1247; }","duration":"103.992483ms","start":"2026-04-22T16:29:52.520407Z","end":"2026-04-22T16:29:52.624399Z","steps":["trace[735569113] 'agreement among raft nodes before linearized reading' (duration: 103.827508ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:52.62449Z","caller":"traceutil/trace.go:171","msg":"trace[327091546] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"172.383913ms","start":"2026-04-22T16:29:52.452069Z","end":"2026-04-22T16:29:52.624453Z","steps":["trace[327091546] 'process raft request' (duration: 171.980863ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:52.624545Z","caller":"traceutil/trace.go:171","msg":"trace[815026461] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"111.754943ms","start":"2026-04-22T16:29:52.512779Z","end":"2026-04-22T16:29:52.624534Z","steps":["trace[815026461] 'process raft request' (duration: 111.351563ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:52.82624Z","caller":"traceutil/trace.go:171","msg":"trace[694578295] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"115.879501ms","start":"2026-04-22T16:29:52.71032Z","end":"2026-04-22T16:29:52.8262Z","steps":["trace[694578295] 'process raft request' (duration: 30.227734ms)","trace[694578295] 'compare' (duration: 85.536184ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:54.955687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.703925ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:29:54.955793Z","caller":"traceutil/trace.go:171","msg":"trace[58727173] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"540.860635ms","start":"2026-04-22T16:29:54.414915Z","end":"2026-04-22T16:29:54.955775Z","steps":["trace[58727173] 'process raft request' (duration: 191.003826ms)","trace[58727173] 'compare' (duration: 349.567642ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:54.955848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:54.414901Z","time spent":"540.926228ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:29:54.96449Z","caller":"traceutil/trace.go:171","msg":"trace[475961865] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1311; }","duration":"512.590494ms","start":"2026-04-22T16:29:54.451889Z","end":"2026-04-22T16:29:54.964479Z","steps":["trace[475961865] 'read index received' (duration: 153.980538ms)","trace[475961865] 'applied index is now lower than readState.Index' (duration: 358.609235ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:29:54.964546Z","caller":"traceutil/trace.go:171","msg":"trace[818657439] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"533.639209ms","start":"2026-04-22T16:29:54.4309Z","end":"2026-04-22T16:29:54.96454Z","steps":["trace[818657439] 'process raft request' (duration: 533.555547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:54.9646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:54.430877Z","time spent":"533.68971ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:29:54.964611Z","caller":"traceutil/trace.go:171","msg":"trace[1456511885] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"545.489924ms","start":"2026-04-22T16:29:54.419027Z","end":"2026-04-22T16:29:54.964517Z","steps":["trace[1456511885] 'process raft request' (duration: 545.360031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:54.964691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.798748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T16:29:54.964743Z","caller":"traceutil/trace.go:171","msg":"trace[4183130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1255; }","duration":"512.88552ms","start":"2026-04-22T16:29:54.451841Z","end":"2026-04-22T16:29:54.964727Z","steps":["trace[4183130] 'agreement among raft nodes before linearized reading' (duration: 512.733097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:54.964785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:54.451824Z","time spent":"512.953642ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T16:29:54.964869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:54.419006Z","time spent":"545.71855ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:29:55.233715Z","caller":"traceutil/trace.go:171","msg":"trace[701996452] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"269.15957ms","start":"2026-04-22T16:29:54.96452Z","end":"2026-04-22T16:29:55.233679Z","steps":["trace[701996452] 'read index received' (duration: 240.222301ms)","trace[701996452] 'applied index is now lower than readState.Index' (duration: 28.936149ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:55.233798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.700444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:29:55.233846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.084246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:29:55.233867Z","caller":"traceutil/trace.go:171","msg":"trace[532781736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1256; }","duration":"571.814946ms","start":"2026-04-22T16:29:54.662036Z","end":"2026-04-22T16:29:55.233851Z","steps":["trace[532781736] 'agreement among raft nodes before linearized reading' (duration: 571.659782ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:55.233885Z","caller":"traceutil/trace.go:171","msg":"trace[65790245] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1256; }","duration":"766.187808ms","start":"2026-04-22T16:29:54.467688Z","end":"2026-04-22T16:29:55.233875Z","steps":["trace[65790245] 'agreement among raft nodes before linearized reading' (duration: 766.092466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:55.233871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.092283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-22T16:29:55.233922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:54.467666Z","time spent":"766.24703ms","remote":"127.0.0.1:44176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-22T16:29:55.233946Z","caller":"traceutil/trace.go:171","msg":"trace[72904228] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1256; }","duration":"606.208407ms","start":"2026-04-22T16:29:54.627713Z","end":"2026-04-22T16:29:55.233922Z","steps":["trace[72904228] 'agreement among raft nodes before linearized reading' (duration: 606.005362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:55.233957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.452618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-22T16:29:55.233999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.699802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-22T16:29:55.233994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:54.627693Z","time spent":"606.292639ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-22T16:29:55.234019Z","caller":"traceutil/trace.go:171","msg":"trace[208929049] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1256; }","duration":"515.737855ms","start":"2026-04-22T16:29:54.718276Z","end":"2026-04-22T16:29:55.234014Z","steps":["trace[208929049] 'agreement among raft nodes before linearized reading' (duration: 515.640721ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:55.234016Z","caller":"traceutil/trace.go:171","msg":"trace[1237404360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1256; }","duration":"729.563832ms","start":"2026-04-22T16:29:54.504442Z","end":"2026-04-22T16:29:55.234006Z","steps":["trace[1237404360] 'agreement among raft nodes before linearized reading' (duration: 729.422807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:55.234003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.621025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T16:29:55.234106Z","caller":"traceutil/trace.go:171","msg":"trace[1576231534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1256; }","duration":"604.7614ms","start":"2026-04-22T16:29:54.629329Z","end":"2026-04-22T16:29:55.23409Z","steps":["trace[1576231534] 'agreement among raft nodes before linearized reading' (duration: 604.529713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:55.234172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:54.629312Z","time spent":"604.848962ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T16:29:55.234048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:54.504422Z","time spent":"729.622133ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T16:29:55.234051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:54.718263Z","time spent":"515.776225ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T16:29:55.2339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:54.662021Z","time spent":"571.874338ms","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T16:29:55.234173Z","caller":"traceutil/trace.go:171","msg":"trace[121841133] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"603.721793ms","start":"2026-04-22T16:29:54.630039Z","end":"2026-04-22T16:29:55.233761Z","steps":["trace[121841133] 'process raft request' (duration: 574.693211ms)","trace[121841133] 'compare' (duration: 28.832937ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:55.234836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:54.630016Z","time spent":"604.395499ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:29:55.615924Z","caller":"traceutil/trace.go:171","msg":"trace[69879769] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"647.756634ms","start":"2026-04-22T16:29:54.968155Z","end":"2026-04-22T16:29:55.615912Z","steps":["trace[69879769] 'process raft request' (duration: 647.526767ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:55.616112Z","caller":"traceutil/trace.go:171","msg":"trace[1411475977] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"376.770576ms","start":"2026-04-22T16:29:55.239326Z","end":"2026-04-22T16:29:55.616097Z","steps":["trace[1411475977] 'process raft request' (duration: 376.730635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:55.616224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:55.239316Z","time spent":"376.844357ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:29:55.61635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:54.968142Z","time spent":"647.969388ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:29:55.616485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.230592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:29:55.616524Z","caller":"traceutil/trace.go:171","msg":"trace[1155400277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1262; }","duration":"381.335885ms","start":"2026-04-22T16:29:55.235179Z","end":"2026-04-22T16:29:55.616515Z","steps":["trace[1155400277] 'agreement among raft nodes before linearized reading' (duration: 381.259543ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:55.616419Z","caller":"traceutil/trace.go:171","msg":"trace[461622065] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1315; }","duration":"381.14812ms","start":"2026-04-22T16:29:55.235258Z","end":"2026-04-22T16:29:55.616406Z","steps":["trace[461622065] 'read index received' (duration: 288.037138ms)","trace[461622065] 'applied index is now lower than readState.Index' (duration: 93.109972ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:55.616551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:55.235164Z","time spent":"381.381955ms","remote":"127.0.0.1:43988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T16:29:55.616615Z","caller":"traceutil/trace.go:171","msg":"trace[68395248] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"378.153921ms","start":"2026-04-22T16:29:55.238428Z","end":"2026-04-22T16:29:55.616582Z","steps":["trace[68395248] 'process raft request' (duration: 377.555946ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:29:55.616679Z","caller":"traceutil/trace.go:171","msg":"trace[2046175166] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"377.911814ms","start":"2026-04-22T16:29:55.238756Z","end":"2026-04-22T16:29:55.616668Z","steps":["trace[2046175166] 'process raft request' (duration: 377.261048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:55.616736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:55.238748Z","time spent":"377.956596ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:29:55.616775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:55.238416Z","time spent":"378.268324ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:29:55.617031Z","caller":"traceutil/trace.go:171","msg":"trace[1359945331] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"378.930952ms","start":"2026-04-22T16:29:55.238089Z","end":"2026-04-22T16:29:55.61702Z","steps":["trace[1359945331] 'process raft request' (duration: 377.838574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:55.617097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:55.23807Z","time spent":"378.995114ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:29:55.615837Z","caller":"traceutil/trace.go:171","msg":"trace[1127805473] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"648.152673ms","start":"2026-04-22T16:29:54.967646Z","end":"2026-04-22T16:29:55.615799Z","steps":["trace[1127805473] 'process raft request' (duration: 555.179315ms)","trace[1127805473] 'compare' (duration: 92.439054ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:55.617664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:54.967628Z","time spent":"649.989431ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:30:05.463671Z","caller":"traceutil/trace.go:171","msg":"trace[2106586278] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"482.405682ms","start":"2026-04-22T16:30:04.981239Z","end":"2026-04-22T16:30:05.463645Z","steps":["trace[2106586278] 'process raft request' (duration: 482.270897ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:30:05.463752Z","caller":"traceutil/trace.go:171","msg":"trace[1242564160] linearizableReadLoop","detail":"{readStateIndex:1387; appliedIndex:1387; }","duration":"458.966204ms","start":"2026-04-22T16:30:05.004753Z","end":"2026-04-22T16:30:05.46372Z","steps":["trace[1242564160] 'read index received' (duration: 458.946944ms)","trace[1242564160] 'applied index is now lower than readState.Index' (duration: 16.83µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:30:05.464004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.221291ms","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-04-22T16:30:05.464193Z","caller":"traceutil/trace.go:171","msg":"trace[318607732] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1326; }","duration":"459.449317ms","start":"2026-04-22T16:30:05.004728Z","end":"2026-04-22T16:30:05.464177Z","steps":["trace[318607732] 'agreement among raft nodes before linearized reading' (duration: 459.078987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:05.464246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:05.004716Z","time spent":"459.516508ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-22T16:30:05.464431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:04.981213Z","time spent":"482.867524ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:30:05.965029Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17139747686947646584,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:30:06.465517Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17139747686947646584,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:30:06.521858Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.058282863s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T16:30:06.522766Z","caller":"traceutil/trace.go:171","msg":"trace[590736688] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"1.230427511s","start":"2026-04-22T16:30:05.292315Z","end":"2026-04-22T16:30:06.522742Z","steps":["trace[590736688] 'process raft request' (duration: 1.229736522s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:30:06.523055Z","caller":"traceutil/trace.go:171","msg":"trace[854643099] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1387; }","duration":"1.05888462s","start":"2026-04-22T16:30:05.464149Z","end":"2026-04-22T16:30:06.523033Z","steps":["trace[854643099] 'read index received' (duration: 1.057917014s)","trace[854643099] 'applied index is now lower than readState.Index' (duration: 966.446µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:30:06.523298Z","caller":"traceutil/trace.go:171","msg":"trace[1789745662] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"1.054383322s","start":"2026-04-22T16:30:05.468903Z","end":"2026-04-22T16:30:06.523286Z","steps":["trace[1789745662] 'process raft request' (duration: 1.054033362s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:06.523401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:05.468885Z","time spent":"1.054442853s","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:30:06.522911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:05.292293Z","time spent":"1.230547353s","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:30:06.52364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145674956s","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-04-22T16:30:06.523673Z","caller":"traceutil/trace.go:171","msg":"trace[1245394035] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1328; }","duration":"1.145737147s","start":"2026-04-22T16:30:05.377926Z","end":"2026-04-22T16:30:06.523664Z","steps":["trace[1245394035] 'agreement among raft nodes before linearized reading' (duration: 1.145627585s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:06.523701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:05.37791Z","time spent":"1.145785288s","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-22T16:30:06.523826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.373499985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:30:06.523891Z","caller":"traceutil/trace.go:171","msg":"trace[922567537] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1328; }","duration":"1.373595587s","start":"2026-04-22T16:30:05.150278Z","end":"2026-04-22T16:30:06.523873Z","steps":["trace[922567537] 'agreement among raft nodes before linearized reading' (duration: 1.373490715s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:06.523922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.744735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"warn","ts":"2026-04-22T16:30:06.523927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:05.150268Z","time spent":"1.373650759s","remote":"127.0.0.1:37052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-04-22T16:30:06.523952Z","caller":"traceutil/trace.go:171","msg":"trace[63223673] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1328; }","duration":"247.830088ms","start":"2026-04-22T16:30:06.276112Z","end":"2026-04-22T16:30:06.523942Z","steps":["trace[63223673] 'agreement among raft nodes before linearized reading' (duration: 247.757325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:06.524025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.397517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:30:06.524045Z","caller":"traceutil/trace.go:171","msg":"trace[256541160] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1328; }","duration":"595.417037ms","start":"2026-04-22T16:30:05.928622Z","end":"2026-04-22T16:30:06.524039Z","steps":["trace[256541160] 'agreement among raft nodes before linearized reading' (duration: 595.386287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:06.523853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.054839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-22T16:30:06.524071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:05.928566Z","time spent":"595.49983ms","remote":"127.0.0.1:43976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T16:30:06.524091Z","caller":"traceutil/trace.go:171","msg":"trace[779214551] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1328; }","duration":"853.330476ms","start":"2026-04-22T16:30:05.670751Z","end":"2026-04-22T16:30:06.524082Z","steps":["trace[779214551] 'agreement among raft nodes before linearized reading' (duration: 853.027218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:06.524124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:05.670731Z","time spent":"853.380157ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T16:30:06.524243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.30186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T16:30:06.524269Z","caller":"traceutil/trace.go:171","msg":"trace[2004549940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1328; }","duration":"757.34231ms","start":"2026-04-22T16:30:05.766918Z","end":"2026-04-22T16:30:06.524261Z","steps":["trace[2004549940] 'agreement among raft nodes before linearized reading' (duration: 757.265379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:06.524293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:05.766912Z","time spent":"757.376141ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T16:30:06.524565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.977765ms","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-04-22T16:30:06.524556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.019719ms","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-04-22T16:30:06.524596Z","caller":"traceutil/trace.go:171","msg":"trace[440982930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1328; }","duration":"812.049777ms","start":"2026-04-22T16:30:05.712537Z","end":"2026-04-22T16:30:06.524587Z","steps":["trace[440982930] 'agreement among raft nodes before linearized reading' (duration: 811.940385ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:30:06.524613Z","caller":"traceutil/trace.go:171","msg":"trace[1175591440] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1328; }","duration":"760.102982ms","start":"2026-04-22T16:30:05.764498Z","end":"2026-04-22T16:30:06.524601Z","steps":["trace[1175591440] 'agreement among raft nodes before linearized reading' (duration: 759.930458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:06.524624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:05.712483Z","time spent":"812.135539ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T16:30:06.524648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:05.764484Z","time spent":"760.156733ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T16:30:06.524731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.931185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T16:30:06.524765Z","caller":"traceutil/trace.go:171","msg":"trace[339029008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1328; }","duration":"757.986516ms","start":"2026-04-22T16:30:05.766769Z","end":"2026-04-22T16:30:06.524756Z","steps":["trace[339029008] 'agreement among raft nodes before linearized reading' (duration: 757.895514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:06.524793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:30:05.766753Z","time spent":"758.034459ms","remote":"127.0.0.1:44170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T16:30:36.789566Z","caller":"traceutil/trace.go:171","msg":"trace[1645376585] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"113.862877ms","start":"2026-04-22T16:30:36.675686Z","end":"2026-04-22T16:30:36.789549Z","steps":["trace[1645376585] 'process raft request' (duration: 113.077288ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:30:40.930796Z","caller":"traceutil/trace.go:171","msg":"trace[640814518] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"125.329997ms","start":"2026-04-22T16:30:40.805436Z","end":"2026-04-22T16:30:40.930766Z","steps":["trace[640814518] 'process raft request' (duration: 125.170604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:30:41.15546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.90447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:30:41.15557Z","caller":"traceutil/trace.go:171","msg":"trace[379182370] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"220.610785ms","start":"2026-04-22T16:30:40.934946Z","end":"2026-04-22T16:30:41.155557Z","steps":["trace[379182370] 'process raft request' (duration: 100.558251ms)","trace[379182370] 'compare' (duration: 119.792407ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:30:41.155898Z","caller":"traceutil/trace.go:171","msg":"trace[206052229] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"220.820649ms","start":"2026-04-22T16:30:40.93507Z","end":"2026-04-22T16:30:41.155891Z","steps":["trace[206052229] 'process raft request' (duration: 220.45745ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:30:41.155954Z","caller":"traceutil/trace.go:171","msg":"trace[1653930483] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"219.400574ms","start":"2026-04-22T16:30:40.936527Z","end":"2026-04-22T16:30:41.155928Z","steps":["trace[1653930483] 'process raft request' (duration: 219.345882ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:30:41.156033Z","caller":"traceutil/trace.go:171","msg":"trace[519761610] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"219.898087ms","start":"2026-04-22T16:30:40.93613Z","end":"2026-04-22T16:30:41.156028Z","steps":["trace[519761610] 'process raft request' (duration: 219.607739ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:30:41.157363Z","caller":"traceutil/trace.go:171","msg":"trace[579132610] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"176.292304ms","start":"2026-04-22T16:30:40.981064Z","end":"2026-04-22T16:30:41.157357Z","steps":["trace[579132610] 'process raft request' (duration: 176.244943ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:31:52.0244Z","caller":"traceutil/trace.go:171","msg":"trace[916580645] linearizableReadLoop","detail":"{readStateIndex:1896; appliedIndex:1895; }","duration":"225.851253ms","start":"2026-04-22T16:31:51.798527Z","end":"2026-04-22T16:31:52.024379Z","steps":["trace[916580645] 'read index received' (duration: 225.672378ms)","trace[916580645] 'applied index is now lower than readState.Index' (duration: 177.975µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:31:52.024474Z","caller":"traceutil/trace.go:171","msg":"trace[1404764596] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"232.388797ms","start":"2026-04-22T16:31:51.792048Z","end":"2026-04-22T16:31:52.024437Z","steps":["trace[1404764596] 'process raft request' (duration: 232.202062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:31:52.024599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.874446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T16:31:52.024647Z","caller":"traceutil/trace.go:171","msg":"trace[1891447515] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1814; }","duration":"188.995808ms","start":"2026-04-22T16:31:51.83564Z","end":"2026-04-22T16:31:52.024636Z","steps":["trace[1891447515] 'agreement among raft nodes before linearized reading' (duration: 188.872436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:31:52.024645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.09955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-22T16:31:52.024731Z","caller":"traceutil/trace.go:171","msg":"trace[1702200708] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1814; }","duration":"226.217382ms","start":"2026-04-22T16:31:51.798493Z","end":"2026-04-22T16:31:52.02471Z","steps":["trace[1702200708] 'agreement among raft nodes before linearized reading' (duration: 226.005667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:31:52.024751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.76172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"info","ts":"2026-04-22T16:31:52.024964Z","caller":"traceutil/trace.go:171","msg":"trace[1308245239] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1814; }","duration":"145.066429ms","start":"2026-04-22T16:31:51.879871Z","end":"2026-04-22T16:31:52.024938Z","steps":["trace[1308245239] 'agreement among raft nodes before linearized reading' (duration: 144.707579ms)"],"step_count":1}