{"level":"warn","ts":"2026-07-23T23:42:14.851523Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T23:42:14.85162Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.177: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.204.45.177:2380","--initial-cluster=instance=https://199.204.45.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.177: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-07-23T23:42:14.851699Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-23T23:42:14.851714Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-07-23T23:42:14.851744Z","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-07-23T23:42:14.852453Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"]} {"level":"info","ts":"2026-07-23T23:42:14.852545Z","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.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.177: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-07-23T23:42:14.857958Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.192814ms"} {"level":"info","ts":"2026-07-23T23:42:14.864488Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7c53a18098324dc9","cluster-id":"4717e8aa221973c8"} {"level":"info","ts":"2026-07-23T23:42:14.864563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=()"} {"level":"info","ts":"2026-07-23T23:42:14.864591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 0"} {"level":"info","ts":"2026-07-23T23:42:14.864605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7c53a18098324dc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-23T23:42:14.864614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 1"} {"level":"info","ts":"2026-07-23T23:42:14.864657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"warn","ts":"2026-07-23T23:42:14.872347Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-23T23:42:14.874207Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-23T23:42:14.875604Z","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-07-23T23:42:14.878529Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7c53a18098324dc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-23T23:42:14.879425Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7c53a18098324dc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-23T23:42:14.879667Z","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-07-23T23:42:14.881965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"info","ts":"2026-07-23T23:42:14.882011Z","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-07-23T23:42:14.882058Z","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-07-23T23:42:14.882222Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","added-peer-id":"7c53a18098324dc9","added-peer-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-07-23T23:42:14.885607Z","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-07-23T23:42:14.885791Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-07-23T23:42:14.885846Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-07-23T23:42:14.885902Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7c53a18098324dc9","initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-23T23:42:14.885947Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-23T23:42:15.565232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 is starting a new election at term 1"} {"level":"info","ts":"2026-07-23T23:42:15.565289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-23T23:42:15.565326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgPreVoteResp from 7c53a18098324dc9 at term 1"} {"level":"info","ts":"2026-07-23T23:42:15.565347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became candidate at term 2"} {"level":"info","ts":"2026-07-23T23:42:15.565359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgVoteResp from 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-07-23T23:42:15.565386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became leader at term 2"} {"level":"info","ts":"2026-07-23T23:42:15.565409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7c53a18098324dc9 elected leader 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-07-23T23:42:15.566646Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7c53a18098324dc9","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.177:2379]}","request-path":"/0/members/7c53a18098324dc9/attributes","cluster-id":"4717e8aa221973c8","publish-timeout":"7s"} {"level":"info","ts":"2026-07-23T23:42:15.566821Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T23:42:15.566864Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-23T23:42:15.566959Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:42:15.566975Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-23T23:42:15.567063Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-23T23:42:15.568667Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-23T23:42:15.568767Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:42:15.568886Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:42:15.568921Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-23T23:42:15.574716Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.177:2379"} {"level":"info","ts":"2026-07-23T23:42:40.831576Z","caller":"traceutil/trace.go:171","msg":"trace[1856224739] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"203.353063ms","start":"2026-07-23T23:42:40.628194Z","end":"2026-07-23T23:42:40.831547Z","steps":["trace[1856224739] 'process raft request' (duration: 203.150698ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:42:56.27632Z","caller":"traceutil/trace.go:171","msg":"trace[782516878] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"100.079448ms","start":"2026-07-23T23:42:56.176215Z","end":"2026-07-23T23:42:56.276295Z","steps":["trace[782516878] 'process raft request' (duration: 99.583877ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:42:56.866189Z","caller":"traceutil/trace.go:171","msg":"trace[967722251] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"173.051061ms","start":"2026-07-23T23:42:56.69311Z","end":"2026-07-23T23:42:56.866161Z","steps":["trace[967722251] 'process raft request' (duration: 172.51621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:42:57.431845Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186657897108835,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T23:42:57.441289Z","caller":"traceutil/trace.go:171","msg":"trace[1458468741] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"510.187545ms","start":"2026-07-23T23:42:56.931075Z","end":"2026-07-23T23:42:57.441263Z","steps":["trace[1458468741] 'read index received' (duration: 503.260077ms)","trace[1458468741] 'applied index is now lower than readState.Index' (duration: 6.925768ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:42:57.441469Z","caller":"traceutil/trace.go:171","msg":"trace[471159265] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"519.619689ms","start":"2026-07-23T23:42:56.921796Z","end":"2026-07-23T23:42:57.441416Z","steps":["trace[471159265] 'process raft request' (duration: 512.600128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:42:57.442244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.050204ms","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-07-23T23:42:57.442338Z","caller":"traceutil/trace.go:171","msg":"trace[1684328430] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"498.224258ms","start":"2026-07-23T23:42:56.944094Z","end":"2026-07-23T23:42:57.442318Z","steps":["trace[1684328430] 'agreement among raft nodes before linearized reading' (duration: 497.959642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:42:57.442318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.471586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-23T23:42:57.44242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:42:56.921773Z","time spent":"519.803283ms","remote":"127.0.0.1:45212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-23T23:42:57.442424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:42:56.944071Z","time spent":"498.33886ms","remote":"127.0.0.1:44954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-23T23:42:57.442672Z","caller":"traceutil/trace.go:171","msg":"trace[934899109] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"350.695241ms","start":"2026-07-23T23:42:57.091775Z","end":"2026-07-23T23:42:57.44247Z","steps":["trace[934899109] 'agreement among raft nodes before linearized reading' (duration: 350.325883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:42:57.442764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:42:57.091731Z","time spent":"350.988717ms","remote":"127.0.0.1:44954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-23T23:42:57.442302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.900843ms","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-07-23T23:42:57.443079Z","caller":"traceutil/trace.go:171","msg":"trace[216934846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"299.66026ms","start":"2026-07-23T23:42:57.143306Z","end":"2026-07-23T23:42:57.442966Z","steps":["trace[216934846] 'agreement among raft nodes before linearized reading' (duration: 298.780791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:42:57.44359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.515236ms","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-07-23T23:42:57.443724Z","caller":"traceutil/trace.go:171","msg":"trace[1001754247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"512.665628ms","start":"2026-07-23T23:42:56.93102Z","end":"2026-07-23T23:42:57.443686Z","steps":["trace[1001754247] 'agreement among raft nodes before linearized reading' (duration: 510.550853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:42:57.444071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:42:56.930999Z","time spent":"512.805862ms","remote":"127.0.0.1:44954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-23T23:43:05.459268Z","caller":"traceutil/trace.go:171","msg":"trace[142956074] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"133.782574ms","start":"2026-07-23T23:43:05.325464Z","end":"2026-07-23T23:43:05.459247Z","steps":["trace[142956074] 'process raft request' (duration: 133.632801ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:19.864979Z","caller":"traceutil/trace.go:171","msg":"trace[1804474158] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:830; }","duration":"281.881255ms","start":"2026-07-23T23:43:19.583061Z","end":"2026-07-23T23:43:19.864942Z","steps":["trace[1804474158] 'read index received' (duration: 223.184782ms)","trace[1804474158] 'applied index is now lower than readState.Index' (duration: 58.695343ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:43:19.865014Z","caller":"traceutil/trace.go:171","msg":"trace[1665530817] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"502.379967ms","start":"2026-07-23T23:43:19.362606Z","end":"2026-07-23T23:43:19.864986Z","steps":["trace[1665530817] 'process raft request' (duration: 443.656933ms)","trace[1665530817] 'compare' (duration: 58.160903ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:43:19.865221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.1455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-23T23:43:19.86529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:19.362585Z","time spent":"502.481148ms","remote":"127.0.0.1:45144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:43:19.865285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.188675ms","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-07-23T23:43:19.865333Z","caller":"traceutil/trace.go:171","msg":"trace[2071289594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:799; }","duration":"279.281907ms","start":"2026-07-23T23:43:19.586038Z","end":"2026-07-23T23:43:19.86532Z","steps":["trace[2071289594] 'agreement among raft nodes before linearized reading' (duration: 279.103704ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:19.865325Z","caller":"traceutil/trace.go:171","msg":"trace[384945649] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:799; }","duration":"282.297393ms","start":"2026-07-23T23:43:19.583005Z","end":"2026-07-23T23:43:19.865302Z","steps":["trace[384945649] 'agreement among raft nodes before linearized reading' (duration: 282.042188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:19.865543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.071821ms","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-07-23T23:43:19.865594Z","caller":"traceutil/trace.go:171","msg":"trace[313290004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:799; }","duration":"280.171934ms","start":"2026-07-23T23:43:19.585409Z","end":"2026-07-23T23:43:19.86558Z","steps":["trace[313290004] 'agreement among raft nodes before linearized reading' (duration: 279.688565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:19.8656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.647173ms","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-07-23T23:43:19.865757Z","caller":"traceutil/trace.go:171","msg":"trace[939827191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"279.839827ms","start":"2026-07-23T23:43:19.585895Z","end":"2026-07-23T23:43:19.865735Z","steps":["trace[939827191] 'agreement among raft nodes before linearized reading' (duration: 279.333518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:24.45861Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186657897109237,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-23T23:43:24.749065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219721984s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T23:43:24.749149Z","caller":"traceutil/trace.go:171","msg":"trace[325099648] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:820; }","duration":"1.219864446s","start":"2026-07-23T23:43:23.529264Z","end":"2026-07-23T23:43:24.749129Z","steps":["trace[325099648] 'count revisions from in-memory index tree' (duration: 1.219626722s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:24.749195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:23.529243Z","time spent":"1.219941988s","remote":"127.0.0.1:45114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:43:24.749285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131107934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T23:43:24.749412Z","caller":"traceutil/trace.go:171","msg":"trace[1550609820] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:820; }","duration":"1.131295007s","start":"2026-07-23T23:43:23.618083Z","end":"2026-07-23T23:43:24.749378Z","steps":["trace[1550609820] 'count revisions from in-memory index tree' (duration: 1.131032782s)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:24.749475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:23.618062Z","time spent":"1.131401869s","remote":"127.0.0.1:45016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":52,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:43:24.815208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.921017ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-23T23:43:24.824903Z","caller":"traceutil/trace.go:171","msg":"trace[1900938725] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:852; }","duration":"866.657011ms","start":"2026-07-23T23:43:23.958219Z","end":"2026-07-23T23:43:24.824876Z","steps":["trace[1900938725] 'read index received' (duration: 575.97547ms)","trace[1900938725] 'applied index is now lower than readState.Index' (duration: 290.679981ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:43:24.825183Z","caller":"traceutil/trace.go:171","msg":"trace[929013491] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"540.737251ms","start":"2026-07-23T23:43:24.284415Z","end":"2026-07-23T23:43:24.825152Z","steps":["trace[929013491] 'process raft request' (duration: 540.232582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:24.825335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:24.28439Z","time spent":"540.853583ms","remote":"127.0.0.1:44922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:43:24.825362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.130159ms","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-07-23T23:43:24.825411Z","caller":"traceutil/trace.go:171","msg":"trace[898808694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:821; }","duration":"867.442154ms","start":"2026-07-23T23:43:23.957954Z","end":"2026-07-23T23:43:24.825396Z","steps":["trace[898808694] 'agreement among raft nodes before linearized reading' (duration: 867.255701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:24.825449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:23.957938Z","time spent":"867.501606ms","remote":"127.0.0.1:44954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-23T23:43:24.825678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.010097ms","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-07-23T23:43:24.825705Z","caller":"traceutil/trace.go:171","msg":"trace[1511056316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:821; }","duration":"867.050908ms","start":"2026-07-23T23:43:23.958646Z","end":"2026-07-23T23:43:24.825697Z","steps":["trace[1511056316] 'agreement among raft nodes before linearized reading' (duration: 866.932115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:24.825726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:23.958638Z","time spent":"867.083758ms","remote":"127.0.0.1:44954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-23T23:43:24.825962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.267941ms","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-07-23T23:43:24.826008Z","caller":"traceutil/trace.go:171","msg":"trace[1426572948] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:821; }","duration":"867.317483ms","start":"2026-07-23T23:43:23.958674Z","end":"2026-07-23T23:43:24.825992Z","steps":["trace[1426572948] 'agreement among raft nodes before linearized reading' (duration: 867.201611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:24.826093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.119128ms","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-07-23T23:43:24.826127Z","caller":"traceutil/trace.go:171","msg":"trace[1464098090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:821; }","duration":"867.17018ms","start":"2026-07-23T23:43:23.958948Z","end":"2026-07-23T23:43:24.826118Z","steps":["trace[1464098090] 'agreement among raft nodes before linearized reading' (duration: 867.083278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:24.826043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:23.95867Z","time spent":"867.366934ms","remote":"127.0.0.1:44954","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-07-23T23:43:24.826152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:43:23.958936Z","time spent":"867.209851ms","remote":"127.0.0.1:44954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-23T23:43:29.075012Z","caller":"traceutil/trace.go:171","msg":"trace[1945695716] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"169.459124ms","start":"2026-07-23T23:43:28.905529Z","end":"2026-07-23T23:43:29.074988Z","steps":["trace[1945695716] 'process raft request' (duration: 164.630381ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:29.075343Z","caller":"traceutil/trace.go:171","msg":"trace[1114910790] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"166.388501ms","start":"2026-07-23T23:43:28.908938Z","end":"2026-07-23T23:43:29.075326Z","steps":["trace[1114910790] 'process raft request' (duration: 165.965644ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:30.161064Z","caller":"traceutil/trace.go:171","msg":"trace[1134071079] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"158.212944ms","start":"2026-07-23T23:43:30.002824Z","end":"2026-07-23T23:43:30.161037Z","steps":["trace[1134071079] 'process raft request' (duration: 158.037621ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:35.249941Z","caller":"traceutil/trace.go:171","msg":"trace[1835274862] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"104.865132ms","start":"2026-07-23T23:43:35.145046Z","end":"2026-07-23T23:43:35.249911Z","steps":["trace[1835274862] 'process raft request' (duration: 90.153968ms)","trace[1835274862] 'compare' (duration: 14.572202ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:43:36.734656Z","caller":"traceutil/trace.go:171","msg":"trace[1231467461] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"199.632409ms","start":"2026-07-23T23:43:36.535001Z","end":"2026-07-23T23:43:36.734634Z","steps":["trace[1231467461] 'process raft request' (duration: 199.554097ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:36.734758Z","caller":"traceutil/trace.go:171","msg":"trace[612700526] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"203.651625ms","start":"2026-07-23T23:43:36.531066Z","end":"2026-07-23T23:43:36.734717Z","steps":["trace[612700526] 'process raft request' (duration: 174.028315ms)","trace[612700526] 'compare' (duration: 29.209953ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:43:37.070465Z","caller":"traceutil/trace.go:171","msg":"trace[495536208] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"246.817262ms","start":"2026-07-23T23:43:36.823626Z","end":"2026-07-23T23:43:37.070443Z","steps":["trace[495536208] 'process raft request' (duration: 246.67113ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:37.072397Z","caller":"traceutil/trace.go:171","msg":"trace[796048628] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:983; }","duration":"134.953323ms","start":"2026-07-23T23:43:36.937428Z","end":"2026-07-23T23:43:37.072382Z","steps":["trace[796048628] 'read index received' (duration: 132.835109ms)","trace[796048628] 'applied index is now lower than readState.Index' (duration: 2.117424ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:43:37.072564Z","caller":"traceutil/trace.go:171","msg":"trace[2085725848] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"137.792831ms","start":"2026-07-23T23:43:36.934686Z","end":"2026-07-23T23:43:37.072479Z","steps":["trace[2085725848] 'process raft request' (duration: 137.609678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:37.072743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.30277ms","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-07-23T23:43:37.072853Z","caller":"traceutil/trace.go:171","msg":"trace[835703066] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:950; }","duration":"135.460492ms","start":"2026-07-23T23:43:36.937381Z","end":"2026-07-23T23:43:37.072841Z","steps":["trace[835703066] 'agreement among raft nodes before linearized reading' (duration: 135.123196ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:37.262271Z","caller":"traceutil/trace.go:171","msg":"trace[1200388417] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"107.344464ms","start":"2026-07-23T23:43:37.154906Z","end":"2026-07-23T23:43:37.26225Z","steps":["trace[1200388417] 'process raft request' (duration: 107.196862ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:37.421082Z","caller":"traceutil/trace.go:171","msg":"trace[1200277255] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:991; }","duration":"163.825843ms","start":"2026-07-23T23:43:37.257235Z","end":"2026-07-23T23:43:37.42106Z","steps":["trace[1200277255] 'read index received' (duration: 4.879381ms)","trace[1200277255] 'applied index is now lower than readState.Index' (duration: 158.945482ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:43:37.421133Z","caller":"traceutil/trace.go:171","msg":"trace[700065833] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"187.706615ms","start":"2026-07-23T23:43:37.233406Z","end":"2026-07-23T23:43:37.421113Z","steps":["trace[700065833] 'process raft request' (duration: 179.079453ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:37.421357Z","caller":"traceutil/trace.go:171","msg":"trace[1715816721] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"184.695366ms","start":"2026-07-23T23:43:37.236651Z","end":"2026-07-23T23:43:37.421346Z","steps":["trace[1715816721] 'process raft request' (duration: 184.34555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:37.4216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.355062ms","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-07-23T23:43:37.421636Z","caller":"traceutil/trace.go:171","msg":"trace[1418965416] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:959; }","duration":"164.436942ms","start":"2026-07-23T23:43:37.257188Z","end":"2026-07-23T23:43:37.421625Z","steps":["trace[1418965416] 'agreement among raft nodes before linearized reading' (duration: 164.30279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:37.421636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.666484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:43:37.421677Z","caller":"traceutil/trace.go:171","msg":"trace[626808167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"114.740366ms","start":"2026-07-23T23:43:37.306925Z","end":"2026-07-23T23:43:37.421665Z","steps":["trace[626808167] 'agreement among raft nodes before linearized reading' (duration: 114.670055ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:37.686204Z","caller":"traceutil/trace.go:171","msg":"trace[658836592] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"147.980652ms","start":"2026-07-23T23:43:37.538177Z","end":"2026-07-23T23:43:37.686158Z","steps":["trace[658836592] 'process raft request' (duration: 141.693848ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:37.686217Z","caller":"traceutil/trace.go:171","msg":"trace[301093828] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"144.010046ms","start":"2026-07-23T23:43:37.542174Z","end":"2026-07-23T23:43:37.686184Z","steps":["trace[301093828] 'process raft request' (duration: 143.905425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:43:37.686349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.517326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12751"} {"level":"info","ts":"2026-07-23T23:43:37.686426Z","caller":"traceutil/trace.go:171","msg":"trace[2094693363] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:969; }","duration":"107.635039ms","start":"2026-07-23T23:43:37.578771Z","end":"2026-07-23T23:43:37.686406Z","steps":["trace[2094693363] 'agreement among raft nodes before linearized reading' (duration: 107.401645ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:37.70778Z","caller":"traceutil/trace.go:171","msg":"trace[247180829] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"111.439112ms","start":"2026-07-23T23:43:37.59632Z","end":"2026-07-23T23:43:37.707759Z","steps":["trace[247180829] 'process raft request' (duration: 111.32911ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:43:38.758089Z","caller":"traceutil/trace.go:171","msg":"trace[789600021] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"162.259482ms","start":"2026-07-23T23:43:38.595804Z","end":"2026-07-23T23:43:38.758063Z","steps":["trace[789600021] 'process raft request' (duration: 161.49621ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:15.463198Z","caller":"traceutil/trace.go:171","msg":"trace[131920513] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"125.613264ms","start":"2026-07-23T23:44:15.337527Z","end":"2026-07-23T23:44:15.46314Z","steps":["trace[131920513] 'process raft request' (duration: 112.394041ms)","trace[131920513] 'compare' (duration: 12.94267ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:44:15.463347Z","caller":"traceutil/trace.go:171","msg":"trace[382513925] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"125.26306ms","start":"2026-07-23T23:44:15.338056Z","end":"2026-07-23T23:44:15.463319Z","steps":["trace[382513925] 'process raft request' (duration: 124.974296ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:44:42.01796Z","caller":"traceutil/trace.go:171","msg":"trace[838827203] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"161.588255ms","start":"2026-07-23T23:44:41.856353Z","end":"2026-07-23T23:44:42.017941Z","steps":["trace[838827203] 'process raft request' (duration: 161.490644ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:01.982756Z","caller":"traceutil/trace.go:171","msg":"trace[542156836] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"128.57456ms","start":"2026-07-23T23:45:01.854157Z","end":"2026-07-23T23:45:01.982731Z","steps":["trace[542156836] 'process raft request' (duration: 128.431008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:02.253659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.536087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:45:02.253922Z","caller":"traceutil/trace.go:171","msg":"trace[2131213185] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1323; }","duration":"333.622654ms","start":"2026-07-23T23:45:01.920281Z","end":"2026-07-23T23:45:02.253904Z","steps":["trace[2131213185] 'read index received' (duration: 62.319526ms)","trace[2131213185] 'applied index is now lower than readState.Index' (duration: 271.302038ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:45:02.254027Z","caller":"traceutil/trace.go:171","msg":"trace[2020075245] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"398.645242ms","start":"2026-07-23T23:45:01.855369Z","end":"2026-07-23T23:45:02.254015Z","steps":["trace[2020075245] 'process raft request' (duration: 398.41397ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:02.253984Z","caller":"traceutil/trace.go:171","msg":"trace[1780919126] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"398.859555ms","start":"2026-07-23T23:45:01.855046Z","end":"2026-07-23T23:45:02.253905Z","steps":["trace[1780919126] 'process raft request' (duration: 295.941113ms)","trace[1780919126] 'compare' (duration: 102.256954ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:45:02.254096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:45:01.855346Z","time spent":"398.714754ms","remote":"127.0.0.1:44954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:45:02.254303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.455028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-23T23:45:02.254297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:45:01.855016Z","time spent":"399.098309ms","remote":"127.0.0.1:44954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-23T23:45:02.254404Z","caller":"traceutil/trace.go:171","msg":"trace[1754854298] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1273; }","duration":"129.57292ms","start":"2026-07-23T23:45:02.124776Z","end":"2026-07-23T23:45:02.254349Z","steps":["trace[1754854298] 'agreement among raft nodes before linearized reading' (duration: 129.431488ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:02.254525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.247582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:45:02.254572Z","caller":"traceutil/trace.go:171","msg":"trace[400192311] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1273; }","duration":"334.294432ms","start":"2026-07-23T23:45:01.920263Z","end":"2026-07-23T23:45:02.254558Z","steps":["trace[400192311] 'agreement among raft nodes before linearized reading' (duration: 334.224411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:02.254605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:45:01.92025Z","time spent":"334.346903ms","remote":"127.0.0.1:37918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-07-23T23:45:06.141273Z","caller":"traceutil/trace.go:171","msg":"trace[552488953] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1347; }","duration":"116.894493ms","start":"2026-07-23T23:45:06.024351Z","end":"2026-07-23T23:45:06.141246Z","steps":["trace[552488953] 'read index received' (duration: 116.729251ms)","trace[552488953] 'applied index is now lower than readState.Index' (duration: 164.112µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:45:06.141437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.052995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T23:45:06.141479Z","caller":"traceutil/trace.go:171","msg":"trace[944109082] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1293; }","duration":"117.123716ms","start":"2026-07-23T23:45:06.024346Z","end":"2026-07-23T23:45:06.141469Z","steps":["trace[944109082] 'agreement among raft nodes before linearized reading' (duration: 117.000174ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:06.141416Z","caller":"traceutil/trace.go:171","msg":"trace[566737613] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"139.801502ms","start":"2026-07-23T23:45:06.001547Z","end":"2026-07-23T23:45:06.141348Z","steps":["trace[566737613] 'process raft request' (duration: 139.490468ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:06.758889Z","caller":"traceutil/trace.go:171","msg":"trace[1749894830] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1352; }","duration":"280.160651ms","start":"2026-07-23T23:45:06.478642Z","end":"2026-07-23T23:45:06.758802Z","steps":["trace[1749894830] 'read index received' (duration: 278.506421ms)","trace[1749894830] 'applied index is now lower than readState.Index' (duration: 1.65239ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:45:06.759158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.507885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:45:06.759139Z","caller":"traceutil/trace.go:171","msg":"trace[1259234141] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"286.688551ms","start":"2026-07-23T23:45:06.472408Z","end":"2026-07-23T23:45:06.759097Z","steps":["trace[1259234141] 'process raft request' (duration: 284.789398ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:06.759201Z","caller":"traceutil/trace.go:171","msg":"trace[1028682216] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1298; }","duration":"280.626007ms","start":"2026-07-23T23:45:06.478563Z","end":"2026-07-23T23:45:06.759189Z","steps":["trace[1028682216] 'agreement among raft nodes before linearized reading' (duration: 280.522006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:06.759301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.563631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3adb3cdc-328d-43e7-aca3-662ed66c0316\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-07-23T23:45:06.759353Z","caller":"traceutil/trace.go:171","msg":"trace[495530750] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3adb3cdc-328d-43e7-aca3-662ed66c0316; range_end:; response_count:1; response_revision:1298; }","duration":"186.671613ms","start":"2026-07-23T23:45:06.572668Z","end":"2026-07-23T23:45:06.75934Z","steps":["trace[495530750] 'agreement among raft nodes before linearized reading' (duration: 186.485721ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:07.725076Z","caller":"traceutil/trace.go:171","msg":"trace[121177460] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"196.296126ms","start":"2026-07-23T23:45:07.528753Z","end":"2026-07-23T23:45:07.725049Z","steps":["trace[121177460] 'process raft request' (duration: 195.969172ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:08.62433Z","caller":"traceutil/trace.go:171","msg":"trace[1919951102] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"131.844769ms","start":"2026-07-23T23:45:08.492462Z","end":"2026-07-23T23:45:08.624307Z","steps":["trace[1919951102] 'process raft request' (duration: 122.00888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:12.603697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.222055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:45:12.603839Z","caller":"traceutil/trace.go:171","msg":"trace[326436286] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"226.259657ms","start":"2026-07-23T23:45:12.377548Z","end":"2026-07-23T23:45:12.603807Z","steps":["trace[326436286] 'process raft request' (duration: 112.84141ms)","trace[326436286] 'compare' (duration: 113.076003ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:45:12.60402Z","caller":"traceutil/trace.go:171","msg":"trace[1621041428] linearizableReadLoop","detail":"{readStateIndex:1388; appliedIndex:1386; }","duration":"208.605824ms","start":"2026-07-23T23:45:12.395396Z","end":"2026-07-23T23:45:12.604001Z","steps":["trace[1621041428] 'read index received' (duration: 68.787359ms)","trace[1621041428] 'applied index is now lower than readState.Index' (duration: 139.817135ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:45:12.604997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.587426ms","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-07-23T23:45:12.605054Z","caller":"traceutil/trace.go:171","msg":"trace[631892249] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1333; }","duration":"209.683517ms","start":"2026-07-23T23:45:12.395355Z","end":"2026-07-23T23:45:12.605038Z","steps":["trace[631892249] 'agreement among raft nodes before linearized reading' (duration: 208.851077ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:16.059624Z","caller":"traceutil/trace.go:171","msg":"trace[68056254] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1403; }","duration":"177.118413ms","start":"2026-07-23T23:45:15.882481Z","end":"2026-07-23T23:45:16.059599Z","steps":["trace[68056254] 'read index received' (duration: 176.86146ms)","trace[68056254] 'applied index is now lower than readState.Index' (duration: 255.733µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:45:16.059781Z","caller":"traceutil/trace.go:171","msg":"trace[495197298] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"183.361728ms","start":"2026-07-23T23:45:15.87638Z","end":"2026-07-23T23:45:16.059741Z","steps":["trace[495197298] 'process raft request' (duration: 183.029354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:16.06008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.583978ms","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-07-23T23:45:16.060147Z","caller":"traceutil/trace.go:171","msg":"trace[862931660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1347; }","duration":"177.70218ms","start":"2026-07-23T23:45:15.882432Z","end":"2026-07-23T23:45:16.060134Z","steps":["trace[862931660] 'agreement among raft nodes before linearized reading' (duration: 177.271845ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:38.73226Z","caller":"traceutil/trace.go:171","msg":"trace[987880690] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"121.256448ms","start":"2026-07-23T23:45:38.610972Z","end":"2026-07-23T23:45:38.732228Z","steps":["trace[987880690] 'process raft request' (duration: 87.828642ms)","trace[987880690] 'compare' (duration: 33.309641ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:45:46.496421Z","caller":"traceutil/trace.go:171","msg":"trace[169381572] linearizableReadLoop","detail":"{readStateIndex:1517; appliedIndex:1516; }","duration":"169.376893ms","start":"2026-07-23T23:45:46.327019Z","end":"2026-07-23T23:45:46.496395Z","steps":["trace[169381572] 'read index received' (duration: 169.187825ms)","trace[169381572] 'applied index is now lower than readState.Index' (duration: 187.958µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:45:46.496504Z","caller":"traceutil/trace.go:171","msg":"trace[466986069] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"169.865513ms","start":"2026-07-23T23:45:46.326614Z","end":"2026-07-23T23:45:46.496479Z","steps":["trace[466986069] 'process raft request' (duration: 169.602502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:46.496634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.596911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T23:45:46.49673Z","caller":"traceutil/trace.go:171","msg":"trace[412349277] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1454; }","duration":"169.738908ms","start":"2026-07-23T23:45:46.326969Z","end":"2026-07-23T23:45:46.496708Z","steps":["trace[412349277] 'agreement among raft nodes before linearized reading' (duration: 169.54183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:47.361278Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605186657897110793,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-23T23:45:47.504506Z","caller":"traceutil/trace.go:171","msg":"trace[784519796] linearizableReadLoop","detail":"{readStateIndex:1520; appliedIndex:1519; }","duration":"643.54675ms","start":"2026-07-23T23:45:46.860932Z","end":"2026-07-23T23:45:47.504478Z","steps":["trace[784519796] 'read index received' (duration: 637.23433ms)","trace[784519796] 'applied index is now lower than readState.Index' (duration: 6.31124ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:45:47.504693Z","caller":"traceutil/trace.go:171","msg":"trace[815096293] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"679.904981ms","start":"2026-07-23T23:45:46.824769Z","end":"2026-07-23T23:45:47.504674Z","steps":["trace[815096293] 'process raft request' (duration: 673.361632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:47.504836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:45:46.824739Z","time spent":"679.999325ms","remote":"127.0.0.1:44954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:45:47.504904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.787125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:45:47.505026Z","caller":"traceutil/trace.go:171","msg":"trace[1028770252] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1457; }","duration":"417.937481ms","start":"2026-07-23T23:45:47.087062Z","end":"2026-07-23T23:45:47.504999Z","steps":["trace[1028770252] 'agreement among raft nodes before linearized reading' (duration: 417.710791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:47.505087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:45:47.08704Z","time spent":"418.035495ms","remote":"127.0.0.1:44772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:45:47.505308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.362815ms","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-07-23T23:45:47.505388Z","caller":"traceutil/trace.go:171","msg":"trace[1643482881] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1457; }","duration":"644.487861ms","start":"2026-07-23T23:45:46.860881Z","end":"2026-07-23T23:45:47.505369Z","steps":["trace[1643482881] 'agreement among raft nodes before linearized reading' (duration: 644.288992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:47.505434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:45:46.860862Z","time spent":"644.563803ms","remote":"127.0.0.1:44954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-23T23:45:47.505563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.616785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:45:47.505682Z","caller":"traceutil/trace.go:171","msg":"trace[236329635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1457; }","duration":"125.820884ms","start":"2026-07-23T23:45:47.379833Z","end":"2026-07-23T23:45:47.505654Z","steps":["trace[236329635] 'agreement among raft nodes before linearized reading' (duration: 125.406036ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:50.671089Z","caller":"traceutil/trace.go:171","msg":"trace[803594926] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"102.760472ms","start":"2026-07-23T23:45:50.5683Z","end":"2026-07-23T23:45:50.67106Z","steps":["trace[803594926] 'process raft request' (duration: 60.331538ms)","trace[803594926] 'compare' (duration: 42.289738ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:45:52.446655Z","caller":"traceutil/trace.go:171","msg":"trace[2012645288] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"147.431768ms","start":"2026-07-23T23:45:52.298973Z","end":"2026-07-23T23:45:52.446405Z","steps":["trace[2012645288] 'process raft request' (duration: 147.258721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:53.775235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.551879ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-23T23:45:54.102933Z","caller":"traceutil/trace.go:171","msg":"trace[491744539] linearizableReadLoop","detail":"{readStateIndex:1549; appliedIndex:1548; }","duration":"170.185359ms","start":"2026-07-23T23:45:53.932726Z","end":"2026-07-23T23:45:54.102912Z","steps":["trace[491744539] 'read index received' (duration: 170.055163ms)","trace[491744539] 'applied index is now lower than readState.Index' (duration: 129.225µs)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:45:54.103081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.339665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5912"} {"level":"info","ts":"2026-07-23T23:45:54.103064Z","caller":"traceutil/trace.go:171","msg":"trace[1356216397] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"172.15707ms","start":"2026-07-23T23:45:53.93085Z","end":"2026-07-23T23:45:54.103007Z","steps":["trace[1356216397] 'process raft request' (duration: 171.89365ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:45:54.103124Z","caller":"traceutil/trace.go:171","msg":"trace[1299327615] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1483; }","duration":"170.425809ms","start":"2026-07-23T23:45:53.932689Z","end":"2026-07-23T23:45:54.103114Z","steps":["trace[1299327615] 'agreement among raft nodes before linearized reading' (duration: 170.346396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:54.103261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.743153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:3548"} {"level":"info","ts":"2026-07-23T23:45:54.103344Z","caller":"traceutil/trace.go:171","msg":"trace[2013685567] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:1483; }","duration":"122.90221ms","start":"2026-07-23T23:45:53.980425Z","end":"2026-07-23T23:45:54.103327Z","steps":["trace[2013685567] 'agreement among raft nodes before linearized reading' (duration: 122.699851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:45:54.103449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.94521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" limit:500 ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-07-23T23:45:54.103488Z","caller":"traceutil/trace.go:171","msg":"trace[1195093809] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:1; response_revision:1483; }","duration":"108.018414ms","start":"2026-07-23T23:45:53.995457Z","end":"2026-07-23T23:45:54.103476Z","steps":["trace[1195093809] 'agreement among raft nodes before linearized reading' (duration: 107.839946ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:46:00.818843Z","caller":"traceutil/trace.go:171","msg":"trace[704930453] linearizableReadLoop","detail":"{readStateIndex:1572; appliedIndex:1571; }","duration":"113.409726ms","start":"2026-07-23T23:46:00.705417Z","end":"2026-07-23T23:46:00.818827Z","steps":["trace[704930453] 'read index received' (duration: 113.240459ms)","trace[704930453] 'applied index is now lower than readState.Index' (duration: 168.237µs)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:46:00.818936Z","caller":"traceutil/trace.go:171","msg":"trace[888285288] transaction","detail":"{read_only:false; response_revision:1505; number_of_response:1; }","duration":"136.015119ms","start":"2026-07-23T23:46:00.682914Z","end":"2026-07-23T23:46:00.818929Z","steps":["trace[888285288] 'process raft request' (duration: 135.803881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:00.819659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.228778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kubelet-api-admin\" ","response":"range_response_count:1 size:662"} {"level":"info","ts":"2026-07-23T23:46:00.819719Z","caller":"traceutil/trace.go:171","msg":"trace[205836863] range","detail":"{range_begin:/registry/clusterroles/system:kubelet-api-admin; range_end:; response_count:1; response_revision:1505; }","duration":"114.327252ms","start":"2026-07-23T23:46:00.705379Z","end":"2026-07-23T23:46:00.819706Z","steps":["trace[205836863] 'agreement among raft nodes before linearized reading' (duration: 113.647055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:01.266555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.541046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-23T23:46:01.266733Z","caller":"traceutil/trace.go:171","msg":"trace[1742219098] transaction","detail":"{read_only:false; response_revision:1508; number_of_response:1; }","duration":"151.813331ms","start":"2026-07-23T23:46:01.114888Z","end":"2026-07-23T23:46:01.266701Z","steps":["trace[1742219098] 'process raft request' (duration: 25.040486ms)","trace[1742219098] 'compare' (duration: 126.40683ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:46:02.285643Z","caller":"traceutil/trace.go:171","msg":"trace[1335597249] linearizableReadLoop","detail":"{readStateIndex:1577; appliedIndex:1576; }","duration":"618.837521ms","start":"2026-07-23T23:46:01.666783Z","end":"2026-07-23T23:46:02.28562Z","steps":["trace[1335597249] 'read index received' (duration: 8.946066ms)","trace[1335597249] 'applied index is now lower than readState.Index' (duration: 609.890515ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:46:02.285799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.993977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:generic-garbage-collector\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-07-23T23:46:02.285844Z","caller":"traceutil/trace.go:171","msg":"trace[1078857455] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:generic-garbage-collector; range_end:; response_count:1; response_revision:1510; }","duration":"619.07271ms","start":"2026-07-23T23:46:01.66676Z","end":"2026-07-23T23:46:02.285833Z","steps":["trace[1078857455] 'agreement among raft nodes before linearized reading' (duration: 618.977336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:02.285884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:46:01.66675Z","time spent":"619.124642ms","remote":"127.0.0.1:45016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":797,"request content":"key:\"/registry/clusterrolebindings/system:controller:generic-garbage-collector\" "} {"level":"info","ts":"2026-07-23T23:46:02.285816Z","caller":"traceutil/trace.go:171","msg":"trace[783883435] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"680.630509ms","start":"2026-07-23T23:46:01.605107Z","end":"2026-07-23T23:46:02.285738Z","steps":["trace[783883435] 'process raft request' (duration: 680.372639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:02.286025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.205966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-problem-detector\" ","response":"range_response_count:1 size:655"} {"level":"warn","ts":"2026-07-23T23:46:02.286129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.827946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-23T23:46:02.286202Z","caller":"traceutil/trace.go:171","msg":"trace[422632954] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1510; }","duration":"445.9442ms","start":"2026-07-23T23:46:01.840245Z","end":"2026-07-23T23:46:02.286189Z","steps":["trace[422632954] 'agreement among raft nodes before linearized reading' (duration: 445.840506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:02.28624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:46:01.840227Z","time spent":"446.003893ms","remote":"127.0.0.1:45074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-07-23T23:46:02.286224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:46:01.605099Z","time spent":"680.863059ms","remote":"127.0.0.1:44954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-23T23:46:02.286417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.910486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-23T23:46:02.286157Z","caller":"traceutil/trace.go:171","msg":"trace[81136620] range","detail":"{range_begin:/registry/clusterroles/system:node-problem-detector; range_end:; response_count:1; response_revision:1510; }","duration":"616.347582ms","start":"2026-07-23T23:46:01.669777Z","end":"2026-07-23T23:46:02.286125Z","steps":["trace[81136620] 'agreement among raft nodes before linearized reading' (duration: 616.116923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:02.286714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-23T23:46:01.66977Z","time spent":"616.926544ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":677,"request content":"key:\"/registry/clusterroles/system:node-problem-detector\" "} {"level":"info","ts":"2026-07-23T23:46:02.286504Z","caller":"traceutil/trace.go:171","msg":"trace[269253335] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1510; }","duration":"124.098104ms","start":"2026-07-23T23:46:02.162383Z","end":"2026-07-23T23:46:02.286481Z","steps":["trace[269253335] 'agreement among raft nodes before linearized reading' (duration: 123.694658ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:46:03.071332Z","caller":"traceutil/trace.go:171","msg":"trace[1175037559] linearizableReadLoop","detail":"{readStateIndex:1580; appliedIndex:1579; }","duration":"173.57945ms","start":"2026-07-23T23:46:02.897728Z","end":"2026-07-23T23:46:03.071308Z","steps":["trace[1175037559] 'read index received' (duration: 89.602238ms)","trace[1175037559] 'applied index is now lower than readState.Index' (duration: 83.976242ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:46:03.071415Z","caller":"traceutil/trace.go:171","msg":"trace[927932703] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"174.057759ms","start":"2026-07-23T23:46:02.897318Z","end":"2026-07-23T23:46:03.071376Z","steps":["trace[927932703] 'process raft request' (duration: 90.097697ms)","trace[927932703] 'compare' (duration: 83.715682ms)"],"step_count":2} {"level":"warn","ts":"2026-07-23T23:46:03.071581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.826159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:service-account-issuer-discovery\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-07-23T23:46:03.07163Z","caller":"traceutil/trace.go:171","msg":"trace[316181903] range","detail":"{range_begin:/registry/clusterroles/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:1513; }","duration":"173.892082ms","start":"2026-07-23T23:46:02.897724Z","end":"2026-07-23T23:46:03.071616Z","steps":["trace[316181903] 'agreement among raft nodes before linearized reading' (duration: 173.712635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:03.071837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.664906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:job-controller\" ","response":"range_response_count:1 size:731"} {"level":"info","ts":"2026-07-23T23:46:03.071977Z","caller":"traceutil/trace.go:171","msg":"trace[356726850] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:job-controller; range_end:; response_count:1; response_revision:1513; }","duration":"166.837433ms","start":"2026-07-23T23:46:02.905117Z","end":"2026-07-23T23:46:03.071954Z","steps":["trace[356726850] 'agreement among raft nodes before linearized reading' (duration: 166.453608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:03.353902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.933611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-07-23T23:46:03.353992Z","caller":"traceutil/trace.go:171","msg":"trace[1558088798] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:1514; }","duration":"127.040055ms","start":"2026-07-23T23:46:03.226933Z","end":"2026-07-23T23:46:03.353973Z","steps":["trace[1558088798] 'range keys from in-memory index tree' (duration: 126.885429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:03.353905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.197081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:volume-scheduler\" ","response":"range_response_count:1 size:723"} {"level":"info","ts":"2026-07-23T23:46:03.354088Z","caller":"traceutil/trace.go:171","msg":"trace[1405713339] range","detail":"{range_begin:/registry/clusterroles/system:volume-scheduler; range_end:; response_count:1; response_revision:1514; }","duration":"129.43766ms","start":"2026-07-23T23:46:03.224635Z","end":"2026-07-23T23:46:03.354073Z","steps":["trace[1405713339] 'range keys from in-memory index tree' (duration: 129.086676ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:46:04.417179Z","caller":"traceutil/trace.go:171","msg":"trace[922596688] transaction","detail":"{read_only:false; response_revision:1516; number_of_response:1; }","duration":"122.568096ms","start":"2026-07-23T23:46:04.294578Z","end":"2026-07-23T23:46:04.417147Z","steps":["trace[922596688] 'process raft request' (duration: 122.434221ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:46:18.713184Z","caller":"traceutil/trace.go:171","msg":"trace[2052237965] linearizableReadLoop","detail":"{readStateIndex:1633; appliedIndex:1632; }","duration":"222.468645ms","start":"2026-07-23T23:46:18.490679Z","end":"2026-07-23T23:46:18.713147Z","steps":["trace[2052237965] 'read index received' (duration: 221.340194ms)","trace[2052237965] 'applied index is now lower than readState.Index' (duration: 1.125421ms)"],"step_count":2} {"level":"info","ts":"2026-07-23T23:46:18.713189Z","caller":"traceutil/trace.go:171","msg":"trace[108100587] transaction","detail":"{read_only:false; response_revision:1562; number_of_response:1; }","duration":"226.43047ms","start":"2026-07-23T23:46:18.486726Z","end":"2026-07-23T23:46:18.713157Z","steps":["trace[108100587] 'process raft request' (duration: 225.352211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:18.713409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.761119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-07-23T23:46:18.713453Z","caller":"traceutil/trace.go:171","msg":"trace[1690456915] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:1; response_revision:1562; }","duration":"173.814981ms","start":"2026-07-23T23:46:18.539628Z","end":"2026-07-23T23:46:18.713443Z","steps":["trace[1690456915] 'agreement among raft nodes before linearized reading' (duration: 173.704347ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:18.713603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.43866ms","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-07-23T23:46:18.713624Z","caller":"traceutil/trace.go:171","msg":"trace[481442314] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1562; }","duration":"172.486902ms","start":"2026-07-23T23:46:18.541133Z","end":"2026-07-23T23:46:18.71362Z","steps":["trace[481442314] 'agreement among raft nodes before linearized reading' (duration: 172.399919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-23T23:46:18.713509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.787266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:1 size:201"} {"level":"info","ts":"2026-07-23T23:46:18.714208Z","caller":"traceutil/trace.go:171","msg":"trace[1652334526] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:1; response_revision:1562; }","duration":"223.528703ms","start":"2026-07-23T23:46:18.490636Z","end":"2026-07-23T23:46:18.714165Z","steps":["trace[1652334526] 'agreement among raft nodes before linearized reading' (duration: 222.62002ms)"],"step_count":1} {"level":"info","ts":"2026-07-23T23:46:18.844355Z","caller":"traceutil/trace.go:171","msg":"trace[434770868] transaction","detail":"{read_only:false; response_revision:1564; number_of_response:1; }","duration":"126.022719ms","start":"2026-07-23T23:46:18.718303Z","end":"2026-07-23T23:46:18.844326Z","steps":["trace[434770868] 'process raft request' (duration: 99.615931ms)","trace[434770868] 'compare' (duration: 26.049465ms)"],"step_count":2}