{"level":"warn","ts":"2026-05-15T08:11:39.475011Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-15T08:11:39.475133Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.128: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.128:2380","--initial-cluster=instance=https://199.19.213.128:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.128:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.128:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-15T08:11:39.475208Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-15T08:11:39.475215Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.128:2380"]} {"level":"info","ts":"2026-05-15T08:11:39.475235Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-15T08:11:39.476166Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.128:2379"]} {"level":"info","ts":"2026-05-15T08:11:39.47624Z","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.128:2380"],"listen-peer-urls":["https://199.19.213.128:2380"],"advertise-client-urls":["https://199.19.213.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.128:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-15T08:11:39.480544Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.624841ms"} {"level":"info","ts":"2026-05-15T08:11:39.487162Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a463a6aa9b462de2","cluster-id":"cd3d14b8ed85e2f2"} {"level":"info","ts":"2026-05-15T08:11:39.487228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 switched to configuration voters=()"} {"level":"info","ts":"2026-05-15T08:11:39.487246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became follower at term 0"} {"level":"info","ts":"2026-05-15T08:11:39.487451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a463a6aa9b462de2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-15T08:11:39.487459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became follower at term 1"} {"level":"info","ts":"2026-05-15T08:11:39.487491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 switched to configuration voters=(11845494696594255330)"} {"level":"warn","ts":"2026-05-15T08:11:39.500677Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-15T08:11:39.503174Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-15T08:11:39.505062Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-15T08:11:39.507838Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a463a6aa9b462de2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-15T08:11:39.508182Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-15T08:11:39.508253Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-15T08:11:39.508263Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-15T08:11:39.508379Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a463a6aa9b462de2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-15T08:11:39.508944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 switched to configuration voters=(11845494696594255330)"} {"level":"info","ts":"2026-05-15T08:11:39.509076Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cd3d14b8ed85e2f2","local-member-id":"a463a6aa9b462de2","added-peer-id":"a463a6aa9b462de2","added-peer-peer-urls":["https://199.19.213.128:2380"]} {"level":"info","ts":"2026-05-15T08:11:39.515681Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-15T08:11:39.515983Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a463a6aa9b462de2","initial-advertise-peer-urls":["https://199.19.213.128:2380"],"listen-peer-urls":["https://199.19.213.128:2380"],"advertise-client-urls":["https://199.19.213.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-15T08:11:39.516023Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-15T08:11:39.516259Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.128:2380"} {"level":"info","ts":"2026-05-15T08:11:39.516281Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.128:2380"} {"level":"info","ts":"2026-05-15T08:11:39.687971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 is starting a new election at term 1"} {"level":"info","ts":"2026-05-15T08:11:39.688046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-15T08:11:39.688091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 received MsgPreVoteResp from a463a6aa9b462de2 at term 1"} {"level":"info","ts":"2026-05-15T08:11:39.688118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became candidate at term 2"} {"level":"info","ts":"2026-05-15T08:11:39.688151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 received MsgVoteResp from a463a6aa9b462de2 at term 2"} {"level":"info","ts":"2026-05-15T08:11:39.688173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became leader at term 2"} {"level":"info","ts":"2026-05-15T08:11:39.688199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a463a6aa9b462de2 elected leader a463a6aa9b462de2 at term 2"} {"level":"info","ts":"2026-05-15T08:11:39.689377Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a463a6aa9b462de2","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.128:2379]}","request-path":"/0/members/a463a6aa9b462de2/attributes","cluster-id":"cd3d14b8ed85e2f2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-15T08:11:39.68997Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-15T08:11:39.690441Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T08:11:39.689938Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-15T08:11:39.690726Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-15T08:11:39.69079Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-15T08:11:39.691906Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cd3d14b8ed85e2f2","local-member-id":"a463a6aa9b462de2","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T08:11:39.69204Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T08:11:39.692081Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-15T08:11:39.693378Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-15T08:11:39.695868Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.128:2379"} {"level":"info","ts":"2026-05-15T08:11:46.003333Z","caller":"traceutil/trace.go:171","msg":"trace[1486287446] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:315; }","duration":"107.107816ms","start":"2026-05-15T08:11:45.896195Z","end":"2026-05-15T08:11:46.003302Z","steps":["trace[1486287446] 'read index received' (duration: 47.085764ms)","trace[1486287446] 'applied index is now lower than readState.Index' (duration: 60.020562ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:11:46.003509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.278901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-15T08:11:46.003492Z","caller":"traceutil/trace.go:171","msg":"trace[1777554243] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"119.689256ms","start":"2026-05-15T08:11:45.883753Z","end":"2026-05-15T08:11:46.003442Z","steps":["trace[1777554243] 'process raft request' (duration: 59.564232ms)","trace[1777554243] 'compare' (duration: 59.841478ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:11:46.003571Z","caller":"traceutil/trace.go:171","msg":"trace[1400908177] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:308; }","duration":"107.418674ms","start":"2026-05-15T08:11:45.896141Z","end":"2026-05-15T08:11:46.003559Z","steps":["trace[1400908177] 'agreement among raft nodes before linearized reading' (duration: 107.283981ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:11:46.266293Z","caller":"traceutil/trace.go:171","msg":"trace[599486048] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:317; }","duration":"172.033075ms","start":"2026-05-15T08:11:46.094225Z","end":"2026-05-15T08:11:46.266258Z","steps":["trace[599486048] 'read index received' (duration: 85.291543ms)","trace[599486048] 'applied index is now lower than readState.Index' (duration: 86.739822ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:11:46.266419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.166927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:11:46.266456Z","caller":"traceutil/trace.go:171","msg":"trace[829393155] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:310; }","duration":"172.251159ms","start":"2026-05-15T08:11:46.094185Z","end":"2026-05-15T08:11:46.266436Z","steps":["trace[829393155] 'agreement among raft nodes before linearized reading' (duration: 172.179008ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:11:46.266604Z","caller":"traceutil/trace.go:171","msg":"trace[1077143046] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"252.672575ms","start":"2026-05-15T08:11:46.013908Z","end":"2026-05-15T08:11:46.266581Z","steps":["trace[1077143046] 'process raft request' (duration: 165.684708ms)","trace[1077143046] 'compare' (duration: 86.519808ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:12:12.178892Z","caller":"traceutil/trace.go:171","msg":"trace[623255946] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"253.147205ms","start":"2026-05-15T08:12:11.925729Z","end":"2026-05-15T08:12:12.178876Z","steps":["trace[623255946] 'process raft request' (duration: 252.981041ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:12:12.178871Z","caller":"traceutil/trace.go:171","msg":"trace[734045886] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"163.442754ms","start":"2026-05-15T08:12:12.0154Z","end":"2026-05-15T08:12:12.178843Z","steps":["trace[734045886] 'read index received' (duration: 163.326312ms)","trace[734045886] 'applied index is now lower than readState.Index' (duration: 115.542µs)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:12:12.178981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.562617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:12:12.179041Z","caller":"traceutil/trace.go:171","msg":"trace[78451142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:480; }","duration":"163.667659ms","start":"2026-05-15T08:12:12.015359Z","end":"2026-05-15T08:12:12.179026Z","steps":["trace[78451142] 'agreement among raft nodes before linearized reading' (duration: 163.564657ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:12:15.361602Z","caller":"traceutil/trace.go:171","msg":"trace[884833500] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"312.672146ms","start":"2026-05-15T08:12:15.048902Z","end":"2026-05-15T08:12:15.361574Z","steps":["trace[884833500] 'process raft request' (duration: 312.492472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:15.36229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.048878Z","time spent":"312.80508ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T08:12:15.417069Z","caller":"traceutil/trace.go:171","msg":"trace[991559440] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"359.936174ms","start":"2026-05-15T08:12:15.057107Z","end":"2026-05-15T08:12:15.417044Z","steps":["trace[991559440] 'process raft request' (duration: 359.564547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:15.417174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.057086Z","time spent":"360.035786ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T08:12:15.417255Z","caller":"traceutil/trace.go:171","msg":"trace[919131606] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"367.178258ms","start":"2026-05-15T08:12:15.050068Z","end":"2026-05-15T08:12:15.417246Z","steps":["trace[919131606] 'process raft request' (duration: 366.401703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:15.417277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.05005Z","time spent":"367.214309ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T08:12:15.417318Z","caller":"traceutil/trace.go:171","msg":"trace[510881335] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"367.041346ms","start":"2026-05-15T08:12:15.050271Z","end":"2026-05-15T08:12:15.417313Z","steps":["trace[510881335] 'process raft request' (duration: 366.308781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:15.417335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.050266Z","time spent":"367.060306ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T08:12:15.417427Z","caller":"traceutil/trace.go:171","msg":"trace[1847489185] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"362.974514ms","start":"2026-05-15T08:12:15.054445Z","end":"2026-05-15T08:12:15.417419Z","steps":["trace[1847489185] 'process raft request' (duration: 362.183888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:15.417449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.054437Z","time spent":"363.001235ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T08:12:15.417477Z","caller":"traceutil/trace.go:171","msg":"trace[1616791095] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"359.145749ms","start":"2026-05-15T08:12:15.058322Z","end":"2026-05-15T08:12:15.417467Z","steps":["trace[1616791095] 'process raft request' (duration: 358.495286ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:12:15.417494Z","caller":"traceutil/trace.go:171","msg":"trace[1989579665] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"334.136392ms","start":"2026-05-15T08:12:15.083344Z","end":"2026-05-15T08:12:15.41748Z","steps":["trace[1989579665] 'process raft request' (duration: 333.613592ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:12:15.417418Z","caller":"traceutil/trace.go:171","msg":"trace[1241384470] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"360.046486ms","start":"2026-05-15T08:12:15.057348Z","end":"2026-05-15T08:12:15.417395Z","steps":["trace[1241384470] 'process raft request' (duration: 359.362573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:15.417545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.057307Z","time spent":"360.2263ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T08:12:15.417559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.083312Z","time spent":"334.212324ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T08:12:15.417562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.058307Z","time spent":"359.198729ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T08:12:15.417649Z","caller":"traceutil/trace.go:171","msg":"trace[1581785220] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"323.049432ms","start":"2026-05-15T08:12:15.094591Z","end":"2026-05-15T08:12:15.41764Z","steps":["trace[1581785220] 'process raft request' (duration: 322.4178ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:12:15.417667Z","caller":"traceutil/trace.go:171","msg":"trace[784489042] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"344.13486ms","start":"2026-05-15T08:12:15.073526Z","end":"2026-05-15T08:12:15.417661Z","steps":["trace[784489042] 'process raft request' (duration: 343.335475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:15.417696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.07351Z","time spent":"344.171991ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T08:12:15.417694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.094563Z","time spent":"323.109944ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T08:12:15.417752Z","caller":"traceutil/trace.go:171","msg":"trace[740024582] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"337.408868ms","start":"2026-05-15T08:12:15.080337Z","end":"2026-05-15T08:12:15.417746Z","steps":["trace[740024582] 'process raft request' (duration: 336.568181ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:12:15.417765Z","caller":"traceutil/trace.go:171","msg":"trace[1131432259] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"157.516067ms","start":"2026-05-15T08:12:15.260234Z","end":"2026-05-15T08:12:15.41775Z","steps":["trace[1131432259] 'process raft request' (duration: 156.851583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:15.417777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.080321Z","time spent":"337.442008ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T08:12:15.9531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.43088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-15T08:12:15.953406Z","caller":"traceutil/trace.go:171","msg":"trace[314090010] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"514.394705ms","start":"2026-05-15T08:12:15.438996Z","end":"2026-05-15T08:12:15.953391Z","steps":["trace[314090010] 'process raft request' (duration: 514.331474ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:12:15.953546Z","caller":"traceutil/trace.go:171","msg":"trace[238975897] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"519.461545ms","start":"2026-05-15T08:12:15.434066Z","end":"2026-05-15T08:12:15.953527Z","steps":["trace[238975897] 'process raft request' (duration: 519.119939ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:12:15.953638Z","caller":"traceutil/trace.go:171","msg":"trace[1264205479] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"522.670939ms","start":"2026-05-15T08:12:15.430957Z","end":"2026-05-15T08:12:15.953628Z","steps":["trace[1264205479] 'process raft request' (duration: 38.328431ms)","trace[1264205479] 'compare' (duration: 483.151234ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:12:15.953648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.438987Z","time spent":"514.449356ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:12:15.953677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.430947Z","time spent":"522.71144ms","remote":"127.0.0.1:39618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T08:12:15.953815Z","caller":"traceutil/trace.go:171","msg":"trace[212935598] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"519.052316ms","start":"2026-05-15T08:12:15.434754Z","end":"2026-05-15T08:12:15.953806Z","steps":["trace[212935598] 'process raft request' (duration: 518.494495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:15.953857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.434743Z","time spent":"519.093217ms","remote":"127.0.0.1:39618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T08:12:15.95396Z","caller":"traceutil/trace.go:171","msg":"trace[2050919458] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"519.597048ms","start":"2026-05-15T08:12:15.434355Z","end":"2026-05-15T08:12:15.953952Z","steps":["trace[2050919458] 'process raft request' (duration: 518.928475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:15.954009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.434312Z","time spent":"519.668889ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:12:15.954069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.434049Z","time spent":"519.537067ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:12:15.957273Z","caller":"traceutil/trace.go:171","msg":"trace[734489972] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"489.733755ms","start":"2026-05-15T08:12:15.467528Z","end":"2026-05-15T08:12:15.957261Z","steps":["trace[734489972] 'process raft request' (duration: 489.694005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:15.957344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.467507Z","time spent":"489.803976ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:12:15.957462Z","caller":"traceutil/trace.go:171","msg":"trace[160305464] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"513.67278ms","start":"2026-05-15T08:12:15.44378Z","end":"2026-05-15T08:12:15.957453Z","steps":["trace[160305464] 'process raft request' (duration: 513.267902ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:12:15.957425Z","caller":"traceutil/trace.go:171","msg":"trace[2049510458] transaction","detail":"{read_only:false; number_of_response:1; response_revision:542; }","duration":"515.521117ms","start":"2026-05-15T08:12:15.441862Z","end":"2026-05-15T08:12:15.957383Z","steps":["trace[2049510458] 'process raft request' (duration: 515.146409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:15.957507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.443772Z","time spent":"513.713171ms","remote":"127.0.0.1:39618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T08:12:15.957619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.441855Z","time spent":"515.651519ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6938,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:12:15.961559Z","caller":"traceutil/trace.go:171","msg":"trace[2043304807] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"511.281654ms","start":"2026-05-15T08:12:15.450251Z","end":"2026-05-15T08:12:15.961533Z","steps":["trace[2043304807] 'process raft request' (duration: 506.845095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:15.961627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.450233Z","time spent":"511.357025ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:12:15.961761Z","caller":"traceutil/trace.go:171","msg":"trace[216971550] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"507.11863ms","start":"2026-05-15T08:12:15.454631Z","end":"2026-05-15T08:12:15.96175Z","steps":["trace[216971550] 'process raft request' (duration: 502.530488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:15.961812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.454596Z","time spent":"507.189432ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:12:15.978384Z","caller":"traceutil/trace.go:171","msg":"trace[459518741] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:563; }","duration":"167.11725ms","start":"2026-05-15T08:12:15.811227Z","end":"2026-05-15T08:12:15.978344Z","steps":["trace[459518741] 'read index received' (duration: 147.03094ms)","trace[459518741] 'applied index is now lower than readState.Index' (duration: 20.08536ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:12:15.978453Z","caller":"traceutil/trace.go:171","msg":"trace[317362529] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"439.246972ms","start":"2026-05-15T08:12:15.539174Z","end":"2026-05-15T08:12:15.978421Z","steps":["trace[317362529] 'process raft request' (duration: 439.12007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:15.978515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.276812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-15T08:12:15.978601Z","caller":"traceutil/trace.go:171","msg":"trace[1197266759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:548; }","duration":"167.417035ms","start":"2026-05-15T08:12:15.811175Z","end":"2026-05-15T08:12:15.978592Z","steps":["trace[1197266759] 'agreement among raft nodes before linearized reading' (duration: 167.273003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:15.978665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.53915Z","time spent":"439.425996ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:12:15.979009Z","caller":"traceutil/trace.go:171","msg":"trace[3632979] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"497.02139ms","start":"2026-05-15T08:12:15.481962Z","end":"2026-05-15T08:12:15.978983Z","steps":["trace[3632979] 'process raft request' (duration: 496.190744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:15.979162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:15.481927Z","time spent":"497.172403ms","remote":"127.0.0.1:39136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T08:12:16.283833Z","caller":"traceutil/trace.go:171","msg":"trace[623574224] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"101.604288ms","start":"2026-05-15T08:12:16.182203Z","end":"2026-05-15T08:12:16.283807Z","steps":["trace[623574224] 'compare' (duration: 92.462086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:19.5679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.191251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:12:19.56796Z","caller":"traceutil/trace.go:171","msg":"trace[1796128174] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:609; }","duration":"155.275192ms","start":"2026-05-15T08:12:19.412674Z","end":"2026-05-15T08:12:19.56795Z","steps":["trace[1796128174] 'count revisions from in-memory index tree' (duration: 155.16018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:19.568038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.740618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:12:19.568058Z","caller":"traceutil/trace.go:171","msg":"trace[1533935559] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:609; }","duration":"180.793739ms","start":"2026-05-15T08:12:19.387259Z","end":"2026-05-15T08:12:19.568052Z","steps":["trace[1533935559] 'range keys from in-memory index tree' (duration: 180.630216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:19.568198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.712001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:12:19.568219Z","caller":"traceutil/trace.go:171","msg":"trace[1998152798] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:609; }","duration":"155.771912ms","start":"2026-05-15T08:12:19.412442Z","end":"2026-05-15T08:12:19.568214Z","steps":["trace[1998152798] 'range keys from in-memory index tree' (duration: 155.68024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:23.44948Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306378982637724777,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-15T08:12:23.459805Z","caller":"traceutil/trace.go:171","msg":"trace[500824594] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"565.830412ms","start":"2026-05-15T08:12:22.893937Z","end":"2026-05-15T08:12:23.459767Z","steps":["trace[500824594] 'process raft request' (duration: 565.628678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:23.461522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:22.893926Z","time spent":"565.954085ms","remote":"127.0.0.1:39282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:12:23.944865Z","caller":"traceutil/trace.go:171","msg":"trace[1241886046] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"933.96704ms","start":"2026-05-15T08:12:23.010859Z","end":"2026-05-15T08:12:23.944826Z","steps":["trace[1241886046] 'process raft request' (duration: 932.94622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:23.945103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:23.010838Z","time spent":"934.132003ms","remote":"127.0.0.1:39196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:12:23.944792Z","caller":"traceutil/trace.go:171","msg":"trace[989704937] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:650; }","duration":"995.794067ms","start":"2026-05-15T08:12:22.948955Z","end":"2026-05-15T08:12:23.944749Z","steps":["trace[989704937] 'read index received' (duration: 510.660187ms)","trace[989704937] 'applied index is now lower than readState.Index' (duration: 485.13248ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:12:23.945592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.43423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-15T08:12:23.945936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.600725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-15T08:12:23.945757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.888312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.128\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-15T08:12:23.945857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.77212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-15T08:12:23.948126Z","caller":"traceutil/trace.go:171","msg":"trace[997505705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"997.014772ms","start":"2026-05-15T08:12:22.948905Z","end":"2026-05-15T08:12:23.945919Z","steps":["trace[997505705] 'agreement among raft nodes before linearized reading' (duration: 996.011171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:23.948873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:22.948881Z","time spent":"999.95554ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-15T08:12:23.948232Z","caller":"traceutil/trace.go:171","msg":"trace[585615826] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"852.671487ms","start":"2026-05-15T08:12:23.093297Z","end":"2026-05-15T08:12:23.945969Z","steps":["trace[585615826] 'agreement among raft nodes before linearized reading' (duration: 852.505194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:23.949293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:23.093277Z","time spent":"855.997232ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-15T08:12:23.94829Z","caller":"traceutil/trace.go:171","msg":"trace[454652871] range","detail":"{range_begin:/registry/masterleases/199.19.213.128; range_end:; response_count:1; response_revision:633; }","duration":"987.292639ms","start":"2026-05-15T08:12:22.958785Z","end":"2026-05-15T08:12:23.946077Z","steps":["trace[454652871] 'agreement among raft nodes before linearized reading' (duration: 986.85149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:23.949641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:22.958756Z","time spent":"990.85691ms","remote":"127.0.0.1:39122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.128\" "} {"level":"info","ts":"2026-05-15T08:12:23.948317Z","caller":"traceutil/trace.go:171","msg":"trace[552963831] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"295.099747ms","start":"2026-05-15T08:12:23.65103Z","end":"2026-05-15T08:12:23.946129Z","steps":["trace[552963831] 'agreement among raft nodes before linearized reading' (duration: 294.73272ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:12:24.119716Z","caller":"traceutil/trace.go:171","msg":"trace[2126586903] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"162.328552ms","start":"2026-05-15T08:12:23.957357Z","end":"2026-05-15T08:12:24.119686Z","steps":["trace[2126586903] 'process raft request' (duration: 161.963035ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:12:24.119877Z","caller":"traceutil/trace.go:171","msg":"trace[700591099] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"162.520957ms","start":"2026-05-15T08:12:23.957344Z","end":"2026-05-15T08:12:24.119865Z","steps":["trace[700591099] 'process raft request' (duration: 161.823113ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:12:24.119832Z","caller":"traceutil/trace.go:171","msg":"trace[1585775149] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"162.440184ms","start":"2026-05-15T08:12:23.95736Z","end":"2026-05-15T08:12:24.1198Z","steps":["trace[1585775149] 'process raft request' (duration: 161.985025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:25.515341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.562342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-15T08:12:25.515458Z","caller":"traceutil/trace.go:171","msg":"trace[137930449] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.36065412s","start":"2026-05-15T08:12:24.154784Z","end":"2026-05-15T08:12:25.515438Z","steps":["trace[137930449] 'process raft request' (duration: 619.933475ms)","trace[137930449] 'compare' (duration: 740.023851ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:12:25.515514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:24.154772Z","time spent":"1.360718741s","remote":"127.0.0.1:39282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-15T08:12:27.303398Z","caller":"traceutil/trace.go:171","msg":"trace[22226367] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"225.83ms","start":"2026-05-15T08:12:27.077536Z","end":"2026-05-15T08:12:27.303365Z","steps":["trace[22226367] 'process raft request' (duration: 225.625276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:40.390835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.732439ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-15T08:12:40.391064Z","caller":"traceutil/trace.go:171","msg":"trace[270926622] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"424.446675ms","start":"2026-05-15T08:12:39.966598Z","end":"2026-05-15T08:12:40.391044Z","steps":["trace[270926622] 'read index received' (duration: 175.433481ms)","trace[270926622] 'applied index is now lower than readState.Index' (duration: 249.011594ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:12:40.391261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.885602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T08:12:40.391342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.349362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-15T08:12:40.391383Z","caller":"traceutil/trace.go:171","msg":"trace[1648499606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:794; }","duration":"167.420774ms","start":"2026-05-15T08:12:40.223949Z","end":"2026-05-15T08:12:40.39137Z","steps":["trace[1648499606] 'agreement among raft nodes before linearized reading' (duration: 167.356232ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:12:40.391356Z","caller":"traceutil/trace.go:171","msg":"trace[241628289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"375.999004ms","start":"2026-05-15T08:12:40.015345Z","end":"2026-05-15T08:12:40.391344Z","steps":["trace[241628289] 'agreement among raft nodes before linearized reading' (duration: 375.864622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:40.391479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.406903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-15T08:12:40.391603Z","caller":"traceutil/trace.go:171","msg":"trace[489349123] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:794; }","duration":"171.577136ms","start":"2026-05-15T08:12:40.220002Z","end":"2026-05-15T08:12:40.391579Z","steps":["trace[489349123] 'agreement among raft nodes before linearized reading' (duration: 171.334001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:40.391711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.998755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-15T08:12:40.391611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:40.015338Z","time spent":"376.221828ms","remote":"127.0.0.1:39086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T08:12:40.391252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.638359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qmv4b\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-15T08:12:40.391785Z","caller":"traceutil/trace.go:171","msg":"trace[1248874796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:794; }","duration":"174.128478ms","start":"2026-05-15T08:12:40.217642Z","end":"2026-05-15T08:12:40.391771Z","steps":["trace[1248874796] 'agreement among raft nodes before linearized reading' (duration: 173.889263ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:12:40.391916Z","caller":"traceutil/trace.go:171","msg":"trace[1148009815] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qmv4b; range_end:; response_count:1; response_revision:794; }","duration":"425.289032ms","start":"2026-05-15T08:12:39.96656Z","end":"2026-05-15T08:12:40.391849Z","steps":["trace[1148009815] 'agreement among raft nodes before linearized reading' (duration: 424.537307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:12:40.392107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:12:39.966542Z","time spent":"425.485467ms","remote":"127.0.0.1:39282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-qmv4b\" "} {"level":"warn","ts":"2026-05-15T08:12:40.39232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.570908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-15T08:12:40.392355Z","caller":"traceutil/trace.go:171","msg":"trace[978478622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"164.643209ms","start":"2026-05-15T08:12:40.227704Z","end":"2026-05-15T08:12:40.392347Z","steps":["trace[978478622] 'agreement among raft nodes before linearized reading' (duration: 163.846522ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:13:15.799455Z","caller":"traceutil/trace.go:171","msg":"trace[1052197960] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"397.461511ms","start":"2026-05-15T08:13:15.401969Z","end":"2026-05-15T08:13:15.79943Z","steps":["trace[1052197960] 'process raft request' (duration: 397.320718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:13:15.79962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:13:15.40195Z","time spent":"397.596765ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:13:16.581065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.669276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T08:13:16.581164Z","caller":"traceutil/trace.go:171","msg":"trace[259410044] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1012; }","duration":"230.819779ms","start":"2026-05-15T08:13:16.350327Z","end":"2026-05-15T08:13:16.581147Z","steps":["trace[259410044] 'count revisions from in-memory index tree' (duration: 230.564405ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:13:16.917819Z","caller":"traceutil/trace.go:171","msg":"trace[422894082] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"314.84312ms","start":"2026-05-15T08:13:16.602955Z","end":"2026-05-15T08:13:16.917798Z","steps":["trace[422894082] 'read index received' (duration: 314.693347ms)","trace[422894082] 'applied index is now lower than readState.Index' (duration: 148.813µs)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:13:16.918058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.083146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-15T08:13:16.918107Z","caller":"traceutil/trace.go:171","msg":"trace[1384713829] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1013; }","duration":"315.156567ms","start":"2026-05-15T08:13:16.602933Z","end":"2026-05-15T08:13:16.91809Z","steps":["trace[1384713829] 'agreement among raft nodes before linearized reading' (duration: 315.001304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:13:16.918111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.932097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-15T08:13:16.918174Z","caller":"traceutil/trace.go:171","msg":"trace[1810486623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1013; }","duration":"303.063599ms","start":"2026-05-15T08:13:16.615097Z","end":"2026-05-15T08:13:16.918161Z","steps":["trace[1810486623] 'agreement among raft nodes before linearized reading' (duration: 302.853605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:13:16.918209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:13:16.615079Z","time spent":"303.12384ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-15T08:13:16.918242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:13:16.602928Z","time spent":"315.29912ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-15T08:13:16.917916Z","caller":"traceutil/trace.go:171","msg":"trace[1469213241] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"317.14728ms","start":"2026-05-15T08:13:16.60074Z","end":"2026-05-15T08:13:16.917888Z","steps":["trace[1469213241] 'process raft request' (duration: 316.929445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:13:16.918373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.829781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-15T08:13:16.918477Z","caller":"traceutil/trace.go:171","msg":"trace[1811818562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1013; }","duration":"292.953963ms","start":"2026-05-15T08:13:16.625484Z","end":"2026-05-15T08:13:16.918438Z","steps":["trace[1811818562] 'agreement among raft nodes before linearized reading' (duration: 292.604586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:13:16.918509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:13:16.600724Z","time spent":"317.702651ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:13:40.43597Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306378982637725765,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-15T08:13:40.554726Z","caller":"traceutil/trace.go:171","msg":"trace[425253969] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"619.558275ms","start":"2026-05-15T08:13:39.935141Z","end":"2026-05-15T08:13:40.5547Z","steps":["trace[425253969] 'read index received' (duration: 619.285719ms)","trace[425253969] 'applied index is now lower than readState.Index' (duration: 271.176µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:13:40.555006Z","caller":"traceutil/trace.go:171","msg":"trace[258883255] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"634.069146ms","start":"2026-05-15T08:13:39.920928Z","end":"2026-05-15T08:13:40.554997Z","steps":["trace[258883255] 'process raft request' (duration: 633.579376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:13:40.555099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:13:39.920907Z","time spent":"634.125077ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:13:40.55537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.728537ms","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-05-15T08:13:40.555403Z","caller":"traceutil/trace.go:171","msg":"trace[880731648] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1091; }","duration":"183.800468ms","start":"2026-05-15T08:13:40.371594Z","end":"2026-05-15T08:13:40.555395Z","steps":["trace[880731648] 'agreement among raft nodes before linearized reading' (duration: 183.738746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:13:40.555414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.226039ms","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":"warn","ts":"2026-05-15T08:13:40.555441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.319407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:13:40.55553Z","caller":"traceutil/trace.go:171","msg":"trace[86424771] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1091; }","duration":"620.398773ms","start":"2026-05-15T08:13:39.935106Z","end":"2026-05-15T08:13:40.555504Z","steps":["trace[86424771] 'agreement among raft nodes before linearized reading' (duration: 620.171148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:13:40.55559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:13:39.935091Z","time spent":"620.488194ms","remote":"127.0.0.1:39514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-05-15T08:13:40.555605Z","caller":"traceutil/trace.go:171","msg":"trace[1528661942] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1091; }","duration":"540.560002ms","start":"2026-05-15T08:13:40.014996Z","end":"2026-05-15T08:13:40.555556Z","steps":["trace[1528661942] 'agreement among raft nodes before linearized reading' (duration: 540.295436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:13:40.555698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:13:40.014974Z","time spent":"540.712925ms","remote":"127.0.0.1:39086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-15T08:13:43.188865Z","caller":"traceutil/trace.go:171","msg":"trace[1482571065] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1136; }","duration":"172.169569ms","start":"2026-05-15T08:13:43.016669Z","end":"2026-05-15T08:13:43.188838Z","steps":["trace[1482571065] 'read index received' (duration: 134.327113ms)","trace[1482571065] 'applied index is now lower than readState.Index' (duration: 37.840876ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:13:43.188868Z","caller":"traceutil/trace.go:171","msg":"trace[86695820] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"202.371653ms","start":"2026-05-15T08:13:42.986475Z","end":"2026-05-15T08:13:43.188847Z","steps":["trace[86695820] 'process raft request' (duration: 164.64432ms)","trace[86695820] 'compare' (duration: 37.484628ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:13:43.189192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.502037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:13:43.189315Z","caller":"traceutil/trace.go:171","msg":"trace[1122483144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"172.692421ms","start":"2026-05-15T08:13:43.016594Z","end":"2026-05-15T08:13:43.189287Z","steps":["trace[1122483144] 'agreement among raft nodes before linearized reading' (duration: 172.490676ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:13:43.398005Z","caller":"traceutil/trace.go:171","msg":"trace[940814398] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"202.967847ms","start":"2026-05-15T08:13:43.195008Z","end":"2026-05-15T08:13:43.397976Z","steps":["trace[940814398] 'process raft request' (duration: 152.720816ms)","trace[940814398] 'compare' (duration: 50.073008ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:13:43.695357Z","caller":"traceutil/trace.go:171","msg":"trace[40185950] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1137; }","duration":"499.17247ms","start":"2026-05-15T08:13:43.196156Z","end":"2026-05-15T08:13:43.695329Z","steps":["trace[40185950] 'read index received' (duration: 151.769385ms)","trace[40185950] 'applied index is now lower than readState.Index' (duration: 347.401535ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:13:43.695495Z","caller":"traceutil/trace.go:171","msg":"trace[1925631230] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"500.331684ms","start":"2026-05-15T08:13:43.195116Z","end":"2026-05-15T08:13:43.695448Z","steps":["trace[1925631230] 'process raft request' (duration: 500.012928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:13:43.69558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.386604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-15T08:13:43.695626Z","caller":"traceutil/trace.go:171","msg":"trace[480816020] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1100; }","duration":"499.482986ms","start":"2026-05-15T08:13:43.19613Z","end":"2026-05-15T08:13:43.695613Z","steps":["trace[480816020] 'agreement among raft nodes before linearized reading' (duration: 499.366623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:13:43.695666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:13:43.19612Z","time spent":"499.537077ms","remote":"127.0.0.1:39380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-05-15T08:13:43.695712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:13:43.195107Z","time spent":"500.484757ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:13:43.695883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.226438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-15T08:13:43.695932Z","caller":"traceutil/trace.go:171","msg":"trace[578530869] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1100; }","duration":"345.31125ms","start":"2026-05-15T08:13:43.350608Z","end":"2026-05-15T08:13:43.695919Z","steps":["trace[578530869] 'agreement among raft nodes before linearized reading' (duration: 345.105266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:13:43.695966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:13:43.350594Z","time spent":"345.363791ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-15T08:13:43.695963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.190843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-15T08:13:43.696076Z","caller":"traceutil/trace.go:171","msg":"trace[1928215383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1100; }","duration":"448.338026ms","start":"2026-05-15T08:13:43.247704Z","end":"2026-05-15T08:13:43.696042Z","steps":["trace[1928215383] 'agreement among raft nodes before linearized reading' (duration: 448.001819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:13:43.696132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:13:43.247691Z","time spent":"448.428179ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-15T08:13:43.696233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.544634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-15T08:13:43.696295Z","caller":"traceutil/trace.go:171","msg":"trace[1279307085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1100; }","duration":"443.609935ms","start":"2026-05-15T08:13:43.252671Z","end":"2026-05-15T08:13:43.696281Z","steps":["trace[1279307085] 'agreement among raft nodes before linearized reading' (duration: 443.350759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:13:43.696332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:13:43.252603Z","time spent":"443.721787ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-15T08:13:44.879482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.547243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:13:44.879618Z","caller":"traceutil/trace.go:171","msg":"trace[863222777] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1104; }","duration":"107.780938ms","start":"2026-05-15T08:13:44.7718Z","end":"2026-05-15T08:13:44.879581Z","steps":["trace[863222777] 'count revisions from in-memory index tree' (duration: 107.433811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:13:59.471064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.987396ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-15T08:13:59.471577Z","caller":"traceutil/trace.go:171","msg":"trace[1984503947] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"774.796319ms","start":"2026-05-15T08:13:58.696758Z","end":"2026-05-15T08:13:59.471554Z","steps":["trace[1984503947] 'process raft request' (duration: 774.433811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:13:59.471704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:13:58.69673Z","time spent":"774.894791ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:13:59.471963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.304275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:13:59.472003Z","caller":"traceutil/trace.go:171","msg":"trace[416511668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1202; }","duration":"446.389937ms","start":"2026-05-15T08:13:59.025602Z","end":"2026-05-15T08:13:59.471992Z","steps":["trace[416511668] 'agreement among raft nodes before linearized reading' (duration: 446.320285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:13:59.47204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:13:59.025577Z","time spent":"446.456259ms","remote":"127.0.0.1:39086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-15T08:13:59.471436Z","caller":"traceutil/trace.go:171","msg":"trace[294092491] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1244; }","duration":"445.748323ms","start":"2026-05-15T08:13:59.025666Z","end":"2026-05-15T08:13:59.471415Z","steps":["trace[294092491] 'read index received' (duration: 90.165736ms)","trace[294092491] 'applied index is now lower than readState.Index' (duration: 355.581177ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:13:59.472252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.709446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:13:59.472278Z","caller":"traceutil/trace.go:171","msg":"trace[789687243] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1202; }","duration":"210.779978ms","start":"2026-05-15T08:13:59.261489Z","end":"2026-05-15T08:13:59.472269Z","steps":["trace[789687243] 'agreement among raft nodes before linearized reading' (duration: 210.711236ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:14:11.967616Z","caller":"traceutil/trace.go:171","msg":"trace[421468131] linearizableReadLoop","detail":"{readStateIndex:1289; appliedIndex:1288; }","duration":"105.794563ms","start":"2026-05-15T08:14:11.86179Z","end":"2026-05-15T08:14:11.967585Z","steps":["trace[421468131] 'read index received' (duration: 105.539797ms)","trace[421468131] 'applied index is now lower than readState.Index' (duration: 253.516µs)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:14:11.967752Z","caller":"traceutil/trace.go:171","msg":"trace[1494820425] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"107.140831ms","start":"2026-05-15T08:14:11.860596Z","end":"2026-05-15T08:14:11.967737Z","steps":["trace[1494820425] 'process raft request' (duration: 106.795943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:11.968035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.233141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-15T08:14:11.968073Z","caller":"traceutil/trace.go:171","msg":"trace[1936405339] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1243; }","duration":"106.370795ms","start":"2026-05-15T08:14:11.861691Z","end":"2026-05-15T08:14:11.968062Z","steps":["trace[1936405339] 'agreement among raft nodes before linearized reading' (duration: 106.202311ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:14:15.138672Z","caller":"traceutil/trace.go:171","msg":"trace[1835570381] linearizableReadLoop","detail":"{readStateIndex:1316; appliedIndex:1315; }","duration":"125.359992ms","start":"2026-05-15T08:14:15.013275Z","end":"2026-05-15T08:14:15.138635Z","steps":["trace[1835570381] 'read index received' (duration: 65.527868ms)","trace[1835570381] 'applied index is now lower than readState.Index' (duration: 59.830764ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:14:15.138929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.613958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:14:15.138971Z","caller":"traceutil/trace.go:171","msg":"trace[640649629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1269; }","duration":"125.70987ms","start":"2026-05-15T08:14:15.013244Z","end":"2026-05-15T08:14:15.138954Z","steps":["trace[640649629] 'agreement among raft nodes before linearized reading' (duration: 125.526246ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:14:15.139144Z","caller":"traceutil/trace.go:171","msg":"trace[714036356] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"150.146193ms","start":"2026-05-15T08:14:14.988961Z","end":"2026-05-15T08:14:15.139107Z","steps":["trace[714036356] 'process raft request' (duration: 89.90144ms)","trace[714036356] 'compare' (duration: 59.61694ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:14:16.254893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.176142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-15T08:14:16.255056Z","caller":"traceutil/trace.go:171","msg":"trace[1484862073] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"516.072674ms","start":"2026-05-15T08:14:15.738967Z","end":"2026-05-15T08:14:16.25504Z","steps":["trace[1484862073] 'process raft request' (duration: 516.027003ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:14:16.255075Z","caller":"traceutil/trace.go:171","msg":"trace[1630347755] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"650.330417ms","start":"2026-05-15T08:14:15.604725Z","end":"2026-05-15T08:14:16.255056Z","steps":["trace[1630347755] 'read index received' (duration: 271.920311ms)","trace[1630347755] 'applied index is now lower than readState.Index' (duration: 378.408946ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:14:16.255103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:15.738939Z","time spent":"516.142226ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:14:16.255157Z","caller":"traceutil/trace.go:171","msg":"trace[1361329113] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"720.425542ms","start":"2026-05-15T08:14:15.534724Z","end":"2026-05-15T08:14:16.255149Z","steps":["trace[1361329113] 'process raft request' (duration: 341.912073ms)","trace[1361329113] 'compare' (duration: 378.051278ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:14:16.255228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:15.534696Z","time spent":"720.475933ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:14:16.255439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.714955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-15T08:14:16.255467Z","caller":"traceutil/trace.go:171","msg":"trace[1584320201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1275; }","duration":"650.793137ms","start":"2026-05-15T08:14:15.60467Z","end":"2026-05-15T08:14:16.255463Z","steps":["trace[1584320201] 'agreement among raft nodes before linearized reading' (duration: 650.677154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:16.255484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:15.604647Z","time spent":"650.832178ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-15T08:14:16.255571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.235726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-15T08:14:16.25559Z","caller":"traceutil/trace.go:171","msg":"trace[1426478965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1275; }","duration":"272.292657ms","start":"2026-05-15T08:14:15.983294Z","end":"2026-05-15T08:14:16.255587Z","steps":["trace[1426478965] 'agreement among raft nodes before linearized reading' (duration: 272.247776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:16.255672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.954127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-15T08:14:16.255692Z","caller":"traceutil/trace.go:171","msg":"trace[441813443] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1275; }","duration":"194.992328ms","start":"2026-05-15T08:14:16.060694Z","end":"2026-05-15T08:14:16.255686Z","steps":["trace[441813443] 'agreement among raft nodes before linearized reading' (duration: 194.946107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:16.255755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.492993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-15T08:14:16.255773Z","caller":"traceutil/trace.go:171","msg":"trace[1859459708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1275; }","duration":"266.531474ms","start":"2026-05-15T08:14:15.989237Z","end":"2026-05-15T08:14:16.255769Z","steps":["trace[1859459708] 'agreement among raft nodes before linearized reading' (duration: 266.491203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:16.255777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.955885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:14:16.255839Z","caller":"traceutil/trace.go:171","msg":"trace[1220485074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"241.050727ms","start":"2026-05-15T08:14:16.014772Z","end":"2026-05-15T08:14:16.255823Z","steps":["trace[1220485074] 'agreement among raft nodes before linearized reading' (duration: 240.925885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:16.255941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.356871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-15T08:14:16.255999Z","caller":"traceutil/trace.go:171","msg":"trace[1987809913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1275; }","duration":"199.424303ms","start":"2026-05-15T08:14:16.056564Z","end":"2026-05-15T08:14:16.255989Z","steps":["trace[1987809913] 'agreement among raft nodes before linearized reading' (duration: 199.257669ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:14:16.547254Z","caller":"traceutil/trace.go:171","msg":"trace[553029653] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"286.040794ms","start":"2026-05-15T08:14:16.261186Z","end":"2026-05-15T08:14:16.547226Z","steps":["trace[553029653] 'process raft request' (duration: 221.056208ms)","trace[553029653] 'compare' (duration: 64.805372ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:14:16.596145Z","caller":"traceutil/trace.go:171","msg":"trace[1403984074] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"333.532053ms","start":"2026-05-15T08:14:16.262576Z","end":"2026-05-15T08:14:16.596108Z","steps":["trace[1403984074] 'process raft request' (duration: 333.460031ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:14:16.596167Z","caller":"traceutil/trace.go:171","msg":"trace[659242937] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"334.927132ms","start":"2026-05-15T08:14:16.261197Z","end":"2026-05-15T08:14:16.596124Z","steps":["trace[659242937] 'process raft request' (duration: 334.620526ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:14:16.596264Z","caller":"traceutil/trace.go:171","msg":"trace[418062384] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"333.285388ms","start":"2026-05-15T08:14:16.262955Z","end":"2026-05-15T08:14:16.59624Z","steps":["trace[418062384] 'process raft request' (duration: 333.114494ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:14:16.596287Z","caller":"traceutil/trace.go:171","msg":"trace[291243937] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"333.84246ms","start":"2026-05-15T08:14:16.262404Z","end":"2026-05-15T08:14:16.596246Z","steps":["trace[291243937] 'process raft request' (duration: 333.598655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:16.596361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:16.262569Z","time spent":"333.674806ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:14:16.596409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:16.262948Z","time spent":"333.371399ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:14:16.596414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:16.26118Z","time spent":"335.140567ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:14:16.596463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:16.262397Z","time spent":"333.972132ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:14:18.513313Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306378982637726193,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-15T08:14:18.520867Z","caller":"traceutil/trace.go:171","msg":"trace[363661743] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"948.52663ms","start":"2026-05-15T08:14:17.572317Z","end":"2026-05-15T08:14:18.520843Z","steps":["trace[363661743] 'process raft request' (duration: 948.393857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:18.521042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:17.572289Z","time spent":"948.648903ms","remote":"127.0.0.1:39276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5883,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:14:18.855776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.791765ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-15T08:14:18.856301Z","caller":"traceutil/trace.go:171","msg":"trace[1707723808] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"586.769481ms","start":"2026-05-15T08:14:18.269485Z","end":"2026-05-15T08:14:18.856254Z","steps":["trace[1707723808] 'process raft request' (duration: 586.380443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:18.856416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:18.269457Z","time spent":"586.890813ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:14:18.856516Z","caller":"traceutil/trace.go:171","msg":"trace[88839293] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1328; }","duration":"843.234589ms","start":"2026-05-15T08:14:18.013242Z","end":"2026-05-15T08:14:18.856477Z","steps":["trace[88839293] 'read index received' (duration: 507.797086ms)","trace[88839293] 'applied index is now lower than readState.Index' (duration: 335.436223ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:14:18.856641Z","caller":"traceutil/trace.go:171","msg":"trace[1592083038] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"478.26226ms","start":"2026-05-15T08:14:18.378368Z","end":"2026-05-15T08:14:18.85663Z","steps":["trace[1592083038] 'process raft request' (duration: 477.840161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:18.856771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:18.378343Z","time spent":"478.351232ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:14:18.856821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.303418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-15T08:14:18.856867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.225741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-15T08:14:18.856883Z","caller":"traceutil/trace.go:171","msg":"trace[655946291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1283; }","duration":"306.395759ms","start":"2026-05-15T08:14:18.550475Z","end":"2026-05-15T08:14:18.85687Z","steps":["trace[655946291] 'agreement among raft nodes before linearized reading' (duration: 306.236116ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:14:18.856906Z","caller":"traceutil/trace.go:171","msg":"trace[662479026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1283; }","duration":"255.303853ms","start":"2026-05-15T08:14:18.601591Z","end":"2026-05-15T08:14:18.856895Z","steps":["trace[662479026] 'agreement among raft nodes before linearized reading' (duration: 255.17154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:18.85699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.413725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-15T08:14:18.857026Z","caller":"traceutil/trace.go:171","msg":"trace[2017337585] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1283; }","duration":"255.467316ms","start":"2026-05-15T08:14:18.60155Z","end":"2026-05-15T08:14:18.857017Z","steps":["trace[2017337585] 'agreement among raft nodes before linearized reading' (duration: 255.384904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:18.857006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.722169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-15T08:14:18.857099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.177971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-15T08:14:18.857138Z","caller":"traceutil/trace.go:171","msg":"trace[1099965003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1283; }","duration":"256.251813ms","start":"2026-05-15T08:14:18.600873Z","end":"2026-05-15T08:14:18.857124Z","steps":["trace[1099965003] 'agreement among raft nodes before linearized reading' (duration: 256.151091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:18.857143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.913042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-15T08:14:18.857127Z","caller":"traceutil/trace.go:171","msg":"trace[463393987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1283; }","duration":"843.894553ms","start":"2026-05-15T08:14:18.013215Z","end":"2026-05-15T08:14:18.857109Z","steps":["trace[463393987] 'agreement among raft nodes before linearized reading' (duration: 843.669469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:18.856917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:18.550456Z","time spent":"306.454561ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-15T08:14:18.857247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:18.013199Z","time spent":"844.026887ms","remote":"127.0.0.1:39086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-15T08:14:18.857261Z","caller":"traceutil/trace.go:171","msg":"trace[2145278985] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1283; }","duration":"253.978795ms","start":"2026-05-15T08:14:18.6032Z","end":"2026-05-15T08:14:18.857179Z","steps":["trace[2145278985] 'agreement among raft nodes before linearized reading' (duration: 253.878022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:19.972799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.495418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-15T08:14:19.97462Z","caller":"traceutil/trace.go:171","msg":"trace[939861796] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"548.350496ms","start":"2026-05-15T08:14:19.425487Z","end":"2026-05-15T08:14:19.973837Z","steps":["trace[939861796] 'read index received' (duration: 13.50861ms)","trace[939861796] 'applied index is now lower than readState.Index' (duration: 534.837726ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:14:19.974768Z","caller":"traceutil/trace.go:171","msg":"trace[102257563] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"569.109602ms","start":"2026-05-15T08:14:19.405627Z","end":"2026-05-15T08:14:19.974737Z","steps":["trace[102257563] 'process raft request' (duration: 33.429138ms)","trace[102257563] 'compare' (duration: 533.235433ms)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:14:19.97486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:19.405611Z","time spent":"569.185224ms","remote":"127.0.0.1:39174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-15T08:14:19.975223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.741096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:14:19.975255Z","caller":"traceutil/trace.go:171","msg":"trace[1171898058] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1292; }","duration":"549.835399ms","start":"2026-05-15T08:14:19.425411Z","end":"2026-05-15T08:14:19.975246Z","steps":["trace[1171898058] 'agreement among raft nodes before linearized reading' (duration: 549.728737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:19.97531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:19.42538Z","time spent":"549.92363ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-15T08:14:19.975479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.290038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-15T08:14:19.9755Z","caller":"traceutil/trace.go:171","msg":"trace[1052441693] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1292; }","duration":"231.350028ms","start":"2026-05-15T08:14:19.744144Z","end":"2026-05-15T08:14:19.975494Z","steps":["trace[1052441693] 'agreement among raft nodes before linearized reading' (duration: 231.304197ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:14:20.170972Z","caller":"traceutil/trace.go:171","msg":"trace[1438349182] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"130.325098ms","start":"2026-05-15T08:14:20.040623Z","end":"2026-05-15T08:14:20.170948Z","steps":["trace[1438349182] 'process raft request' (duration: 130.255517ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:14:20.171241Z","caller":"traceutil/trace.go:171","msg":"trace[1098029735] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"132.457055ms","start":"2026-05-15T08:14:20.038769Z","end":"2026-05-15T08:14:20.171226Z","steps":["trace[1098029735] 'process raft request' (duration: 74.434649ms)","trace[1098029735] 'compare' (duration: 57.520194ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:14:30.394848Z","caller":"traceutil/trace.go:171","msg":"trace[2025623881] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1416; }","duration":"159.582838ms","start":"2026-05-15T08:14:30.23524Z","end":"2026-05-15T08:14:30.394823Z","steps":["trace[2025623881] 'read index received' (duration: 159.373313ms)","trace[2025623881] 'applied index is now lower than readState.Index' (duration: 207.845µs)"],"step_count":2} {"level":"warn","ts":"2026-05-15T08:14:30.395062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.799502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-15T08:14:30.395103Z","caller":"traceutil/trace.go:171","msg":"trace[1094061224] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1367; }","duration":"159.909144ms","start":"2026-05-15T08:14:30.235178Z","end":"2026-05-15T08:14:30.395087Z","steps":["trace[1094061224] 'agreement among raft nodes before linearized reading' (duration: 159.750991ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:14:30.395055Z","caller":"traceutil/trace.go:171","msg":"trace[249537710] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"275.516086ms","start":"2026-05-15T08:14:30.119479Z","end":"2026-05-15T08:14:30.394995Z","steps":["trace[249537710] 'process raft request' (duration: 275.20327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:31.491304Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.044172314s","expected-duration":"1s"} {"level":"info","ts":"2026-05-15T08:14:31.491741Z","caller":"traceutil/trace.go:171","msg":"trace[1805754147] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"1.044691335s","start":"2026-05-15T08:14:30.447019Z","end":"2026-05-15T08:14:31.49171Z","steps":["trace[1805754147] 'process raft request' (duration: 1.044531282s)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:31.492103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:30.446992Z","time spent":"1.044807068s","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-15T08:14:31.544232Z","caller":"traceutil/trace.go:171","msg":"trace[1787600150] linearizableReadLoop","detail":"{readStateIndex:1419; appliedIndex:1417; }","duration":"551.970263ms","start":"2026-05-15T08:14:30.992245Z","end":"2026-05-15T08:14:31.544215Z","steps":["trace[1787600150] 'read index received' (duration: 499.367594ms)","trace[1787600150] 'applied index is now lower than readState.Index' (duration: 52.601559ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:14:31.544335Z","caller":"traceutil/trace.go:171","msg":"trace[1151506519] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"632.439061ms","start":"2026-05-15T08:14:30.911869Z","end":"2026-05-15T08:14:31.544308Z","steps":["trace[1151506519] 'process raft request' (duration: 632.255397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:31.544393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.154038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-15T08:14:31.544411Z","caller":"traceutil/trace.go:171","msg":"trace[392745495] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1369; }","duration":"552.226659ms","start":"2026-05-15T08:14:30.992178Z","end":"2026-05-15T08:14:31.544405Z","steps":["trace[392745495] 'agreement among raft nodes before linearized reading' (duration: 552.111547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:31.544427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:30.992161Z","time spent":"552.26221ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-15T08:14:31.544467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:30.911832Z","time spent":"632.555704ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-15T08:14:31.544518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.876076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-15T08:14:31.54457Z","caller":"traceutil/trace.go:171","msg":"trace[1788147997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1369; }","duration":"527.940587ms","start":"2026-05-15T08:14:31.016613Z","end":"2026-05-15T08:14:31.544553Z","steps":["trace[1788147997] 'agreement among raft nodes before linearized reading' (duration: 527.860766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:31.544614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.763125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-15T08:14:31.544609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:31.016601Z","time spent":"527.997499ms","remote":"127.0.0.1:39086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-15T08:14:31.544662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.512739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-15T08:14:31.544674Z","caller":"traceutil/trace.go:171","msg":"trace[1243544615] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1369; }","duration":"276.57344ms","start":"2026-05-15T08:14:31.268097Z","end":"2026-05-15T08:14:31.544671Z","steps":["trace[1243544615] 'agreement among raft nodes before linearized reading' (duration: 276.540389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:31.544733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.019189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-15T08:14:31.544744Z","caller":"traceutil/trace.go:171","msg":"trace[1282621908] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1369; }","duration":"521.044179ms","start":"2026-05-15T08:14:31.023697Z","end":"2026-05-15T08:14:31.544741Z","steps":["trace[1282621908] 'agreement among raft nodes before linearized reading' (duration: 521.008848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:31.544756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:31.02369Z","time spent":"521.06329ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-15T08:14:31.544831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.633462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-15T08:14:31.544844Z","caller":"traceutil/trace.go:171","msg":"trace[1853949665] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1369; }","duration":"527.700283ms","start":"2026-05-15T08:14:31.01714Z","end":"2026-05-15T08:14:31.54484Z","steps":["trace[1853949665] 'agreement among raft nodes before linearized reading' (duration: 527.662193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:31.544857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:31.017111Z","time spent":"527.742934ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-15T08:14:31.544629Z","caller":"traceutil/trace.go:171","msg":"trace[172031506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1369; }","duration":"521.777246ms","start":"2026-05-15T08:14:31.022847Z","end":"2026-05-15T08:14:31.544624Z","steps":["trace[172031506] 'agreement among raft nodes before linearized reading' (duration: 521.734275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:31.544989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:31.02282Z","time spent":"522.150834ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-15T08:14:31.544913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.685555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-15T08:14:31.545114Z","caller":"traceutil/trace.go:171","msg":"trace[575869079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1369; }","duration":"522.873609ms","start":"2026-05-15T08:14:31.022204Z","end":"2026-05-15T08:14:31.545077Z","steps":["trace[575869079] 'agreement among raft nodes before linearized reading' (duration: 522.682285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:14:31.545221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-15T08:14:31.022189Z","time spent":"522.993762ms","remote":"127.0.0.1:39372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-15T08:17:52.917471Z","caller":"traceutil/trace.go:171","msg":"trace[1728020227] transaction","detail":"{read_only:false; response_revision:2322; number_of_response:1; }","duration":"268.285783ms","start":"2026-05-15T08:17:52.649157Z","end":"2026-05-15T08:17:52.917443Z","steps":["trace[1728020227] 'process raft request' (duration: 268.035547ms)"],"step_count":1} {"level":"info","ts":"2026-05-15T08:17:52.925876Z","caller":"traceutil/trace.go:171","msg":"trace[1736774268] linearizableReadLoop","detail":"{readStateIndex:2414; appliedIndex:2413; }","duration":"213.246236ms","start":"2026-05-15T08:17:52.712611Z","end":"2026-05-15T08:17:52.925857Z","steps":["trace[1736774268] 'read index received' (duration: 205.434991ms)","trace[1736774268] 'applied index is now lower than readState.Index' (duration: 7.810325ms)"],"step_count":2} {"level":"info","ts":"2026-05-15T08:17:52.92614Z","caller":"traceutil/trace.go:171","msg":"trace[1931522180] transaction","detail":"{read_only:false; response_revision:2323; number_of_response:1; }","duration":"276.748861ms","start":"2026-05-15T08:17:52.649379Z","end":"2026-05-15T08:17:52.926128Z","steps":["trace[1931522180] 'process raft request' (duration: 276.403674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:17:52.926494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.562832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-15T08:17:52.926595Z","caller":"traceutil/trace.go:171","msg":"trace[773667902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2323; }","duration":"152.695875ms","start":"2026-05-15T08:17:52.773869Z","end":"2026-05-15T08:17:52.926564Z","steps":["trace[773667902] 'agreement among raft nodes before linearized reading' (duration: 152.371878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:17:52.926682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.649924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-15T08:17:52.926707Z","caller":"traceutil/trace.go:171","msg":"trace[1277004785] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2323; }","duration":"148.694156ms","start":"2026-05-15T08:17:52.778005Z","end":"2026-05-15T08:17:52.9267Z","steps":["trace[1277004785] 'agreement among raft nodes before linearized reading' (duration: 148.622684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:17:52.926794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.456107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-15T08:17:52.92681Z","caller":"traceutil/trace.go:171","msg":"trace[1760386591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2323; }","duration":"151.491198ms","start":"2026-05-15T08:17:52.775313Z","end":"2026-05-15T08:17:52.926805Z","steps":["trace[1760386591] 'agreement among raft nodes before linearized reading' (duration: 151.442977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-15T08:17:52.926535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.91024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-15T08:17:52.926861Z","caller":"traceutil/trace.go:171","msg":"trace[1866178799] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2323; }","duration":"214.280168ms","start":"2026-05-15T08:17:52.712569Z","end":"2026-05-15T08:17:52.92685Z","steps":["trace[1866178799] 'agreement among raft nodes before linearized reading' (duration: 213.876779ms)"],"step_count":1}