{"level":"warn","ts":"2026-03-31T11:05:16.549791Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T11:05:16.549866Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.123: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.123:2380","--initial-cluster=instance=https://199.19.213.123:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.123:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.123: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-03-31T11:05:16.549919Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T11:05:16.549929Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.123:2380"]} {"level":"info","ts":"2026-03-31T11:05:16.549953Z","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-03-31T11:05:16.55062Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.123:2379"]} {"level":"info","ts":"2026-03-31T11:05:16.550883Z","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.123:2380"],"listen-peer-urls":["https://199.19.213.123:2380"],"advertise-client-urls":["https://199.19.213.123:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.123:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.123: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-03-31T11:05:16.630405Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"77.36406ms"} {"level":"info","ts":"2026-03-31T11:05:16.73703Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3e4829a3cabe4fce","cluster-id":"b469cfed817d8605"} {"level":"info","ts":"2026-03-31T11:05:16.73712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T11:05:16.737145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became follower at term 0"} {"level":"info","ts":"2026-03-31T11:05:16.737158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3e4829a3cabe4fce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T11:05:16.737173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became follower at term 1"} {"level":"info","ts":"2026-03-31T11:05:16.737214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce switched to configuration voters=(4487882812132577230)"} {"level":"warn","ts":"2026-03-31T11:05:16.923451Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T11:05:16.948674Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T11:05:16.963578Z","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-03-31T11:05:17.019943Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3e4829a3cabe4fce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T11:05:17.020126Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3e4829a3cabe4fce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T11:05:17.020478Z","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-03-31T11:05:17.020512Z","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-03-31T11:05:17.020522Z","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-03-31T11:05:17.026741Z","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-03-31T11:05:17.026842Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.123:2380"} {"level":"info","ts":"2026-03-31T11:05:17.026893Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.123:2380"} {"level":"info","ts":"2026-03-31T11:05:17.02703Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3e4829a3cabe4fce","initial-advertise-peer-urls":["https://199.19.213.123:2380"],"listen-peer-urls":["https://199.19.213.123:2380"],"advertise-client-urls":["https://199.19.213.123:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.123:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T11:05:17.027076Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T11:05:17.032689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce switched to configuration voters=(4487882812132577230)"} {"level":"info","ts":"2026-03-31T11:05:17.032794Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b469cfed817d8605","local-member-id":"3e4829a3cabe4fce","added-peer-id":"3e4829a3cabe4fce","added-peer-peer-urls":["https://199.19.213.123:2380"]} {"level":"info","ts":"2026-03-31T11:05:17.739563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T11:05:17.739621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T11:05:17.739655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce received MsgPreVoteResp from 3e4829a3cabe4fce at term 1"} {"level":"info","ts":"2026-03-31T11:05:17.739675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became candidate at term 2"} {"level":"info","ts":"2026-03-31T11:05:17.739685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce received MsgVoteResp from 3e4829a3cabe4fce at term 2"} {"level":"info","ts":"2026-03-31T11:05:17.739701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3e4829a3cabe4fce became leader at term 2"} {"level":"info","ts":"2026-03-31T11:05:17.739714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3e4829a3cabe4fce elected leader 3e4829a3cabe4fce at term 2"} {"level":"info","ts":"2026-03-31T11:05:17.742645Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T11:05:17.761801Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T11:05:17.761792Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3e4829a3cabe4fce","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.123:2379]}","request-path":"/0/members/3e4829a3cabe4fce/attributes","cluster-id":"b469cfed817d8605","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T11:05:17.761857Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T11:05:17.762223Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T11:05:17.762285Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T11:05:17.765404Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.123:2379"} {"level":"info","ts":"2026-03-31T11:05:17.765439Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-31T11:05:17.765939Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b469cfed817d8605","local-member-id":"3e4829a3cabe4fce","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T11:05:17.766069Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T11:05:17.766103Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-03-31T11:05:17.984252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.571386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-31T11:05:17.984325Z","caller":"traceutil/trace.go:171","msg":"trace[889413791] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"101.698128ms","start":"2026-03-31T11:05:17.882614Z","end":"2026-03-31T11:05:17.984312Z","steps":["trace[889413791] 'count revisions from in-memory index tree' (duration: 101.498785ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:17.984433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.944805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-31T11:05:17.98445Z","caller":"traceutil/trace.go:171","msg":"trace[621204462] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"125.986225ms","start":"2026-03-31T11:05:17.85846Z","end":"2026-03-31T11:05:17.984446Z","steps":["trace[621204462] 'count revisions from in-memory index tree' (duration: 125.860484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:17.984639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.420287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-31T11:05:17.984658Z","caller":"traceutil/trace.go:171","msg":"trace[925944647] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"115.459398ms","start":"2026-03-31T11:05:17.869194Z","end":"2026-03-31T11:05:17.984654Z","steps":["trace[925944647] 'count revisions from in-memory index tree' (duration: 115.380857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:17.985292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.543103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-31T11:05:17.986943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.863162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-31T11:05:17.986987Z","caller":"traceutil/trace.go:171","msg":"trace[1350698029] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"117.898853ms","start":"2026-03-31T11:05:17.869068Z","end":"2026-03-31T11:05:17.986967Z","steps":["trace[1350698029] 'range keys from in-memory index tree' (duration: 117.793951ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:17.985373Z","caller":"traceutil/trace.go:171","msg":"trace[1577110064] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"102.844529ms","start":"2026-03-31T11:05:17.88251Z","end":"2026-03-31T11:05:17.985354Z","steps":["trace[1577110064] 'range keys from in-memory index tree' (duration: 102.036074ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:24.324889Z","caller":"traceutil/trace.go:171","msg":"trace[1666485596] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"117.486026ms","start":"2026-03-31T11:05:24.207371Z","end":"2026-03-31T11:05:24.324857Z","steps":["trace[1666485596] 'process raft request' (duration: 75.922472ms)","trace[1666485596] 'compare' (duration: 41.227758ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:05:24.523672Z","caller":"traceutil/trace.go:171","msg":"trace[1034100612] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"194.227163ms","start":"2026-03-31T11:05:24.3294Z","end":"2026-03-31T11:05:24.523627Z","steps":["trace[1034100612] 'process raft request' (duration: 139.717955ms)","trace[1034100612] 'compare' (duration: 54.219523ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:05:24.524847Z","caller":"traceutil/trace.go:171","msg":"trace[1587436298] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"191.665384ms","start":"2026-03-31T11:05:24.33314Z","end":"2026-03-31T11:05:24.524805Z","steps":["trace[1587436298] 'process raft request' (duration: 191.515752ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:24.892378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.00296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:05:24.892559Z","caller":"traceutil/trace.go:171","msg":"trace[1894795448] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"284.156689ms","start":"2026-03-31T11:05:24.608384Z","end":"2026-03-31T11:05:24.892541Z","steps":["trace[1894795448] 'process raft request' (duration: 284.088827ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:24.892613Z","caller":"traceutil/trace.go:171","msg":"trace[1962797631] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"306.136473ms","start":"2026-03-31T11:05:24.586444Z","end":"2026-03-31T11:05:24.89258Z","steps":["trace[1962797631] 'process raft request' (duration: 60.4504ms)","trace[1962797631] 'compare' (duration: 244.792516ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:05:24.892727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:24.586422Z","time spent":"306.252495ms","remote":"127.0.0.1:58230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":205,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:05:29.357555Z","caller":"traceutil/trace.go:171","msg":"trace[1831046397] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:385; }","duration":"105.51715ms","start":"2026-03-31T11:05:29.252013Z","end":"2026-03-31T11:05:29.35753Z","steps":["trace[1831046397] 'read index received' (duration: 68.701386ms)","trace[1831046397] 'applied index is now lower than readState.Index' (duration: 36.814824ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:05:29.357682Z","caller":"traceutil/trace.go:171","msg":"trace[1765861999] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"106.263914ms","start":"2026-03-31T11:05:29.251407Z","end":"2026-03-31T11:05:29.357671Z","steps":["trace[1765861999] 'process raft request' (duration: 69.398598ms)","trace[1765861999] 'compare' (duration: 36.59501ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:05:29.357854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.943619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-31T11:05:29.357934Z","caller":"traceutil/trace.go:171","msg":"trace[1860931024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:375; }","duration":"105.03734ms","start":"2026-03-31T11:05:29.25288Z","end":"2026-03-31T11:05:29.357918Z","steps":["trace[1860931024] 'agreement among raft nodes before linearized reading' (duration: 104.883778ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:29.358253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.219113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-31T11:05:29.358293Z","caller":"traceutil/trace.go:171","msg":"trace[1726257977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:375; }","duration":"106.280104ms","start":"2026-03-31T11:05:29.252001Z","end":"2026-03-31T11:05:29.358281Z","steps":["trace[1726257977] 'agreement among raft nodes before linearized reading' (duration: 106.190892ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:33.528389Z","caller":"traceutil/trace.go:171","msg":"trace[995421370] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"144.77445ms","start":"2026-03-31T11:05:33.383587Z","end":"2026-03-31T11:05:33.528361Z","steps":["trace[995421370] 'process raft request' (duration: 144.532426ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:35.927199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5750706687731877291,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T11:05:35.931371Z","caller":"traceutil/trace.go:171","msg":"trace[1365938280] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"510.603339ms","start":"2026-03-31T11:05:35.420741Z","end":"2026-03-31T11:05:35.931344Z","steps":["trace[1365938280] 'process raft request' (duration: 510.436656ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:35.931876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:35.420692Z","time spent":"510.742312ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:05:35.967439Z","caller":"traceutil/trace.go:171","msg":"trace[359692697] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:433; }","duration":"540.289228ms","start":"2026-03-31T11:05:35.427096Z","end":"2026-03-31T11:05:35.967385Z","steps":["trace[359692697] 'read index received' (duration: 504.050326ms)","trace[359692697] 'applied index is now lower than readState.Index' (duration: 36.237412ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:05:35.967518Z","caller":"traceutil/trace.go:171","msg":"trace[219344616] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"360.022939ms","start":"2026-03-31T11:05:35.607453Z","end":"2026-03-31T11:05:35.967476Z","steps":["trace[219344616] 'process raft request' (duration: 359.745784ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:35.967683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.208588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:05:35.967753Z","caller":"traceutil/trace.go:171","msg":"trace[1854696268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"514.345211ms","start":"2026-03-31T11:05:35.453389Z","end":"2026-03-31T11:05:35.967734Z","steps":["trace[1854696268] 'agreement among raft nodes before linearized reading' (duration: 514.210668ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:35.967816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:35.453369Z","time spent":"514.432752ms","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T11:05:35.967788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.641716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4795"} {"level":"warn","ts":"2026-03-31T11:05:35.967888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.491702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-31T11:05:35.967915Z","caller":"traceutil/trace.go:171","msg":"trace[1465517827] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:422; }","duration":"540.827289ms","start":"2026-03-31T11:05:35.427059Z","end":"2026-03-31T11:05:35.967886Z","steps":["trace[1465517827] 'agreement among raft nodes before linearized reading' (duration: 540.536834ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:35.967955Z","caller":"traceutil/trace.go:171","msg":"trace[1538193246] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:422; }","duration":"435.619704ms","start":"2026-03-31T11:05:35.532321Z","end":"2026-03-31T11:05:35.967941Z","steps":["trace[1538193246] 'agreement among raft nodes before linearized reading' (duration: 435.434241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:35.967977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:35.42703Z","time spent":"540.937262ms","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4818,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-03-31T11:05:35.967721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:35.607431Z","time spent":"360.177603ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:05:35.967993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:35.532289Z","time spent":"435.696346ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T11:05:35.968553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.896534ms","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-03-31T11:05:35.969188Z","caller":"traceutil/trace.go:171","msg":"trace[108847371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"161.568557ms","start":"2026-03-31T11:05:35.807594Z","end":"2026-03-31T11:05:35.969163Z","steps":["trace[108847371] 'agreement among raft nodes before linearized reading' (duration: 160.048149ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:50.175427Z","caller":"traceutil/trace.go:171","msg":"trace[811532791] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:505; }","duration":"123.085581ms","start":"2026-03-31T11:05:50.052324Z","end":"2026-03-31T11:05:50.175409Z","steps":["trace[811532791] 'read index received' (duration: 14.142437ms)","trace[811532791] 'applied index is now lower than readState.Index' (duration: 108.942504ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:05:50.175476Z","caller":"traceutil/trace.go:171","msg":"trace[31794053] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"180.659677ms","start":"2026-03-31T11:05:49.994797Z","end":"2026-03-31T11:05:50.175456Z","steps":["trace[31794053] 'process raft request' (duration: 118.625358ms)","trace[31794053] 'compare' (duration: 61.933497ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:05:50.175557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.213124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:05:50.175595Z","caller":"traceutil/trace.go:171","msg":"trace[1022511737] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:489; }","duration":"123.297026ms","start":"2026-03-31T11:05:50.052288Z","end":"2026-03-31T11:05:50.175585Z","steps":["trace[1022511737] 'agreement among raft nodes before linearized reading' (duration: 123.227374ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:50.364311Z","caller":"traceutil/trace.go:171","msg":"trace[2107768634] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:507; }","duration":"183.428259ms","start":"2026-03-31T11:05:50.18079Z","end":"2026-03-31T11:05:50.364218Z","steps":["trace[2107768634] 'read index received' (duration: 46.780512ms)","trace[2107768634] 'applied index is now lower than readState.Index' (duration: 136.645787ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:05:50.364349Z","caller":"traceutil/trace.go:171","msg":"trace[932687734] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"184.655563ms","start":"2026-03-31T11:05:50.179638Z","end":"2026-03-31T11:05:50.364294Z","steps":["trace[932687734] 'process raft request' (duration: 96.640363ms)","trace[932687734] 'compare' (duration: 87.261575ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:05:50.365286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.654634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-31T11:05:50.365392Z","caller":"traceutil/trace.go:171","msg":"trace[619172927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:491; }","duration":"113.780207ms","start":"2026-03-31T11:05:50.251581Z","end":"2026-03-31T11:05:50.365361Z","steps":["trace[619172927] 'agreement among raft nodes before linearized reading' (duration: 113.45002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:50.367477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.67028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:05:50.367567Z","caller":"traceutil/trace.go:171","msg":"trace[391682025] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:0; response_revision:491; }","duration":"186.773332ms","start":"2026-03-31T11:05:50.180771Z","end":"2026-03-31T11:05:50.367544Z","steps":["trace[391682025] 'agreement among raft nodes before linearized reading' (duration: 183.634013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:50.590841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.591636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:05:50.590922Z","caller":"traceutil/trace.go:171","msg":"trace[437315222] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:492; }","duration":"127.711068ms","start":"2026-03-31T11:05:50.463193Z","end":"2026-03-31T11:05:50.590904Z","steps":["trace[437315222] 'range keys from in-memory index tree' (duration: 127.516374ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:51.405815Z","caller":"traceutil/trace.go:171","msg":"trace[365152426] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"116.312533ms","start":"2026-03-31T11:05:51.289478Z","end":"2026-03-31T11:05:51.405791Z","steps":["trace[365152426] 'process raft request' (duration: 44.54562ms)","trace[365152426] 'compare' (duration: 71.561679ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:05:51.406255Z","caller":"traceutil/trace.go:171","msg":"trace[1308081669] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"115.328254ms","start":"2026-03-31T11:05:51.290865Z","end":"2026-03-31T11:05:51.406193Z","steps":["trace[1308081669] 'process raft request' (duration: 114.871316ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:51.406603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.113192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-03-31T11:05:51.406691Z","caller":"traceutil/trace.go:171","msg":"trace[912999970] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:518; }","duration":"113.241215ms","start":"2026-03-31T11:05:51.293434Z","end":"2026-03-31T11:05:51.406675Z","steps":["trace[912999970] 'agreement among raft nodes before linearized reading' (duration: 113.054192ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:51.406839Z","caller":"traceutil/trace.go:171","msg":"trace[1426037487] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"114.49078ms","start":"2026-03-31T11:05:51.292328Z","end":"2026-03-31T11:05:51.406819Z","steps":["trace[1426037487] 'process raft request' (duration: 113.670264ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:51.406466Z","caller":"traceutil/trace.go:171","msg":"trace[1289884679] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:529; }","duration":"112.750516ms","start":"2026-03-31T11:05:51.29346Z","end":"2026-03-31T11:05:51.406211Z","steps":["trace[1289884679] 'read index received' (duration: 2.202551ms)","trace[1289884679] 'applied index is now lower than readState.Index' (duration: 110.541234ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:05:51.955889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.661891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:05:51.956038Z","caller":"traceutil/trace.go:171","msg":"trace[781355602] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"469.482765ms","start":"2026-03-31T11:05:51.486502Z","end":"2026-03-31T11:05:51.955985Z","steps":["trace[781355602] 'read index received' (duration: 344.681921ms)","trace[781355602] 'applied index is now lower than readState.Index' (duration: 124.799424ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:05:51.956352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.714142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-03-31T11:05:51.956454Z","caller":"traceutil/trace.go:171","msg":"trace[1814172388] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:523; }","duration":"282.875595ms","start":"2026-03-31T11:05:51.673559Z","end":"2026-03-31T11:05:51.956434Z","steps":["trace[1814172388] 'agreement among raft nodes before linearized reading' (duration: 282.698972ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:51.956479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.884584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-03-31T11:05:51.956517Z","caller":"traceutil/trace.go:171","msg":"trace[412754362] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:523; }","duration":"431.921375ms","start":"2026-03-31T11:05:51.524583Z","end":"2026-03-31T11:05:51.956504Z","steps":["trace[412754362] 'agreement among raft nodes before linearized reading' (duration: 431.564499ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:51.95656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:51.524511Z","time spent":"432.040617ms","remote":"127.0.0.1:58126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329971,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-03-31T11:05:51.956408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.863711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4632"} {"level":"info","ts":"2026-03-31T11:05:51.956733Z","caller":"traceutil/trace.go:171","msg":"trace[685149152] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"470.35445ms","start":"2026-03-31T11:05:51.486332Z","end":"2026-03-31T11:05:51.956687Z","steps":["trace[685149152] 'process raft request' (duration: 344.789053ms)","trace[685149152] 'compare' (duration: 124.499408ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:05:51.956746Z","caller":"traceutil/trace.go:171","msg":"trace[709910208] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:523; }","duration":"470.257338ms","start":"2026-03-31T11:05:51.486462Z","end":"2026-03-31T11:05:51.956719Z","steps":["trace[709910208] 'agreement among raft nodes before linearized reading' (duration: 469.658687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:51.956913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:51.48632Z","time spent":"470.498563ms","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4668,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:05:51.956906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.364789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"warn","ts":"2026-03-31T11:05:51.956916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:51.486445Z","time spent":"470.431352ms","remote":"127.0.0.1:58504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":4655,"request content":"key:\"/registry/deployments/kube-system/cilium-operator\" "} {"level":"info","ts":"2026-03-31T11:05:51.956979Z","caller":"traceutil/trace.go:171","msg":"trace[1173850917] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:523; }","duration":"383.47384ms","start":"2026-03-31T11:05:51.573485Z","end":"2026-03-31T11:05:51.956959Z","steps":["trace[1173850917] 'agreement among raft nodes before linearized reading' (duration: 383.247166ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:51.957044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:51.573456Z","time spent":"383.578764ms","remote":"127.0.0.1:58230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":543,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"info","ts":"2026-03-31T11:05:52.078933Z","caller":"traceutil/trace.go:171","msg":"trace[670775841] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"114.410998ms","start":"2026-03-31T11:05:51.964493Z","end":"2026-03-31T11:05:52.078904Z","steps":["trace[670775841] 'process raft request' (duration: 114.218613ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:52.361629Z","caller":"traceutil/trace.go:171","msg":"trace[1541895064] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"117.683679ms","start":"2026-03-31T11:05:52.243922Z","end":"2026-03-31T11:05:52.361606Z","steps":["trace[1541895064] 'process raft request' (duration: 117.541107ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:53.572644Z","caller":"traceutil/trace.go:171","msg":"trace[1099438274] transaction","detail":"{read_only:false; number_of_response:0; response_revision:554; }","duration":"107.15322ms","start":"2026-03-31T11:05:53.465462Z","end":"2026-03-31T11:05:53.572615Z","steps":["trace[1099438274] 'process raft request' (duration: 53.637931ms)","trace[1099438274] 'compare' (duration: 53.364677ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:05:53.572682Z","caller":"traceutil/trace.go:171","msg":"trace[365824378] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"103.624294ms","start":"2026-03-31T11:05:53.469035Z","end":"2026-03-31T11:05:53.572659Z","steps":["trace[365824378] 'process raft request' (duration: 103.499072ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:53.606187Z","caller":"traceutil/trace.go:171","msg":"trace[296359568] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"124.757902ms","start":"2026-03-31T11:05:53.481407Z","end":"2026-03-31T11:05:53.606164Z","steps":["trace[296359568] 'process raft request' (duration: 124.450277ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:53.606208Z","caller":"traceutil/trace.go:171","msg":"trace[2098673513] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"124.505278ms","start":"2026-03-31T11:05:53.48168Z","end":"2026-03-31T11:05:53.606185Z","steps":["trace[2098673513] 'process raft request' (duration: 124.381765ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:53.606241Z","caller":"traceutil/trace.go:171","msg":"trace[1808789580] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"116.1573ms","start":"2026-03-31T11:05:53.490067Z","end":"2026-03-31T11:05:53.606224Z","steps":["trace[1808789580] 'process raft request' (duration: 116.052188ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:53.88029Z","caller":"traceutil/trace.go:171","msg":"trace[762572409] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"151.926674ms","start":"2026-03-31T11:05:53.728332Z","end":"2026-03-31T11:05:53.880259Z","steps":["trace[762572409] 'process raft request' (duration: 146.88736ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:53.881041Z","caller":"traceutil/trace.go:171","msg":"trace[1677856664] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"147.980961ms","start":"2026-03-31T11:05:53.733046Z","end":"2026-03-31T11:05:53.881027Z","steps":["trace[1677856664] 'process raft request' (duration: 147.209526ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:53.881156Z","caller":"traceutil/trace.go:171","msg":"trace[4798042] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"115.798644ms","start":"2026-03-31T11:05:53.765267Z","end":"2026-03-31T11:05:53.881064Z","steps":["trace[4798042] 'process raft request' (duration: 115.59574ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:55.57022Z","caller":"traceutil/trace.go:171","msg":"trace[1848435946] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"119.926852ms","start":"2026-03-31T11:05:55.450267Z","end":"2026-03-31T11:05:55.570194Z","steps":["trace[1848435946] 'read index received' (duration: 119.742369ms)","trace[1848435946] 'applied index is now lower than readState.Index' (duration: 183.483µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:05:55.570302Z","caller":"traceutil/trace.go:171","msg":"trace[337779407] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"152.911194ms","start":"2026-03-31T11:05:55.417367Z","end":"2026-03-31T11:05:55.570278Z","steps":["trace[337779407] 'process raft request' (duration: 152.69242ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:55.570402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.110535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:05:55.570441Z","caller":"traceutil/trace.go:171","msg":"trace[1014536433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"120.205887ms","start":"2026-03-31T11:05:55.450225Z","end":"2026-03-31T11:05:55.570431Z","steps":["trace[1014536433] 'agreement among raft nodes before linearized reading' (duration: 120.107616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:56.555781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.357581ms","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-03-31T11:05:56.555897Z","caller":"traceutil/trace.go:171","msg":"trace[1686778325] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"733.843969ms","start":"2026-03-31T11:05:55.822027Z","end":"2026-03-31T11:05:56.555871Z","steps":["trace[1686778325] 'process raft request' (duration: 704.709759ms)","trace[1686778325] 'compare' (duration: 28.915916ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:05:56.555941Z","caller":"traceutil/trace.go:171","msg":"trace[1679068265] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:637; }","duration":"259.530484ms","start":"2026-03-31T11:05:56.296389Z","end":"2026-03-31T11:05:56.555919Z","steps":["trace[1679068265] 'range keys from in-memory index tree' (duration: 259.34354ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:56.556003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:55.822017Z","time spent":"733.92889ms","remote":"127.0.0.1:58068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:05:56.844283Z","caller":"traceutil/trace.go:171","msg":"trace[1886322947] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"835.005206ms","start":"2026-03-31T11:05:56.009239Z","end":"2026-03-31T11:05:56.844244Z","steps":["trace[1886322947] 'process raft request' (duration: 833.664481ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:56.844325Z","caller":"traceutil/trace.go:171","msg":"trace[1295960037] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:658; }","duration":"699.334898ms","start":"2026-03-31T11:05:56.144964Z","end":"2026-03-31T11:05:56.844299Z","steps":["trace[1295960037] 'read index received' (duration: 381.7857ms)","trace[1295960037] 'applied index is now lower than readState.Index' (duration: 317.548358ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:05:56.844333Z","caller":"traceutil/trace.go:171","msg":"trace[1581507865] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"635.210519ms","start":"2026-03-31T11:05:56.209095Z","end":"2026-03-31T11:05:56.844305Z","steps":["trace[1581507865] 'process raft request' (duration: 635.029926ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:05:56.84438Z","caller":"traceutil/trace.go:171","msg":"trace[1062633626] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"508.844846ms","start":"2026-03-31T11:05:56.335481Z","end":"2026-03-31T11:05:56.844326Z","steps":["trace[1062633626] 'process raft request' (duration: 508.721693ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:56.84445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:56.009206Z","time spent":"835.169489ms","remote":"127.0.0.1:58068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T11:05:56.844482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:56.20906Z","time spent":"635.354902ms","remote":"127.0.0.1:58068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:05:56.844498Z","caller":"traceutil/trace.go:171","msg":"trace[351076583] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"353.907175ms","start":"2026-03-31T11:05:56.490581Z","end":"2026-03-31T11:05:56.844488Z","steps":["trace[351076583] 'process raft request' (duration: 353.67498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:56.844569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:56.335455Z","time spent":"509.023098ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4559,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:05:56.8446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:56.490565Z","time spent":"353.964275ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:05:56.844679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.706205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-31T11:05:56.844717Z","caller":"traceutil/trace.go:171","msg":"trace[1050132953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"699.803926ms","start":"2026-03-31T11:05:56.144898Z","end":"2026-03-31T11:05:56.844702Z","steps":["trace[1050132953] 'agreement among raft nodes before linearized reading' (duration: 699.662254ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:56.844744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:56.14488Z","time spent":"699.858327ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T11:05:56.849397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.132259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:05:56.849461Z","caller":"traceutil/trace.go:171","msg":"trace[1874290726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"677.231711ms","start":"2026-03-31T11:05:56.172215Z","end":"2026-03-31T11:05:56.849446Z","steps":["trace[1874290726] 'agreement among raft nodes before linearized reading' (duration: 677.121359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:56.849501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:56.172198Z","time spent":"677.295242ms","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T11:05:56.849775Z","caller":"traceutil/trace.go:171","msg":"trace[250466893] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"288.398289ms","start":"2026-03-31T11:05:56.561366Z","end":"2026-03-31T11:05:56.849765Z","steps":["trace[250466893] 'process raft request' (duration: 287.819459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:56.85018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.459967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:05:56.850234Z","caller":"traceutil/trace.go:171","msg":"trace[802774977] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:644; }","duration":"246.539669ms","start":"2026-03-31T11:05:56.603681Z","end":"2026-03-31T11:05:56.850221Z","steps":["trace[802774977] 'agreement among raft nodes before linearized reading' (duration: 246.466708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:56.850553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.834052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T11:05:56.850605Z","caller":"traceutil/trace.go:171","msg":"trace[914515848] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:644; }","duration":"389.887443ms","start":"2026-03-31T11:05:56.460705Z","end":"2026-03-31T11:05:56.850592Z","steps":["trace[914515848] 'agreement among raft nodes before linearized reading' (duration: 389.737399ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:56.850644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:56.460677Z","time spent":"389.956834ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T11:05:56.850672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.172023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:05:56.850727Z","caller":"traceutil/trace.go:171","msg":"trace[729748339] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"394.242935ms","start":"2026-03-31T11:05:56.456467Z","end":"2026-03-31T11:05:56.85071Z","steps":["trace[729748339] 'agreement among raft nodes before linearized reading' (duration: 394.145103ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:56.850789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:56.456458Z","time spent":"394.324797ms","remote":"127.0.0.1:57992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T11:05:56.850838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.708103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:05:56.850896Z","caller":"traceutil/trace.go:171","msg":"trace[1365020317] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:644; }","duration":"447.791954ms","start":"2026-03-31T11:05:56.403088Z","end":"2026-03-31T11:05:56.85088Z","steps":["trace[1365020317] 'agreement among raft nodes before linearized reading' (duration: 447.652952ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:56.850929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:56.403074Z","time spent":"447.847476ms","remote":"127.0.0.1:58068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-03-31T11:05:56.851012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.805509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-31T11:05:56.851048Z","caller":"traceutil/trace.go:171","msg":"trace[1510472895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:644; }","duration":"470.874189ms","start":"2026-03-31T11:05:56.380164Z","end":"2026-03-31T11:05:56.851038Z","steps":["trace[1510472895] 'agreement among raft nodes before linearized reading' (duration: 470.756007ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:56.851074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:56.380145Z","time spent":"470.923831ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T11:05:56.851095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.054737ms","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-03-31T11:05:56.85113Z","caller":"traceutil/trace.go:171","msg":"trace[124588198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:644; }","duration":"441.110458ms","start":"2026-03-31T11:05:56.410011Z","end":"2026-03-31T11:05:56.851121Z","steps":["trace[124588198] 'agreement among raft nodes before linearized reading' (duration: 440.998066ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:05:56.851156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:56.409967Z","time spent":"441.183459ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T11:06:00.59688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.306994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-03-31T11:06:00.596949Z","caller":"traceutil/trace.go:171","msg":"trace[5772543] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:680; }","duration":"606.452246ms","start":"2026-03-31T11:05:59.990482Z","end":"2026-03-31T11:06:00.596934Z","steps":["trace[5772543] 'range keys from in-memory index tree' (duration: 606.026049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:00.596978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:05:59.990457Z","time spent":"606.513938ms","remote":"127.0.0.1:58068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188518,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-03-31T11:06:00.59703Z","caller":"traceutil/trace.go:171","msg":"trace[1084106024] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"360.781463ms","start":"2026-03-31T11:06:00.236216Z","end":"2026-03-31T11:06:00.596998Z","steps":["trace[1084106024] 'process raft request' (duration: 360.0371ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:00.597189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:00.236195Z","time spent":"360.917935ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:06:00.617996Z","caller":"traceutil/trace.go:171","msg":"trace[245488016] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:702; }","duration":"165.788656ms","start":"2026-03-31T11:06:00.452151Z","end":"2026-03-31T11:06:00.617939Z","steps":["trace[245488016] 'read index received' (duration: 144.166759ms)","trace[245488016] 'applied index is now lower than readState.Index' (duration: 21.620747ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:00.618149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.984839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:00.618187Z","caller":"traceutil/trace.go:171","msg":"trace[40739282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"166.087281ms","start":"2026-03-31T11:06:00.452088Z","end":"2026-03-31T11:06:00.618176Z","steps":["trace[40739282] 'agreement among raft nodes before linearized reading' (duration: 165.98466ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:00.618425Z","caller":"traceutil/trace.go:171","msg":"trace[33293893] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"313.763267ms","start":"2026-03-31T11:06:00.304652Z","end":"2026-03-31T11:06:00.618415Z","steps":["trace[33293893] 'process raft request' (duration: 308.07791ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:00.618535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:00.304616Z","time spent":"313.839168ms","remote":"127.0.0.1:58126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:06:04.417748Z","caller":"traceutil/trace.go:171","msg":"trace[1920878657] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"865.740295ms","start":"2026-03-31T11:06:03.551989Z","end":"2026-03-31T11:06:04.417729Z","steps":["trace[1920878657] 'process raft request' (duration: 865.608023ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:04.417959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:03.55197Z","time spent":"865.902059ms","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:06:05.023053Z","caller":"traceutil/trace.go:171","msg":"trace[657723023] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"125.71558ms","start":"2026-03-31T11:06:04.897314Z","end":"2026-03-31T11:06:05.023029Z","steps":["trace[657723023] 'process raft request' (duration: 125.579048ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:05.040381Z","caller":"traceutil/trace.go:171","msg":"trace[1566740475] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:729; }","duration":"126.647629ms","start":"2026-03-31T11:06:04.913711Z","end":"2026-03-31T11:06:05.040359Z","steps":["trace[1566740475] 'read index received' (duration: 109.205649ms)","trace[1566740475] 'applied index is now lower than readState.Index' (duration: 17.44073ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:05.040466Z","caller":"traceutil/trace.go:171","msg":"trace[1941034360] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"142.819103ms","start":"2026-03-31T11:06:04.897609Z","end":"2026-03-31T11:06:05.040428Z","steps":["trace[1941034360] 'process raft request' (duration: 142.520917ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:05.040516Z","caller":"traceutil/trace.go:171","msg":"trace[131375497] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"141.423637ms","start":"2026-03-31T11:06:04.899053Z","end":"2026-03-31T11:06:05.040476Z","steps":["trace[131375497] 'process raft request' (duration: 141.178332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:05.04072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.981084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-31T11:06:05.040772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.083629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-31T11:06:05.040786Z","caller":"traceutil/trace.go:171","msg":"trace[1310846289] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:709; }","duration":"127.100157ms","start":"2026-03-31T11:06:04.91367Z","end":"2026-03-31T11:06:05.040771Z","steps":["trace[1310846289] 'agreement among raft nodes before linearized reading' (duration: 126.860593ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:05.040842Z","caller":"traceutil/trace.go:171","msg":"trace[1005270665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:709; }","duration":"125.375264ms","start":"2026-03-31T11:06:04.915453Z","end":"2026-03-31T11:06:05.040828Z","steps":["trace[1005270665] 'agreement among raft nodes before linearized reading' (duration: 125.264892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:05.807192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.453141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:06:05.807415Z","caller":"traceutil/trace.go:171","msg":"trace[326613248] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"762.722894ms","start":"2026-03-31T11:06:05.044671Z","end":"2026-03-31T11:06:05.807394Z","steps":["trace[326613248] 'process raft request' (duration: 762.645032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:05.807506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:05.044648Z","time spent":"762.813536ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:06:05.80762Z","caller":"traceutil/trace.go:171","msg":"trace[1608510874] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"762.928828ms","start":"2026-03-31T11:06:05.044646Z","end":"2026-03-31T11:06:05.807575Z","steps":["trace[1608510874] 'process raft request' (duration: 571.996497ms)","trace[1608510874] 'compare' (duration: 190.190426ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:05.807771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:05.044626Z","time spent":"763.06684ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:06:07.924262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.472146562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:07.924351Z","caller":"traceutil/trace.go:171","msg":"trace[18173171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"1.472291215s","start":"2026-03-31T11:06:06.452042Z","end":"2026-03-31T11:06:07.924333Z","steps":["trace[18173171] 'range keys from in-memory index tree' (duration: 1.47199868s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:07.924368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.970966ms","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-03-31T11:06:07.9244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:06.452023Z","time spent":"1.472362596s","remote":"127.0.0.1:57992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T11:06:07.924363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.627680265s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T11:06:07.92443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.960725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T11:06:07.924502Z","caller":"traceutil/trace.go:171","msg":"trace[1381209617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:711; }","duration":"879.076148ms","start":"2026-03-31T11:06:07.045406Z","end":"2026-03-31T11:06:07.924483Z","steps":["trace[1381209617] 'range keys from in-memory index tree' (duration: 878.776932ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:07.924524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.423083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-31T11:06:07.924555Z","caller":"traceutil/trace.go:171","msg":"trace[318729552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:711; }","duration":"897.482655ms","start":"2026-03-31T11:06:07.027066Z","end":"2026-03-31T11:06:07.924549Z","steps":["trace[318729552] 'range keys from in-memory index tree' (duration: 897.317732ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:07.924487Z","caller":"traceutil/trace.go:171","msg":"trace[1376232171] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:711; }","duration":"1.627818097s","start":"2026-03-31T11:06:06.296651Z","end":"2026-03-31T11:06:07.924469Z","steps":["trace[1376232171] 'range keys from in-memory index tree' (duration: 1.627665795s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:07.924576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:07.027051Z","time spent":"897.519015ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T11:06:07.924575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:07.045384Z","time spent":"879.15647ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-31T11:06:07.924421Z","caller":"traceutil/trace.go:171","msg":"trace[927734033] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:711; }","duration":"879.034507ms","start":"2026-03-31T11:06:07.045373Z","end":"2026-03-31T11:06:07.924408Z","steps":["trace[927734033] 'range keys from in-memory index tree' (duration: 878.810073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:07.924662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.750917479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T11:06:07.924663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:07.045343Z","time spent":"879.298922ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-31T11:06:07.924709Z","caller":"traceutil/trace.go:171","msg":"trace[1449906876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"1.750997101s","start":"2026-03-31T11:06:06.173698Z","end":"2026-03-31T11:06:07.924695Z","steps":["trace[1449906876] 'range keys from in-memory index tree' (duration: 1.750816528s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:07.924744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:06.173678Z","time spent":"1.751059621s","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T11:06:07.924928Z","caller":"traceutil/trace.go:171","msg":"trace[955328270] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:734; }","duration":"113.615062ms","start":"2026-03-31T11:06:07.811298Z","end":"2026-03-31T11:06:07.924913Z","steps":["trace[955328270] 'read index received' (duration: 28.042799ms)","trace[955328270] 'applied index is now lower than readState.Index' (duration: 85.571243ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:07.925051Z","caller":"traceutil/trace.go:171","msg":"trace[811492341] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"876.840736ms","start":"2026-03-31T11:06:07.048189Z","end":"2026-03-31T11:06:07.925029Z","steps":["trace[811492341] 'process raft request' (duration: 791.16741ms)","trace[811492341] 'compare' (duration: 84.688927ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:07.925105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.359868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-31T11:06:07.925101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.792756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-31T11:06:07.925135Z","caller":"traceutil/trace.go:171","msg":"trace[697871425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:712; }","duration":"113.428479ms","start":"2026-03-31T11:06:07.811697Z","end":"2026-03-31T11:06:07.925126Z","steps":["trace[697871425] 'agreement among raft nodes before linearized reading' (duration: 113.305767ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:07.92515Z","caller":"traceutil/trace.go:171","msg":"trace[1122777200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:712; }","duration":"113.876128ms","start":"2026-03-31T11:06:07.811262Z","end":"2026-03-31T11:06:07.925138Z","steps":["trace[1122777200] 'agreement among raft nodes before linearized reading' (duration: 113.706714ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:07.92517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:07.048169Z","time spent":"876.929017ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:06:08.055513Z","caller":"traceutil/trace.go:171","msg":"trace[832994305] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"126.531176ms","start":"2026-03-31T11:06:07.928956Z","end":"2026-03-31T11:06:08.055487Z","steps":["trace[832994305] 'process raft request' (duration: 105.512979ms)","trace[832994305] 'compare' (duration: 20.888564ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:08.055832Z","caller":"traceutil/trace.go:171","msg":"trace[932096937] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"126.72374ms","start":"2026-03-31T11:06:07.929075Z","end":"2026-03-31T11:06:08.055799Z","steps":["trace[932096937] 'process raft request' (duration: 126.377303ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:08.055855Z","caller":"traceutil/trace.go:171","msg":"trace[1359828778] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"126.241332ms","start":"2026-03-31T11:06:07.929589Z","end":"2026-03-31T11:06:08.05583Z","steps":["trace[1359828778] 'process raft request' (duration: 126.102728ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:08.055912Z","caller":"traceutil/trace.go:171","msg":"trace[1221709430] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"126.231052ms","start":"2026-03-31T11:06:07.92965Z","end":"2026-03-31T11:06:08.055882Z","steps":["trace[1221709430] 'process raft request' (duration: 126.12005ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:08.06065Z","caller":"traceutil/trace.go:171","msg":"trace[311120363] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:735; }","duration":"126.511526ms","start":"2026-03-31T11:06:07.934103Z","end":"2026-03-31T11:06:08.060615Z","steps":["trace[311120363] 'read index received' (duration: 87.433619ms)","trace[311120363] 'applied index is now lower than readState.Index' (duration: 39.076837ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:08.060752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.638898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:08.060805Z","caller":"traceutil/trace.go:171","msg":"trace[16100111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:717; }","duration":"126.72443ms","start":"2026-03-31T11:06:07.934064Z","end":"2026-03-31T11:06:08.060789Z","steps":["trace[16100111] 'agreement among raft nodes before linearized reading' (duration: 126.621198ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:10.829693Z","caller":"traceutil/trace.go:171","msg":"trace[1028403518] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"117.886134ms","start":"2026-03-31T11:06:10.711775Z","end":"2026-03-31T11:06:10.829661Z","steps":["trace[1028403518] 'process raft request' (duration: 89.101831ms)","trace[1028403518] 'compare' (duration: 28.505997ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:16.371442Z","caller":"traceutil/trace.go:171","msg":"trace[823684148] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"146.659996ms","start":"2026-03-31T11:06:16.224756Z","end":"2026-03-31T11:06:16.371416Z","steps":["trace[823684148] 'read index received' (duration: 127.004435ms)","trace[823684148] 'applied index is now lower than readState.Index' (duration: 19.653561ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:16.37145Z","caller":"traceutil/trace.go:171","msg":"trace[1272386180] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"164.450002ms","start":"2026-03-31T11:06:16.206978Z","end":"2026-03-31T11:06:16.371428Z","steps":["trace[1272386180] 'process raft request' (duration: 144.745551ms)","trace[1272386180] 'compare' (duration: 19.476387ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:16.371627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.866019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:2075"} {"level":"info","ts":"2026-03-31T11:06:16.371666Z","caller":"traceutil/trace.go:171","msg":"trace[2119579771] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:771; }","duration":"146.952071ms","start":"2026-03-31T11:06:16.224703Z","end":"2026-03-31T11:06:16.371655Z","steps":["trace[2119579771] 'agreement among raft nodes before linearized reading' (duration: 146.797068ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:22.39601Z","caller":"traceutil/trace.go:171","msg":"trace[758777101] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"103.483167ms","start":"2026-03-31T11:06:22.292503Z","end":"2026-03-31T11:06:22.395986Z","steps":["trace[758777101] 'process raft request' (duration: 79.73378ms)","trace[758777101] 'compare' (duration: 23.645444ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:22.419906Z","caller":"traceutil/trace.go:171","msg":"trace[73704917] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"127.135891ms","start":"2026-03-31T11:06:22.292756Z","end":"2026-03-31T11:06:22.419892Z","steps":["trace[73704917] 'process raft request' (duration: 127.022179ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:22.420158Z","caller":"traceutil/trace.go:171","msg":"trace[1130294397] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"127.071629ms","start":"2026-03-31T11:06:22.293066Z","end":"2026-03-31T11:06:22.420138Z","steps":["trace[1130294397] 'process raft request' (duration: 126.787544ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:22.420264Z","caller":"traceutil/trace.go:171","msg":"trace[672515889] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"125.670233ms","start":"2026-03-31T11:06:22.294572Z","end":"2026-03-31T11:06:22.420242Z","steps":["trace[672515889] 'process raft request' (duration: 125.531991ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:24.952349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5750706687731878145,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:06:25.45326Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5750706687731878145,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:06:25.9543Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5750706687731878145,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T11:06:25.970311Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.498986319s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T11:06:25.970931Z","caller":"traceutil/trace.go:171","msg":"trace[1383136098] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"1.520944893s","start":"2026-03-31T11:06:24.449964Z","end":"2026-03-31T11:06:25.970909Z","steps":["trace[1383136098] 'process raft request' (duration: 1.520697909s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:25.971283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:24.449938Z","time spent":"1.521070405s","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:06:25.975697Z","caller":"traceutil/trace.go:171","msg":"trace[1001962687] linearizableReadLoop","detail":"{readStateIndex:859; appliedIndex:856; }","duration":"1.523546543s","start":"2026-03-31T11:06:24.452126Z","end":"2026-03-31T11:06:25.975673Z","steps":["trace[1001962687] 'read index received' (duration: 1.518428507s)","trace[1001962687] 'applied index is now lower than readState.Index' (duration: 5.117216ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:25.975787Z","caller":"traceutil/trace.go:171","msg":"trace[118565015] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"1.525676281s","start":"2026-03-31T11:06:24.450102Z","end":"2026-03-31T11:06:25.975778Z","steps":["trace[118565015] 'process raft request' (duration: 1.525516139s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:25.975886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328597696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rvxvg\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-03-31T11:06:25.975902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:24.450074Z","time spent":"1.525744353s","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:06:25.975923Z","caller":"traceutil/trace.go:171","msg":"trace[2111418618] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rvxvg; range_end:; response_count:1; response_revision:830; }","duration":"1.328684228s","start":"2026-03-31T11:06:24.647229Z","end":"2026-03-31T11:06:25.975913Z","steps":["trace[2111418618] 'agreement among raft nodes before linearized reading' (duration: 1.328582736s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:25.975949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:24.647207Z","time spent":"1.328736978s","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rvxvg\" "} {"level":"warn","ts":"2026-03-31T11:06:25.975959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.523816947s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T11:06:25.975951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.704745ms","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-03-31T11:06:25.976024Z","caller":"traceutil/trace.go:171","msg":"trace[806660747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:830; }","duration":"1.52392588s","start":"2026-03-31T11:06:24.452079Z","end":"2026-03-31T11:06:25.976005Z","steps":["trace[806660747] 'agreement among raft nodes before linearized reading' (duration: 1.523807117s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:25.976071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:24.452059Z","time spent":"1.524003552s","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T11:06:25.976092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.606766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T11:06:25.976113Z","caller":"traceutil/trace.go:171","msg":"trace[1962861393] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:830; }","duration":"910.661857ms","start":"2026-03-31T11:06:25.065446Z","end":"2026-03-31T11:06:25.976108Z","steps":["trace[1962861393] 'agreement among raft nodes before linearized reading' (duration: 910.618177ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:25.976104Z","caller":"traceutil/trace.go:171","msg":"trace[998993686] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:830; }","duration":"325.874448ms","start":"2026-03-31T11:06:25.650143Z","end":"2026-03-31T11:06:25.976017Z","steps":["trace[998993686] 'agreement among raft nodes before linearized reading' (duration: 325.691604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:25.976137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:25.065428Z","time spent":"910.703238ms","remote":"127.0.0.1:58486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:06:25.976219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:25.650114Z","time spent":"326.034322ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-03-31T11:06:25.976309Z","caller":"traceutil/trace.go:171","msg":"trace[445541010] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"1.526295674s","start":"2026-03-31T11:06:24.44998Z","end":"2026-03-31T11:06:25.976275Z","steps":["trace[445541010] 'process raft request' (duration: 1.5255646s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:25.976495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:24.449958Z","time spent":"1.526445057s","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:06:29.0164Z","caller":"traceutil/trace.go:171","msg":"trace[31891004] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"343.765175ms","start":"2026-03-31T11:06:28.672611Z","end":"2026-03-31T11:06:29.016377Z","steps":["trace[31891004] 'process raft request' (duration: 343.540371ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:29.016762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:28.672592Z","time spent":"343.961869ms","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4216,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:06:30.314686Z","caller":"traceutil/trace.go:171","msg":"trace[349903715] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"324.457212ms","start":"2026-03-31T11:06:29.990207Z","end":"2026-03-31T11:06:30.314664Z","steps":["trace[349903715] 'process raft request' (duration: 322.499155ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:30.315015Z","caller":"traceutil/trace.go:171","msg":"trace[1147473711] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"323.987832ms","start":"2026-03-31T11:06:29.991Z","end":"2026-03-31T11:06:30.314988Z","steps":["trace[1147473711] 'process raft request' (duration: 323.614465ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:30.31509Z","caller":"traceutil/trace.go:171","msg":"trace[1813889033] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"291.172935ms","start":"2026-03-31T11:06:30.023894Z","end":"2026-03-31T11:06:30.315067Z","steps":["trace[1813889033] 'process raft request' (duration: 290.95334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:30.315144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:29.990188Z","time spent":"324.87428ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:06:30.315487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:29.990977Z","time spent":"324.095605ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:06:30.473156Z","caller":"traceutil/trace.go:171","msg":"trace[1331935444] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:877; }","duration":"149.47334ms","start":"2026-03-31T11:06:30.323656Z","end":"2026-03-31T11:06:30.473129Z","steps":["trace[1331935444] 'read index received' (duration: 138.737279ms)","trace[1331935444] 'applied index is now lower than readState.Index' (duration: 10.734781ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:30.473279Z","caller":"traceutil/trace.go:171","msg":"trace[1642278373] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"150.067321ms","start":"2026-03-31T11:06:30.3232Z","end":"2026-03-31T11:06:30.473267Z","steps":["trace[1642278373] 'process raft request' (duration: 139.203148ms)","trace[1642278373] 'compare' (duration: 10.598889ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:30.473564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.894868ms","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-03-31T11:06:30.473653Z","caller":"traceutil/trace.go:171","msg":"trace[2087594043] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:848; }","duration":"149.97692ms","start":"2026-03-31T11:06:30.323622Z","end":"2026-03-31T11:06:30.473599Z","steps":["trace[2087594043] 'agreement among raft nodes before linearized reading' (duration: 149.832138ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:30.792513Z","caller":"traceutil/trace.go:171","msg":"trace[1812409558] linearizableReadLoop","detail":"{readStateIndex:880; appliedIndex:878; }","duration":"300.120974ms","start":"2026-03-31T11:06:30.492367Z","end":"2026-03-31T11:06:30.792488Z","steps":["trace[1812409558] 'read index received' (duration: 16.007012ms)","trace[1812409558] 'applied index is now lower than readState.Index' (duration: 284.112982ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:30.792614Z","caller":"traceutil/trace.go:171","msg":"trace[2023085776] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"312.514838ms","start":"2026-03-31T11:06:30.480055Z","end":"2026-03-31T11:06:30.79257Z","steps":["trace[2023085776] 'process raft request' (duration: 263.796012ms)","trace[2023085776] 'compare' (duration: 48.496921ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:30.792735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.350909ms","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-03-31T11:06:30.792766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:30.480035Z","time spent":"312.64126ms","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2882,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:06:30.792811Z","caller":"traceutil/trace.go:171","msg":"trace[623183068] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:850; }","duration":"300.471051ms","start":"2026-03-31T11:06:30.492326Z","end":"2026-03-31T11:06:30.792797Z","steps":["trace[623183068] 'agreement among raft nodes before linearized reading' (duration: 300.255707ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:30.79286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:30.49231Z","time spent":"300.538212ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-31T11:06:31.119748Z","caller":"traceutil/trace.go:171","msg":"trace[1629867339] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:880; }","duration":"320.547518ms","start":"2026-03-31T11:06:30.799174Z","end":"2026-03-31T11:06:31.119722Z","steps":["trace[1629867339] 'read index received' (duration: 20.140829ms)","trace[1629867339] 'applied index is now lower than readState.Index' (duration: 300.405549ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:31.119903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.701731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/envoy-gateway-system/\" range_end:\"/registry/resourcequotas/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:31.119974Z","caller":"traceutil/trace.go:171","msg":"trace[189437278] range","detail":"{range_begin:/registry/resourcequotas/envoy-gateway-system/; range_end:/registry/resourcequotas/envoy-gateway-system0; response_count:0; response_revision:852; }","duration":"320.784323ms","start":"2026-03-31T11:06:30.799152Z","end":"2026-03-31T11:06:31.119936Z","steps":["trace[189437278] 'agreement among raft nodes before linearized reading' (duration: 320.670071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:31.12002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:30.799144Z","time spent":"320.865534ms","remote":"127.0.0.1:58124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/envoy-gateway-system/\" range_end:\"/registry/resourcequotas/envoy-gateway-system0\" "} {"level":"info","ts":"2026-03-31T11:06:31.120093Z","caller":"traceutil/trace.go:171","msg":"trace[1628957724] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"321.629368ms","start":"2026-03-31T11:06:30.798188Z","end":"2026-03-31T11:06:31.119817Z","steps":["trace[1628957724] 'process raft request' (duration: 321.158639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:31.120282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:30.798148Z","time spent":"322.000926ms","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":728,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:06:31.177189Z","caller":"traceutil/trace.go:171","msg":"trace[896378413] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"268.412757ms","start":"2026-03-31T11:06:30.90872Z","end":"2026-03-31T11:06:31.177132Z","steps":["trace[896378413] 'process raft request' (duration: 268.249094ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:31.433853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.29317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:06:31.434411Z","caller":"traceutil/trace.go:171","msg":"trace[22793651] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"312.310663ms","start":"2026-03-31T11:06:31.122069Z","end":"2026-03-31T11:06:31.43438Z","steps":["trace[22793651] 'process raft request' (duration: 205.439124ms)","trace[22793651] 'compare' (duration: 106.172606ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:31.434655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:31.122045Z","time spent":"312.573719ms","remote":"127.0.0.1:58230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T11:06:31.693489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.392578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:06:31.693593Z","caller":"traceutil/trace.go:171","msg":"trace[1695930482] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"254.390135ms","start":"2026-03-31T11:06:31.439184Z","end":"2026-03-31T11:06:31.693574Z","steps":["trace[1695930482] 'process raft request' (duration: 111.847024ms)","trace[1695930482] 'compare' (duration: 142.260606ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:32.100651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.915075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:06:32.10089Z","caller":"traceutil/trace.go:171","msg":"trace[1316516476] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"403.016939ms","start":"2026-03-31T11:06:31.697845Z","end":"2026-03-31T11:06:32.100861Z","steps":["trace[1316516476] 'process raft request' (duration: 291.818079ms)","trace[1316516476] 'compare' (duration: 110.810483ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:32.100988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:31.69782Z","time spent":"403.127802ms","remote":"127.0.0.1:58396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1929,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:06:32.517856Z","caller":"traceutil/trace.go:171","msg":"trace[676561923] linearizableReadLoop","detail":"{readStateIndex:893; appliedIndex:892; }","duration":"198.567934ms","start":"2026-03-31T11:06:32.319265Z","end":"2026-03-31T11:06:32.517833Z","steps":["trace[676561923] 'read index received' (duration: 123.711236ms)","trace[676561923] 'applied index is now lower than readState.Index' (duration: 74.855778ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:32.517933Z","caller":"traceutil/trace.go:171","msg":"trace[709419796] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"271.588177ms","start":"2026-03-31T11:06:32.246314Z","end":"2026-03-31T11:06:32.517902Z","steps":["trace[709419796] 'process raft request' (duration: 196.75092ms)","trace[709419796] 'compare' (duration: 74.642994ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:32.518099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.822159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T11:06:32.518139Z","caller":"traceutil/trace.go:171","msg":"trace[1386083571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:863; }","duration":"198.917711ms","start":"2026-03-31T11:06:32.319212Z","end":"2026-03-31T11:06:32.51813Z","steps":["trace[1386083571] 'agreement among raft nodes before linearized reading' (duration: 198.761388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:32.518526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.901941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-31T11:06:32.518573Z","caller":"traceutil/trace.go:171","msg":"trace[599561583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:863; }","duration":"199.010972ms","start":"2026-03-31T11:06:32.319551Z","end":"2026-03-31T11:06:32.518562Z","steps":["trace[599561583] 'agreement among raft nodes before linearized reading' (duration: 198.811859ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:32.715411Z","caller":"traceutil/trace.go:171","msg":"trace[1232542939] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"192.072222ms","start":"2026-03-31T11:06:32.523316Z","end":"2026-03-31T11:06:32.715388Z","steps":["trace[1232542939] 'process raft request' (duration: 192.034511ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:32.715761Z","caller":"traceutil/trace.go:171","msg":"trace[957941252] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"193.328036ms","start":"2026-03-31T11:06:32.522422Z","end":"2026-03-31T11:06:32.71575Z","steps":["trace[957941252] 'process raft request' (duration: 192.828467ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:32.715716Z","caller":"traceutil/trace.go:171","msg":"trace[925657878] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"195.209621ms","start":"2026-03-31T11:06:32.520478Z","end":"2026-03-31T11:06:32.715688Z","steps":["trace[925657878] 'process raft request' (duration: 129.858952ms)","trace[925657878] 'compare' (duration: 64.741048ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:32.715901Z","caller":"traceutil/trace.go:171","msg":"trace[1990526327] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"192.673953ms","start":"2026-03-31T11:06:32.5232Z","end":"2026-03-31T11:06:32.715873Z","steps":["trace[1990526327] 'process raft request' (duration: 192.114903ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:33.050509Z","caller":"traceutil/trace.go:171","msg":"trace[1028331817] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:906; }","duration":"167.797276ms","start":"2026-03-31T11:06:32.882689Z","end":"2026-03-31T11:06:33.050486Z","steps":["trace[1028331817] 'read index received' (duration: 151.440479ms)","trace[1028331817] 'applied index is now lower than readState.Index' (duration: 16.355727ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:33.050718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.00929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:5266"} {"level":"info","ts":"2026-03-31T11:06:33.050779Z","caller":"traceutil/trace.go:171","msg":"trace[145831261] range","detail":"{range_begin:/registry/deployments/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:876; }","duration":"168.084071ms","start":"2026-03-31T11:06:32.882664Z","end":"2026-03-31T11:06:33.050748Z","steps":["trace[145831261] 'agreement among raft nodes before linearized reading' (duration: 167.911748ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:33.05107Z","caller":"traceutil/trace.go:171","msg":"trace[379108229] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"171.513205ms","start":"2026-03-31T11:06:32.879211Z","end":"2026-03-31T11:06:33.050724Z","steps":["trace[379108229] 'process raft request' (duration: 154.885032ms)","trace[379108229] 'compare' (duration: 16.275576ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:33.393657Z","caller":"traceutil/trace.go:171","msg":"trace[1421751370] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"336.421596ms","start":"2026-03-31T11:06:33.057214Z","end":"2026-03-31T11:06:33.393636Z","steps":["trace[1421751370] 'process raft request' (duration: 336.292144ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:33.393956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:33.057186Z","time spent":"336.527119ms","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":755,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T11:06:33.770664Z","caller":"traceutil/trace.go:171","msg":"trace[1928687358] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"712.50481ms","start":"2026-03-31T11:06:33.058123Z","end":"2026-03-31T11:06:33.770628Z","steps":["trace[1928687358] 'process raft request' (duration: 712.285215ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:33.770717Z","caller":"traceutil/trace.go:171","msg":"trace[879876698] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"709.705437ms","start":"2026-03-31T11:06:33.060986Z","end":"2026-03-31T11:06:33.770691Z","steps":["trace[879876698] 'process raft request' (duration: 709.566603ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:33.770864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:33.058107Z","time spent":"712.641552ms","remote":"127.0.0.1:58212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:06:33.771051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:33.06097Z","time spent":"709.818059ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5099,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:06:33.77133Z","caller":"traceutil/trace.go:171","msg":"trace[520596833] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"709.569184ms","start":"2026-03-31T11:06:33.061677Z","end":"2026-03-31T11:06:33.771246Z","steps":["trace[520596833] 'process raft request' (duration: 708.925112ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:33.771581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:33.061666Z","time spent":"709.783529ms","remote":"127.0.0.1:58504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:06:33.774458Z","caller":"traceutil/trace.go:171","msg":"trace[1981135402] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:908; }","duration":"321.974135ms","start":"2026-03-31T11:06:33.45246Z","end":"2026-03-31T11:06:33.774434Z","steps":["trace[1981135402] 'read index received' (duration: 317.870547ms)","trace[1981135402] 'applied index is now lower than readState.Index' (duration: 4.102138ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:33.774806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.098777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:33.774901Z","caller":"traceutil/trace.go:171","msg":"trace[1176422784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:881; }","duration":"322.446644ms","start":"2026-03-31T11:06:33.452405Z","end":"2026-03-31T11:06:33.774851Z","steps":["trace[1176422784] 'agreement among raft nodes before linearized reading' (duration: 322.107687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:33.775135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:33.452384Z","time spent":"322.636918ms","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T11:06:33.774728Z","caller":"traceutil/trace.go:171","msg":"trace[151653112] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"596.558289ms","start":"2026-03-31T11:06:33.17813Z","end":"2026-03-31T11:06:33.774688Z","steps":["trace[151653112] 'process raft request' (duration: 593.169376ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:33.777458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:33.178103Z","time spent":"599.27955ms","remote":"127.0.0.1:58126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036298,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:06:34.031513Z","caller":"traceutil/trace.go:171","msg":"trace[859242102] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:913; }","duration":"137.832512ms","start":"2026-03-31T11:06:33.893659Z","end":"2026-03-31T11:06:34.031491Z","steps":["trace[859242102] 'read index received' (duration: 82.376069ms)","trace[859242102] 'applied index is now lower than readState.Index' (duration: 55.455523ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:34.031633Z","caller":"traceutil/trace.go:171","msg":"trace[401797580] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"251.276986ms","start":"2026-03-31T11:06:33.780318Z","end":"2026-03-31T11:06:34.031595Z","steps":["trace[401797580] 'process raft request' (duration: 195.804002ms)","trace[401797580] 'compare' (duration: 55.161759ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:34.031972Z","caller":"traceutil/trace.go:171","msg":"trace[955270519] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"247.393282ms","start":"2026-03-31T11:06:33.784547Z","end":"2026-03-31T11:06:34.03194Z","steps":["trace[955270519] 'process raft request' (duration: 246.888073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:34.03275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.074177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050264"} {"level":"info","ts":"2026-03-31T11:06:34.032805Z","caller":"traceutil/trace.go:171","msg":"trace[1969771177] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:884; }","duration":"139.184098ms","start":"2026-03-31T11:06:33.893607Z","end":"2026-03-31T11:06:34.032791Z","steps":["trace[1969771177] 'agreement among raft nodes before linearized reading' (duration: 138.014626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:34.380881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.878975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:06:34.380985Z","caller":"traceutil/trace.go:171","msg":"trace[1285861437] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"314.076946ms","start":"2026-03-31T11:06:34.066891Z","end":"2026-03-31T11:06:34.380968Z","steps":["trace[1285861437] 'read index received' (duration: 135.976147ms)","trace[1285861437] 'applied index is now lower than readState.Index' (duration: 178.099489ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:06:34.38107Z","caller":"traceutil/trace.go:171","msg":"trace[797496720] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"339.946232ms","start":"2026-03-31T11:06:34.041092Z","end":"2026-03-31T11:06:34.381039Z","steps":["trace[797496720] 'process raft request' (duration: 161.824963ms)","trace[797496720] 'compare' (duration: 177.717342ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:06:34.381356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.420123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-31T11:06:34.381425Z","caller":"traceutil/trace.go:171","msg":"trace[677730083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:885; }","duration":"248.546615ms","start":"2026-03-31T11:06:34.13286Z","end":"2026-03-31T11:06:34.381407Z","steps":["trace[677730083] 'agreement among raft nodes before linearized reading' (duration: 248.293201ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:34.381433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:34.041064Z","time spent":"340.135406ms","remote":"127.0.0.1:58504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5601,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:06:34.382152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.668744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050264"} {"level":"info","ts":"2026-03-31T11:06:34.382208Z","caller":"traceutil/trace.go:171","msg":"trace[1466709809] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:885; }","duration":"157.759306ms","start":"2026-03-31T11:06:34.224432Z","end":"2026-03-31T11:06:34.382191Z","steps":["trace[1466709809] 'agreement among raft nodes before linearized reading' (duration: 156.743328ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:34.38224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.33612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-03-31T11:06:34.382304Z","caller":"traceutil/trace.go:171","msg":"trace[1914606131] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:885; }","duration":"315.434702ms","start":"2026-03-31T11:06:34.066853Z","end":"2026-03-31T11:06:34.382287Z","steps":["trace[1914606131] 'agreement among raft nodes before linearized reading' (duration: 314.20061ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:34.382351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:06:34.066834Z","time spent":"315.506573ms","remote":"127.0.0.1:58230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":869,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"info","ts":"2026-03-31T11:06:34.926849Z","caller":"traceutil/trace.go:171","msg":"trace[209954692] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"202.500278ms","start":"2026-03-31T11:06:34.724324Z","end":"2026-03-31T11:06:34.926825Z","steps":["trace[209954692] 'process raft request' (duration: 202.254874ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:34.927064Z","caller":"traceutil/trace.go:171","msg":"trace[1060305202] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"202.513418ms","start":"2026-03-31T11:06:34.72452Z","end":"2026-03-31T11:06:34.927034Z","steps":["trace[1060305202] 'process raft request' (duration: 202.257393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:06:35.329041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.771762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/eg\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:06:35.329145Z","caller":"traceutil/trace.go:171","msg":"trace[1369313384] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/eg; range_end:; response_count:0; response_revision:892; }","duration":"111.911004ms","start":"2026-03-31T11:06:35.217217Z","end":"2026-03-31T11:06:35.329128Z","steps":["trace[1369313384] 'range keys from in-memory index tree' (duration: 111.714661ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:54.875517Z","caller":"traceutil/trace.go:171","msg":"trace[601133055] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"206.31774ms","start":"2026-03-31T11:06:54.669179Z","end":"2026-03-31T11:06:54.875497Z","steps":["trace[601133055] 'process raft request' (duration: 206.204668ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:06:57.342539Z","caller":"traceutil/trace.go:171","msg":"trace[193093283] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"143.162132ms","start":"2026-03-31T11:06:57.199357Z","end":"2026-03-31T11:06:57.342519Z","steps":["trace[193093283] 'process raft request' (duration: 61.711691ms)","trace[193093283] 'compare' (duration: 81.357859ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:07:04.750383Z","caller":"traceutil/trace.go:171","msg":"trace[524965634] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"117.137243ms","start":"2026-03-31T11:07:04.633222Z","end":"2026-03-31T11:07:04.750359Z","steps":["trace[524965634] 'process raft request' (duration: 116.998461ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:06.343331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230660133s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:07:06.343428Z","caller":"traceutil/trace.go:171","msg":"trace[521696499] linearizableReadLoop","detail":"{readStateIndex:1094; appliedIndex:1093; }","duration":"1.316581811s","start":"2026-03-31T11:07:05.026829Z","end":"2026-03-31T11:07:06.343411Z","steps":["trace[521696499] 'read index received' (duration: 85.64137ms)","trace[521696499] 'applied index is now lower than readState.Index' (duration: 1.230939311s)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:07:06.343627Z","caller":"traceutil/trace.go:171","msg":"trace[31885566] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"1.408681252s","start":"2026-03-31T11:07:04.934932Z","end":"2026-03-31T11:07:06.343613Z","steps":["trace[31885566] 'process raft request' (duration: 177.664712ms)","trace[31885566] 'compare' (duration: 1.230096943s)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:07:06.343695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:04.934901Z","time spent":"1.408760753s","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:07:06.34384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.317000138s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-31T11:07:06.343904Z","caller":"traceutil/trace.go:171","msg":"trace[524640834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1055; }","duration":"1.317072889s","start":"2026-03-31T11:07:05.026815Z","end":"2026-03-31T11:07:06.343888Z","steps":["trace[524640834] 'agreement among raft nodes before linearized reading' (duration: 1.316880716s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:06.343941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:05.026744Z","time spent":"1.317189371s","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T11:07:06.344212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150445506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-31T11:07:06.344238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.794898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:07:06.344294Z","caller":"traceutil/trace.go:171","msg":"trace[932354379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1055; }","duration":"1.150513037s","start":"2026-03-31T11:07:05.193734Z","end":"2026-03-31T11:07:06.344247Z","steps":["trace[932354379] 'agreement among raft nodes before linearized reading' (duration: 1.150414535s)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:06.344302Z","caller":"traceutil/trace.go:171","msg":"trace[889880648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1055; }","duration":"893.88338ms","start":"2026-03-31T11:07:05.450402Z","end":"2026-03-31T11:07:06.344285Z","steps":["trace[889880648] 'agreement among raft nodes before linearized reading' (duration: 893.789469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:06.344292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085464493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T11:07:06.344335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:05.450386Z","time spent":"893.941661ms","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T11:07:06.344329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:05.193716Z","time spent":"1.150605319s","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T11:07:06.344368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104467461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-31T11:07:06.344383Z","caller":"traceutil/trace.go:171","msg":"trace[1597186006] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1055; }","duration":"1.085607886s","start":"2026-03-31T11:07:05.258743Z","end":"2026-03-31T11:07:06.344351Z","steps":["trace[1597186006] 'agreement among raft nodes before linearized reading' (duration: 1.085454633s)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:06.344422Z","caller":"traceutil/trace.go:171","msg":"trace[22064666] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1055; }","duration":"1.104553263s","start":"2026-03-31T11:07:05.239852Z","end":"2026-03-31T11:07:06.344405Z","steps":["trace[22064666] 'agreement among raft nodes before linearized reading' (duration: 1.104366639s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:06.344433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:05.258734Z","time spent":"1.085686747s","remote":"127.0.0.1:52930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:07:06.344457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:05.239836Z","time spent":"1.104612283s","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T11:07:06.344834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.602486ms","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-03-31T11:07:06.344937Z","caller":"traceutil/trace.go:171","msg":"trace[120234943] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1055; }","duration":"968.665147ms","start":"2026-03-31T11:07:05.376195Z","end":"2026-03-31T11:07:06.34486Z","steps":["trace[120234943] 'agreement among raft nodes before linearized reading' (duration: 967.986904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:06.344969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:05.37618Z","time spent":"968.780689ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-31T11:07:06.67644Z","caller":"traceutil/trace.go:171","msg":"trace[295592713] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"328.468417ms","start":"2026-03-31T11:07:06.347952Z","end":"2026-03-31T11:07:06.67642Z","steps":["trace[295592713] 'process raft request' (duration: 328.367915ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:06.67655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:06.347939Z","time spent":"328.553168ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:06.677537Z","caller":"traceutil/trace.go:171","msg":"trace[1875353688] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"328.275293ms","start":"2026-03-31T11:07:06.349237Z","end":"2026-03-31T11:07:06.677512Z","steps":["trace[1875353688] 'process raft request' (duration: 328.202272ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:06.677578Z","caller":"traceutil/trace.go:171","msg":"trace[810692106] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"329.199611ms","start":"2026-03-31T11:07:06.348357Z","end":"2026-03-31T11:07:06.677557Z","steps":["trace[810692106] 'process raft request' (duration: 328.956157ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:06.677688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.638143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T11:07:06.677692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:06.349231Z","time spent":"328.387935ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:06.677732Z","caller":"traceutil/trace.go:171","msg":"trace[2036386315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"225.721685ms","start":"2026-03-31T11:07:06.452002Z","end":"2026-03-31T11:07:06.677724Z","steps":["trace[2036386315] 'agreement among raft nodes before linearized reading' (duration: 225.617113ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:06.677691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:06.34834Z","time spent":"329.287463ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:06.677574Z","caller":"traceutil/trace.go:171","msg":"trace[570557031] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1094; }","duration":"225.520281ms","start":"2026-03-31T11:07:06.452034Z","end":"2026-03-31T11:07:06.677554Z","steps":["trace[570557031] 'read index received' (duration: 224.358729ms)","trace[570557031] 'applied index is now lower than readState.Index' (duration: 1.160402ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:07:06.677899Z","caller":"traceutil/trace.go:171","msg":"trace[736440574] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"328.161001ms","start":"2026-03-31T11:07:06.349727Z","end":"2026-03-31T11:07:06.677888Z","steps":["trace[736440574] 'process raft request' (duration: 327.750414ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:06.677957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:06.349708Z","time spent":"328.219063ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:10.883384Z","caller":"traceutil/trace.go:171","msg":"trace[652207858] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"126.102372ms","start":"2026-03-31T11:07:10.757255Z","end":"2026-03-31T11:07:10.883357Z","steps":["trace[652207858] 'process raft request' (duration: 126.02528ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:10.883429Z","caller":"traceutil/trace.go:171","msg":"trace[1724014279] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"126.815174ms","start":"2026-03-31T11:07:10.756582Z","end":"2026-03-31T11:07:10.883397Z","steps":["trace[1724014279] 'process raft request' (duration: 32.289547ms)","trace[1724014279] 'compare' (duration: 94.277413ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:07:10.883583Z","caller":"traceutil/trace.go:171","msg":"trace[1831462114] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"126.286785ms","start":"2026-03-31T11:07:10.757281Z","end":"2026-03-31T11:07:10.883568Z","steps":["trace[1831462114] 'process raft request' (duration: 126.03825ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:17.124975Z","caller":"traceutil/trace.go:171","msg":"trace[36863079] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"181.428723ms","start":"2026-03-31T11:07:16.943509Z","end":"2026-03-31T11:07:17.124937Z","steps":["trace[36863079] 'read index received' (duration: 86.369545ms)","trace[36863079] 'applied index is now lower than readState.Index' (duration: 95.057628ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:07:17.125236Z","caller":"traceutil/trace.go:171","msg":"trace[462731733] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"205.958204ms","start":"2026-03-31T11:07:16.91925Z","end":"2026-03-31T11:07:17.125208Z","steps":["trace[462731733] 'process raft request' (duration: 110.547729ms)","trace[462731733] 'compare' (duration: 94.941376ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:07:17.125589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.937142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-31T11:07:17.125592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.057324ms","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-03-31T11:07:17.125655Z","caller":"traceutil/trace.go:171","msg":"trace[1759624676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1091; }","duration":"182.074924ms","start":"2026-03-31T11:07:16.943566Z","end":"2026-03-31T11:07:17.125641Z","steps":["trace[1759624676] 'agreement among raft nodes before linearized reading' (duration: 181.882471ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:17.125671Z","caller":"traceutil/trace.go:171","msg":"trace[312252336] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1091; }","duration":"182.170946ms","start":"2026-03-31T11:07:16.943479Z","end":"2026-03-31T11:07:17.12565Z","steps":["trace[312252336] 'agreement among raft nodes before linearized reading' (duration: 181.888721ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:17.337976Z","caller":"traceutil/trace.go:171","msg":"trace[1579661395] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"208.358188ms","start":"2026-03-31T11:07:17.129584Z","end":"2026-03-31T11:07:17.337942Z","steps":["trace[1579661395] 'process raft request' (duration: 195.100039ms)","trace[1579661395] 'compare' (duration: 12.860242ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:07:29.086325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.790118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T11:07:29.086464Z","caller":"traceutil/trace.go:171","msg":"trace[104581150] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1128; }","duration":"124.015913ms","start":"2026-03-31T11:07:28.962415Z","end":"2026-03-31T11:07:29.086431Z","steps":["trace[104581150] 'range keys from in-memory index tree' (duration: 123.533093ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:29.239037Z","caller":"traceutil/trace.go:171","msg":"trace[1563191360] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"148.533596ms","start":"2026-03-31T11:07:29.090465Z","end":"2026-03-31T11:07:29.238999Z","steps":["trace[1563191360] 'process raft request' (duration: 148.112328ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:31.369977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.268595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:07:31.370216Z","caller":"traceutil/trace.go:171","msg":"trace[1779033289] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"1.079270907s","start":"2026-03-31T11:07:30.290928Z","end":"2026-03-31T11:07:31.370198Z","steps":["trace[1779033289] 'process raft request' (duration: 1.079150145s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:31.370285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:30.290902Z","time spent":"1.079353409s","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:31.370462Z","caller":"traceutil/trace.go:171","msg":"trace[467122529] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"1.971716015s","start":"2026-03-31T11:07:29.398734Z","end":"2026-03-31T11:07:31.37045Z","steps":["trace[467122529] 'process raft request' (duration: 998.89225ms)","trace[467122529] 'compare' (duration: 972.151183ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:07:31.370518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:29.398714Z","time spent":"1.971777376s","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:31.370639Z","caller":"traceutil/trace.go:171","msg":"trace[1403699007] linearizableReadLoop","detail":"{readStateIndex:1176; appliedIndex:1175; }","duration":"1.137484335s","start":"2026-03-31T11:07:30.233142Z","end":"2026-03-31T11:07:31.370627Z","steps":["trace[1403699007] 'read index received' (duration: 164.497978ms)","trace[1403699007] 'applied index is now lower than readState.Index' (duration: 972.985407ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:07:31.37094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.078705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:07:31.371093Z","caller":"traceutil/trace.go:171","msg":"trace[904127794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1134; }","duration":"916.255578ms","start":"2026-03-31T11:07:30.454819Z","end":"2026-03-31T11:07:31.371074Z","steps":["trace[904127794] 'agreement among raft nodes before linearized reading' (duration: 916.065514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:31.371248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:30.454803Z","time spent":"916.433592ms","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T11:07:31.371458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138295672s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:07:31.371538Z","caller":"traceutil/trace.go:171","msg":"trace[1774637555] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1134; }","duration":"1.138423664s","start":"2026-03-31T11:07:30.233092Z","end":"2026-03-31T11:07:31.371516Z","steps":["trace[1774637555] 'agreement among raft nodes before linearized reading' (duration: 1.13770182s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:31.371583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:30.233071Z","time spent":"1.138501796s","remote":"127.0.0.1:58470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:07:31.371703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.070588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T11:07:31.371797Z","caller":"traceutil/trace.go:171","msg":"trace[1991785625] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"874.970138ms","start":"2026-03-31T11:07:30.496784Z","end":"2026-03-31T11:07:31.371754Z","steps":["trace[1991785625] 'process raft request' (duration: 873.377778ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:31.371784Z","caller":"traceutil/trace.go:171","msg":"trace[2046805984] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1134; }","duration":"129.18976ms","start":"2026-03-31T11:07:31.242573Z","end":"2026-03-31T11:07:31.371763Z","steps":["trace[2046805984] 'agreement among raft nodes before linearized reading' (duration: 128.995477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:31.371976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:30.496735Z","time spent":"875.09976ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:07:31.37213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.489143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-31T11:07:31.372213Z","caller":"traceutil/trace.go:171","msg":"trace[2107797529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1134; }","duration":"448.606854ms","start":"2026-03-31T11:07:30.923594Z","end":"2026-03-31T11:07:31.372201Z","steps":["trace[2107797529] 'agreement among raft nodes before linearized reading' (duration: 448.346608ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:31.372295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:30.923549Z","time spent":"448.735117ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T11:07:31.5561Z","caller":"traceutil/trace.go:171","msg":"trace[878335473] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"178.582274ms","start":"2026-03-31T11:07:31.377489Z","end":"2026-03-31T11:07:31.556071Z","steps":["trace[878335473] 'process raft request' (duration: 178.413561ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:31.556298Z","caller":"traceutil/trace.go:171","msg":"trace[1987728376] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"178.966541ms","start":"2026-03-31T11:07:31.377311Z","end":"2026-03-31T11:07:31.556277Z","steps":["trace[1987728376] 'process raft request' (duration: 137.483627ms)","trace[1987728376] 'compare' (duration: 40.912814ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:07:31.556436Z","caller":"traceutil/trace.go:171","msg":"trace[1097951638] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"179.033623ms","start":"2026-03-31T11:07:31.377389Z","end":"2026-03-31T11:07:31.556423Z","steps":["trace[1097951638] 'process raft request' (duration: 178.455882ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:32.462304Z","caller":"traceutil/trace.go:171","msg":"trace[385562795] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"407.661971ms","start":"2026-03-31T11:07:32.05459Z","end":"2026-03-31T11:07:32.462252Z","steps":["trace[385562795] 'process raft request' (duration: 407.326965ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:32.462479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:32.054564Z","time spent":"407.822074ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:07:32.961461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.896452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:07:32.961613Z","caller":"traceutil/trace.go:171","msg":"trace[1778174290] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"109.611711ms","start":"2026-03-31T11:07:32.851944Z","end":"2026-03-31T11:07:32.961556Z","steps":["trace[1778174290] 'read index received' (duration: 92.193µs)","trace[1778174290] 'applied index is now lower than readState.Index' (duration: 109.518378ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:07:32.961734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.784034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-31T11:07:32.961768Z","caller":"traceutil/trace.go:171","msg":"trace[1725976948] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1140; }","duration":"109.861076ms","start":"2026-03-31T11:07:32.851898Z","end":"2026-03-31T11:07:32.961759Z","steps":["trace[1725976948] 'agreement among raft nodes before linearized reading' (duration: 109.753294ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:32.961823Z","caller":"traceutil/trace.go:171","msg":"trace[1326600022] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"191.261584ms","start":"2026-03-31T11:07:32.770528Z","end":"2026-03-31T11:07:32.96179Z","steps":["trace[1326600022] 'process raft request' (duration: 49.949444ms)","trace[1326600022] 'compare' (duration: 140.752439ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:07:33.542068Z","caller":"traceutil/trace.go:171","msg":"trace[100802611] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"163.768724ms","start":"2026-03-31T11:07:33.378259Z","end":"2026-03-31T11:07:33.542027Z","steps":["trace[100802611] 'process raft request' (duration: 163.465408ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:34.672151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033163096s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:07:34.672243Z","caller":"traceutil/trace.go:171","msg":"trace[1643965286] linearizableReadLoop","detail":"{readStateIndex:1187; appliedIndex:1185; }","duration":"1.252250046s","start":"2026-03-31T11:07:33.419977Z","end":"2026-03-31T11:07:34.672227Z","steps":["trace[1643965286] 'read index received' (duration: 121.830502ms)","trace[1643965286] 'applied index is now lower than readState.Index' (duration: 1.130418584s)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:07:34.672461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219832553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:07:34.672495Z","caller":"traceutil/trace.go:171","msg":"trace[997820793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1142; }","duration":"1.219902695s","start":"2026-03-31T11:07:33.452583Z","end":"2026-03-31T11:07:34.672485Z","steps":["trace[997820793] 'agreement among raft nodes before linearized reading' (duration: 1.219851734s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:34.672526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:33.452561Z","time spent":"1.219956665s","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T11:07:34.672746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252756985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-31T11:07:34.672793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113164148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-31T11:07:34.672798Z","caller":"traceutil/trace.go:171","msg":"trace[888903633] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"1.293448194s","start":"2026-03-31T11:07:33.379318Z","end":"2026-03-31T11:07:34.672766Z","steps":["trace[888903633] 'process raft request' (duration: 259.598614ms)","trace[888903633] 'compare' (duration: 1.033047284s)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:07:34.672825Z","caller":"traceutil/trace.go:171","msg":"trace[829270180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1142; }","duration":"1.113237119s","start":"2026-03-31T11:07:33.559579Z","end":"2026-03-31T11:07:34.672816Z","steps":["trace[829270180] 'agreement among raft nodes before linearized reading' (duration: 1.113128337s)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:34.672821Z","caller":"traceutil/trace.go:171","msg":"trace[1616819271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1142; }","duration":"1.252929648s","start":"2026-03-31T11:07:33.419874Z","end":"2026-03-31T11:07:34.672804Z","steps":["trace[1616819271] 'agreement among raft nodes before linearized reading' (duration: 1.25245336s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:34.672853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:33.55955Z","time spent":"1.11329692s","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T11:07:34.672867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:33.419852Z","time spent":"1.253006939s","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T11:07:34.672865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112555037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-31T11:07:34.672897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:33.379295Z","time spent":"1.293552645s","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:34.672942Z","caller":"traceutil/trace.go:171","msg":"trace[1108919384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1142; }","duration":"1.112676609s","start":"2026-03-31T11:07:33.560248Z","end":"2026-03-31T11:07:34.672924Z","steps":["trace[1108919384] 'agreement among raft nodes before linearized reading' (duration: 1.112433024s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:34.672997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:33.56023Z","time spent":"1.112755001s","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T11:07:34.672993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000470608s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:07:34.673084Z","caller":"traceutil/trace.go:171","msg":"trace[696059192] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1142; }","duration":"1.000595702s","start":"2026-03-31T11:07:33.672465Z","end":"2026-03-31T11:07:34.67306Z","steps":["trace[696059192] 'agreement among raft nodes before linearized reading' (duration: 1.000448549s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:34.673204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:33.672446Z","time spent":"1.000730384s","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:07:34.67359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114255919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-31T11:07:34.673645Z","caller":"traceutil/trace.go:171","msg":"trace[1928594973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1142; }","duration":"1.11434706s","start":"2026-03-31T11:07:33.559285Z","end":"2026-03-31T11:07:34.673632Z","steps":["trace[1928594973] 'agreement among raft nodes before linearized reading' (duration: 1.113461063s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:34.673685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:33.559262Z","time spent":"1.114417791s","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-31T11:07:35.06442Z","caller":"traceutil/trace.go:171","msg":"trace[1798108381] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"387.003371ms","start":"2026-03-31T11:07:34.677387Z","end":"2026-03-31T11:07:35.064391Z","steps":["trace[1798108381] 'process raft request' (duration: 386.838868ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:35.064584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:34.67737Z","time spent":"387.128644ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:35.079963Z","caller":"traceutil/trace.go:171","msg":"trace[1948845055] linearizableReadLoop","detail":"{readStateIndex:1191; appliedIndex:1188; }","duration":"399.159861ms","start":"2026-03-31T11:07:34.680784Z","end":"2026-03-31T11:07:35.079944Z","steps":["trace[1948845055] 'read index received' (duration: 384.028775ms)","trace[1948845055] 'applied index is now lower than readState.Index' (duration: 15.130276ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:07:35.080076Z","caller":"traceutil/trace.go:171","msg":"trace[1523006497] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"402.414062ms","start":"2026-03-31T11:07:34.677619Z","end":"2026-03-31T11:07:35.080033Z","steps":["trace[1523006497] 'process raft request' (duration: 402.165738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:35.080179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:34.677611Z","time spent":"402.507124ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:35.080262Z","caller":"traceutil/trace.go:171","msg":"trace[401682118] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"401.970993ms","start":"2026-03-31T11:07:34.678279Z","end":"2026-03-31T11:07:35.080249Z","steps":["trace[401682118] 'process raft request' (duration: 401.610877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:35.08037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:34.678268Z","time spent":"402.026444ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:35.08049Z","caller":"traceutil/trace.go:171","msg":"trace[1370537519] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"402.089756ms","start":"2026-03-31T11:07:34.678341Z","end":"2026-03-31T11:07:35.080431Z","steps":["trace[1370537519] 'process raft request' (duration: 401.571026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:35.080731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:34.678334Z","time spent":"402.271819ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:07:35.080105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.304123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:07:35.080824Z","caller":"traceutil/trace.go:171","msg":"trace[588983473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1146; }","duration":"400.057366ms","start":"2026-03-31T11:07:34.680756Z","end":"2026-03-31T11:07:35.080814Z","steps":["trace[588983473] 'agreement among raft nodes before linearized reading' (duration: 399.262032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:35.080926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:34.680747Z","time spent":"400.129198ms","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T11:07:37.724737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.623585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-31T11:07:37.724873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.976218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T11:07:37.72491Z","caller":"traceutil/trace.go:171","msg":"trace[1052397305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1148; }","duration":"641.019099ms","start":"2026-03-31T11:07:37.083881Z","end":"2026-03-31T11:07:37.7249Z","steps":["trace[1052397305] 'range keys from in-memory index tree' (duration: 640.870467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.724907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.05963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-31T11:07:37.724941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:37.083876Z","time spent":"641.05838ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-31T11:07:37.724976Z","caller":"traceutil/trace.go:171","msg":"trace[1647691785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1148; }","duration":"641.159902ms","start":"2026-03-31T11:07:37.083799Z","end":"2026-03-31T11:07:37.724958Z","steps":["trace[1647691785] 'range keys from in-memory index tree' (duration: 640.927078ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.724964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.403368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-31T11:07:37.725023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:37.083786Z","time spent":"641.229053ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T11:07:37.724986Z","caller":"traceutil/trace.go:171","msg":"trace[1884759858] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1148; }","duration":"655.85364ms","start":"2026-03-31T11:07:37.069026Z","end":"2026-03-31T11:07:37.724879Z","steps":["trace[1884759858] 'range keys from in-memory index tree' (duration: 655.397681ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:37.72508Z","caller":"traceutil/trace.go:171","msg":"trace[152576512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1148; }","duration":"639.560372ms","start":"2026-03-31T11:07:37.085495Z","end":"2026-03-31T11:07:37.725055Z","steps":["trace[152576512] 'range keys from in-memory index tree' (duration: 639.214846ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.725142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:37.085485Z","time spent":"639.641933ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T11:07:37.724791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.581147ms","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":"warn","ts":"2026-03-31T11:07:37.724822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.248509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:07:37.72524Z","caller":"traceutil/trace.go:171","msg":"trace[1383927983] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1148; }","duration":"231.079095ms","start":"2026-03-31T11:07:37.494136Z","end":"2026-03-31T11:07:37.725216Z","steps":["trace[1383927983] 'count revisions from in-memory index tree' (duration: 230.493114ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:37.725325Z","caller":"traceutil/trace.go:171","msg":"trace[1738929422] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1148; }","duration":"410.750629ms","start":"2026-03-31T11:07:37.314559Z","end":"2026-03-31T11:07:37.72531Z","steps":["trace[1738929422] 'count revisions from in-memory index tree' (duration: 410.162358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.725392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:37.314505Z","time spent":"410.872731ms","remote":"127.0.0.1:53030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:07:37.72474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.149661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:07:37.72562Z","caller":"traceutil/trace.go:171","msg":"trace[1563632946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1148; }","duration":"273.074009ms","start":"2026-03-31T11:07:37.45251Z","end":"2026-03-31T11:07:37.725584Z","steps":["trace[1563632946] 'range keys from in-memory index tree' (duration: 272.06922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:37.725179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:37.069004Z","time spent":"656.154245ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T11:07:38.227867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5750706687731878884,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T11:07:38.375706Z","caller":"traceutil/trace.go:171","msg":"trace[1597453622] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"808.643655ms","start":"2026-03-31T11:07:37.567029Z","end":"2026-03-31T11:07:38.375672Z","steps":["trace[1597453622] 'process raft request' (duration: 808.43883ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:38.375895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:37.566993Z","time spent":"808.806138ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:07:38.656706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.237173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:07:38.6572Z","caller":"traceutil/trace.go:171","msg":"trace[549839848] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"927.43131ms","start":"2026-03-31T11:07:37.729733Z","end":"2026-03-31T11:07:38.657164Z","steps":["trace[549839848] 'process raft request' (duration: 709.584254ms)","trace[549839848] 'compare' (duration: 216.638043ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:07:38.6574Z","caller":"traceutil/trace.go:171","msg":"trace[1517174459] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"927.558592ms","start":"2026-03-31T11:07:37.729812Z","end":"2026-03-31T11:07:38.657371Z","steps":["trace[1517174459] 'process raft request' (duration: 927.294518ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:07:38.657499Z","caller":"traceutil/trace.go:171","msg":"trace[2100215270] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"927.212765ms","start":"2026-03-31T11:07:37.730252Z","end":"2026-03-31T11:07:38.657464Z","steps":["trace[2100215270] 'process raft request' (duration: 927.035842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:38.65752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:37.729713Z","time spent":"927.573672ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:07:38.657566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:37.729786Z","time spent":"927.684364ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:07:38.657618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:37.730241Z","time spent":"927.326337ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:38.657718Z","caller":"traceutil/trace.go:171","msg":"trace[86689271] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"927.375737ms","start":"2026-03-31T11:07:37.730324Z","end":"2026-03-31T11:07:38.657699Z","steps":["trace[86689271] 'process raft request' (duration: 927.014161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:38.657795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:37.730302Z","time spent":"927.455399ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:38.692776Z","caller":"traceutil/trace.go:171","msg":"trace[86751951] linearizableReadLoop","detail":"{readStateIndex:1199; appliedIndex:1193; }","duration":"966.003117ms","start":"2026-03-31T11:07:37.726752Z","end":"2026-03-31T11:07:38.692755Z","steps":["trace[86751951] 'read index received' (duration: 648.727104ms)","trace[86751951] 'applied index is now lower than readState.Index' (duration: 317.274223ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:07:38.692934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.238672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:07:38.693013Z","caller":"traceutil/trace.go:171","msg":"trace[1666103337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1153; }","duration":"966.342273ms","start":"2026-03-31T11:07:37.726625Z","end":"2026-03-31T11:07:38.692967Z","steps":["trace[1666103337] 'agreement among raft nodes before linearized reading' (duration: 966.215921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:38.69306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:37.726611Z","time spent":"966.439346ms","remote":"127.0.0.1:57992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T11:07:57.34325Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5750706687731879138,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T11:07:57.453914Z","caller":"traceutil/trace.go:171","msg":"trace[1191391366] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"611.712075ms","start":"2026-03-31T11:07:56.842172Z","end":"2026-03-31T11:07:57.453884Z","steps":["trace[1191391366] 'read index received' (duration: 611.46582ms)","trace[1191391366] 'applied index is now lower than readState.Index' (duration: 245.305µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:07:57.454052Z","caller":"traceutil/trace.go:171","msg":"trace[255895454] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"652.885524ms","start":"2026-03-31T11:07:56.8011Z","end":"2026-03-31T11:07:57.453986Z","steps":["trace[255895454] 'process raft request' (duration: 652.619518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.454167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.96594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T11:07:57.454201Z","caller":"traceutil/trace.go:171","msg":"trace[229440683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1270; }","duration":"612.056302ms","start":"2026-03-31T11:07:56.842133Z","end":"2026-03-31T11:07:57.454189Z","steps":["trace[229440683] 'agreement among raft nodes before linearized reading' (duration: 611.884789ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.454217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.593039ms","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-03-31T11:07:57.454227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:56.80108Z","time spent":"653.050907ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:57.454274Z","caller":"traceutil/trace.go:171","msg":"trace[1274737589] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1270; }","duration":"507.684161ms","start":"2026-03-31T11:07:56.946577Z","end":"2026-03-31T11:07:57.454262Z","steps":["trace[1274737589] 'agreement among raft nodes before linearized reading' (duration: 507.535859ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.454303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:56.946565Z","time spent":"507.731932ms","remote":"127.0.0.1:58304","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-03-31T11:07:57.454319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.71139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-31T11:07:57.454342Z","caller":"traceutil/trace.go:171","msg":"trace[923363561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1270; }","duration":"606.784502ms","start":"2026-03-31T11:07:56.847551Z","end":"2026-03-31T11:07:57.454335Z","steps":["trace[923363561] 'agreement among raft nodes before linearized reading' (duration: 606.71349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.454316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.207317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-31T11:07:57.454234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:56.842119Z","time spent":"612.107823ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-31T11:07:57.454418Z","caller":"traceutil/trace.go:171","msg":"trace[261544595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1270; }","duration":"610.36032ms","start":"2026-03-31T11:07:56.844037Z","end":"2026-03-31T11:07:57.454397Z","steps":["trace[261544595] 'agreement among raft nodes before linearized reading' (duration: 610.042684ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.454468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:56.844023Z","time spent":"610.436772ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T11:07:57.454364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:56.847523Z","time spent":"606.837903ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T11:07:57.907169Z","caller":"traceutil/trace.go:171","msg":"trace[2069923052] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"448.585653ms","start":"2026-03-31T11:07:57.458567Z","end":"2026-03-31T11:07:57.907153Z","steps":["trace[2069923052] 'process raft request' (duration: 448.472771ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.907279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:57.458545Z","time spent":"448.672275ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:57.912827Z","caller":"traceutil/trace.go:171","msg":"trace[1702621183] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"454.20104ms","start":"2026-03-31T11:07:57.458602Z","end":"2026-03-31T11:07:57.912803Z","steps":["trace[1702621183] 'process raft request' (duration: 454.041887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.912969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:57.458581Z","time spent":"454.311221ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:57.913071Z","caller":"traceutil/trace.go:171","msg":"trace[118301518] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"454.384953ms","start":"2026-03-31T11:07:57.458672Z","end":"2026-03-31T11:07:57.913057Z","steps":["trace[118301518] 'process raft request' (duration: 454.095397ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.913157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:57.458664Z","time spent":"454.445075ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:07:57.913306Z","caller":"traceutil/trace.go:171","msg":"trace[1656012777] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"452.533699ms","start":"2026-03-31T11:07:57.460739Z","end":"2026-03-31T11:07:57.913273Z","steps":["trace[1656012777] 'process raft request' (duration: 452.272385ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:07:57.913529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:07:57.46073Z","time spent":"452.678922ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:08:09.660423Z","caller":"traceutil/trace.go:171","msg":"trace[664828361] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"143.215766ms","start":"2026-03-31T11:08:09.517181Z","end":"2026-03-31T11:08:09.660397Z","steps":["trace[664828361] 'process raft request' (duration: 143.044673ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:10.884438Z","caller":"traceutil/trace.go:171","msg":"trace[450696708] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"243.599472ms","start":"2026-03-31T11:08:10.640819Z","end":"2026-03-31T11:08:10.884418Z","steps":["trace[450696708] 'process raft request' (duration: 243.458329ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:12.339711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.203471ms","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-03-31T11:08:12.339781Z","caller":"traceutil/trace.go:171","msg":"trace[1801790755] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1317; }","duration":"372.343884ms","start":"2026-03-31T11:08:11.96742Z","end":"2026-03-31T11:08:12.339764Z","steps":["trace[1801790755] 'range keys from in-memory index tree' (duration: 371.992096ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:12.339823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:11.967401Z","time spent":"372.413145ms","remote":"127.0.0.1:58304","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-03-31T11:08:12.339823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.607403ms","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-03-31T11:08:12.339909Z","caller":"traceutil/trace.go:171","msg":"trace[630584553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1317; }","duration":"368.739615ms","start":"2026-03-31T11:08:11.971151Z","end":"2026-03-31T11:08:12.339891Z","steps":["trace[630584553] 'range keys from in-memory index tree' (duration: 368.445169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:12.339903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.524535ms","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-03-31T11:08:12.33995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:11.971134Z","time spent":"368.808396ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-31T11:08:12.33999Z","caller":"traceutil/trace.go:171","msg":"trace[174842529] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1317; }","duration":"365.638658ms","start":"2026-03-31T11:08:11.974327Z","end":"2026-03-31T11:08:12.339966Z","steps":["trace[174842529] 'range keys from in-memory index tree' (duration: 365.354291ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:12.340038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:11.974316Z","time spent":"365.711959ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T11:08:12.340225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.166053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-31T11:08:12.340282Z","caller":"traceutil/trace.go:171","msg":"trace[335770536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1317; }","duration":"370.231324ms","start":"2026-03-31T11:08:11.970017Z","end":"2026-03-31T11:08:12.340248Z","steps":["trace[335770536] 'range keys from in-memory index tree' (duration: 370.038831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:12.34035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:11.969997Z","time spent":"370.343326ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T11:08:12.570632Z","caller":"traceutil/trace.go:171","msg":"trace[1353246458] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"491.288151ms","start":"2026-03-31T11:08:12.07932Z","end":"2026-03-31T11:08:12.570608Z","steps":["trace[1353246458] 'process raft request' (duration: 491.127478ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:12.570812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:12.079286Z","time spent":"491.420733ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:08:13.314309Z","caller":"traceutil/trace.go:171","msg":"trace[1711376086] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"955.963958ms","start":"2026-03-31T11:08:12.358321Z","end":"2026-03-31T11:08:13.314285Z","steps":["trace[1711376086] 'process raft request' (duration: 955.906006ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:13.314393Z","caller":"traceutil/trace.go:171","msg":"trace[1937760870] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"956.055499ms","start":"2026-03-31T11:08:12.358304Z","end":"2026-03-31T11:08:13.314359Z","steps":["trace[1937760870] 'process raft request' (duration: 955.886116ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:13.314466Z","caller":"traceutil/trace.go:171","msg":"trace[1883351351] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1371; }","duration":"862.577854ms","start":"2026-03-31T11:08:12.451849Z","end":"2026-03-31T11:08:13.314426Z","steps":["trace[1883351351] 'read index received' (duration: 118.663862ms)","trace[1883351351] 'applied index is now lower than readState.Index' (duration: 743.908242ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:08:13.314571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:12.358286Z","time spent":"956.178983ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:08:13.314561Z","caller":"traceutil/trace.go:171","msg":"trace[1055429038] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"956.100851ms","start":"2026-03-31T11:08:12.358405Z","end":"2026-03-31T11:08:13.314506Z","steps":["trace[1055429038] 'process raft request' (duration: 955.850885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:13.314628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.775058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:08:13.314688Z","caller":"traceutil/trace.go:171","msg":"trace[1865797461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1322; }","duration":"862.880701ms","start":"2026-03-31T11:08:12.451798Z","end":"2026-03-31T11:08:13.314678Z","steps":["trace[1865797461] 'agreement among raft nodes before linearized reading' (duration: 862.786889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:13.31473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:12.451771Z","time spent":"862.953912ms","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T11:08:13.314326Z","caller":"traceutil/trace.go:171","msg":"trace[1952644872] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"955.995118ms","start":"2026-03-31T11:08:12.358304Z","end":"2026-03-31T11:08:13.314299Z","steps":["trace[1952644872] 'process raft request' (duration: 955.749823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:13.31489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:12.358286Z","time spent":"956.514599ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:08:13.314669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:12.358297Z","time spent":"956.10004ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:08:13.315939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:12.358373Z","time spent":"956.295775ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:08:13.324341Z","caller":"traceutil/trace.go:171","msg":"trace[1928754243] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"434.768723ms","start":"2026-03-31T11:08:12.889561Z","end":"2026-03-31T11:08:13.32433Z","steps":["trace[1928754243] 'process raft request' (duration: 434.570388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:13.324428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:12.88954Z","time spent":"434.840455ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:08:13.324579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.393749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.123\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-31T11:08:13.324606Z","caller":"traceutil/trace.go:171","msg":"trace[1150265155] range","detail":"{range_begin:/registry/masterleases/199.19.213.123; range_end:; response_count:1; response_revision:1323; }","duration":"603.459389ms","start":"2026-03-31T11:08:12.72114Z","end":"2026-03-31T11:08:13.3246Z","steps":["trace[1150265155] 'agreement among raft nodes before linearized reading' (duration: 603.375957ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:13.324624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:12.721121Z","time spent":"603.4981ms","remote":"127.0.0.1:58042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.123\" "} {"level":"info","ts":"2026-03-31T11:08:13.499144Z","caller":"traceutil/trace.go:171","msg":"trace[1285164781] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"162.881797ms","start":"2026-03-31T11:08:13.336234Z","end":"2026-03-31T11:08:13.499116Z","steps":["trace[1285164781] 'process raft request' (duration: 128.685701ms)","trace[1285164781] 'compare' (duration: 34.042623ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:08:13.93044Z","caller":"traceutil/trace.go:171","msg":"trace[1732526711] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"188.859517ms","start":"2026-03-31T11:08:13.741551Z","end":"2026-03-31T11:08:13.93041Z","steps":["trace[1732526711] 'process raft request' (duration: 108.378087ms)","trace[1732526711] 'compare' (duration: 80.350568ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:08:15.95303Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5750706687731879300,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T11:08:16.036847Z","caller":"traceutil/trace.go:171","msg":"trace[835828109] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"713.453768ms","start":"2026-03-31T11:08:15.323374Z","end":"2026-03-31T11:08:16.036827Z","steps":["trace[835828109] 'process raft request' (duration: 713.334775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:16.03697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:15.323362Z","time spent":"713.544369ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:08:16.240306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.845301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:08:16.240616Z","caller":"traceutil/trace.go:171","msg":"trace[782156742] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"916.38945ms","start":"2026-03-31T11:08:15.324181Z","end":"2026-03-31T11:08:16.240571Z","steps":["trace[782156742] 'process raft request' (duration: 916.305288ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:16.240624Z","caller":"traceutil/trace.go:171","msg":"trace[1169441147] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"916.429011ms","start":"2026-03-31T11:08:15.324161Z","end":"2026-03-31T11:08:16.24059Z","steps":["trace[1169441147] 'process raft request' (duration: 916.239678ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:16.240676Z","caller":"traceutil/trace.go:171","msg":"trace[1789049759] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1381; }","duration":"788.710048ms","start":"2026-03-31T11:08:15.451958Z","end":"2026-03-31T11:08:16.240668Z","steps":["trace[1789049759] 'read index received' (duration: 584.785847ms)","trace[1789049759] 'applied index is now lower than readState.Index' (duration: 203.923691ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:08:16.240735Z","caller":"traceutil/trace.go:171","msg":"trace[1494365823] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"912.559867ms","start":"2026-03-31T11:08:15.328163Z","end":"2026-03-31T11:08:16.240723Z","steps":["trace[1494365823] 'process raft request' (duration: 912.375314ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:16.240787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:15.324147Z","time spent":"916.541823ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:08:16.24081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.50851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-31T11:08:16.240792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.830141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:08:16.240842Z","caller":"traceutil/trace.go:171","msg":"trace[156764618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1331; }","duration":"306.576771ms","start":"2026-03-31T11:08:15.934249Z","end":"2026-03-31T11:08:16.240825Z","steps":["trace[156764618] 'agreement among raft nodes before linearized reading' (duration: 306.48155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:16.240861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:15.93423Z","time spent":"306.626702ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T11:08:16.240864Z","caller":"traceutil/trace.go:171","msg":"trace[610516619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1331; }","duration":"788.950494ms","start":"2026-03-31T11:08:15.451897Z","end":"2026-03-31T11:08:16.240848Z","steps":["trace[610516619] 'agreement among raft nodes before linearized reading' (duration: 788.837442ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:16.240794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:15.328146Z","time spent":"912.619379ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:08:16.240903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:15.451875Z","time spent":"789.020225ms","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T11:08:16.240493Z","caller":"traceutil/trace.go:171","msg":"trace[1595046488] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"917.048103ms","start":"2026-03-31T11:08:15.323411Z","end":"2026-03-31T11:08:16.240459Z","steps":["trace[1595046488] 'process raft request' (duration: 775.817425ms)","trace[1595046488] 'compare' (duration: 140.470004ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:08:16.241033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:15.323396Z","time spent":"917.549194ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:08:16.24106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:15.324157Z","time spent":"916.519753ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:08:16.79839Z","caller":"traceutil/trace.go:171","msg":"trace[794046046] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"143.860148ms","start":"2026-03-31T11:08:16.654498Z","end":"2026-03-31T11:08:16.798359Z","steps":["trace[794046046] 'process raft request' (duration: 143.714085ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:17.040365Z","caller":"traceutil/trace.go:171","msg":"trace[1672953227] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"120.962865ms","start":"2026-03-31T11:08:16.919378Z","end":"2026-03-31T11:08:17.040341Z","steps":["trace[1672953227] 'process raft request' (duration: 77.199398ms)","trace[1672953227] 'compare' (duration: 43.524912ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:08:17.450995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.917738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:08:17.451106Z","caller":"traceutil/trace.go:171","msg":"trace[1022520616] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"407.549829ms","start":"2026-03-31T11:08:17.04354Z","end":"2026-03-31T11:08:17.45109Z","steps":["trace[1022520616] 'process raft request' (duration: 191.483858ms)","trace[1022520616] 'compare' (duration: 215.818596ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:08:17.45115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:17.043515Z","time spent":"407.616759ms","remote":"127.0.0.1:58108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T11:08:18.139945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.942986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5795"} {"level":"info","ts":"2026-03-31T11:08:18.140007Z","caller":"traceutil/trace.go:171","msg":"trace[1281143332] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1335; }","duration":"534.046557ms","start":"2026-03-31T11:08:17.605945Z","end":"2026-03-31T11:08:18.139991Z","steps":["trace[1281143332] 'range keys from in-memory index tree' (duration: 533.738673ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:18.140034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:17.605927Z","time spent":"534.102119ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5818,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-31T11:08:18.842247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.395699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:08:18.842622Z","caller":"traceutil/trace.go:171","msg":"trace[1614858868] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"692.94166ms","start":"2026-03-31T11:08:18.149626Z","end":"2026-03-31T11:08:18.842568Z","steps":["trace[1614858868] 'process raft request' (duration: 298.131692ms)","trace[1614858868] 'compare' (duration: 393.927721ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:08:18.842791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:18.149617Z","time spent":"693.067772ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5921,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:08:19.129129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.770466ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-31T11:08:19.12933Z","caller":"traceutil/trace.go:171","msg":"trace[133195924] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1391; }","duration":"883.786015ms","start":"2026-03-31T11:08:18.245524Z","end":"2026-03-31T11:08:19.12931Z","steps":["trace[133195924] 'read index received' (duration: 202.329012ms)","trace[133195924] 'applied index is now lower than readState.Index' (duration: 681.455433ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:08:19.129568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.03195ms","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-03-31T11:08:19.129604Z","caller":"traceutil/trace.go:171","msg":"trace[1894719427] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1337; }","duration":"884.127302ms","start":"2026-03-31T11:08:18.245467Z","end":"2026-03-31T11:08:19.129594Z","steps":["trace[1894719427] 'agreement among raft nodes before linearized reading' (duration: 883.957539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:19.12965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:18.245438Z","time spent":"884.200823ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-31T11:08:19.129698Z","caller":"traceutil/trace.go:171","msg":"trace[33701273] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"882.769336ms","start":"2026-03-31T11:08:18.246858Z","end":"2026-03-31T11:08:19.129628Z","steps":["trace[33701273] 'process raft request' (duration: 882.387059ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:19.129786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.698533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-31T11:08:19.129819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.359039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T11:08:19.12992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.57907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:08:19.129956Z","caller":"traceutil/trace.go:171","msg":"trace[783430914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1338; }","duration":"676.649851ms","start":"2026-03-31T11:08:18.453297Z","end":"2026-03-31T11:08:19.129947Z","steps":["trace[783430914] 'agreement among raft nodes before linearized reading' (duration: 676.5977ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:19.129944Z","caller":"traceutil/trace.go:171","msg":"trace[1580459173] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1338; }","duration":"493.502932ms","start":"2026-03-31T11:08:18.636411Z","end":"2026-03-31T11:08:19.129914Z","steps":["trace[1580459173] 'agreement among raft nodes before linearized reading' (duration: 493.292408ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:19.129951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:18.24684Z","time spent":"882.984419ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:08:19.129991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:18.453278Z","time spent":"676.706682ms","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T11:08:19.13001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:18.636351Z","time spent":"493.643975ms","remote":"127.0.0.1:52972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:08:19.130082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.748894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-31T11:08:19.130124Z","caller":"traceutil/trace.go:171","msg":"trace[1114108598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1338; }","duration":"811.831435ms","start":"2026-03-31T11:08:18.318283Z","end":"2026-03-31T11:08:19.130115Z","steps":["trace[1114108598] 'agreement among raft nodes before linearized reading' (duration: 811.730444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:19.130158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:18.318264Z","time spent":"811.887606ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T11:08:19.130224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.912237ms","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-03-31T11:08:19.13026Z","caller":"traceutil/trace.go:171","msg":"trace[2004859136] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1338; }","duration":"882.994799ms","start":"2026-03-31T11:08:18.247256Z","end":"2026-03-31T11:08:19.130251Z","steps":["trace[2004859136] 'agreement among raft nodes before linearized reading' (duration: 882.905827ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:19.13029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:18.247231Z","time spent":"883.05286ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T11:08:19.130398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.255679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-31T11:08:19.129867Z","caller":"traceutil/trace.go:171","msg":"trace[728727940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1338; }","duration":"883.806345ms","start":"2026-03-31T11:08:18.246041Z","end":"2026-03-31T11:08:19.129847Z","steps":["trace[728727940] 'agreement among raft nodes before linearized reading' (duration: 883.57194ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:19.130425Z","caller":"traceutil/trace.go:171","msg":"trace[406909026] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1338; }","duration":"284.344591ms","start":"2026-03-31T11:08:18.846073Z","end":"2026-03-31T11:08:19.130418Z","steps":["trace[406909026] 'agreement among raft nodes before linearized reading' (duration: 284.28775ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:19.130454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:18.24603Z","time spent":"884.406555ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T11:08:19.856241Z","caller":"traceutil/trace.go:171","msg":"trace[1223509851] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"719.229876ms","start":"2026-03-31T11:08:19.136977Z","end":"2026-03-31T11:08:19.856207Z","steps":["trace[1223509851] 'process raft request' (duration: 718.917551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:19.856407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:19.136961Z","time spent":"719.356478ms","remote":"127.0.0.1:58452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:08:19.856759Z","caller":"traceutil/trace.go:171","msg":"trace[295157697] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1394; }","duration":"719.311848ms","start":"2026-03-31T11:08:19.137415Z","end":"2026-03-31T11:08:19.856727Z","steps":["trace[295157697] 'read index received' (duration: 52.36423ms)","trace[295157697] 'applied index is now lower than readState.Index' (duration: 666.946408ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:08:19.856837Z","caller":"traceutil/trace.go:171","msg":"trace[2023117401] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"719.714585ms","start":"2026-03-31T11:08:19.137066Z","end":"2026-03-31T11:08:19.85678Z","steps":["trace[2023117401] 'process raft request' (duration: 719.065623ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:19.856911Z","caller":"traceutil/trace.go:171","msg":"trace[1973084434] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"718.510002ms","start":"2026-03-31T11:08:19.138388Z","end":"2026-03-31T11:08:19.856898Z","steps":["trace[1973084434] 'process raft request' (duration: 718.283868ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:19.856937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:19.137056Z","time spent":"719.829397ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:08:19.856974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:19.138383Z","time spent":"718.555393ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:08:19.85705Z","caller":"traceutil/trace.go:171","msg":"trace[903506671] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"719.96319ms","start":"2026-03-31T11:08:19.137078Z","end":"2026-03-31T11:08:19.857041Z","steps":["trace[903506671] 'process raft request' (duration: 719.265377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:19.857107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:19.13707Z","time spent":"719.99792ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:08:19.857124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.673304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:5 size:6179"} {"level":"info","ts":"2026-03-31T11:08:19.857164Z","caller":"traceutil/trace.go:171","msg":"trace[1748063595] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:5; response_revision:1343; }","duration":"719.766847ms","start":"2026-03-31T11:08:19.137385Z","end":"2026-03-31T11:08:19.857152Z","steps":["trace[1748063595] 'agreement among raft nodes before linearized reading' (duration: 719.533212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:19.85721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:19.137371Z","time spent":"719.829287ms","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":5,"response size":6202,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"warn","ts":"2026-03-31T11:08:19.85722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.48224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-03-31T11:08:19.857253Z","caller":"traceutil/trace.go:171","msg":"trace[206638250] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1343; }","duration":"719.52151ms","start":"2026-03-31T11:08:19.137722Z","end":"2026-03-31T11:08:19.857243Z","steps":["trace[206638250] 'agreement among raft nodes before linearized reading' (duration: 719.455669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:19.85728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:19.137718Z","time spent":"719.556052ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-03-31T11:08:26.422553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.027317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:08:26.422634Z","caller":"traceutil/trace.go:171","msg":"trace[456643259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1412; }","duration":"248.175349ms","start":"2026-03-31T11:08:26.17444Z","end":"2026-03-31T11:08:26.422616Z","steps":["trace[456643259] 'range keys from in-memory index tree' (duration: 247.934174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:26.422739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.528708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-31T11:08:26.422858Z","caller":"traceutil/trace.go:171","msg":"trace[1495953782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1412; }","duration":"182.651661ms","start":"2026-03-31T11:08:26.240173Z","end":"2026-03-31T11:08:26.422825Z","steps":["trace[1495953782] 'range keys from in-memory index tree' (duration: 182.379266ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:26.422923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.651114ms","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-03-31T11:08:26.423051Z","caller":"traceutil/trace.go:171","msg":"trace[1763980923] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1412; }","duration":"125.827837ms","start":"2026-03-31T11:08:26.297207Z","end":"2026-03-31T11:08:26.423035Z","steps":["trace[1763980923] 'range keys from in-memory index tree' (duration: 125.635574ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:26.970905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5750706687731879451,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T11:08:27.038185Z","caller":"traceutil/trace.go:171","msg":"trace[341926930] linearizableReadLoop","detail":"{readStateIndex:1470; appliedIndex:1469; }","duration":"567.411678ms","start":"2026-03-31T11:08:26.47075Z","end":"2026-03-31T11:08:27.038162Z","steps":["trace[341926930] 'read index received' (duration: 567.296576ms)","trace[341926930] 'applied index is now lower than readState.Index' (duration: 113.882µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:08:27.038349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.583971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:08:27.03831Z","caller":"traceutil/trace.go:171","msg":"trace[1501644687] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"610.873079ms","start":"2026-03-31T11:08:26.427382Z","end":"2026-03-31T11:08:27.038255Z","steps":["trace[1501644687] 'process raft request' (duration: 610.635905ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:27.038394Z","caller":"traceutil/trace.go:171","msg":"trace[309335572] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1413; }","duration":"567.689503ms","start":"2026-03-31T11:08:26.470689Z","end":"2026-03-31T11:08:27.038379Z","steps":["trace[309335572] 'agreement among raft nodes before linearized reading' (duration: 567.607612ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:27.038438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:26.470649Z","time spent":"567.779634ms","remote":"127.0.0.1:52854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-03-31T11:08:27.038454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.686815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-31T11:08:27.038555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:26.42736Z","time spent":"611.059022ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:08:27.038616Z","caller":"traceutil/trace.go:171","msg":"trace[1799825078] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1413; }","duration":"295.85991ms","start":"2026-03-31T11:08:26.742703Z","end":"2026-03-31T11:08:27.038563Z","steps":["trace[1799825078] 'agreement among raft nodes before linearized reading' (duration: 295.684766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:27.038625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.923369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-03-31T11:08:27.03873Z","caller":"traceutil/trace.go:171","msg":"trace[1575178275] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1413; }","duration":"431.118243ms","start":"2026-03-31T11:08:26.607592Z","end":"2026-03-31T11:08:27.038711Z","steps":["trace[1575178275] 'agreement among raft nodes before linearized reading' (duration: 430.761896ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:27.038812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:26.607575Z","time spent":"431.225515ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":644,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-03-31T11:08:27.366539Z","caller":"traceutil/trace.go:171","msg":"trace[1088644293] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"320.805469ms","start":"2026-03-31T11:08:27.045709Z","end":"2026-03-31T11:08:27.366514Z","steps":["trace[1088644293] 'process raft request' (duration: 320.640596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:27.366911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:27.045682Z","time spent":"320.924742ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T11:08:27.375033Z","caller":"traceutil/trace.go:171","msg":"trace[575962607] linearizableReadLoop","detail":"{readStateIndex:1472; appliedIndex:1470; }","duration":"150.628476ms","start":"2026-03-31T11:08:27.22439Z","end":"2026-03-31T11:08:27.375018Z","steps":["trace[575962607] 'read index received' (duration: 142.005683ms)","trace[575962607] 'applied index is now lower than readState.Index' (duration: 8.621883ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:08:27.375079Z","caller":"traceutil/trace.go:171","msg":"trace[112560427] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"204.402071ms","start":"2026-03-31T11:08:27.170653Z","end":"2026-03-31T11:08:27.375056Z","steps":["trace[112560427] 'process raft request' (duration: 204.270909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:27.375291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.89726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-31T11:08:27.375331Z","caller":"traceutil/trace.go:171","msg":"trace[1712233339] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1415; }","duration":"150.990912ms","start":"2026-03-31T11:08:27.224329Z","end":"2026-03-31T11:08:27.37532Z","steps":["trace[1712233339] 'agreement among raft nodes before linearized reading' (duration: 150.770708ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:42.002012Z","caller":"traceutil/trace.go:171","msg":"trace[576007402] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"456.09632ms","start":"2026-03-31T11:08:41.545663Z","end":"2026-03-31T11:08:42.001759Z","steps":["trace[576007402] 'process raft request' (duration: 455.948317ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:08:42.0023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:08:41.545647Z","time spent":"456.53705ms","remote":"127.0.0.1:58140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":606,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:08:43.752057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.608164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:08:43.752159Z","caller":"traceutil/trace.go:171","msg":"trace[1997292312] transaction","detail":"{read_only:false; response_revision:1497; number_of_response:1; }","duration":"231.827314ms","start":"2026-03-31T11:08:43.520311Z","end":"2026-03-31T11:08:43.752139Z","steps":["trace[1997292312] 'process raft request' (duration: 83.005474ms)","trace[1997292312] 'compare' (duration: 148.484822ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:08:45.346815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.532457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T11:08:45.346914Z","caller":"traceutil/trace.go:171","msg":"trace[2065925623] linearizableReadLoop","detail":"{readStateIndex:1566; appliedIndex:1565; }","duration":"150.276243ms","start":"2026-03-31T11:08:45.196621Z","end":"2026-03-31T11:08:45.346897Z","steps":["trace[2065925623] 'read index received' (duration: 49.821µs)","trace[2065925623] 'applied index is now lower than readState.Index' (duration: 150.225232ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T11:08:45.347381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.748313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-31T11:08:45.347445Z","caller":"traceutil/trace.go:171","msg":"trace[492514095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1505; }","duration":"150.863965ms","start":"2026-03-31T11:08:45.196568Z","end":"2026-03-31T11:08:45.347432Z","steps":["trace[492514095] 'agreement among raft nodes before linearized reading' (duration: 150.681201ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T11:08:45.347207Z","caller":"traceutil/trace.go:171","msg":"trace[1179135998] transaction","detail":"{read_only:false; response_revision:1505; number_of_response:1; }","duration":"216.478527ms","start":"2026-03-31T11:08:45.130704Z","end":"2026-03-31T11:08:45.347182Z","steps":["trace[1179135998] 'process raft request' (duration: 40.46463ms)","trace[1179135998] 'compare' (duration: 175.407033ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:11:55.115471Z","caller":"traceutil/trace.go:171","msg":"trace[674960818] linearizableReadLoop","detail":"{readStateIndex:2596; appliedIndex:2595; }","duration":"325.829376ms","start":"2026-03-31T11:11:54.789614Z","end":"2026-03-31T11:11:55.115443Z","steps":["trace[674960818] 'read index received' (duration: 325.756074ms)","trace[674960818] 'applied index is now lower than readState.Index' (duration: 72.192µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T11:11:55.115874Z","caller":"traceutil/trace.go:171","msg":"trace[2082707907] transaction","detail":"{read_only:false; response_revision:2496; number_of_response:1; }","duration":"371.291943ms","start":"2026-03-31T11:11:54.744563Z","end":"2026-03-31T11:11:55.115855Z","steps":["trace[2082707907] 'process raft request' (duration: 370.710591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:11:55.116238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:11:54.744543Z","time spent":"371.383224ms","remote":"127.0.0.1:58304","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T11:11:55.116326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.707652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T11:11:55.116482Z","caller":"traceutil/trace.go:171","msg":"trace[1732238804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2496; }","duration":"326.858225ms","start":"2026-03-31T11:11:54.789562Z","end":"2026-03-31T11:11:55.11642Z","steps":["trace[1732238804] 'agreement among raft nodes before linearized reading' (duration: 326.715873ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T11:11:55.116579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T11:11:54.789542Z","time spent":"326.986397ms","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}