{"level":"warn","ts":"2026-04-02T16:48:26.006988Z","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-02T16:48:26.007081Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.238: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.238:2380","--initial-cluster=instance=https://199.19.213.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.238: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-02T16:48:26.007154Z","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-02T16:48:26.00717Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.238:2380"]} {"level":"info","ts":"2026-04-02T16:48:26.00719Z","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-02T16:48:26.008054Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.238:2379"]} {"level":"info","ts":"2026-04-02T16:48:26.008164Z","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.238:2380"],"listen-peer-urls":["https://199.19.213.238:2380"],"advertise-client-urls":["https://199.19.213.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.238: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-02T16:48:26.012646Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.2218ms"} {"level":"info","ts":"2026-04-02T16:48:26.019684Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8cf6035374726417","cluster-id":"e837e6e114b087e2"} {"level":"info","ts":"2026-04-02T16:48:26.019777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T16:48:26.019888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became follower at term 0"} {"level":"info","ts":"2026-04-02T16:48:26.019921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8cf6035374726417 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T16:48:26.01994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became follower at term 1"} {"level":"info","ts":"2026-04-02T16:48:26.020002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 switched to configuration voters=(10157309666551555095)"} {"level":"warn","ts":"2026-04-02T16:48:26.026834Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T16:48:26.02824Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T16:48:26.029725Z","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-02T16:48:26.032673Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8cf6035374726417","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T16:48:26.035746Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8cf6035374726417","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T16:48:26.035764Z","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-02T16:48:26.036297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 switched to configuration voters=(10157309666551555095)"} {"level":"info","ts":"2026-04-02T16:48:26.036392Z","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-02T16:48:26.03641Z","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-02T16:48:26.036924Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e837e6e114b087e2","local-member-id":"8cf6035374726417","added-peer-id":"8cf6035374726417","added-peer-peer-urls":["https://199.19.213.238:2380"]} {"level":"info","ts":"2026-04-02T16:48:26.03883Z","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-02T16:48:26.039007Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8cf6035374726417","initial-advertise-peer-urls":["https://199.19.213.238:2380"],"listen-peer-urls":["https://199.19.213.238:2380"],"advertise-client-urls":["https://199.19.213.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T16:48:26.039036Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T16:48:26.039132Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.238:2380"} {"level":"info","ts":"2026-04-02T16:48:26.039148Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.238:2380"} {"level":"info","ts":"2026-04-02T16:48:26.731975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T16:48:26.732029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T16:48:26.732044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 received MsgPreVoteResp from 8cf6035374726417 at term 1"} {"level":"info","ts":"2026-04-02T16:48:26.732058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became candidate at term 2"} {"level":"info","ts":"2026-04-02T16:48:26.732066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 received MsgVoteResp from 8cf6035374726417 at term 2"} {"level":"info","ts":"2026-04-02T16:48:26.732084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became leader at term 2"} {"level":"info","ts":"2026-04-02T16:48:26.732098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8cf6035374726417 elected leader 8cf6035374726417 at term 2"} {"level":"info","ts":"2026-04-02T16:48:26.733053Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8cf6035374726417","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.238:2379]}","request-path":"/0/members/8cf6035374726417/attributes","cluster-id":"e837e6e114b087e2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T16:48:26.733283Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:48:26.734138Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T16:48:26.734164Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T16:48:26.734214Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e837e6e114b087e2","local-member-id":"8cf6035374726417","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:48:26.734308Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:48:26.734338Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:48:26.734386Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T16:48:26.737328Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T16:48:26.734072Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T16:48:26.739243Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.238:2379"} {"level":"info","ts":"2026-04-02T16:48:56.049952Z","caller":"traceutil/trace.go:171","msg":"trace[1406717590] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"198.496754ms","start":"2026-04-02T16:48:55.85142Z","end":"2026-04-02T16:48:56.049917Z","steps":["trace[1406717590] 'process raft request' (duration: 193.607044ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:48:56.050858Z","caller":"traceutil/trace.go:171","msg":"trace[1582596099] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"196.29739ms","start":"2026-04-02T16:48:55.854536Z","end":"2026-04-02T16:48:56.050833Z","steps":["trace[1582596099] 'process raft request' (duration: 196.069615ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:48:56.051073Z","caller":"traceutil/trace.go:171","msg":"trace[542926807] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"119.707378ms","start":"2026-04-02T16:48:55.931339Z","end":"2026-04-02T16:48:56.051046Z","steps":["trace[542926807] 'process raft request' (duration: 119.625296ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:48:56.051275Z","caller":"traceutil/trace.go:171","msg":"trace[1776558467] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"187.138255ms","start":"2026-04-02T16:48:55.864121Z","end":"2026-04-02T16:48:56.05126Z","steps":["trace[1776558467] 'process raft request' (duration: 186.591271ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:48:56.051324Z","caller":"traceutil/trace.go:171","msg":"trace[1087490789] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"185.430163ms","start":"2026-04-02T16:48:55.865889Z","end":"2026-04-02T16:48:56.051319Z","steps":["trace[1087490789] 'process raft request' (duration: 184.863009ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:48:56.05137Z","caller":"traceutil/trace.go:171","msg":"trace[404823598] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"176.063782ms","start":"2026-04-02T16:48:55.875303Z","end":"2026-04-02T16:48:56.051366Z","steps":["trace[404823598] 'process raft request' (duration: 175.474538ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:48:56.051422Z","caller":"traceutil/trace.go:171","msg":"trace[1866656071] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"170.045174ms","start":"2026-04-02T16:48:55.881372Z","end":"2026-04-02T16:48:56.051417Z","steps":["trace[1866656071] 'process raft request' (duration: 169.43641ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:48:56.051472Z","caller":"traceutil/trace.go:171","msg":"trace[533576985] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"168.458657ms","start":"2026-04-02T16:48:55.883008Z","end":"2026-04-02T16:48:56.051467Z","steps":["trace[533576985] 'process raft request' (duration: 167.832692ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:48:56.05155Z","caller":"traceutil/trace.go:171","msg":"trace[247977104] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"168.359635ms","start":"2026-04-02T16:48:55.883185Z","end":"2026-04-02T16:48:56.051545Z","steps":["trace[247977104] 'process raft request' (duration: 167.679078ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:48:56.051668Z","caller":"traceutil/trace.go:171","msg":"trace[970162739] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"160.379159ms","start":"2026-04-02T16:48:55.891279Z","end":"2026-04-02T16:48:56.051658Z","steps":["trace[970162739] 'process raft request' (duration: 159.655371ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:48:56.051748Z","caller":"traceutil/trace.go:171","msg":"trace[1175907076] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"161.301131ms","start":"2026-04-02T16:48:55.890437Z","end":"2026-04-02T16:48:56.051738Z","steps":["trace[1175907076] 'process raft request' (duration: 160.46917ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:48:56.401099Z","caller":"traceutil/trace.go:171","msg":"trace[1560698582] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"223.809306ms","start":"2026-04-02T16:48:56.177263Z","end":"2026-04-02T16:48:56.401073Z","steps":["trace[1560698582] 'process raft request' (duration: 218.453775ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:48:56.402025Z","caller":"traceutil/trace.go:171","msg":"trace[1541898268] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"178.725339ms","start":"2026-04-02T16:48:56.223271Z","end":"2026-04-02T16:48:56.401996Z","steps":["trace[1541898268] 'process raft request' (duration: 178.605076ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:48:56.402246Z","caller":"traceutil/trace.go:171","msg":"trace[1933342444] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"223.055797ms","start":"2026-04-02T16:48:56.17918Z","end":"2026-04-02T16:48:56.402236Z","steps":["trace[1933342444] 'process raft request' (duration: 222.423571ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:48:56.402341Z","caller":"traceutil/trace.go:171","msg":"trace[776867215] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"221.067659ms","start":"2026-04-02T16:48:56.181262Z","end":"2026-04-02T16:48:56.402329Z","steps":["trace[776867215] 'process raft request' (duration: 220.487775ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:48:56.402591Z","caller":"traceutil/trace.go:171","msg":"trace[1552261611] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"202.880623ms","start":"2026-04-02T16:48:56.199698Z","end":"2026-04-02T16:48:56.402579Z","steps":["trace[1552261611] 'process raft request' (duration: 202.102864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:48:57.773738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.258402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:48:57.773853Z","caller":"traceutil/trace.go:171","msg":"trace[1542112027] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"857.662271ms","start":"2026-04-02T16:48:56.916177Z","end":"2026-04-02T16:48:57.773839Z","steps":["trace[1542112027] 'process raft request' (duration: 306.851176ms)","trace[1542112027] 'compare' (duration: 549.861282ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:48:57.7739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:48:56.916156Z","time spent":"857.721422ms","remote":"127.0.0.1:40500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:48:57.836909Z","caller":"traceutil/trace.go:171","msg":"trace[734406129] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:590; }","duration":"672.486933ms","start":"2026-04-02T16:48:57.164401Z","end":"2026-04-02T16:48:57.836887Z","steps":["trace[734406129] 'read index received' (duration: 58.474346ms)","trace[734406129] 'applied index is now lower than readState.Index' (duration: 614.011497ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:48:57.837002Z","caller":"traceutil/trace.go:171","msg":"trace[462860997] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"780.705911ms","start":"2026-04-02T16:48:57.056286Z","end":"2026-04-02T16:48:57.836991Z","steps":["trace[462860997] 'process raft request' (duration: 780.164968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:48:57.837083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:48:57.056266Z","time spent":"780.757723ms","remote":"127.0.0.1:40500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:48:57.837278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.847172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-02T16:48:57.837359Z","caller":"traceutil/trace.go:171","msg":"trace[1242552192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"673.000836ms","start":"2026-04-02T16:48:57.164338Z","end":"2026-04-02T16:48:57.837338Z","steps":["trace[1242552192] 'agreement among raft nodes before linearized reading' (duration: 672.705889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:48:57.837404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:48:57.164316Z","time spent":"673.079558ms","remote":"127.0.0.1:40734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T16:48:57.844589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.890011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:48:57.844773Z","caller":"traceutil/trace.go:171","msg":"trace[66056194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"420.111606ms","start":"2026-04-02T16:48:57.424626Z","end":"2026-04-02T16:48:57.844737Z","steps":["trace[66056194] 'agreement among raft nodes before linearized reading' (duration: 419.83119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:48:57.844821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:48:57.424607Z","time spent":"420.201368ms","remote":"127.0.0.1:40436","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-02T16:49:02.393076Z","caller":"traceutil/trace.go:171","msg":"trace[180497046] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"170.207ms","start":"2026-04-02T16:49:02.222831Z","end":"2026-04-02T16:49:02.393038Z","steps":["trace[180497046] 'process raft request' (duration: 169.248567ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:02.71967Z","caller":"traceutil/trace.go:171","msg":"trace[1019675205] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"266.581836ms","start":"2026-04-02T16:49:02.453064Z","end":"2026-04-02T16:49:02.719646Z","steps":["trace[1019675205] 'process raft request' (duration: 266.527444ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:02.719738Z","caller":"traceutil/trace.go:171","msg":"trace[1852024778] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"430.259166ms","start":"2026-04-02T16:49:02.289436Z","end":"2026-04-02T16:49:02.719695Z","steps":["trace[1852024778] 'process raft request' (duration: 401.040238ms)","trace[1852024778] 'compare' (duration: 28.954922ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:49:02.719922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:49:02.28939Z","time spent":"430.43276ms","remote":"127.0.0.1:40734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:49:03.926892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.435023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:49:03.92709Z","caller":"traceutil/trace.go:171","msg":"trace[2073884675] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"1.130931051s","start":"2026-04-02T16:49:02.796106Z","end":"2026-04-02T16:49:03.927037Z","steps":["trace[2073884675] 'process raft request' (duration: 223.242602ms)","trace[2073884675] 'compare' (duration: 907.079545ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:49:03.92725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:49:02.796096Z","time spent":"1.131066634s","remote":"127.0.0.1:40632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:49:03.927224Z","caller":"traceutil/trace.go:171","msg":"trace[1130114759] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"1.118815914s","start":"2026-04-02T16:49:02.808374Z","end":"2026-04-02T16:49:03.92719Z","steps":["trace[1130114759] 'read index received' (duration: 210.977501ms)","trace[1130114759] 'applied index is now lower than readState.Index' (duration: 907.834713ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:49:03.927575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119180243s","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-02T16:49:03.927683Z","caller":"traceutil/trace.go:171","msg":"trace[1544246714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"1.119342747s","start":"2026-04-02T16:49:02.808314Z","end":"2026-04-02T16:49:03.927657Z","steps":["trace[1544246714] 'agreement among raft nodes before linearized reading' (duration: 1.118993089s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:03.927753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:49:02.808296Z","time spent":"1.119448169s","remote":"127.0.0.1:40734","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-02T16:49:03.931271Z","caller":"traceutil/trace.go:171","msg":"trace[957250281] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"786.693948ms","start":"2026-04-02T16:49:03.144551Z","end":"2026-04-02T16:49:03.931245Z","steps":["trace[957250281] 'process raft request' (duration: 782.604198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:03.931283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118236369s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T16:49:03.931335Z","caller":"traceutil/trace.go:171","msg":"trace[938744286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"1.118330701s","start":"2026-04-02T16:49:02.812987Z","end":"2026-04-02T16:49:03.931318Z","steps":["trace[938744286] 'agreement among raft nodes before linearized reading' (duration: 1.118165047s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:03.931364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:49:02.81296Z","time spent":"1.118398593s","remote":"127.0.0.1:40734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T16:49:03.931389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:49:03.144512Z","time spent":"786.802541ms","remote":"127.0.0.1:40554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:49:03.931531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.904012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:49:03.931728Z","caller":"traceutil/trace.go:171","msg":"trace[353480002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"506.165699ms","start":"2026-04-02T16:49:03.425557Z","end":"2026-04-02T16:49:03.931723Z","steps":["trace[353480002] 'agreement among raft nodes before linearized reading' (duration: 505.955484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:03.931747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:49:03.425522Z","time spent":"506.21936ms","remote":"127.0.0.1:40436","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-02T16:49:05.158582Z","caller":"traceutil/trace.go:171","msg":"trace[383175205] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"242.288069ms","start":"2026-04-02T16:49:04.916259Z","end":"2026-04-02T16:49:05.158547Z","steps":["trace[383175205] 'process raft request' (duration: 231.120005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:20.251526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.620635ms","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-02T16:49:20.251554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.081787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qbtll\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-02T16:49:20.251602Z","caller":"traceutil/trace.go:171","msg":"trace[2041694543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:781; }","duration":"191.730557ms","start":"2026-04-02T16:49:20.05985Z","end":"2026-04-02T16:49:20.251581Z","steps":["trace[2041694543] 'range keys from in-memory index tree' (duration: 191.41374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:20.251567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.691061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-02T16:49:20.25174Z","caller":"traceutil/trace.go:171","msg":"trace[1172317982] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:781; }","duration":"192.937217ms","start":"2026-04-02T16:49:20.058768Z","end":"2026-04-02T16:49:20.251705Z","steps":["trace[1172317982] 'range keys from in-memory index tree' (duration: 192.451056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:20.251621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.923495ms","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-02T16:49:20.251843Z","caller":"traceutil/trace.go:171","msg":"trace[650419920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:781; }","duration":"188.200621ms","start":"2026-04-02T16:49:20.063631Z","end":"2026-04-02T16:49:20.251832Z","steps":["trace[650419920] 'range keys from in-memory index tree' (duration: 187.74831ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:20.25164Z","caller":"traceutil/trace.go:171","msg":"trace[346123358] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qbtll; range_end:; response_count:1; response_revision:781; }","duration":"372.21423ms","start":"2026-04-02T16:49:19.879409Z","end":"2026-04-02T16:49:20.251623Z","steps":["trace[346123358] 'range keys from in-memory index tree' (duration: 371.864771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:20.251922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:49:19.879388Z","time spent":"372.523047ms","remote":"127.0.0.1:40632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qbtll\" "} {"level":"info","ts":"2026-04-02T16:49:20.47157Z","caller":"traceutil/trace.go:171","msg":"trace[1660421098] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"215.176394ms","start":"2026-04-02T16:49:20.25635Z","end":"2026-04-02T16:49:20.471527Z","steps":["trace[1660421098] 'process raft request' (duration: 214.982999ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:20.475268Z","caller":"traceutil/trace.go:171","msg":"trace[1206948797] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"217.961843ms","start":"2026-04-02T16:49:20.257281Z","end":"2026-04-02T16:49:20.475243Z","steps":["trace[1206948797] 'process raft request' (duration: 217.785418ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:20.475651Z","caller":"traceutil/trace.go:171","msg":"trace[1440910073] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"217.756407ms","start":"2026-04-02T16:49:20.257876Z","end":"2026-04-02T16:49:20.475633Z","steps":["trace[1440910073] 'process raft request' (duration: 217.315957ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:20.475711Z","caller":"traceutil/trace.go:171","msg":"trace[1957215317] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"213.560803ms","start":"2026-04-02T16:49:20.262125Z","end":"2026-04-02T16:49:20.475686Z","steps":["trace[1957215317] 'process raft request' (duration: 213.329968ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:55.186956Z","caller":"traceutil/trace.go:171","msg":"trace[686202714] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"123.478764ms","start":"2026-04-02T16:49:55.063456Z","end":"2026-04-02T16:49:55.186935Z","steps":["trace[686202714] 'process raft request' (duration: 123.32965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:55.387049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.538823ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-02T16:49:55.387159Z","caller":"traceutil/trace.go:171","msg":"trace[1796544325] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1026; }","duration":"210.719883ms","start":"2026-04-02T16:49:55.17642Z","end":"2026-04-02T16:49:55.38714Z","steps":["trace[1796544325] 'read index received' (duration: 10.349933ms)","trace[1796544325] 'applied index is now lower than readState.Index' (duration: 200.36836ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:49:55.387255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.830125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T16:49:55.387291Z","caller":"traceutil/trace.go:171","msg":"trace[424926841] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:996; }","duration":"210.926647ms","start":"2026-04-02T16:49:55.176354Z","end":"2026-04-02T16:49:55.38728Z","steps":["trace[424926841] 'agreement among raft nodes before linearized reading' (duration: 210.834675ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:49:56.160176Z","caller":"traceutil/trace.go:171","msg":"trace[46379865] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"457.744192ms","start":"2026-04-02T16:49:55.70241Z","end":"2026-04-02T16:49:56.160154Z","steps":["trace[46379865] 'process raft request' (duration: 457.569478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:49:56.160327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:49:55.702361Z","time spent":"457.887495ms","remote":"127.0.0.1:40734","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-02T16:49:56.614044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.482063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:49:56.614129Z","caller":"traceutil/trace.go:171","msg":"trace[815910312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:997; }","duration":"188.624667ms","start":"2026-04-02T16:49:56.425483Z","end":"2026-04-02T16:49:56.614108Z","steps":["trace[815910312] 'range keys from in-memory index tree' (duration: 188.401322ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:50:19.601889Z","caller":"traceutil/trace.go:171","msg":"trace[1540834608] linearizableReadLoop","detail":"{readStateIndex:1110; appliedIndex:1109; }","duration":"261.688028ms","start":"2026-04-02T16:50:19.340176Z","end":"2026-04-02T16:50:19.601864Z","steps":["trace[1540834608] 'read index received' (duration: 261.414102ms)","trace[1540834608] 'applied index is now lower than readState.Index' (duration: 272.546µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:50:19.602004Z","caller":"traceutil/trace.go:171","msg":"trace[1155652528] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"291.849976ms","start":"2026-04-02T16:50:19.31012Z","end":"2026-04-02T16:50:19.60197Z","steps":["trace[1155652528] 'process raft request' (duration: 291.57783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:19.602136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.951614ms","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-02T16:50:19.602174Z","caller":"traceutil/trace.go:171","msg":"trace[960477730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1073; }","duration":"262.058296ms","start":"2026-04-02T16:50:19.340106Z","end":"2026-04-02T16:50:19.602164Z","steps":["trace[960477730] 'agreement among raft nodes before linearized reading' (duration: 261.913143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:19.602207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.027339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:50:19.602263Z","caller":"traceutil/trace.go:171","msg":"trace[1907206134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1073; }","duration":"170.122291ms","start":"2026-04-02T16:50:19.432123Z","end":"2026-04-02T16:50:19.602246Z","steps":["trace[1907206134] 'agreement among raft nodes before linearized reading' (duration: 170.028449ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:50:19.899458Z","caller":"traceutil/trace.go:171","msg":"trace[762439750] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1110; }","duration":"241.361163ms","start":"2026-04-02T16:50:19.658066Z","end":"2026-04-02T16:50:19.899427Z","steps":["trace[762439750] 'read index received' (duration: 217.324023ms)","trace[762439750] 'applied index is now lower than readState.Index' (duration: 24.03596ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:50:19.899532Z","caller":"traceutil/trace.go:171","msg":"trace[1576381764] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"290.997797ms","start":"2026-04-02T16:50:19.608506Z","end":"2026-04-02T16:50:19.899504Z","steps":["trace[1576381764] 'process raft request' (duration: 266.927536ms)","trace[1576381764] 'compare' (duration: 23.830455ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:50:19.899665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.584518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:50:19.899736Z","caller":"traceutil/trace.go:171","msg":"trace[730329825] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1074; }","duration":"241.741842ms","start":"2026-04-02T16:50:19.657977Z","end":"2026-04-02T16:50:19.899719Z","steps":["trace[730329825] 'agreement among raft nodes before linearized reading' (duration: 241.605459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:19.900532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.414986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-02T16:50:19.900586Z","caller":"traceutil/trace.go:171","msg":"trace[1999140963] range","detail":"{range_begin:/registry/masterleases/199.19.213.238; range_end:; response_count:1; response_revision:1074; }","duration":"238.512648ms","start":"2026-04-02T16:50:19.662061Z","end":"2026-04-02T16:50:19.900574Z","steps":["trace[1999140963] 'agreement among raft nodes before linearized reading' (duration: 238.335894ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:50:20.893488Z","caller":"traceutil/trace.go:171","msg":"trace[1798267146] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"337.064252ms","start":"2026-04-02T16:50:20.556405Z","end":"2026-04-02T16:50:20.893469Z","steps":["trace[1798267146] 'process raft request' (duration: 336.918758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:20.893594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:20.556383Z","time spent":"337.155033ms","remote":"127.0.0.1:40734","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-02T16:50:21.292057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.811722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:50:21.292194Z","caller":"traceutil/trace.go:171","msg":"trace[1838239253] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"393.9364ms","start":"2026-04-02T16:50:20.898224Z","end":"2026-04-02T16:50:21.29216Z","steps":["trace[1838239253] 'process raft request' (duration: 285.943613ms)","trace[1838239253] 'compare' (duration: 107.423023ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:50:21.292384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:20.898203Z","time spent":"394.112444ms","remote":"127.0.0.1:40734","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-02T16:50:21.301437Z","caller":"traceutil/trace.go:171","msg":"trace[1967254640] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"401.876868ms","start":"2026-04-02T16:50:20.899547Z","end":"2026-04-02T16:50:21.301423Z","steps":["trace[1967254640] 'process raft request' (duration: 401.849207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:21.301509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:20.899529Z","time spent":"401.95216ms","remote":"127.0.0.1:40734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:50:21.301512Z","caller":"traceutil/trace.go:171","msg":"trace[147068950] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"403.031343ms","start":"2026-04-02T16:50:20.898455Z","end":"2026-04-02T16:50:21.301487Z","steps":["trace[147068950] 'process raft request' (duration: 402.87339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:21.301689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:20.898425Z","time spent":"403.168827ms","remote":"127.0.0.1:40734","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-02T16:50:22.508018Z","caller":"traceutil/trace.go:171","msg":"trace[179988021] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"153.667952ms","start":"2026-04-02T16:50:22.354311Z","end":"2026-04-02T16:50:22.507979Z","steps":["trace[179988021] 'process raft request' (duration: 153.384705ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:50:34.088666Z","caller":"traceutil/trace.go:171","msg":"trace[946947395] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"113.48525ms","start":"2026-04-02T16:50:33.975158Z","end":"2026-04-02T16:50:34.088643Z","steps":["trace[946947395] 'process raft request' (duration: 112.912877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:36.760797Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.267353089s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T16:50:36.766031Z","caller":"traceutil/trace.go:171","msg":"trace[1164284294] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1218; }","duration":"1.368796618s","start":"2026-04-02T16:50:35.397213Z","end":"2026-04-02T16:50:36.766009Z","steps":["trace[1164284294] 'read index received' (duration: 96.253602ms)","trace[1164284294] 'applied index is now lower than readState.Index' (duration: 1.272541956s)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:50:36.766159Z","caller":"traceutil/trace.go:171","msg":"trace[449819586] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"1.369457964s","start":"2026-04-02T16:50:35.396686Z","end":"2026-04-02T16:50:36.766144Z","steps":["trace[449819586] 'process raft request' (duration: 1.369026794s)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:50:36.766162Z","caller":"traceutil/trace.go:171","msg":"trace[185990614] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"1.092808879s","start":"2026-04-02T16:50:35.673328Z","end":"2026-04-02T16:50:36.766137Z","steps":["trace[185990614] 'process raft request' (duration: 1.092613214s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:36.766261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:35.396673Z","time spent":"1.369519805s","remote":"127.0.0.1:40734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:50:36.766292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:35.673306Z","time spent":"1.092901211s","remote":"127.0.0.1:40734","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-02T16:50:36.76656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.36934484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:50:36.766601Z","caller":"traceutil/trace.go:171","msg":"trace[1983202601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1178; }","duration":"1.369410993s","start":"2026-04-02T16:50:35.397179Z","end":"2026-04-02T16:50:36.76659Z","steps":["trace[1983202601] 'agreement among raft nodes before linearized reading' (duration: 1.36934957s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:36.766633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:35.397167Z","time spent":"1.369460283s","remote":"127.0.0.1:40436","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-02T16:50:36.766863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356175935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T16:50:36.766913Z","caller":"traceutil/trace.go:171","msg":"trace[276267461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1178; }","duration":"1.356231957s","start":"2026-04-02T16:50:35.410667Z","end":"2026-04-02T16:50:36.766899Z","steps":["trace[276267461] 'agreement among raft nodes before linearized reading' (duration: 1.356096944s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:36.766951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:35.41066Z","time spent":"1.356283358s","remote":"127.0.0.1:40734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T16:50:36.766982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.910269ms","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-02T16:50:36.767022Z","caller":"traceutil/trace.go:171","msg":"trace[1837930620] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1178; }","duration":"673.974421ms","start":"2026-04-02T16:50:36.093038Z","end":"2026-04-02T16:50:36.767012Z","steps":["trace[1837930620] 'agreement among raft nodes before linearized reading' (duration: 673.858998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:36.767054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:36.093025Z","time spent":"674.023062ms","remote":"127.0.0.1:40734","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-02T16:50:36.767102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.435584ms","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-02T16:50:36.767141Z","caller":"traceutil/trace.go:171","msg":"trace[79176027] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1178; }","duration":"821.514166ms","start":"2026-04-02T16:50:35.945616Z","end":"2026-04-02T16:50:36.767131Z","steps":["trace[79176027] 'agreement among raft nodes before linearized reading' (duration: 821.456594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:36.767176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:35.945597Z","time spent":"821.571106ms","remote":"127.0.0.1:47202","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":"warn","ts":"2026-04-02T16:50:36.76724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.567035ms","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-02T16:50:36.767275Z","caller":"traceutil/trace.go:171","msg":"trace[1198887038] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1178; }","duration":"167.624716ms","start":"2026-04-02T16:50:36.599642Z","end":"2026-04-02T16:50:36.767267Z","steps":["trace[1198887038] 'agreement among raft nodes before linearized reading' (duration: 167.536054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:36.767307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343157383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:50:36.767342Z","caller":"traceutil/trace.go:171","msg":"trace[1785335672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1178; }","duration":"1.343229615s","start":"2026-04-02T16:50:35.424104Z","end":"2026-04-02T16:50:36.767333Z","steps":["trace[1785335672] 'agreement among raft nodes before linearized reading' (duration: 1.343183204s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:36.767372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:35.424084Z","time spent":"1.343282786s","remote":"127.0.0.1:40450","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-02T16:50:48.987211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.104803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:50:48.987371Z","caller":"traceutil/trace.go:171","msg":"trace[1733939258] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1260; }","duration":"157.564755ms","start":"2026-04-02T16:50:48.829788Z","end":"2026-04-02T16:50:48.987353Z","steps":["trace[1733939258] 'read index received' (duration: 2.464996ms)","trace[1733939258] 'applied index is now lower than readState.Index' (duration: 155.098499ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:50:48.987437Z","caller":"traceutil/trace.go:171","msg":"trace[512776975] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"168.882508ms","start":"2026-04-02T16:50:48.818531Z","end":"2026-04-02T16:50:48.987414Z","steps":["trace[512776975] 'process raft request' (duration: 53.470209ms)","trace[512776975] 'compare' (duration: 114.912408ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:50:48.987553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.749699ms","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-02T16:50:48.987618Z","caller":"traceutil/trace.go:171","msg":"trace[326986658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1217; }","duration":"157.852241ms","start":"2026-04-02T16:50:48.829753Z","end":"2026-04-02T16:50:48.987605Z","steps":["trace[326986658] 'agreement among raft nodes before linearized reading' (duration: 157.679567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:48.987683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.673283ms","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-02T16:50:48.987731Z","caller":"traceutil/trace.go:171","msg":"trace[117601649] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1217; }","duration":"143.733814ms","start":"2026-04-02T16:50:48.843984Z","end":"2026-04-02T16:50:48.987718Z","steps":["trace[117601649] 'agreement among raft nodes before linearized reading' (duration: 143.634512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:48.987546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.88899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T16:50:48.988074Z","caller":"traceutil/trace.go:171","msg":"trace[2077757405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1217; }","duration":"153.442583ms","start":"2026-04-02T16:50:48.83462Z","end":"2026-04-02T16:50:48.988062Z","steps":["trace[2077757405] 'agreement among raft nodes before linearized reading' (duration: 152.826169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:52.93515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.544964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:50:52.93524Z","caller":"traceutil/trace.go:171","msg":"trace[613759929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1249; }","duration":"510.712178ms","start":"2026-04-02T16:50:52.424509Z","end":"2026-04-02T16:50:52.935221Z","steps":["trace[613759929] 'range keys from in-memory index tree' (duration: 510.424171ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:52.935285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:52.424494Z","time spent":"510.782319ms","remote":"127.0.0.1:40450","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-02T16:50:53.09663Z","caller":"traceutil/trace.go:171","msg":"trace[1419296666] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"228.116308ms","start":"2026-04-02T16:50:52.868494Z","end":"2026-04-02T16:50:53.09661Z","steps":["trace[1419296666] 'process raft request' (duration: 227.838232ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:50:53.58755Z","caller":"traceutil/trace.go:171","msg":"trace[1566230193] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"481.994744ms","start":"2026-04-02T16:50:53.105527Z","end":"2026-04-02T16:50:53.587522Z","steps":["trace[1566230193] 'process raft request' (duration: 481.8284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:53.587714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:53.105503Z","time spent":"482.126357ms","remote":"127.0.0.1:40734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:50:53.711913Z","caller":"traceutil/trace.go:171","msg":"trace[1965226964] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"605.805792ms","start":"2026-04-02T16:50:53.106077Z","end":"2026-04-02T16:50:53.711883Z","steps":["trace[1965226964] 'process raft request' (duration: 527.626199ms)","trace[1965226964] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-controller-manager; req_size:485; } (duration: 77.989589ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:50:53.71209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:53.10607Z","time spent":"605.927704ms","remote":"127.0.0.1:40734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":488,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:50:53.712254Z","caller":"traceutil/trace.go:171","msg":"trace[1310745001] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"606.055928ms","start":"2026-04-02T16:50:53.106172Z","end":"2026-04-02T16:50:53.712228Z","steps":["trace[1310745001] 'process raft request' (duration: 605.657519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:53.712397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:53.106156Z","time spent":"606.16578ms","remote":"127.0.0.1:40734","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-02T16:50:53.712543Z","caller":"traceutil/trace.go:171","msg":"trace[1064131744] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1296; }","duration":"286.502079ms","start":"2026-04-02T16:50:53.426028Z","end":"2026-04-02T16:50:53.71253Z","steps":["trace[1064131744] 'read index received' (duration: 161.31502ms)","trace[1064131744] 'applied index is now lower than readState.Index' (duration: 125.185339ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:50:53.712651Z","caller":"traceutil/trace.go:171","msg":"trace[376817614] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"606.071659ms","start":"2026-04-02T16:50:53.10655Z","end":"2026-04-02T16:50:53.712622Z","steps":["trace[376817614] 'process raft request' (duration: 605.602699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:53.712693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.666692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:50:53.712728Z","caller":"traceutil/trace.go:171","msg":"trace[411895266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1255; }","duration":"286.742824ms","start":"2026-04-02T16:50:53.425975Z","end":"2026-04-02T16:50:53.712718Z","steps":["trace[411895266] 'agreement among raft nodes before linearized reading' (duration: 286.657322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:53.712792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:53.10654Z","time spent":"606.180071ms","remote":"127.0.0.1:40734","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-02T16:50:54.623268Z","caller":"traceutil/trace.go:171","msg":"trace[1869715499] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"356.241302ms","start":"2026-04-02T16:50:54.266991Z","end":"2026-04-02T16:50:54.623232Z","steps":["trace[1869715499] 'process raft request' (duration: 356.002687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:54.623436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:54.266958Z","time spent":"356.397896ms","remote":"127.0.0.1:40536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:50:54.623475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.094936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:50:54.62356Z","caller":"traceutil/trace.go:171","msg":"trace[223049872] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1257; }","duration":"268.255819ms","start":"2026-04-02T16:50:54.355291Z","end":"2026-04-02T16:50:54.623546Z","steps":["trace[223049872] 'agreement among raft nodes before linearized reading' (duration: 268.044205ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:50:54.623261Z","caller":"traceutil/trace.go:171","msg":"trace[856434141] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"267.84778ms","start":"2026-04-02T16:50:54.355333Z","end":"2026-04-02T16:50:54.623181Z","steps":["trace[856434141] 'read index received' (duration: 267.617435ms)","trace[856434141] 'applied index is now lower than readState.Index' (duration: 228.855µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:50:55.020016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.236936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:50:55.020184Z","caller":"traceutil/trace.go:171","msg":"trace[1401363792] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"348.322575ms","start":"2026-04-02T16:50:54.671815Z","end":"2026-04-02T16:50:55.020138Z","steps":["trace[1401363792] 'read index received' (duration: 65.502µs)","trace[1401363792] 'applied index is now lower than readState.Index' (duration: 348.255563ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:50:55.020413Z","caller":"traceutil/trace.go:171","msg":"trace[1037689029] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"390.537503ms","start":"2026-04-02T16:50:54.629861Z","end":"2026-04-02T16:50:55.020399Z","steps":["trace[1037689029] 'process raft request' (duration: 15.820035ms)","trace[1037689029] 'compare' (duration: 373.873888ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:50:55.020508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:54.629842Z","time spent":"390.601875ms","remote":"127.0.0.1:40734","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-02T16:50:55.020944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.098273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:50:55.020985Z","caller":"traceutil/trace.go:171","msg":"trace[655505978] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1258; }","duration":"349.175135ms","start":"2026-04-02T16:50:54.671796Z","end":"2026-04-02T16:50:55.020971Z","steps":["trace[655505978] 'agreement among raft nodes before linearized reading' (duration: 349.045022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:55.021054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:54.671723Z","time spent":"349.318668ms","remote":"127.0.0.1:47320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-02T16:50:55.411101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.136409ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-02T16:50:55.41151Z","caller":"traceutil/trace.go:171","msg":"trace[1162612414] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1304; }","duration":"391.278889ms","start":"2026-04-02T16:50:55.020213Z","end":"2026-04-02T16:50:55.411492Z","steps":["trace[1162612414] 'read index received' (duration: 6.905505ms)","trace[1162612414] 'applied index is now lower than readState.Index' (duration: 384.372234ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:50:55.411524Z","caller":"traceutil/trace.go:171","msg":"trace[478172127] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"674.840832ms","start":"2026-04-02T16:50:54.736657Z","end":"2026-04-02T16:50:55.411498Z","steps":["trace[478172127] 'process raft request' (duration: 674.563335ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:50:55.411594Z","caller":"traceutil/trace.go:171","msg":"trace[1186354319] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"612.758919ms","start":"2026-04-02T16:50:54.798824Z","end":"2026-04-02T16:50:55.411583Z","steps":["trace[1186354319] 'process raft request' (duration: 612.592416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:55.411634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:54.736633Z","time spent":"674.949744ms","remote":"127.0.0.1:40536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:50:55.411682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:54.798793Z","time spent":"612.82545ms","remote":"127.0.0.1:40734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:50:55.411841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.390514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T16:50:55.411918Z","caller":"traceutil/trace.go:171","msg":"trace[1155422683] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1260; }","duration":"511.490566ms","start":"2026-04-02T16:50:54.900409Z","end":"2026-04-02T16:50:55.411899Z","steps":["trace[1155422683] 'agreement among raft nodes before linearized reading' (duration: 511.329803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:55.411972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:54.900356Z","time spent":"511.606739ms","remote":"127.0.0.1:40966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-02T16:50:55.558603Z","caller":"traceutil/trace.go:171","msg":"trace[604778365] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1307; }","duration":"147.034479ms","start":"2026-04-02T16:50:55.411549Z","end":"2026-04-02T16:50:55.558584Z","steps":["trace[604778365] 'read index received' (duration: 139.459549ms)","trace[604778365] 'applied index is now lower than readState.Index' (duration: 7.57398ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:50:55.558715Z","caller":"traceutil/trace.go:171","msg":"trace[1585951924] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"451.139202ms","start":"2026-04-02T16:50:55.107547Z","end":"2026-04-02T16:50:55.558686Z","steps":["trace[1585951924] 'process raft request' (duration: 450.986719ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:50:55.558747Z","caller":"traceutil/trace.go:171","msg":"trace[103934675] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"525.512331ms","start":"2026-04-02T16:50:55.033202Z","end":"2026-04-02T16:50:55.558714Z","steps":["trace[103934675] 'process raft request' (duration: 517.794827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:55.558817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.716246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-02T16:50:55.558844Z","caller":"traceutil/trace.go:171","msg":"trace[1014460776] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1262; }","duration":"525.784228ms","start":"2026-04-02T16:50:55.033052Z","end":"2026-04-02T16:50:55.558837Z","steps":["trace[1014460776] 'agreement among raft nodes before linearized reading' (duration: 525.639534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:55.558864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:55.033185Z","time spent":"525.621014ms","remote":"127.0.0.1:40536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:50:55.558873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:55.033039Z","time spent":"525.826078ms","remote":"127.0.0.1:40734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-02T16:50:55.558878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:55.107529Z","time spent":"451.254275ms","remote":"127.0.0.1:40734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:50:55.573966Z","caller":"traceutil/trace.go:171","msg":"trace[1724863117] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"158.158128ms","start":"2026-04-02T16:50:55.415775Z","end":"2026-04-02T16:50:55.573933Z","steps":["trace[1724863117] 'process raft request' (duration: 158.008054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:55.573997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.513009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:50:55.574304Z","caller":"traceutil/trace.go:171","msg":"trace[1023427496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1263; }","duration":"151.837967ms","start":"2026-04-02T16:50:55.422422Z","end":"2026-04-02T16:50:55.574259Z","steps":["trace[1023427496] 'agreement among raft nodes before linearized reading' (duration: 151.495239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:55.818689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.999955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:50:55.818807Z","caller":"traceutil/trace.go:171","msg":"trace[1445320783] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"101.307044ms","start":"2026-04-02T16:50:55.717479Z","end":"2026-04-02T16:50:55.818786Z","steps":["trace[1445320783] 'read index received' (duration: 66.572µs)","trace[1445320783] 'applied index is now lower than readState.Index' (duration: 101.239092ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:50:55.818888Z","caller":"traceutil/trace.go:171","msg":"trace[1161383842] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"172.697575ms","start":"2026-04-02T16:50:55.646154Z","end":"2026-04-02T16:50:55.818851Z","steps":["trace[1161383842] 'process raft request' (duration: 67.389832ms)","trace[1161383842] 'compare' (duration: 104.812761ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:50:55.819134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.635001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:503"} {"level":"warn","ts":"2026-04-02T16:50:55.819163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.606201ms","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-02T16:50:55.819183Z","caller":"traceutil/trace.go:171","msg":"trace[1534843484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1265; }","duration":"101.696482ms","start":"2026-04-02T16:50:55.717472Z","end":"2026-04-02T16:50:55.819169Z","steps":["trace[1534843484] 'agreement among raft nodes before linearized reading' (duration: 101.510838ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:50:55.819205Z","caller":"traceutil/trace.go:171","msg":"trace[543327610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1265; }","duration":"101.661192ms","start":"2026-04-02T16:50:55.717532Z","end":"2026-04-02T16:50:55.819193Z","steps":["trace[543327610] 'agreement among raft nodes before linearized reading' (duration: 101.502608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:55.819303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.050997ms","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-02T16:50:55.819339Z","caller":"traceutil/trace.go:171","msg":"trace[1394246062] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1265; }","duration":"101.090548ms","start":"2026-04-02T16:50:55.718238Z","end":"2026-04-02T16:50:55.819329Z","steps":["trace[1394246062] 'agreement among raft nodes before linearized reading' (duration: 100.983315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:56.505923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.870368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:50:56.506036Z","caller":"traceutil/trace.go:171","msg":"trace[1250011833] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"682.31237ms","start":"2026-04-02T16:50:55.823704Z","end":"2026-04-02T16:50:56.506016Z","steps":["trace[1250011833] 'process raft request' (duration: 378.249388ms)","trace[1250011833] 'compare' (duration: 303.743195ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:50:56.506102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:55.823673Z","time spent":"682.397821ms","remote":"127.0.0.1:40734","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-02T16:50:56.52192Z","caller":"traceutil/trace.go:171","msg":"trace[1855317169] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"697.765406ms","start":"2026-04-02T16:50:55.824128Z","end":"2026-04-02T16:50:56.521894Z","steps":["trace[1855317169] 'process raft request' (duration: 697.179402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:56.522088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:55.824093Z","time spent":"697.91216ms","remote":"127.0.0.1:40734","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-02T16:50:56.522255Z","caller":"traceutil/trace.go:171","msg":"trace[744472602] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"698.081823ms","start":"2026-04-02T16:50:55.824159Z","end":"2026-04-02T16:50:56.522241Z","steps":["trace[744472602] 'process raft request' (duration: 697.257454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:56.522329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:55.824147Z","time spent":"698.132605ms","remote":"127.0.0.1:40734","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-02T16:50:56.522467Z","caller":"traceutil/trace.go:171","msg":"trace[1106373452] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"697.080941ms","start":"2026-04-02T16:50:55.825374Z","end":"2026-04-02T16:50:56.522455Z","steps":["trace[1106373452] 'process raft request' (duration: 696.079268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:50:56.522568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:50:55.825361Z","time spent":"697.175753ms","remote":"127.0.0.1:40734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:51:00.630627Z","caller":"traceutil/trace.go:171","msg":"trace[1210369910] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"108.714219ms","start":"2026-04-02T16:51:00.521887Z","end":"2026-04-02T16:51:00.630601Z","steps":["trace[1210369910] 'process raft request' (duration: 108.520804ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:51:07.000145Z","caller":"traceutil/trace.go:171","msg":"trace[1940971645] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1388; }","duration":"335.674542ms","start":"2026-04-02T16:51:06.66444Z","end":"2026-04-02T16:51:07.000115Z","steps":["trace[1940971645] 'read index received' (duration: 335.485737ms)","trace[1940971645] 'applied index is now lower than readState.Index' (duration: 186.865µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:51:07.000216Z","caller":"traceutil/trace.go:171","msg":"trace[1598707585] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"340.238484ms","start":"2026-04-02T16:51:06.659936Z","end":"2026-04-02T16:51:07.000175Z","steps":["trace[1598707585] 'process raft request' (duration: 339.980518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:51:07.000429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.002819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-02T16:51:07.000456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.448153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-02T16:51:07.000467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:51:06.659916Z","time spent":"340.370397ms","remote":"127.0.0.1:40734","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-02T16:51:07.000512Z","caller":"traceutil/trace.go:171","msg":"trace[624749190] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1340; }","duration":"336.119792ms","start":"2026-04-02T16:51:06.664368Z","end":"2026-04-02T16:51:07.000488Z","steps":["trace[624749190] 'agreement among raft nodes before linearized reading' (duration: 335.897297ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:51:07.000515Z","caller":"traceutil/trace.go:171","msg":"trace[419815216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1340; }","duration":"334.551556ms","start":"2026-04-02T16:51:06.665945Z","end":"2026-04-02T16:51:07.000496Z","steps":["trace[419815216] 'agreement among raft nodes before linearized reading' (duration: 334.358242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:51:07.000556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:51:06.66432Z","time spent":"336.228014ms","remote":"127.0.0.1:40734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T16:51:07.000572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:51:06.665928Z","time spent":"334.630877ms","remote":"127.0.0.1:40734","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-02T16:51:07.000925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.636918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T16:51:07.001092Z","caller":"traceutil/trace.go:171","msg":"trace[913510438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1340; }","duration":"334.861053ms","start":"2026-04-02T16:51:06.666212Z","end":"2026-04-02T16:51:07.001073Z","steps":["trace[913510438] 'agreement among raft nodes before linearized reading' (duration: 334.199828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:51:07.001275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:51:06.666207Z","time spent":"335.049857ms","remote":"127.0.0.1:40734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T16:51:07.834601Z","caller":"traceutil/trace.go:171","msg":"trace[604234918] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"829.320197ms","start":"2026-04-02T16:51:07.005205Z","end":"2026-04-02T16:51:07.834525Z","steps":["trace[604234918] 'process raft request' (duration: 829.078972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:51:07.834756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:51:07.005186Z","time spent":"829.490542ms","remote":"127.0.0.1:40734","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-02T16:51:07.870125Z","caller":"traceutil/trace.go:171","msg":"trace[1852609737] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1389; }","duration":"459.824737ms","start":"2026-04-02T16:51:07.410279Z","end":"2026-04-02T16:51:07.870104Z","steps":["trace[1852609737] 'read index received' (duration: 423.983303ms)","trace[1852609737] 'applied index is now lower than readState.Index' (duration: 35.840434ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:51:07.870481Z","caller":"traceutil/trace.go:171","msg":"trace[1358539886] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"865.043309ms","start":"2026-04-02T16:51:07.00541Z","end":"2026-04-02T16:51:07.870453Z","steps":["trace[1358539886] 'process raft request' (duration: 864.594519ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:51:07.870576Z","caller":"traceutil/trace.go:171","msg":"trace[992950407] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"275.734427ms","start":"2026-04-02T16:51:07.59479Z","end":"2026-04-02T16:51:07.870524Z","steps":["trace[992950407] 'process raft request' (duration: 275.258536ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:51:07.870622Z","caller":"traceutil/trace.go:171","msg":"trace[1344050890] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"865.221923ms","start":"2026-04-02T16:51:07.005381Z","end":"2026-04-02T16:51:07.870603Z","steps":["trace[1344050890] 'process raft request' (duration: 863.219087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:51:07.870682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:51:07.005383Z","time spent":"865.221203ms","remote":"127.0.0.1:40734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:51:07.870726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:51:07.005355Z","time spent":"865.306075ms","remote":"127.0.0.1:40734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:51:07.870884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.421231ms","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-02T16:51:07.87103Z","caller":"traceutil/trace.go:171","msg":"trace[1630532331] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1344; }","duration":"460.783079ms","start":"2026-04-02T16:51:07.410228Z","end":"2026-04-02T16:51:07.871011Z","steps":["trace[1630532331] 'agreement among raft nodes before linearized reading' (duration: 460.055693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:51:07.871166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:51:07.410208Z","time spent":"460.942552ms","remote":"127.0.0.1:40734","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-02T16:54:30.524243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.316167ms","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-02T16:54:30.524336Z","caller":"traceutil/trace.go:171","msg":"trace[286406124] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2301; }","duration":"168.477231ms","start":"2026-04-02T16:54:30.355839Z","end":"2026-04-02T16:54:30.524316Z","steps":["trace[286406124] 'range keys from in-memory index tree' (duration: 168.126082ms)"],"step_count":1}