{"level":"warn","ts":"2026-05-20T12:58:23.635336Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T12:58:23.635447Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.86: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.86:2380","--initial-cluster=instance=https://199.19.213.86:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.86:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.86:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-20T12:58:23.635529Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T12:58:23.63555Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-05-20T12:58:23.635581Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-20T12:58:23.636564Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"]} {"level":"info","ts":"2026-05-20T12:58:23.636658Z","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.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.86:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-20T12:58:23.640898Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.969664ms"} {"level":"info","ts":"2026-05-20T12:58:23.647744Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"849a6ffb5e81c109","cluster-id":"d4d86b1dd8829199"} {"level":"info","ts":"2026-05-20T12:58:23.647804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=()"} {"level":"info","ts":"2026-05-20T12:58:23.64783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 0"} {"level":"info","ts":"2026-05-20T12:58:23.647851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 849a6ffb5e81c109 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-20T12:58:23.647861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became follower at term 1"} {"level":"info","ts":"2026-05-20T12:58:23.647901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"warn","ts":"2026-05-20T12:58:23.661656Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-20T12:58:23.663018Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-20T12:58:23.664086Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-20T12:58:23.666272Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"849a6ffb5e81c109","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-20T12:58:23.666391Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"849a6ffb5e81c109","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-20T12:58:23.666511Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-20T12:58:23.666564Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-20T12:58:23.666573Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-20T12:58:23.667937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 switched to configuration voters=(9555072684832964873)"} {"level":"info","ts":"2026-05-20T12:58:23.668109Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","added-peer-id":"849a6ffb5e81c109","added-peer-peer-urls":["https://199.19.213.86:2380"]} {"level":"info","ts":"2026-05-20T12:58:23.674436Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-20T12:58:23.67456Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-05-20T12:58:23.674623Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.86:2380"} {"level":"info","ts":"2026-05-20T12:58:23.674691Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"849a6ffb5e81c109","initial-advertise-peer-urls":["https://199.19.213.86:2380"],"listen-peer-urls":["https://199.19.213.86:2380"],"advertise-client-urls":["https://199.19.213.86:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.86:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-20T12:58:23.67472Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-20T12:58:24.648804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 is starting a new election at term 1"} {"level":"info","ts":"2026-05-20T12:58:24.64891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-20T12:58:24.648942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgPreVoteResp from 849a6ffb5e81c109 at term 1"} {"level":"info","ts":"2026-05-20T12:58:24.648975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became candidate at term 2"} {"level":"info","ts":"2026-05-20T12:58:24.648994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 received MsgVoteResp from 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-05-20T12:58:24.649011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"849a6ffb5e81c109 became leader at term 2"} {"level":"info","ts":"2026-05-20T12:58:24.649035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 849a6ffb5e81c109 elected leader 849a6ffb5e81c109 at term 2"} {"level":"info","ts":"2026-05-20T12:58:24.650719Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T12:58:24.650882Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T12:58:24.650895Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"849a6ffb5e81c109","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.86:2379]}","request-path":"/0/members/849a6ffb5e81c109/attributes","cluster-id":"d4d86b1dd8829199","publish-timeout":"7s"} {"level":"info","ts":"2026-05-20T12:58:24.650899Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T12:58:24.651281Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-20T12:58:24.651313Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-20T12:58:24.651862Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d4d86b1dd8829199","local-member-id":"849a6ffb5e81c109","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T12:58:24.652002Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T12:58:24.652049Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T12:58:24.652929Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-20T12:58:24.654508Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.86:2379"} {"level":"info","ts":"2026-05-20T12:58:27.52074Z","caller":"traceutil/trace.go:171","msg":"trace[898282657] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"208.984384ms","start":"2026-05-20T12:58:27.311723Z","end":"2026-05-20T12:58:27.520707Z","steps":["trace[898282657] 'process raft request' (duration: 205.994728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:58:30.081927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.905659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-20T12:58:30.08207Z","caller":"traceutil/trace.go:171","msg":"trace[158983279] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:283; }","duration":"162.131294ms","start":"2026-05-20T12:58:29.919911Z","end":"2026-05-20T12:58:30.082043Z","steps":["trace[158983279] 'range keys from in-memory index tree' (duration: 161.773294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:58:36.117874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.345936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T12:58:36.11803Z","caller":"traceutil/trace.go:171","msg":"trace[544566186] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"750.289857ms","start":"2026-05-20T12:58:35.36772Z","end":"2026-05-20T12:58:36.11801Z","steps":["trace[544566186] 'process raft request' (duration: 265.770577ms)","trace[544566186] 'compare' (duration: 483.09708ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T12:58:36.118119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:58:35.367698Z","time spent":"750.372359ms","remote":"127.0.0.1:39112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2903,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T12:58:36.781791Z","caller":"traceutil/trace.go:171","msg":"trace[1178529185] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"583.885701ms","start":"2026-05-20T12:58:36.197872Z","end":"2026-05-20T12:58:36.781758Z","steps":["trace[1178529185] 'process raft request' (duration: 581.501482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:58:36.781988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:58:36.197844Z","time spent":"584.042565ms","remote":"127.0.0.1:38786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T12:58:36.782502Z","caller":"traceutil/trace.go:171","msg":"trace[1833212068] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:414; }","duration":"365.512758ms","start":"2026-05-20T12:58:36.416965Z","end":"2026-05-20T12:58:36.782478Z","steps":["trace[1833212068] 'read index received' (duration: 362.464042ms)","trace[1833212068] 'applied index is now lower than readState.Index' (duration: 3.047506ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T12:58:36.782654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.678973ms","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-05-20T12:58:36.782692Z","caller":"traceutil/trace.go:171","msg":"trace[571880675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:399; }","duration":"365.751655ms","start":"2026-05-20T12:58:36.416929Z","end":"2026-05-20T12:58:36.782681Z","steps":["trace[571880675] 'agreement among raft nodes before linearized reading' (duration: 365.661592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:58:36.782738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:58:36.41691Z","time spent":"365.819737ms","remote":"127.0.0.1:38874","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-05-20T12:58:36.782731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.788376ms","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-05-20T12:58:36.782789Z","caller":"traceutil/trace.go:171","msg":"trace[1749904378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:399; }","duration":"118.879299ms","start":"2026-05-20T12:58:36.663894Z","end":"2026-05-20T12:58:36.782773Z","steps":["trace[1749904378] 'agreement among raft nodes before linearized reading' (duration: 118.782016ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:58:36.782532Z","caller":"traceutil/trace.go:171","msg":"trace[1418662862] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"397.679928ms","start":"2026-05-20T12:58:36.384816Z","end":"2026-05-20T12:58:36.782496Z","steps":["trace[1418662862] 'process raft request' (duration: 397.576095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:58:36.783109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:58:36.384782Z","time spent":"398.232183ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T12:58:59.085112Z","caller":"traceutil/trace.go:171","msg":"trace[417685724] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"269.533951ms","start":"2026-05-20T12:58:58.815554Z","end":"2026-05-20T12:58:59.085088Z","steps":["trace[417685724] 'read index received' (duration: 212.262519ms)","trace[417685724] 'applied index is now lower than readState.Index' (duration: 57.270682ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T12:58:59.085205Z","caller":"traceutil/trace.go:171","msg":"trace[2116885261] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"273.674098ms","start":"2026-05-20T12:58:58.811523Z","end":"2026-05-20T12:58:59.085197Z","steps":["trace[2116885261] 'process raft request' (duration: 216.178399ms)","trace[2116885261] 'compare' (duration: 57.240831ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T12:58:59.085366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.780508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T12:58:59.085456Z","caller":"traceutil/trace.go:171","msg":"trace[389371590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"269.923352ms","start":"2026-05-20T12:58:58.81551Z","end":"2026-05-20T12:58:59.085433Z","steps":["trace[389371590] 'agreement among raft nodes before linearized reading' (duration: 269.715816ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:58:59.094727Z","caller":"traceutil/trace.go:171","msg":"trace[1203016009] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"273.674459ms","start":"2026-05-20T12:58:58.821022Z","end":"2026-05-20T12:58:59.094696Z","steps":["trace[1203016009] 'process raft request' (duration: 273.509173ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:58:59.094857Z","caller":"traceutil/trace.go:171","msg":"trace[1059955358] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"273.628897ms","start":"2026-05-20T12:58:58.821193Z","end":"2026-05-20T12:58:59.094822Z","steps":["trace[1059955358] 'process raft request' (duration: 273.442521ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:58:59.094928Z","caller":"traceutil/trace.go:171","msg":"trace[1719881153] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"273.408312ms","start":"2026-05-20T12:58:58.821491Z","end":"2026-05-20T12:58:59.0949Z","steps":["trace[1719881153] 'process raft request' (duration: 273.184305ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:58:59.094955Z","caller":"traceutil/trace.go:171","msg":"trace[1655344990] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"270.841198ms","start":"2026-05-20T12:58:58.824083Z","end":"2026-05-20T12:58:59.094925Z","steps":["trace[1655344990] 'process raft request' (duration: 270.680024ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:58:59.095068Z","caller":"traceutil/trace.go:171","msg":"trace[672360969] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"270.001944ms","start":"2026-05-20T12:58:58.825056Z","end":"2026-05-20T12:58:59.095058Z","steps":["trace[672360969] 'process raft request' (duration: 269.755057ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:58:59.095109Z","caller":"traceutil/trace.go:171","msg":"trace[1043937121] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"266.852834ms","start":"2026-05-20T12:58:58.828244Z","end":"2026-05-20T12:58:59.095097Z","steps":["trace[1043937121] 'process raft request' (duration: 266.614578ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:58:59.0954Z","caller":"traceutil/trace.go:171","msg":"trace[615315933] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"265.207098ms","start":"2026-05-20T12:58:58.830016Z","end":"2026-05-20T12:58:59.095223Z","steps":["trace[615315933] 'process raft request' (duration: 264.923579ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:58:59.095448Z","caller":"traceutil/trace.go:171","msg":"trace[39944699] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"264.536809ms","start":"2026-05-20T12:58:58.830893Z","end":"2026-05-20T12:58:59.095429Z","steps":["trace[39944699] 'process raft request' (duration: 264.196239ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:58:59.095575Z","caller":"traceutil/trace.go:171","msg":"trace[1556999898] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"185.182186ms","start":"2026-05-20T12:58:58.910379Z","end":"2026-05-20T12:58:59.095561Z","steps":["trace[1556999898] 'process raft request' (duration: 185.083883ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:58:59.095574Z","caller":"traceutil/trace.go:171","msg":"trace[702085433] transaction","detail":"{read_only:false; number_of_response:1; response_revision:542; }","duration":"259.486924ms","start":"2026-05-20T12:58:58.836075Z","end":"2026-05-20T12:58:59.095562Z","steps":["trace[702085433] 'process raft request' (duration: 259.3441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:58:59.095634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.689921ms","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-05-20T12:58:59.095667Z","caller":"traceutil/trace.go:171","msg":"trace[442513654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:543; }","duration":"191.748904ms","start":"2026-05-20T12:58:58.903909Z","end":"2026-05-20T12:58:59.095658Z","steps":["trace[442513654] 'agreement among raft nodes before linearized reading' (duration: 191.660601ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:58:59.095683Z","caller":"traceutil/trace.go:171","msg":"trace[1075610467] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"261.494791ms","start":"2026-05-20T12:58:58.834179Z","end":"2026-05-20T12:58:59.095674Z","steps":["trace[1075610467] 'process raft request' (duration: 261.10411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:58:59.095724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.029934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T12:58:59.095769Z","caller":"traceutil/trace.go:171","msg":"trace[551589527] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:543; }","duration":"197.122018ms","start":"2026-05-20T12:58:58.898637Z","end":"2026-05-20T12:58:59.095759Z","steps":["trace[551589527] 'agreement among raft nodes before linearized reading' (duration: 197.002785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:58:59.095862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.218128ms","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-05-20T12:58:59.095906Z","caller":"traceutil/trace.go:171","msg":"trace[839625691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:543; }","duration":"192.288389ms","start":"2026-05-20T12:58:58.903607Z","end":"2026-05-20T12:58:59.095896Z","steps":["trace[839625691] 'agreement among raft nodes before linearized reading' (duration: 192.192496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:03.340201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.972784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T12:59:03.341346Z","caller":"traceutil/trace.go:171","msg":"trace[712050018] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"197.765505ms","start":"2026-05-20T12:59:03.143563Z","end":"2026-05-20T12:59:03.341329Z","steps":["trace[712050018] 'process raft request' (duration: 196.763006ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:59:03.34159Z","caller":"traceutil/trace.go:171","msg":"trace[357543079] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"198.161657ms","start":"2026-05-20T12:59:03.143422Z","end":"2026-05-20T12:59:03.341583Z","steps":["trace[357543079] 'process raft request' (duration: 196.87576ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:59:03.341811Z","caller":"traceutil/trace.go:171","msg":"trace[1334901381] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:638; }","duration":"143.260462ms","start":"2026-05-20T12:59:03.198542Z","end":"2026-05-20T12:59:03.341802Z","steps":["trace[1334901381] 'read index received' (duration: 71.392µs)","trace[1334901381] 'applied index is now lower than readState.Index' (duration: 143.18788ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T12:59:03.342158Z","caller":"traceutil/trace.go:171","msg":"trace[160777141] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"198.31326ms","start":"2026-05-20T12:59:03.142841Z","end":"2026-05-20T12:59:03.341154Z","steps":["trace[160777141] 'compare' (duration: 195.81631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:03.347319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.663876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T12:59:03.347405Z","caller":"traceutil/trace.go:171","msg":"trace[1373170368] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:614; }","duration":"148.786879ms","start":"2026-05-20T12:59:03.1986Z","end":"2026-05-20T12:59:03.347387Z","steps":["trace[1373170368] 'agreement among raft nodes before linearized reading' (duration: 148.631015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:03.347663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.118969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T12:59:03.347681Z","caller":"traceutil/trace.go:171","msg":"trace[1115014346] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:614; }","duration":"149.165461ms","start":"2026-05-20T12:59:03.19851Z","end":"2026-05-20T12:59:03.347675Z","steps":["trace[1115014346] 'agreement among raft nodes before linearized reading' (duration: 143.350655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:03.347983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.984489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T12:59:03.348003Z","caller":"traceutil/trace.go:171","msg":"trace[1632196590] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:614; }","duration":"121.026119ms","start":"2026-05-20T12:59:03.226971Z","end":"2026-05-20T12:59:03.347997Z","steps":["trace[1632196590] 'agreement among raft nodes before linearized reading' (duration: 120.992708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:03.348168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.217284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T12:59:03.348187Z","caller":"traceutil/trace.go:171","msg":"trace[1570201058] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:614; }","duration":"121.283787ms","start":"2026-05-20T12:59:03.226897Z","end":"2026-05-20T12:59:03.348181Z","steps":["trace[1570201058] 'agreement among raft nodes before linearized reading' (duration: 121.248365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:03.348275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.631203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T12:59:03.348297Z","caller":"traceutil/trace.go:171","msg":"trace[716904556] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:614; }","duration":"149.663065ms","start":"2026-05-20T12:59:03.198629Z","end":"2026-05-20T12:59:03.348292Z","steps":["trace[716904556] 'agreement among raft nodes before linearized reading' (duration: 149.629133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:07.177021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.813953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T12:59:07.177191Z","caller":"traceutil/trace.go:171","msg":"trace[978840887] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"795.620152ms","start":"2026-05-20T12:59:06.381531Z","end":"2026-05-20T12:59:07.177151Z","steps":["trace[978840887] 'process raft request' (duration: 162.593124ms)","trace[978840887] 'compare' (duration: 632.313128ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T12:59:07.177247Z","caller":"traceutil/trace.go:171","msg":"trace[221198533] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"161.275695ms","start":"2026-05-20T12:59:07.015956Z","end":"2026-05-20T12:59:07.177232Z","steps":["trace[221198533] 'process raft request' (duration: 161.187102ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:59:07.177339Z","caller":"traceutil/trace.go:171","msg":"trace[549288879] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"369.254563ms","start":"2026-05-20T12:59:06.808067Z","end":"2026-05-20T12:59:07.177322Z","steps":["trace[549288879] 'read index received' (duration: 71.092µs)","trace[549288879] 'applied index is now lower than readState.Index' (duration: 369.182251ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T12:59:07.177462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.386036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T12:59:07.177538Z","caller":"traceutil/trace.go:171","msg":"trace[1659041636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"369.509899ms","start":"2026-05-20T12:59:06.808007Z","end":"2026-05-20T12:59:07.177517Z","steps":["trace[1659041636] 'agreement among raft nodes before linearized reading' (duration: 369.384976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:07.17757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:59:06.807983Z","time spent":"369.580351ms","remote":"127.0.0.1:38612","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-05-20T12:59:07.177633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:59:06.381488Z","time spent":"795.791378ms","remote":"127.0.0.1:38702","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T12:59:08.532359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249971119s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T12:59:08.532444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170138954s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T12:59:08.532479Z","caller":"traceutil/trace.go:171","msg":"trace[1816264101] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:636; }","duration":"1.250117103s","start":"2026-05-20T12:59:07.282331Z","end":"2026-05-20T12:59:08.532448Z","steps":["trace[1816264101] 'range keys from in-memory index tree' (duration: 1.249919157s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:08.532538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:59:07.282324Z","time spent":"1.250202255s","remote":"127.0.0.1:38750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-20T12:59:08.532602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17562248s","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-05-20T12:59:08.532626Z","caller":"traceutil/trace.go:171","msg":"trace[1403999676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"1.175648801s","start":"2026-05-20T12:59:07.356972Z","end":"2026-05-20T12:59:08.532621Z","steps":["trace[1403999676] 'range keys from in-memory index tree' (duration: 1.175181997s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:08.532649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:59:07.35691Z","time spent":"1.175733033s","remote":"127.0.0.1:38874","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-05-20T12:59:08.532678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175692812s","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-05-20T12:59:08.532544Z","caller":"traceutil/trace.go:171","msg":"trace[1344785374] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:636; }","duration":"1.170263078s","start":"2026-05-20T12:59:07.362242Z","end":"2026-05-20T12:59:08.532505Z","steps":["trace[1344785374] 'range keys from in-memory index tree' (duration: 1.169917418s)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:59:08.532738Z","caller":"traceutil/trace.go:171","msg":"trace[1734413175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:636; }","duration":"1.175781215s","start":"2026-05-20T12:59:07.356937Z","end":"2026-05-20T12:59:08.532718Z","steps":["trace[1734413175] 'range keys from in-memory index tree' (duration: 1.175516676s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:08.532732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.616878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-20T12:59:08.532752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:59:07.362231Z","time spent":"1.170503164s","remote":"127.0.0.1:38874","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-05-20T12:59:08.532784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:59:07.356911Z","time spent":"1.175862996s","remote":"127.0.0.1:38874","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-05-20T12:59:08.532809Z","caller":"traceutil/trace.go:171","msg":"trace[117533210] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:636; }","duration":"339.742861ms","start":"2026-05-20T12:59:08.193046Z","end":"2026-05-20T12:59:08.532789Z","steps":["trace[117533210] 'range keys from in-memory index tree' (duration: 339.478983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:08.532847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:59:08.193024Z","time spent":"339.815443ms","remote":"127.0.0.1:38630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.86\" "} {"level":"warn","ts":"2026-05-20T12:59:08.532443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.917157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T12:59:08.532956Z","caller":"traceutil/trace.go:171","msg":"trace[1305954649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"851.480054ms","start":"2026-05-20T12:59:07.681461Z","end":"2026-05-20T12:59:08.532941Z","steps":["trace[1305954649] 'range keys from in-memory index tree' (duration: 850.743603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:08.533024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:59:07.681439Z","time spent":"851.573586ms","remote":"127.0.0.1:38606","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-05-20T12:59:28.266105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.547356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T12:59:28.266203Z","caller":"traceutil/trace.go:171","msg":"trace[1480485756] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:796; }","duration":"132.701981ms","start":"2026-05-20T12:59:28.133481Z","end":"2026-05-20T12:59:28.266183Z","steps":["trace[1480485756] 'count revisions from in-memory index tree' (duration: 132.402183ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:59:31.221348Z","caller":"traceutil/trace.go:171","msg":"trace[1710766318] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"559.977136ms","start":"2026-05-20T12:59:30.661347Z","end":"2026-05-20T12:59:31.221324Z","steps":["trace[1710766318] 'process raft request' (duration: 559.814052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:31.221585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:59:30.661329Z","time spent":"560.093789ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T12:59:31.32605Z","caller":"traceutil/trace.go:171","msg":"trace[54807561] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"662.367026ms","start":"2026-05-20T12:59:30.663649Z","end":"2026-05-20T12:59:31.326016Z","steps":["trace[54807561] 'process raft request' (duration: 662.141658ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T12:59:31.326078Z","caller":"traceutil/trace.go:171","msg":"trace[2068488067] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"662.066236ms","start":"2026-05-20T12:59:30.66398Z","end":"2026-05-20T12:59:31.326047Z","steps":["trace[2068488067] 'process raft request' (duration: 661.940343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:31.32622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:59:30.663631Z","time spent":"662.493709ms","remote":"127.0.0.1:38874","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-05-20T12:59:31.326362Z","caller":"traceutil/trace.go:171","msg":"trace[188280473] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:846; }","duration":"522.022556ms","start":"2026-05-20T12:59:30.80433Z","end":"2026-05-20T12:59:31.326353Z","steps":["trace[188280473] 'read index received' (duration: 416.903151ms)","trace[188280473] 'applied index is now lower than readState.Index' (duration: 105.118395ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T12:59:31.326279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:59:30.663971Z","time spent":"662.194971ms","remote":"127.0.0.1:38874","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-05-20T12:59:31.326435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.107848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T12:59:31.32646Z","caller":"traceutil/trace.go:171","msg":"trace[1290359439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:815; }","duration":"522.1646ms","start":"2026-05-20T12:59:30.804289Z","end":"2026-05-20T12:59:31.326454Z","steps":["trace[1290359439] 'agreement among raft nodes before linearized reading' (duration: 522.091768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T12:59:31.32648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T12:59:30.80427Z","time spent":"522.206151ms","remote":"127.0.0.1:38606","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-05-20T12:59:31.326619Z","caller":"traceutil/trace.go:171","msg":"trace[1796762431] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"109.82127ms","start":"2026-05-20T12:59:31.216782Z","end":"2026-05-20T12:59:31.326603Z","steps":["trace[1796762431] 'process raft request' (duration: 109.195362ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:05.498434Z","caller":"traceutil/trace.go:171","msg":"trace[639370013] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"120.112742ms","start":"2026-05-20T13:00:05.3783Z","end":"2026-05-20T13:00:05.498412Z","steps":["trace[639370013] 'process raft request' (duration: 119.990878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:05.962893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.4563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T13:00:05.96311Z","caller":"traceutil/trace.go:171","msg":"trace[1122044834] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"450.608577ms","start":"2026-05-20T13:00:05.512483Z","end":"2026-05-20T13:00:05.963092Z","steps":["trace[1122044834] 'read index received' (duration: 288.853299ms)","trace[1122044834] 'applied index is now lower than readState.Index' (duration: 161.753848ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T13:00:05.963169Z","caller":"traceutil/trace.go:171","msg":"trace[1018325242] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"452.629844ms","start":"2026-05-20T13:00:05.510509Z","end":"2026-05-20T13:00:05.963139Z","steps":["trace[1018325242] 'process raft request' (duration: 290.816205ms)","trace[1018325242] 'compare' (duration: 160.926934ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T13:00:05.963326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:05.510486Z","time spent":"452.741728ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T13:00:05.963341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.855005ms","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-05-20T13:00:05.963372Z","caller":"traceutil/trace.go:171","msg":"trace[1402967912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1029; }","duration":"450.891026ms","start":"2026-05-20T13:00:05.512472Z","end":"2026-05-20T13:00:05.963363Z","steps":["trace[1402967912] 'agreement among raft nodes before linearized reading' (duration: 450.695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:05.963409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:05.512427Z","time spent":"450.972376ms","remote":"127.0.0.1:38874","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-05-20T13:00:06.170237Z","caller":"traceutil/trace.go:171","msg":"trace[290924724] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1073; }","duration":"207.07585ms","start":"2026-05-20T13:00:05.96314Z","end":"2026-05-20T13:00:06.170216Z","steps":["trace[290924724] 'read index received' (duration: 203.312452ms)","trace[290924724] 'applied index is now lower than readState.Index' (duration: 3.759978ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T13:00:06.170382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.286428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T13:00:06.170436Z","caller":"traceutil/trace.go:171","msg":"trace[1894977473] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1031; }","duration":"225.415282ms","start":"2026-05-20T13:00:05.945009Z","end":"2026-05-20T13:00:06.170425Z","steps":["trace[1894977473] 'agreement among raft nodes before linearized reading' (duration: 225.299289ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:06.170663Z","caller":"traceutil/trace.go:171","msg":"trace[1826037521] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"656.579565ms","start":"2026-05-20T13:00:05.514072Z","end":"2026-05-20T13:00:06.170652Z","steps":["trace[1826037521] 'process raft request' (duration: 652.404776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:06.170757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:05.514057Z","time spent":"656.641346ms","remote":"127.0.0.1:38874","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-05-20T13:00:06.170791Z","caller":"traceutil/trace.go:171","msg":"trace[1863649969] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"578.737687ms","start":"2026-05-20T13:00:05.592029Z","end":"2026-05-20T13:00:06.170767Z","steps":["trace[1863649969] 'process raft request' (duration: 578.12597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:06.17092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:05.592006Z","time spent":"578.83628ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T13:00:06.174182Z","caller":"traceutil/trace.go:171","msg":"trace[1811874346] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"204.332081ms","start":"2026-05-20T13:00:05.969823Z","end":"2026-05-20T13:00:06.174155Z","steps":["trace[1811874346] 'process raft request' (duration: 204.146106ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:09.704314Z","caller":"traceutil/trace.go:171","msg":"trace[813074728] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"183.321513ms","start":"2026-05-20T13:00:09.52096Z","end":"2026-05-20T13:00:09.704281Z","steps":["trace[813074728] 'process raft request' (duration: 183.061506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:10.520787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.692562ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T13:00:10.521213Z","caller":"traceutil/trace.go:171","msg":"trace[1677983511] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1042; }","duration":"344.128924ms","start":"2026-05-20T13:00:10.177058Z","end":"2026-05-20T13:00:10.521187Z","steps":["trace[1677983511] 'range keys from in-memory index tree' (duration: 343.672722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:10.521013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.511966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T13:00:10.521281Z","caller":"traceutil/trace.go:171","msg":"trace[260457682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1042; }","duration":"415.817926ms","start":"2026-05-20T13:00:10.105445Z","end":"2026-05-20T13:00:10.521263Z","steps":["trace[260457682] 'range keys from in-memory index tree' (duration: 415.28645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:10.521341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:10.105425Z","time spent":"415.896389ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T13:00:10.521097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.457496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T13:00:10.521466Z","caller":"traceutil/trace.go:171","msg":"trace[1693692886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1042; }","duration":"415.845986ms","start":"2026-05-20T13:00:10.105612Z","end":"2026-05-20T13:00:10.521458Z","steps":["trace[1693692886] 'range keys from in-memory index tree' (duration: 415.420255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:10.521488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:10.105606Z","time spent":"415.877088ms","remote":"127.0.0.1:38606","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-05-20T13:00:10.61978Z","caller":"traceutil/trace.go:171","msg":"trace[1857424605] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1087; }","duration":"430.974668ms","start":"2026-05-20T13:00:10.18878Z","end":"2026-05-20T13:00:10.619755Z","steps":["trace[1857424605] 'read index received' (duration: 430.933777ms)","trace[1857424605] 'applied index is now lower than readState.Index' (duration: 38.051µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T13:00:10.619843Z","caller":"traceutil/trace.go:171","msg":"trace[1007864962] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"434.840368ms","start":"2026-05-20T13:00:10.184981Z","end":"2026-05-20T13:00:10.619821Z","steps":["trace[1007864962] 'process raft request' (duration: 434.633142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:10.61996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:10.184958Z","time spent":"434.921989ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T13:00:10.620056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.263076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-20T13:00:10.620131Z","caller":"traceutil/trace.go:171","msg":"trace[138050190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1043; }","duration":"431.376869ms","start":"2026-05-20T13:00:10.188736Z","end":"2026-05-20T13:00:10.620113Z","steps":["trace[138050190] 'agreement among raft nodes before linearized reading' (duration: 431.129232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:10.620198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:10.188716Z","time spent":"431.470961ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T13:00:11.120648Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822945301113060,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T13:00:12.469586Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822945301113060,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T13:00:12.522934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00047148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-20T13:00:12.523074Z","caller":"traceutil/trace.go:171","msg":"trace[1553025839] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000644425s","start":"2026-05-20T13:00:10.522395Z","end":"2026-05-20T13:00:12.52304Z","steps":["trace[1553025839] 'agreement among raft nodes before linearized reading' (duration: 2.000460089s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:12.523169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:10.522365Z","time spent":"2.000770778s","remote":"127.0.0.1:38612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T13:00:12.832195Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.21245927s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T13:00:12.83278Z","caller":"traceutil/trace.go:171","msg":"trace[1558040784] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"2.308014891s","start":"2026-05-20T13:00:10.524734Z","end":"2026-05-20T13:00:12.832749Z","steps":["trace[1558040784] 'process raft request' (duration: 2.307787155s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:12.833055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:10.524711Z","time spent":"2.308235758s","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T13:00:12.833582Z","caller":"traceutil/trace.go:171","msg":"trace[561625208] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"363.975969ms","start":"2026-05-20T13:00:12.469576Z","end":"2026-05-20T13:00:12.833552Z","steps":["trace[561625208] 'process raft request' (duration: 363.896236ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:12.833585Z","caller":"traceutil/trace.go:171","msg":"trace[1354454022] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"2.209407152s","start":"2026-05-20T13:00:10.624133Z","end":"2026-05-20T13:00:12.83354Z","steps":["trace[1354454022] 'process raft request' (duration: 2.209200966s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:12.833802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:12.469561Z","time spent":"364.079572ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T13:00:12.833812Z","caller":"traceutil/trace.go:171","msg":"trace[2117636444] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1088; }","duration":"2.21393934s","start":"2026-05-20T13:00:10.619855Z","end":"2026-05-20T13:00:12.833795Z","steps":["trace[2117636444] 'read index received' (duration: 2.212614903s)","trace[2117636444] 'applied index is now lower than readState.Index' (duration: 1.323587ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T13:00:12.833872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:10.624102Z","time spent":"2.209636528s","remote":"127.0.0.1:38874","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-05-20T13:00:12.833936Z","caller":"traceutil/trace.go:171","msg":"trace[1000435463] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"208.812219ms","start":"2026-05-20T13:00:12.62506Z","end":"2026-05-20T13:00:12.833872Z","steps":["trace[1000435463] 'process raft request' (duration: 208.642925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:12.83399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.40973825s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T13:00:12.833999Z","caller":"traceutil/trace.go:171","msg":"trace[914209001] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"307.408667ms","start":"2026-05-20T13:00:12.526567Z","end":"2026-05-20T13:00:12.833975Z","steps":["trace[914209001] 'process raft request' (duration: 306.946174ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:12.834057Z","caller":"traceutil/trace.go:171","msg":"trace[1867594293] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1048; }","duration":"2.409835182s","start":"2026-05-20T13:00:10.4242Z","end":"2026-05-20T13:00:12.834035Z","steps":["trace[1867594293] 'agreement among raft nodes before linearized reading' (duration: 2.409732949s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:12.834096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:12.526545Z","time spent":"307.505191ms","remote":"127.0.0.1:38676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T13:00:12.834116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:10.424183Z","time spent":"2.409913013s","remote":"127.0.0.1:39116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-05-20T13:00:12.834203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.644401964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-20T13:00:12.834257Z","caller":"traceutil/trace.go:171","msg":"trace[1982791189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1048; }","duration":"2.644473037s","start":"2026-05-20T13:00:10.189764Z","end":"2026-05-20T13:00:12.834237Z","steps":["trace[1982791189] 'agreement among raft nodes before linearized reading' (duration: 2.644294001s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:12.834288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.71822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T13:00:12.834329Z","caller":"traceutil/trace.go:171","msg":"trace[1429844037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1048; }","duration":"364.773321ms","start":"2026-05-20T13:00:12.469545Z","end":"2026-05-20T13:00:12.834318Z","steps":["trace[1429844037] 'agreement among raft nodes before linearized reading' (duration: 364.605937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:12.834325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:10.189753Z","time spent":"2.644557738s","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T13:00:12.834354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:12.469507Z","time spent":"364.840434ms","remote":"127.0.0.1:38874","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-05-20T13:00:12.834489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.118417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T13:00:12.834498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.882425ms","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":"warn","ts":"2026-05-20T13:00:12.834508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.600639347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-20T13:00:12.834534Z","caller":"traceutil/trace.go:171","msg":"trace[769576498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1048; }","duration":"296.27195ms","start":"2026-05-20T13:00:12.538248Z","end":"2026-05-20T13:00:12.83452Z","steps":["trace[769576498] 'agreement among raft nodes before linearized reading' (duration: 296.197898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:12.834531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.790532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T13:00:12.834549Z","caller":"traceutil/trace.go:171","msg":"trace[1503229948] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1048; }","duration":"2.60071207s","start":"2026-05-20T13:00:10.233826Z","end":"2026-05-20T13:00:12.834538Z","steps":["trace[1503229948] 'agreement among raft nodes before linearized reading' (duration: 2.600599547s)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:12.834559Z","caller":"traceutil/trace.go:171","msg":"trace[1282161358] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1048; }","duration":"364.973588ms","start":"2026-05-20T13:00:12.469573Z","end":"2026-05-20T13:00:12.834547Z","steps":["trace[1282161358] 'agreement among raft nodes before linearized reading' (duration: 364.875356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:12.834591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:12.469557Z","time spent":"365.02672ms","remote":"127.0.0.1:52708","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":"info","ts":"2026-05-20T13:00:12.834584Z","caller":"traceutil/trace.go:171","msg":"trace[185094558] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1048; }","duration":"364.856844ms","start":"2026-05-20T13:00:12.469713Z","end":"2026-05-20T13:00:12.83457Z","steps":["trace[185094558] 'agreement among raft nodes before linearized reading' (duration: 364.73079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:12.834591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:10.233808Z","time spent":"2.600768941s","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T13:00:12.834627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:12.469709Z","time spent":"364.906355ms","remote":"127.0.0.1:38806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":43,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-20T13:00:34.306047Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822945301113253,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T13:00:34.742166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.626898ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-20T13:00:34.742255Z","caller":"traceutil/trace.go:171","msg":"trace[410138920] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"937.26892ms","start":"2026-05-20T13:00:33.804969Z","end":"2026-05-20T13:00:34.742238Z","steps":["trace[410138920] 'read index received' (duration: 572.486629ms)","trace[410138920] 'applied index is now lower than readState.Index' (duration: 364.780821ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T13:00:34.742322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.355804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T13:00:34.742397Z","caller":"traceutil/trace.go:171","msg":"trace[1937584419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1117; }","duration":"937.434765ms","start":"2026-05-20T13:00:33.804951Z","end":"2026-05-20T13:00:34.742385Z","steps":["trace[1937584419] 'agreement among raft nodes before linearized reading' (duration: 937.330342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:34.742436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:33.804895Z","time spent":"937.532009ms","remote":"127.0.0.1:38606","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-05-20T13:00:35.197085Z","caller":"traceutil/trace.go:171","msg":"trace[415940618] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1167; }","duration":"125.737611ms","start":"2026-05-20T13:00:35.071324Z","end":"2026-05-20T13:00:35.197062Z","steps":["trace[415940618] 'read index received' (duration: 125.479334ms)","trace[415940618] 'applied index is now lower than readState.Index' (duration: 257.177µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T13:00:35.197157Z","caller":"traceutil/trace.go:171","msg":"trace[924202357] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"176.857488ms","start":"2026-05-20T13:00:35.020268Z","end":"2026-05-20T13:00:35.197126Z","steps":["trace[924202357] 'process raft request' (duration: 176.57192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:35.197347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.000739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T13:00:35.197391Z","caller":"traceutil/trace.go:171","msg":"trace[1678090697] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1118; }","duration":"126.06085ms","start":"2026-05-20T13:00:35.071319Z","end":"2026-05-20T13:00:35.19738Z","steps":["trace[1678090697] 'agreement among raft nodes before linearized reading' (duration: 125.873275ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:35.959033Z","caller":"traceutil/trace.go:171","msg":"trace[1247859611] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"757.115358ms","start":"2026-05-20T13:00:35.201881Z","end":"2026-05-20T13:00:35.958997Z","steps":["trace[1247859611] 'process raft request' (duration: 756.906262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:35.959244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:35.20186Z","time spent":"757.271763ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T13:00:37.584197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266252583s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T13:00:37.584497Z","caller":"traceutil/trace.go:171","msg":"trace[1200716442] linearizableReadLoop","detail":"{readStateIndex:1173; appliedIndex:1168; }","duration":"1.780263496s","start":"2026-05-20T13:00:35.804212Z","end":"2026-05-20T13:00:37.584476Z","steps":["trace[1200716442] 'read index received' (duration: 154.531102ms)","trace[1200716442] 'applied index is now lower than readState.Index' (duration: 1.625730754s)"],"step_count":2} {"level":"info","ts":"2026-05-20T13:00:37.58453Z","caller":"traceutil/trace.go:171","msg":"trace[234528925] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"2.382011868s","start":"2026-05-20T13:00:35.202502Z","end":"2026-05-20T13:00:37.584514Z","steps":["trace[234528925] 'process raft request' (duration: 1.115359313s)","trace[234528925] 'compare' (duration: 1.265103621s)"],"step_count":2} {"level":"info","ts":"2026-05-20T13:00:37.584526Z","caller":"traceutil/trace.go:171","msg":"trace[1181012785] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"2.381880425s","start":"2026-05-20T13:00:35.20262Z","end":"2026-05-20T13:00:37.584501Z","steps":["trace[1181012785] 'process raft request' (duration: 2.381666599s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:37.584647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:35.202489Z","time spent":"2.382089391s","remote":"127.0.0.1:38874","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-05-20T13:00:37.584641Z","caller":"traceutil/trace.go:171","msg":"trace[995245400] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"2.381904155s","start":"2026-05-20T13:00:35.202711Z","end":"2026-05-20T13:00:37.584615Z","steps":["trace[995245400] 'process raft request' (duration: 2.381668978s)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:37.584656Z","caller":"traceutil/trace.go:171","msg":"trace[161353188] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"2.381481234s","start":"2026-05-20T13:00:35.20316Z","end":"2026-05-20T13:00:37.584642Z","steps":["trace[161353188] 'process raft request' (duration: 2.381266647s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:37.58467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:35.202615Z","time spent":"2.382022588s","remote":"127.0.0.1:38874","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-05-20T13:00:37.584756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:35.203134Z","time spent":"2.381576906s","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T13:00:37.584608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.78039385s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T13:00:37.584982Z","caller":"traceutil/trace.go:171","msg":"trace[572273347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1123; }","duration":"1.780666737s","start":"2026-05-20T13:00:35.804172Z","end":"2026-05-20T13:00:37.584839Z","steps":["trace[572273347] 'agreement among raft nodes before linearized reading' (duration: 1.780376109s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:37.584957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:35.202685Z","time spent":"2.38201695s","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T13:00:37.585016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:35.804156Z","time spent":"1.780853354s","remote":"127.0.0.1:38612","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-05-20T13:00:38.059492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.579641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T13:00:38.059613Z","caller":"traceutil/trace.go:171","msg":"trace[121208702] linearizableReadLoop","detail":"{readStateIndex:1174; appliedIndex:1173; }","duration":"466.505701ms","start":"2026-05-20T13:00:37.593077Z","end":"2026-05-20T13:00:38.059583Z","steps":["trace[121208702] 'read index received' (duration: 157.703874ms)","trace[121208702] 'applied index is now lower than readState.Index' (duration: 308.799527ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T13:00:38.059631Z","caller":"traceutil/trace.go:171","msg":"trace[78778010] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"854.886704ms","start":"2026-05-20T13:00:37.204703Z","end":"2026-05-20T13:00:38.059589Z","steps":["trace[78778010] 'process raft request' (duration: 546.125047ms)","trace[78778010] 'compare' (duration: 308.461868ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T13:00:38.059738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.669754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T13:00:38.059792Z","caller":"traceutil/trace.go:171","msg":"trace[1768814958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1124; }","duration":"466.755527ms","start":"2026-05-20T13:00:37.593022Z","end":"2026-05-20T13:00:38.059778Z","steps":["trace[1768814958] 'agreement among raft nodes before linearized reading' (duration: 466.664105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:38.059828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:37.204681Z","time spent":"855.045069ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T13:00:38.059837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:37.593007Z","time spent":"466.814648ms","remote":"127.0.0.1:38606","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-05-20T13:00:38.476355Z","caller":"traceutil/trace.go:171","msg":"trace[1201038214] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"411.045589ms","start":"2026-05-20T13:00:38.065006Z","end":"2026-05-20T13:00:38.476051Z","steps":["trace[1201038214] 'process raft request' (duration: 410.833603ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:38.476641Z","caller":"traceutil/trace.go:171","msg":"trace[791786896] linearizableReadLoop","detail":"{readStateIndex:1175; appliedIndex:1175; }","duration":"276.079015ms","start":"2026-05-20T13:00:38.200532Z","end":"2026-05-20T13:00:38.476611Z","steps":["trace[791786896] 'read index received' (duration: 276.067364ms)","trace[791786896] 'applied index is now lower than readState.Index' (duration: 9.07µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T13:00:38.47692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.331932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-20T13:00:38.476979Z","caller":"traceutil/trace.go:171","msg":"trace[98155006] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:1125; }","duration":"276.483317ms","start":"2026-05-20T13:00:38.200482Z","end":"2026-05-20T13:00:38.476965Z","steps":["trace[98155006] 'agreement among raft nodes before linearized reading' (duration: 276.244111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:38.47749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:38.064986Z","time spent":"411.628988ms","remote":"127.0.0.1:38874","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-05-20T13:00:38.789323Z","caller":"traceutil/trace.go:171","msg":"trace[1837074426] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"264.678461ms","start":"2026-05-20T13:00:38.524619Z","end":"2026-05-20T13:00:38.789297Z","steps":["trace[1837074426] 'process raft request' (duration: 242.340675ms)","trace[1837074426] 'compare' (duration: 22.068807ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T13:00:39.161094Z","caller":"traceutil/trace.go:171","msg":"trace[1314718410] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"140.815461ms","start":"2026-05-20T13:00:39.020258Z","end":"2026-05-20T13:00:39.161074Z","steps":["trace[1314718410] 'process raft request' (duration: 140.665817ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:53.844285Z","caller":"traceutil/trace.go:171","msg":"trace[1724339521] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"162.542731ms","start":"2026-05-20T13:00:53.681713Z","end":"2026-05-20T13:00:53.844256Z","steps":["trace[1724339521] 'process raft request' (duration: 162.356106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:53.845192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T13:00:53.845233Z","caller":"traceutil/trace.go:171","msg":"trace[77936056] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1225; }","duration":"137.680913ms","start":"2026-05-20T13:00:53.707544Z","end":"2026-05-20T13:00:53.845225Z","steps":["trace[77936056] 'agreement among raft nodes before linearized reading' (duration: 137.509268ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:53.845022Z","caller":"traceutil/trace.go:171","msg":"trace[590874339] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1280; }","duration":"137.414054ms","start":"2026-05-20T13:00:53.707588Z","end":"2026-05-20T13:00:53.845002Z","steps":["trace[590874339] 'read index received' (duration: 137.301601ms)","trace[590874339] 'applied index is now lower than readState.Index' (duration: 110.873µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T13:00:53.847129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.507396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-20T13:00:53.847152Z","caller":"traceutil/trace.go:171","msg":"trace[2077030926] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1225; }","duration":"138.561017ms","start":"2026-05-20T13:00:53.708585Z","end":"2026-05-20T13:00:53.847146Z","steps":["trace[2077030926] 'agreement among raft nodes before linearized reading' (duration: 138.459134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:53.847385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.534817ms","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-05-20T13:00:53.847408Z","caller":"traceutil/trace.go:171","msg":"trace[145262330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1225; }","duration":"138.652311ms","start":"2026-05-20T13:00:53.708749Z","end":"2026-05-20T13:00:53.847401Z","steps":["trace[145262330] 'agreement among raft nodes before linearized reading' (duration: 138.598019ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:00:55.169516Z","caller":"traceutil/trace.go:171","msg":"trace[142895044] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"612.362976ms","start":"2026-05-20T13:00:54.557133Z","end":"2026-05-20T13:00:55.169496Z","steps":["trace[142895044] 'process raft request' (duration: 612.239293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:55.169634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:54.557111Z","time spent":"612.46286ms","remote":"127.0.0.1:38874","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-05-20T13:00:55.169724Z","caller":"traceutil/trace.go:171","msg":"trace[29177660] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1286; }","duration":"362.308572ms","start":"2026-05-20T13:00:54.807406Z","end":"2026-05-20T13:00:55.169715Z","steps":["trace[29177660] 'read index received' (duration: 362.304062ms)","trace[29177660] 'applied index is now lower than readState.Index' (duration: 3.52µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T13:00:55.169784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.374866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T13:00:55.169823Z","caller":"traceutil/trace.go:171","msg":"trace[802988684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1230; }","duration":"362.441186ms","start":"2026-05-20T13:00:54.807374Z","end":"2026-05-20T13:00:55.169815Z","steps":["trace[802988684] 'agreement among raft nodes before linearized reading' (duration: 362.378884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:00:55.169849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:00:54.807357Z","time spent":"362.486988ms","remote":"127.0.0.1:38606","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-05-20T13:01:11.348936Z","caller":"traceutil/trace.go:171","msg":"trace[1352971613] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"109.156212ms","start":"2026-05-20T13:01:11.239761Z","end":"2026-05-20T13:01:11.348917Z","steps":["trace[1352971613] 'process raft request' (duration: 109.021668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:14.958361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.401118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T13:01:14.958496Z","caller":"traceutil/trace.go:171","msg":"trace[1580077150] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"367.551043ms","start":"2026-05-20T13:01:14.590914Z","end":"2026-05-20T13:01:14.958465Z","steps":["trace[1580077150] 'process raft request' (duration: 44.657002ms)","trace[1580077150] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/endpointslices/kube-system/ceph-csi-rbd-nodeplugin-http-metrics-d928z; req_size:1504; } (duration: 321.890582ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T13:01:14.958592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:14.5909Z","time spent":"367.648117ms","remote":"127.0.0.1:38880","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T13:01:14.962856Z","caller":"traceutil/trace.go:171","msg":"trace[970993880] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"370.552569ms","start":"2026-05-20T13:01:14.592285Z","end":"2026-05-20T13:01:14.962837Z","steps":["trace[970993880] 'process raft request' (duration: 369.902161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:14.962955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:14.592264Z","time spent":"370.638531ms","remote":"127.0.0.1:38780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T13:01:14.963186Z","caller":"traceutil/trace.go:171","msg":"trace[2146060704] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"368.12282ms","start":"2026-05-20T13:01:14.59505Z","end":"2026-05-20T13:01:14.963173Z","steps":["trace[2146060704] 'process raft request' (duration: 367.246755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:14.963241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:14.59497Z","time spent":"368.239873ms","remote":"127.0.0.1:39112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T13:01:18.944329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.014884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-20T13:01:18.944559Z","caller":"traceutil/trace.go:171","msg":"trace[1589814781] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"337.836507ms","start":"2026-05-20T13:01:18.606693Z","end":"2026-05-20T13:01:18.94453Z","steps":["trace[1589814781] 'process raft request' (duration: 200.565575ms)","trace[1589814781] 'compare' (duration: 136.920342ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T13:01:18.944588Z","caller":"traceutil/trace.go:171","msg":"trace[709757334] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1406; }","duration":"156.613702ms","start":"2026-05-20T13:01:18.787953Z","end":"2026-05-20T13:01:18.944566Z","steps":["trace[709757334] 'read index received' (duration: 19.272749ms)","trace[709757334] 'applied index is now lower than readState.Index' (duration: 137.339743ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T13:01:18.944681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:18.606664Z","time spent":"337.95231ms","remote":"127.0.0.1:38676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T13:01:18.94472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.759427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T13:01:18.944775Z","caller":"traceutil/trace.go:171","msg":"trace[810316039] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1348; }","duration":"156.85807ms","start":"2026-05-20T13:01:18.787902Z","end":"2026-05-20T13:01:18.94476Z","steps":["trace[810316039] 'agreement among raft nodes before linearized reading' (duration: 156.733677ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:01:18.944996Z","caller":"traceutil/trace.go:171","msg":"trace[1079616995] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"331.334152ms","start":"2026-05-20T13:01:18.613651Z","end":"2026-05-20T13:01:18.944985Z","steps":["trace[1079616995] 'process raft request' (duration: 330.781787ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:01:18.945036Z","caller":"traceutil/trace.go:171","msg":"trace[1372716467] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"331.171227ms","start":"2026-05-20T13:01:18.613849Z","end":"2026-05-20T13:01:18.94502Z","steps":["trace[1372716467] 'process raft request' (duration: 330.666153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:18.945058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:18.613634Z","time spent":"331.384123ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T13:01:18.945113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:18.613816Z","time spent":"331.25879ms","remote":"127.0.0.1:38676","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-05-20T13:01:19.575848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.73085ms","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-05-20T13:01:19.57596Z","caller":"traceutil/trace.go:171","msg":"trace[1871162563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1349; }","duration":"193.875116ms","start":"2026-05-20T13:01:19.382057Z","end":"2026-05-20T13:01:19.575932Z","steps":["trace[1871162563] 'range keys from in-memory index tree' (duration: 193.558406ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:01:20.26804Z","caller":"traceutil/trace.go:171","msg":"trace[1800804706] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1411; }","duration":"143.724365ms","start":"2026-05-20T13:01:20.124297Z","end":"2026-05-20T13:01:20.268021Z","steps":["trace[1800804706] 'read index received' (duration: 143.660874ms)","trace[1800804706] 'applied index is now lower than readState.Index' (duration: 62.421µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T13:01:20.268591Z","caller":"traceutil/trace.go:171","msg":"trace[753049212] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"177.329003ms","start":"2026-05-20T13:01:20.091223Z","end":"2026-05-20T13:01:20.268552Z","steps":["trace[753049212] 'process raft request' (duration: 176.670684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:20.268848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.531499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-20T13:01:20.268926Z","caller":"traceutil/trace.go:171","msg":"trace[729821518] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1351; }","duration":"144.659132ms","start":"2026-05-20T13:01:20.124254Z","end":"2026-05-20T13:01:20.268913Z","steps":["trace[729821518] 'agreement among raft nodes before linearized reading' (duration: 144.453486ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:01:20.344934Z","caller":"traceutil/trace.go:171","msg":"trace[323332319] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"115.772689ms","start":"2026-05-20T13:01:20.229142Z","end":"2026-05-20T13:01:20.344914Z","steps":["trace[323332319] 'process raft request' (duration: 115.633765ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:01:20.670358Z","caller":"traceutil/trace.go:171","msg":"trace[1902191431] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1412; }","duration":"402.227811ms","start":"2026-05-20T13:01:20.268106Z","end":"2026-05-20T13:01:20.670334Z","steps":["trace[1902191431] 'read index received' (duration: 76.589542ms)","trace[1902191431] 'applied index is now lower than readState.Index' (duration: 325.637009ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T13:01:20.670416Z","caller":"traceutil/trace.go:171","msg":"trace[1848066557] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"397.639861ms","start":"2026-05-20T13:01:20.272741Z","end":"2026-05-20T13:01:20.670381Z","steps":["trace[1848066557] 'process raft request' (duration: 397.547278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:20.670532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.106952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"warn","ts":"2026-05-20T13:01:20.670573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:20.27272Z","time spent":"397.770524ms","remote":"127.0.0.1:38874","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-05-20T13:01:20.670579Z","caller":"traceutil/trace.go:171","msg":"trace[2095701793] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1354; }","duration":"526.184454ms","start":"2026-05-20T13:01:20.14438Z","end":"2026-05-20T13:01:20.670565Z","steps":["trace[2095701793] 'agreement among raft nodes before linearized reading' (duration: 525.988368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:20.670574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.786742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-20T13:01:20.670616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:20.144366Z","time spent":"526.242195ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-20T13:01:20.670622Z","caller":"traceutil/trace.go:171","msg":"trace[242857066] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1354; }","duration":"545.881505ms","start":"2026-05-20T13:01:20.124729Z","end":"2026-05-20T13:01:20.670611Z","steps":["trace[242857066] 'agreement among raft nodes before linearized reading' (duration: 545.70529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:20.67066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:20.124716Z","time spent":"545.935127ms","remote":"127.0.0.1:38874","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-05-20T13:01:20.670663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.155624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-20T13:01:20.670736Z","caller":"traceutil/trace.go:171","msg":"trace[1975202926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1354; }","duration":"545.271038ms","start":"2026-05-20T13:01:20.12545Z","end":"2026-05-20T13:01:20.670721Z","steps":["trace[1975202926] 'agreement among raft nodes before linearized reading' (duration: 545.059912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:20.670769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:20.125427Z","time spent":"545.33574ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-20T13:01:20.670695Z","caller":"traceutil/trace.go:171","msg":"trace[1771156007] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"402.86614ms","start":"2026-05-20T13:01:20.267821Z","end":"2026-05-20T13:01:20.670687Z","steps":["trace[1771156007] 'process raft request' (duration: 402.287994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:20.670882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:20.267796Z","time spent":"403.056465ms","remote":"127.0.0.1:38874","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-05-20T13:01:20.818529Z","caller":"traceutil/trace.go:171","msg":"trace[1707363778] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"139.774753ms","start":"2026-05-20T13:01:20.678737Z","end":"2026-05-20T13:01:20.818512Z","steps":["trace[1707363778] 'process raft request' (duration: 139.747822ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:01:20.818602Z","caller":"traceutil/trace.go:171","msg":"trace[1803387322] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"144.183089ms","start":"2026-05-20T13:01:20.674372Z","end":"2026-05-20T13:01:20.818555Z","steps":["trace[1803387322] 'process raft request' (duration: 112.877077ms)","trace[1803387322] 'compare' (duration: 31.103836ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T13:01:20.818635Z","caller":"traceutil/trace.go:171","msg":"trace[1645528305] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"143.1425ms","start":"2026-05-20T13:01:20.67546Z","end":"2026-05-20T13:01:20.818603Z","steps":["trace[1645528305] 'process raft request' (duration: 142.993555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:30.922313Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822945301113920,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-20T13:01:31.160936Z","caller":"traceutil/trace.go:171","msg":"trace[879208051] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"846.427979ms","start":"2026-05-20T13:01:30.314483Z","end":"2026-05-20T13:01:31.160911Z","steps":["trace[879208051] 'process raft request' (duration: 846.215713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:31.161076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:30.314465Z","time spent":"846.537332ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T13:01:31.191922Z","caller":"traceutil/trace.go:171","msg":"trace[273437097] linearizableReadLoop","detail":"{readStateIndex:1478; appliedIndex:1476; }","duration":"770.491705ms","start":"2026-05-20T13:01:30.421399Z","end":"2026-05-20T13:01:31.191891Z","steps":["trace[273437097] 'read index received' (duration: 739.941984ms)","trace[273437097] 'applied index is now lower than readState.Index' (duration: 30.548391ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T13:01:31.192053Z","caller":"traceutil/trace.go:171","msg":"trace[1267342247] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"489.862069ms","start":"2026-05-20T13:01:30.702179Z","end":"2026-05-20T13:01:31.192041Z","steps":["trace[1267342247] 'process raft request' (duration: 489.651823ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:01:31.192137Z","caller":"traceutil/trace.go:171","msg":"trace[461515386] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"725.136872ms","start":"2026-05-20T13:01:30.466808Z","end":"2026-05-20T13:01:31.191945Z","steps":["trace[461515386] 'process raft request' (duration: 724.885595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:31.192281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.081703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T13:01:31.192343Z","caller":"traceutil/trace.go:171","msg":"trace[220978807] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1415; }","duration":"437.167817ms","start":"2026-05-20T13:01:30.755161Z","end":"2026-05-20T13:01:31.192328Z","steps":["trace[220978807] 'agreement among raft nodes before linearized reading' (duration: 437.080945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:31.192341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.018484ms","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":"warn","ts":"2026-05-20T13:01:31.192387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.027222ms","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-05-20T13:01:31.192407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.547413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-20T13:01:31.192449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.653618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T13:01:31.192448Z","caller":"traceutil/trace.go:171","msg":"trace[351914908] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1415; }","duration":"668.140558ms","start":"2026-05-20T13:01:30.524265Z","end":"2026-05-20T13:01:31.192405Z","steps":["trace[351914908] 'agreement among raft nodes before linearized reading' (duration: 667.901021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:31.192459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.114681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-20T13:01:31.192484Z","caller":"traceutil/trace.go:171","msg":"trace[1570333262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1415; }","duration":"385.715841ms","start":"2026-05-20T13:01:30.806757Z","end":"2026-05-20T13:01:31.192473Z","steps":["trace[1570333262] 'agreement among raft nodes before linearized reading' (duration: 385.665139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:31.192527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.123242ms","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":"warn","ts":"2026-05-20T13:01:31.192546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:30.806742Z","time spent":"385.794693ms","remote":"127.0.0.1:38606","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-05-20T13:01:31.192564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.604067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T13:01:31.192569Z","caller":"traceutil/trace.go:171","msg":"trace[1455821256] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1415; }","duration":"771.183914ms","start":"2026-05-20T13:01:30.421359Z","end":"2026-05-20T13:01:31.192543Z","steps":["trace[1455821256] 'agreement among raft nodes before linearized reading' (duration: 771.138503ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:01:31.192597Z","caller":"traceutil/trace.go:171","msg":"trace[1718202233] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1415; }","duration":"598.686779ms","start":"2026-05-20T13:01:30.593899Z","end":"2026-05-20T13:01:31.192586Z","steps":["trace[1718202233] 'agreement among raft nodes before linearized reading' (duration: 598.615167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:31.192634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:30.593884Z","time spent":"598.74127ms","remote":"127.0.0.1:52626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-20T13:01:31.192581Z","caller":"traceutil/trace.go:171","msg":"trace[1080963919] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1415; }","duration":"360.222693ms","start":"2026-05-20T13:01:30.832306Z","end":"2026-05-20T13:01:31.192529Z","steps":["trace[1080963919] 'agreement among raft nodes before linearized reading' (duration: 359.927825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:31.192661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:30.421341Z","time spent":"771.310747ms","remote":"127.0.0.1:52696","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-05-20T13:01:31.192707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:30.832289Z","time spent":"360.379469ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-20T13:01:31.192459Z","caller":"traceutil/trace.go:171","msg":"trace[257831471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1415; }","duration":"331.101885ms","start":"2026-05-20T13:01:30.861345Z","end":"2026-05-20T13:01:31.192447Z","steps":["trace[257831471] 'agreement among raft nodes before linearized reading' (duration: 330.922719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:31.192814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:30.86128Z","time spent":"331.522735ms","remote":"127.0.0.1:38874","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-05-20T13:01:31.192482Z","caller":"traceutil/trace.go:171","msg":"trace[13246179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1415; }","duration":"333.661037ms","start":"2026-05-20T13:01:30.858803Z","end":"2026-05-20T13:01:31.192464Z","steps":["trace[13246179] 'agreement among raft nodes before linearized reading' (duration: 333.442731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:31.192929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:30.858788Z","time spent":"334.13378ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T13:01:31.192391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:30.755145Z","time spent":"437.234878ms","remote":"127.0.0.1:38846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-05-20T13:01:31.193032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.281302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-20T13:01:31.192493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:30.52425Z","time spent":"668.2335ms","remote":"127.0.0.1:38874","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":"info","ts":"2026-05-20T13:01:31.193107Z","caller":"traceutil/trace.go:171","msg":"trace[1754697232] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1415; }","duration":"482.409626ms","start":"2026-05-20T13:01:30.710681Z","end":"2026-05-20T13:01:31.193091Z","steps":["trace[1754697232] 'agreement among raft nodes before linearized reading' (duration: 481.616073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:01:31.193156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:30.710664Z","time spent":"482.482318ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T13:01:31.192183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:30.702155Z","time spent":"489.94071ms","remote":"127.0.0.1:38874","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-05-20T13:01:31.193426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:01:30.466777Z","time spent":"726.490329ms","remote":"127.0.0.1:38874","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-05-20T13:02:48.825977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.028442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.86\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-20T13:02:48.826942Z","caller":"traceutil/trace.go:171","msg":"trace[1289968068] range","detail":"{range_begin:/registry/masterleases/199.19.213.86; range_end:; response_count:1; response_revision:1772; }","duration":"620.079362ms","start":"2026-05-20T13:02:48.206815Z","end":"2026-05-20T13:02:48.826894Z","steps":["trace[1289968068] 'range keys from in-memory index tree' (duration: 618.28751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:02:48.827133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:02:48.20679Z","time spent":"620.271498ms","remote":"127.0.0.1:38630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.86\" "} {"level":"warn","ts":"2026-05-20T13:03:04.06094Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822945301114951,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T13:03:04.561491Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822945301114951,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T13:03:05.062424Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13909822945301114951,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T13:03:05.328634Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.808197381s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T13:03:05.329667Z","caller":"traceutil/trace.go:171","msg":"trace[1296440051] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"1.809306033s","start":"2026-05-20T13:03:03.520326Z","end":"2026-05-20T13:03:05.329632Z","steps":["trace[1296440051] 'process raft request' (duration: 1.809002174s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:05.329939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:03.520307Z","time spent":"1.809482688s","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T13:03:05.341736Z","caller":"traceutil/trace.go:171","msg":"trace[1706863329] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1919; }","duration":"1.781178224s","start":"2026-05-20T13:03:03.560541Z","end":"2026-05-20T13:03:05.341719Z","steps":["trace[1706863329] 'read index received' (duration: 1.768768168s)","trace[1706863329] 'applied index is now lower than readState.Index' (duration: 12.409136ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T13:03:05.341995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.781437593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-20T13:03:05.342043Z","caller":"traceutil/trace.go:171","msg":"trace[1677879903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1839; }","duration":"1.781525895s","start":"2026-05-20T13:03:03.560508Z","end":"2026-05-20T13:03:05.342034Z","steps":["trace[1677879903] 'agreement among raft nodes before linearized reading' (duration: 1.78134436s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:05.342106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:03.560493Z","time spent":"1.781575566s","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T13:03:05.342345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.682066098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-20T13:03:05.342394Z","caller":"traceutil/trace.go:171","msg":"trace[871769162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1839; }","duration":"1.68214717s","start":"2026-05-20T13:03:03.660233Z","end":"2026-05-20T13:03:05.34238Z","steps":["trace[871769162] 'agreement among raft nodes before linearized reading' (duration: 1.682027576s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:05.342459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:03.660215Z","time spent":"1.682232673s","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T13:03:05.342544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.601091102s","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":"warn","ts":"2026-05-20T13:03:05.342657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.232903675s","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":"warn","ts":"2026-05-20T13:03:05.34275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.601902285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-20T13:03:05.342765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.53662483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T13:03:05.342814Z","caller":"traceutil/trace.go:171","msg":"trace[70534262] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1839; }","duration":"1.536710262s","start":"2026-05-20T13:03:03.806092Z","end":"2026-05-20T13:03:05.342803Z","steps":["trace[70534262] 'agreement among raft nodes before linearized reading' (duration: 1.536638021s)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:03:05.342835Z","caller":"traceutil/trace.go:171","msg":"trace[2111681221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1839; }","duration":"1.601982037s","start":"2026-05-20T13:03:03.740812Z","end":"2026-05-20T13:03:05.342794Z","steps":["trace[2111681221] 'agreement among raft nodes before linearized reading' (duration: 1.601846094s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:05.342865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:03.806073Z","time spent":"1.536782425s","remote":"127.0.0.1:38606","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-05-20T13:03:05.342881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:03.740801Z","time spent":"1.60206824s","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T13:03:05.342961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.715283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T13:03:05.343033Z","caller":"traceutil/trace.go:171","msg":"trace[21829564] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1839; }","duration":"594.853758ms","start":"2026-05-20T13:03:04.748169Z","end":"2026-05-20T13:03:05.343023Z","steps":["trace[21829564] 'agreement among raft nodes before linearized reading' (duration: 594.669512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:05.343107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:04.748138Z","time spent":"594.95352ms","remote":"127.0.0.1:52688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-05-20T13:03:05.342764Z","caller":"traceutil/trace.go:171","msg":"trace[1451280490] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1839; }","duration":"1.23307662s","start":"2026-05-20T13:03:04.109668Z","end":"2026-05-20T13:03:05.342745Z","steps":["trace[1451280490] 'agreement among raft nodes before linearized reading' (duration: 1.232699629s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:05.343346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233469631s","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-05-20T13:03:05.34341Z","caller":"traceutil/trace.go:171","msg":"trace[870369270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1839; }","duration":"1.233553744s","start":"2026-05-20T13:03:04.109841Z","end":"2026-05-20T13:03:05.343395Z","steps":["trace[870369270] 'agreement among raft nodes before linearized reading' (duration: 1.23341164s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:05.343431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186408519s","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:534"} {"level":"warn","ts":"2026-05-20T13:03:05.343467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:04.10983Z","time spent":"1.233624416s","remote":"127.0.0.1:38874","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-05-20T13:03:05.343499Z","caller":"traceutil/trace.go:171","msg":"trace[1302380148] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1839; }","duration":"1.186507864s","start":"2026-05-20T13:03:04.156973Z","end":"2026-05-20T13:03:05.34348Z","steps":["trace[1302380148] 'agreement among raft nodes before linearized reading' (duration: 1.186362879s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:05.343552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:04.156957Z","time spent":"1.186582185s","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-20T13:03:05.34344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:04.109625Z","time spent":"1.23375286s","remote":"127.0.0.1:52664","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-05-20T13:03:05.343117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.60628543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-20T13:03:05.343837Z","caller":"traceutil/trace.go:171","msg":"trace[279035574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1839; }","duration":"1.607043742s","start":"2026-05-20T13:03:03.736782Z","end":"2026-05-20T13:03:05.343826Z","steps":["trace[279035574] 'agreement among raft nodes before linearized reading' (duration: 1.60624634s)"],"step_count":1} {"level":"info","ts":"2026-05-20T13:03:05.342696Z","caller":"traceutil/trace.go:171","msg":"trace[909304171] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1839; }","duration":"1.601252206s","start":"2026-05-20T13:03:03.741427Z","end":"2026-05-20T13:03:05.342679Z","steps":["trace[909304171] 'agreement among raft nodes before linearized reading' (duration: 1.60102373s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:03:05.34396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:03.741421Z","time spent":"1.602518163s","remote":"127.0.0.1:38874","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-05-20T13:03:05.344029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:03:03.736763Z","time spent":"1.607239798s","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-20T13:05:53.768845Z","caller":"traceutil/trace.go:171","msg":"trace[545150220] transaction","detail":"{read_only:false; response_revision:2648; number_of_response:1; }","duration":"416.62997ms","start":"2026-05-20T13:05:53.352193Z","end":"2026-05-20T13:05:53.768823Z","steps":["trace[545150220] 'process raft request' (duration: 416.520607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T13:05:53.769054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T13:05:53.352169Z","time spent":"416.816865ms","remote":"127.0.0.1:38874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}