{"level":"warn","ts":"2026-07-21T06:32:52.1408Z","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-21T06:32:52.140903Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242: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.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242: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-21T06:32:52.140986Z","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-21T06:32:52.140995Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-07-21T06:32:52.141015Z","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-21T06:32:52.141854Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-07-21T06:32:52.141936Z","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.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242: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-21T06:32:52.146154Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.977024ms"} {"level":"info","ts":"2026-07-21T06:32:52.153074Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-07-21T06:32:52.153148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T06:32:52.153174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-07-21T06:32:52.153192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T06:32:52.153207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-07-21T06:32:52.153244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-07-21T06:32:52.158306Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T06:32:52.159377Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T06:32:52.160442Z","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-21T06:32:52.162596Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T06:32:52.162845Z","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-21T06:32:52.162983Z","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-21T06:32:52.16301Z","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-21T06:32:52.162784Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T06:32:52.163925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-07-21T06:32:52.164165Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-07-21T06:32:52.166959Z","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-21T06:32:52.167028Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-07-21T06:32:52.167086Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-07-21T06:32:52.167211Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T06:32:52.167244Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T06:32:52.85373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T06:32:52.853785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T06:32:52.853804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-07-21T06:32:52.853817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-07-21T06:32:52.853822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-07-21T06:32:52.85383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-07-21T06:32:52.853837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-07-21T06:32:52.854771Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T06:32:52.854804Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:32:52.854929Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T06:32:52.855275Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T06:32:52.855417Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T06:32:52.855431Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T06:32:52.857054Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:32:52.857137Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:32:52.857163Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:32:52.857377Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T06:32:52.861691Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-07-21T06:32:57.414929Z","caller":"traceutil/trace.go:171","msg":"trace[3119547] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"162.164504ms","start":"2026-07-21T06:32:57.252737Z","end":"2026-07-21T06:32:57.414901Z","steps":["trace[3119547] 'process raft request' (duration: 74.195124ms)","trace[3119547] 'compare' (duration: 87.809055ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:32:57.599748Z","caller":"traceutil/trace.go:171","msg":"trace[740932947] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"137.331642ms","start":"2026-07-21T06:32:57.462376Z","end":"2026-07-21T06:32:57.599708Z","steps":["trace[740932947] 'process raft request' (duration: 56.679958ms)","trace[740932947] 'compare' (duration: 80.483469ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:32:57.756011Z","caller":"traceutil/trace.go:171","msg":"trace[1791729877] transaction","detail":"{read_only:false; response_revision:218; number_of_response:1; }","duration":"112.709638ms","start":"2026-07-21T06:32:57.643271Z","end":"2026-07-21T06:32:57.755981Z","steps":["trace[1791729877] 'process raft request' (duration: 112.589844ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:57.920163Z","caller":"traceutil/trace.go:171","msg":"trace[688709047] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"111.216554ms","start":"2026-07-21T06:32:57.808887Z","end":"2026-07-21T06:32:57.920103Z","steps":["trace[688709047] 'process raft request' (duration: 58.809181ms)","trace[688709047] 'compare' (duration: 52.223878ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:32:57.920232Z","caller":"traceutil/trace.go:171","msg":"trace[752530978] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"103.666202ms","start":"2026-07-21T06:32:57.816551Z","end":"2026-07-21T06:32:57.920217Z","steps":["trace[752530978] 'process raft request' (duration: 103.60591ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:57.920316Z","caller":"traceutil/trace.go:171","msg":"trace[1416134555] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"111.328348ms","start":"2026-07-21T06:32:57.808955Z","end":"2026-07-21T06:32:57.920283Z","steps":["trace[1416134555] 'process raft request' (duration: 111.07135ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:32:58.159573Z","caller":"traceutil/trace.go:171","msg":"trace[671546956] linearizableReadLoop","detail":"{readStateIndex:234; appliedIndex:233; }","duration":"158.036692ms","start":"2026-07-21T06:32:58.00151Z","end":"2026-07-21T06:32:58.159547Z","steps":["trace[671546956] 'read index received' (duration: 79.049707ms)","trace[671546956] 'applied index is now lower than readState.Index' (duration: 78.986055ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:32:58.159605Z","caller":"traceutil/trace.go:171","msg":"trace[1602334181] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"158.338821ms","start":"2026-07-21T06:32:58.001245Z","end":"2026-07-21T06:32:58.159583Z","steps":["trace[1602334181] 'process raft request' (duration: 79.282754ms)","trace[1602334181] 'compare' (duration: 78.899732ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:32:58.159703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.171136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18c43a681842ca10\" ","response":"range_response_count:1 size:657"} {"level":"info","ts":"2026-07-21T06:32:58.159766Z","caller":"traceutil/trace.go:171","msg":"trace[1731100156] range","detail":"{range_begin:/registry/events/default/instance.18c43a681842ca10; range_end:; response_count:1; response_revision:228; }","duration":"158.287209ms","start":"2026-07-21T06:32:58.00147Z","end":"2026-07-21T06:32:58.159758Z","steps":["trace[1731100156] 'agreement among raft nodes before linearized reading' (duration: 158.173116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:32:58.159872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.142897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:32:58.159974Z","caller":"traceutil/trace.go:171","msg":"trace[165715055] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:0; response_revision:228; }","duration":"108.26792ms","start":"2026-07-21T06:32:58.051676Z","end":"2026-07-21T06:32:58.159944Z","steps":["trace[165715055] 'agreement among raft nodes before linearized reading' (duration: 108.089385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:02.004627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.783543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-21T06:33:02.004737Z","caller":"traceutil/trace.go:171","msg":"trace[465872231] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:309; }","duration":"140.949138ms","start":"2026-07-21T06:33:01.863764Z","end":"2026-07-21T06:33:02.004713Z","steps":["trace[465872231] 'range keys from in-memory index tree' (duration: 140.661729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:02.505648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.421441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:33:02.505771Z","caller":"traceutil/trace.go:171","msg":"trace[609877900] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:325; }","duration":"242.512205ms","start":"2026-07-21T06:33:02.263239Z","end":"2026-07-21T06:33:02.505751Z","steps":["trace[609877900] 'read index received' (duration: 18.092483ms)","trace[609877900] 'applied index is now lower than readState.Index' (duration: 224.418272ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:33:02.505876Z","caller":"traceutil/trace.go:171","msg":"trace[2145863633] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"289.331481ms","start":"2026-07-21T06:33:02.216529Z","end":"2026-07-21T06:33:02.50586Z","steps":["trace[2145863633] 'process raft request' (duration: 64.855248ms)","trace[2145863633] 'compare' (duration: 223.307648ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:33:02.506007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.717559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-07-21T06:33:02.506376Z","caller":"traceutil/trace.go:171","msg":"trace[1280830554] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:314; }","duration":"193.122851ms","start":"2026-07-21T06:33:02.31323Z","end":"2026-07-21T06:33:02.506352Z","steps":["trace[1280830554] 'agreement among raft nodes before linearized reading' (duration: 192.663937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:02.50602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.767103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-21T06:33:02.506519Z","caller":"traceutil/trace.go:171","msg":"trace[420637393] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:314; }","duration":"243.288908ms","start":"2026-07-21T06:33:02.263207Z","end":"2026-07-21T06:33:02.506496Z","steps":["trace[420637393] 'agreement among raft nodes before linearized reading' (duration: 242.739612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:02.506081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.418758ms","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-21T06:33:02.506667Z","caller":"traceutil/trace.go:171","msg":"trace[406115421] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:314; }","duration":"121.047326ms","start":"2026-07-21T06:33:02.385603Z","end":"2026-07-21T06:33:02.506651Z","steps":["trace[406115421] 'agreement among raft nodes before linearized reading' (duration: 120.443748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:02.5061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.920253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-07-21T06:33:02.506774Z","caller":"traceutil/trace.go:171","msg":"trace[489287426] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:314; }","duration":"142.618304ms","start":"2026-07-21T06:33:02.364132Z","end":"2026-07-21T06:33:02.506751Z","steps":["trace[489287426] 'agreement among raft nodes before linearized reading' (duration: 141.938954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:02.506109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.789011ms","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-21T06:33:02.506862Z","caller":"traceutil/trace.go:171","msg":"trace[764845576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:314; }","duration":"192.552055ms","start":"2026-07-21T06:33:02.314286Z","end":"2026-07-21T06:33:02.506838Z","steps":["trace[764845576] 'agreement among raft nodes before linearized reading' (duration: 191.788922ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:02.73671Z","caller":"traceutil/trace.go:171","msg":"trace[82469007] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:326; }","duration":"223.134484ms","start":"2026-07-21T06:33:02.513552Z","end":"2026-07-21T06:33:02.736686Z","steps":["trace[82469007] 'read index received' (duration: 178.591596ms)","trace[82469007] 'applied index is now lower than readState.Index' (duration: 44.541948ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:33:02.736792Z","caller":"traceutil/trace.go:171","msg":"trace[1645621183] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"225.160594ms","start":"2026-07-21T06:33:02.511595Z","end":"2026-07-21T06:33:02.736756Z","steps":["trace[1645621183] 'process raft request' (duration: 180.561784ms)","trace[1645621183] 'compare' (duration: 44.355043ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:33:02.736907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.3325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-07-21T06:33:02.736944Z","caller":"traceutil/trace.go:171","msg":"trace[1508986941] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:316; }","duration":"223.407511ms","start":"2026-07-21T06:33:02.513525Z","end":"2026-07-21T06:33:02.736933Z","steps":["trace[1508986941] 'agreement among raft nodes before linearized reading' (duration: 223.329439ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:02.736836Z","caller":"traceutil/trace.go:171","msg":"trace[914999323] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"225.217376ms","start":"2026-07-21T06:33:02.511609Z","end":"2026-07-21T06:33:02.736827Z","steps":["trace[914999323] 'process raft request' (duration: 225.035701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:02.737225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.945406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-07-21T06:33:02.737369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.419657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-21T06:33:02.737452Z","caller":"traceutil/trace.go:171","msg":"trace[1462622471] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:316; }","duration":"123.557551ms","start":"2026-07-21T06:33:02.613877Z","end":"2026-07-21T06:33:02.737435Z","steps":["trace[1462622471] 'agreement among raft nodes before linearized reading' (duration: 123.359245ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:02.737596Z","caller":"traceutil/trace.go:171","msg":"trace[1274887408] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:316; }","duration":"173.387239ms","start":"2026-07-21T06:33:02.564188Z","end":"2026-07-21T06:33:02.737575Z","steps":["trace[1274887408] 'agreement among raft nodes before linearized reading' (duration: 172.847883ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:09.776775Z","caller":"traceutil/trace.go:171","msg":"trace[1779690667] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"317.516942ms","start":"2026-07-21T06:33:09.459239Z","end":"2026-07-21T06:33:09.776756Z","steps":["trace[1779690667] 'process raft request' (duration: 317.415049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:09.7769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:09.45921Z","time spent":"317.626075ms","remote":"127.0.0.1:41734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:33:18.622103Z","caller":"traceutil/trace.go:171","msg":"trace[87502814] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:423; }","duration":"304.168725ms","start":"2026-07-21T06:33:18.31791Z","end":"2026-07-21T06:33:18.622079Z","steps":["trace[87502814] 'read index received' (duration: 303.974459ms)","trace[87502814] 'applied index is now lower than readState.Index' (duration: 193.416µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:33:18.622565Z","caller":"traceutil/trace.go:171","msg":"trace[103996557] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"322.298319ms","start":"2026-07-21T06:33:18.300224Z","end":"2026-07-21T06:33:18.622522Z","steps":["trace[103996557] 'process raft request' (duration: 321.726011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:18.622623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.74751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:33:18.622718Z","caller":"traceutil/trace.go:171","msg":"trace[904491535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"284.842213ms","start":"2026-07-21T06:33:18.337821Z","end":"2026-07-21T06:33:18.622663Z","steps":["trace[904491535] 'agreement among raft nodes before linearized reading' (duration: 284.74598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:18.622793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:18.300209Z","time spent":"322.468785ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:33:18.622577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.609689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:33:18.623004Z","caller":"traceutil/trace.go:171","msg":"trace[1106041926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"305.125585ms","start":"2026-07-21T06:33:18.317849Z","end":"2026-07-21T06:33:18.622975Z","steps":["trace[1106041926] 'agreement among raft nodes before linearized reading' (duration: 304.561477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:18.623106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:18.317821Z","time spent":"305.245379ms","remote":"127.0.0.1:41546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-21T06:33:18.775453Z","caller":"traceutil/trace.go:171","msg":"trace[1791674436] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"136.968859ms","start":"2026-07-21T06:33:18.63846Z","end":"2026-07-21T06:33:18.775429Z","steps":["trace[1791674436] 'process raft request' (duration: 132.379174ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:25.978207Z","caller":"traceutil/trace.go:171","msg":"trace[1508597047] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"150.945072ms","start":"2026-07-21T06:33:25.82723Z","end":"2026-07-21T06:33:25.978175Z","steps":["trace[1508597047] 'process raft request' (duration: 81.507201ms)","trace[1508597047] 'compare' (duration: 69.295976ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:33:26.857612Z","caller":"traceutil/trace.go:171","msg":"trace[1694085629] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"171.635253ms","start":"2026-07-21T06:33:26.68595Z","end":"2026-07-21T06:33:26.857585Z","steps":["trace[1694085629] 'process raft request' (duration: 114.339429ms)","trace[1694085629] 'compare' (duration: 57.19211ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:33:27.148328Z","caller":"traceutil/trace.go:171","msg":"trace[537599854] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"250.411823ms","start":"2026-07-21T06:33:26.89789Z","end":"2026-07-21T06:33:27.148302Z","steps":["trace[537599854] 'process raft request' (duration: 161.129067ms)","trace[537599854] 'compare' (duration: 89.164922ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:33:32.771889Z","caller":"traceutil/trace.go:171","msg":"trace[2122231810] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"512.851796ms","start":"2026-07-21T06:33:32.259019Z","end":"2026-07-21T06:33:32.771871Z","steps":["trace[2122231810] 'process raft request' (duration: 512.747033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:32.771996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:32.259003Z","time spent":"512.947049ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:33:32.786596Z","caller":"traceutil/trace.go:171","msg":"trace[1207990814] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"517.839629ms","start":"2026-07-21T06:33:32.268728Z","end":"2026-07-21T06:33:32.786568Z","steps":["trace[1207990814] 'process raft request' (duration: 517.488898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:32.786728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:32.268723Z","time spent":"517.941723ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:33:32.786898Z","caller":"traceutil/trace.go:171","msg":"trace[926436138] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"526.575336ms","start":"2026-07-21T06:33:32.260312Z","end":"2026-07-21T06:33:32.786887Z","steps":["trace[926436138] 'process raft request' (duration: 525.663556ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:32.786966Z","caller":"traceutil/trace.go:171","msg":"trace[525465275] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"494.124961ms","start":"2026-07-21T06:33:32.292829Z","end":"2026-07-21T06:33:32.786954Z","steps":["trace[525465275] 'process raft request' (duration: 493.627325ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:32.786955Z","caller":"traceutil/trace.go:171","msg":"trace[633260060] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"516.975191ms","start":"2026-07-21T06:33:32.269956Z","end":"2026-07-21T06:33:32.786931Z","steps":["trace[633260060] 'process raft request' (duration: 516.33434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:32.787025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:32.292806Z","time spent":"494.190434ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:33:32.787052Z","caller":"traceutil/trace.go:171","msg":"trace[572591178] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"518.375368ms","start":"2026-07-21T06:33:32.268655Z","end":"2026-07-21T06:33:32.78703Z","steps":["trace[572591178] 'process raft request' (duration: 517.52139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:32.787064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:32.269947Z","time spent":"517.062254ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:33:32.787086Z","caller":"traceutil/trace.go:171","msg":"trace[677312434] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"494.103271ms","start":"2026-07-21T06:33:32.292904Z","end":"2026-07-21T06:33:32.787007Z","steps":["trace[677312434] 'process raft request' (duration: 493.644006ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:32.787108Z","caller":"traceutil/trace.go:171","msg":"trace[1210171664] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"526.70242ms","start":"2026-07-21T06:33:32.260397Z","end":"2026-07-21T06:33:32.7871Z","steps":["trace[1210171664] 'process raft request' (duration: 525.722348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:32.787121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:32.268636Z","time spent":"518.45236ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:33:32.787151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:32.260392Z","time spent":"526.738031ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:33:32.787155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:32.292899Z","time spent":"494.225085ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:33:32.787182Z","caller":"traceutil/trace.go:171","msg":"trace[559033248] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"510.706665ms","start":"2026-07-21T06:33:32.27646Z","end":"2026-07-21T06:33:32.787167Z","steps":["trace[559033248] 'process raft request' (duration: 509.854417ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:32.787202Z","caller":"traceutil/trace.go:171","msg":"trace[1380466349] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:522; }","duration":"447.13494ms","start":"2026-07-21T06:33:32.340054Z","end":"2026-07-21T06:33:32.787189Z","steps":["trace[1380466349] 'read index received' (duration: 431.808427ms)","trace[1380466349] 'applied index is now lower than readState.Index' (duration: 15.325363ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:33:32.787222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:32.276447Z","time spent":"510.758457ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:33:32.787226Z","caller":"traceutil/trace.go:171","msg":"trace[993430672] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"457.695127ms","start":"2026-07-21T06:33:32.329522Z","end":"2026-07-21T06:33:32.787217Z","steps":["trace[993430672] 'process raft request' (duration: 457.106837ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:32.78724Z","caller":"traceutil/trace.go:171","msg":"trace[866337274] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"366.561036ms","start":"2026-07-21T06:33:32.420669Z","end":"2026-07-21T06:33:32.78723Z","steps":["trace[866337274] 'process raft request' (duration: 366.294097ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:32.787272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:32.329498Z","time spent":"457.752418ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:33:32.787282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:32.42065Z","time spent":"366.610028ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:33:32.787309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.245403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:33:32.787331Z","caller":"traceutil/trace.go:171","msg":"trace[737502257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"447.296805ms","start":"2026-07-21T06:33:32.340028Z","end":"2026-07-21T06:33:32.787325Z","steps":["trace[737502257] 'agreement among raft nodes before linearized reading' (duration: 447.216263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:32.78735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:32.340016Z","time spent":"447.330226ms","remote":"127.0.0.1:41546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:33:32.787376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:32.260303Z","time spent":"527.049331ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:33:32.786924Z","caller":"traceutil/trace.go:171","msg":"trace[195131464] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"506.501377ms","start":"2026-07-21T06:33:32.280402Z","end":"2026-07-21T06:33:32.786904Z","steps":["trace[195131464] 'process raft request' (duration: 505.948879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:32.78818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:32.28039Z","time spent":"507.758558ms","remote":"127.0.0.1:41596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:33:39.43649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.949603ms","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-07-21T06:33:39.436565Z","caller":"traceutil/trace.go:171","msg":"trace[1464002066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"227.068007ms","start":"2026-07-21T06:33:39.209483Z","end":"2026-07-21T06:33:39.436551Z","steps":["trace[1464002066] 'range keys from in-memory index tree' (duration: 226.815169ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:39.516982Z","caller":"traceutil/trace.go:171","msg":"trace[1965528021] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"204.993082ms","start":"2026-07-21T06:33:39.311969Z","end":"2026-07-21T06:33:39.516962Z","steps":["trace[1965528021] 'process raft request' (duration: 204.260838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:39.517063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.808188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:33:39.5171Z","caller":"traceutil/trace.go:171","msg":"trace[917043625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"177.907852ms","start":"2026-07-21T06:33:39.339182Z","end":"2026-07-21T06:33:39.51709Z","steps":["trace[917043625] 'agreement among raft nodes before linearized reading' (duration: 177.830669ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:39.516957Z","caller":"traceutil/trace.go:171","msg":"trace[428337351] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"177.684934ms","start":"2026-07-21T06:33:39.339242Z","end":"2026-07-21T06:33:39.516927Z","steps":["trace[428337351] 'read index received' (duration: 176.692901ms)","trace[428337351] 'applied index is now lower than readState.Index' (duration: 991.123µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:33:40.184763Z","caller":"traceutil/trace.go:171","msg":"trace[341073276] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"641.640548ms","start":"2026-07-21T06:33:39.543103Z","end":"2026-07-21T06:33:40.184744Z","steps":["trace[341073276] 'process raft request' (duration: 641.523904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:40.184876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:39.543077Z","time spent":"641.739142ms","remote":"127.0.0.1:41734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:33:41.436067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.325549ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:33:41.43621Z","caller":"traceutil/trace.go:171","msg":"trace[880243697] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:615; }","duration":"994.484214ms","start":"2026-07-21T06:33:40.441708Z","end":"2026-07-21T06:33:41.436192Z","steps":["trace[880243697] 'range keys from in-memory index tree' (duration: 994.306638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:41.436527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104140181s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:33:41.436787Z","caller":"traceutil/trace.go:171","msg":"trace[1931413664] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"1.856087309s","start":"2026-07-21T06:33:39.580656Z","end":"2026-07-21T06:33:41.436743Z","steps":["trace[1931413664] 'process raft request' (duration: 751.67794ms)","trace[1931413664] 'compare' (duration: 1.103998796s)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:33:41.436926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:39.580623Z","time spent":"1.856236384s","remote":"127.0.0.1:42046","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:33:41.437123Z","caller":"traceutil/trace.go:171","msg":"trace[1555640710] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"1.097254731s","start":"2026-07-21T06:33:40.339843Z","end":"2026-07-21T06:33:41.437098Z","steps":["trace[1555640710] 'read index received' (duration: 29.461µs)","trace[1555640710] 'applied index is now lower than readState.Index' (duration: 1.09722268s)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:33:42.234268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.665016ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-21T06:33:42.234362Z","caller":"traceutil/trace.go:171","msg":"trace[396809734] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"797.16207ms","start":"2026-07-21T06:33:41.437185Z","end":"2026-07-21T06:33:42.234347Z","steps":["trace[396809734] 'read index received' (duration: 43.092µs)","trace[396809734] 'applied index is now lower than readState.Index' (duration: 797.117898ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:33:42.234447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.894595057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:33:42.234484Z","caller":"traceutil/trace.go:171","msg":"trace[1619465412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"1.894666189s","start":"2026-07-21T06:33:40.339806Z","end":"2026-07-21T06:33:42.234472Z","steps":["trace[1619465412] 'agreement among raft nodes before linearized reading' (duration: 1.097377765s)","trace[1619465412] 'range keys from in-memory index tree' (duration: 797.232332ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:33:42.234518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:40.33979Z","time spent":"1.89471853s","remote":"127.0.0.1:41546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:33:42.23481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.27698289s","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-21T06:33:42.23485Z","caller":"traceutil/trace.go:171","msg":"trace[338529393] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"1.277042812s","start":"2026-07-21T06:33:40.957797Z","end":"2026-07-21T06:33:42.23484Z","steps":["trace[338529393] 'agreement among raft nodes before linearized reading' (duration: 1.276895637s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:42.234877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:40.957788Z","time spent":"1.277082833s","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T06:33:42.234891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278747479s","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-21T06:33:42.234921Z","caller":"traceutil/trace.go:171","msg":"trace[441429689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"1.278838192s","start":"2026-07-21T06:33:40.956073Z","end":"2026-07-21T06:33:42.234911Z","steps":["trace[441429689] 'agreement among raft nodes before linearized reading' (duration: 1.278761249s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:42.234946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:40.956052Z","time spent":"1.278886763s","remote":"127.0.0.1:41836","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":"warn","ts":"2026-07-21T06:33:42.234813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.047485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:33:42.235162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395310547s","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-21T06:33:42.235201Z","caller":"traceutil/trace.go:171","msg":"trace[818341119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"1.39540825s","start":"2026-07-21T06:33:40.839785Z","end":"2026-07-21T06:33:42.235193Z","steps":["trace[818341119] 'agreement among raft nodes before linearized reading' (duration: 1.395290416s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:42.235233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:40.839763Z","time spent":"1.395462551s","remote":"127.0.0.1:41836","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-07-21T06:33:42.235059Z","caller":"traceutil/trace.go:171","msg":"trace[1043974046] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:616; }","duration":"785.323564ms","start":"2026-07-21T06:33:41.449721Z","end":"2026-07-21T06:33:42.235045Z","steps":["trace[1043974046] 'agreement among raft nodes before linearized reading' (duration: 785.062015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:42.235381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:41.449703Z","time spent":"785.644045ms","remote":"127.0.0.1:41792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-21T06:33:42.235381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.887713ms","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-07-21T06:33:42.235432Z","caller":"traceutil/trace.go:171","msg":"trace[569694579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"703.996217ms","start":"2026-07-21T06:33:41.531423Z","end":"2026-07-21T06:33:42.235419Z","steps":["trace[569694579] 'agreement among raft nodes before linearized reading' (duration: 703.682206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:42.235485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:41.531406Z","time spent":"704.070959ms","remote":"127.0.0.1:41836","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-21T06:33:44.018228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.59339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:33:44.018336Z","caller":"traceutil/trace.go:171","msg":"trace[1093315446] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:653; }","duration":"246.712654ms","start":"2026-07-21T06:33:43.771606Z","end":"2026-07-21T06:33:44.018319Z","steps":["trace[1093315446] 'range keys from in-memory index tree' (duration: 246.489935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:46.881741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.724112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-07-21T06:33:46.881827Z","caller":"traceutil/trace.go:171","msg":"trace[1777498168] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:697; }","duration":"110.873807ms","start":"2026-07-21T06:33:46.770932Z","end":"2026-07-21T06:33:46.881806Z","steps":["trace[1777498168] 'range keys from in-memory index tree' (duration: 110.534926ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:49.964609Z","caller":"traceutil/trace.go:171","msg":"trace[921962746] transaction","detail":"{read_only:false; number_of_response:0; response_revision:727; }","duration":"104.833502ms","start":"2026-07-21T06:33:49.859747Z","end":"2026-07-21T06:33:49.96458Z","steps":["trace[921962746] 'process raft request' (duration: 104.721518ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:33:54.559742Z","caller":"traceutil/trace.go:171","msg":"trace[943958735] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"104.988026ms","start":"2026-07-21T06:33:54.454727Z","end":"2026-07-21T06:33:54.559715Z","steps":["trace[943958735] 'process raft request' (duration: 70.101064ms)","trace[943958735] 'compare' (duration: 34.512899ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:33:55.705343Z","caller":"traceutil/trace.go:171","msg":"trace[1296722932] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"187.960478ms","start":"2026-07-21T06:33:55.517361Z","end":"2026-07-21T06:33:55.705322Z","steps":["trace[1296722932] 'process raft request' (duration: 187.814033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:33:59.838982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115839283019645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:34:00.289529Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.214537795s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T06:34:00.289895Z","caller":"traceutil/trace.go:171","msg":"trace[361184762] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"1.214971651s","start":"2026-07-21T06:33:59.074904Z","end":"2026-07-21T06:34:00.289876Z","steps":["trace[361184762] 'process raft request' (duration: 1.214793914s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:34:00.290013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:59.074886Z","time spent":"1.215063823s","remote":"127.0.0.1:41734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4857,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:34:00.297608Z","caller":"traceutil/trace.go:171","msg":"trace[1653238053] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"725.172284ms","start":"2026-07-21T06:33:59.572415Z","end":"2026-07-21T06:34:00.297587Z","steps":["trace[1653238053] 'process raft request' (duration: 725.009609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:34:00.297752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:59.572393Z","time spent":"725.281138ms","remote":"127.0.0.1:41728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5052,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:34:00.297916Z","caller":"traceutil/trace.go:171","msg":"trace[1004344894] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"637.645825ms","start":"2026-07-21T06:33:59.660245Z","end":"2026-07-21T06:34:00.297891Z","steps":["trace[1004344894] 'process raft request' (duration: 637.293853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:34:00.298035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:59.66022Z","time spent":"637.755879ms","remote":"127.0.0.1:41792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:34:00.298763Z","caller":"traceutil/trace.go:171","msg":"trace[968016644] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"631.488983ms","start":"2026-07-21T06:33:59.667257Z","end":"2026-07-21T06:34:00.298746Z","steps":["trace[968016644] 'process raft request' (duration: 630.512209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:34:00.298868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:59.667242Z","time spent":"631.574616ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:34:00.299532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.004056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:34:00.299565Z","caller":"traceutil/trace.go:171","msg":"trace[1699135327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:786; }","duration":"961.071128ms","start":"2026-07-21T06:33:59.338486Z","end":"2026-07-21T06:34:00.299557Z","steps":["trace[1699135327] 'agreement among raft nodes before linearized reading' (duration: 961.009266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:34:00.299585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:59.338469Z","time spent":"961.1107ms","remote":"127.0.0.1:41546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-21T06:34:00.298514Z","caller":"traceutil/trace.go:171","msg":"trace[785763374] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:812; }","duration":"959.9655ms","start":"2026-07-21T06:33:59.338528Z","end":"2026-07-21T06:34:00.298493Z","steps":["trace[785763374] 'read index received' (duration: 951.25263ms)","trace[785763374] 'applied index is now lower than readState.Index' (duration: 8.71143ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:34:00.307896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.29227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:34:00.307938Z","caller":"traceutil/trace.go:171","msg":"trace[912386886] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:789; }","duration":"725.383282ms","start":"2026-07-21T06:33:59.582543Z","end":"2026-07-21T06:34:00.307927Z","steps":["trace[912386886] 'agreement among raft nodes before linearized reading' (duration: 725.2989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:34:00.307968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:59.582528Z","time spent":"725.431125ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:34:00.308043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.431346ms","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-21T06:34:00.308161Z","caller":"traceutil/trace.go:171","msg":"trace[990176250] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:789; }","duration":"516.574531ms","start":"2026-07-21T06:33:59.791573Z","end":"2026-07-21T06:34:00.308147Z","steps":["trace[990176250] 'agreement among raft nodes before linearized reading' (duration: 516.326762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:34:00.308205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:33:59.791469Z","time spent":"516.728516ms","remote":"127.0.0.1:41836","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-07-21T06:34:31.884076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115839283020176,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T06:34:32.114379Z","caller":"traceutil/trace.go:171","msg":"trace[1200628209] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"731.129426ms","start":"2026-07-21T06:34:31.383227Z","end":"2026-07-21T06:34:32.114357Z","steps":["trace[1200628209] 'read index received' (duration: 721.089258ms)","trace[1200628209] 'applied index is now lower than readState.Index' (duration: 10.038688ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:34:32.11449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.24886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:34:32.114516Z","caller":"traceutil/trace.go:171","msg":"trace[31315559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"731.303903ms","start":"2026-07-21T06:34:31.383202Z","end":"2026-07-21T06:34:32.114506Z","steps":["trace[31315559] 'agreement among raft nodes before linearized reading' (duration: 731.24281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:34:32.11454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:34:31.383193Z","time spent":"731.342004ms","remote":"127.0.0.1:41546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-21T06:34:33.242742Z","caller":"traceutil/trace.go:171","msg":"trace[1130491809] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"612.60703ms","start":"2026-07-21T06:34:32.630113Z","end":"2026-07-21T06:34:33.24272Z","steps":["trace[1130491809] 'process raft request' (duration: 612.474195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:34:33.242878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:34:32.630098Z","time spent":"612.710264ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:34:34.718186Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115839283020195,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T06:34:35.0123Z","caller":"traceutil/trace.go:171","msg":"trace[2060417] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"794.501625ms","start":"2026-07-21T06:34:34.217776Z","end":"2026-07-21T06:34:35.012277Z","steps":["trace[2060417] 'process raft request' (duration: 794.377661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:34:35.012427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:34:34.217762Z","time spent":"794.598268ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:34:36.385889Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.373906152s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T06:34:36.386174Z","caller":"traceutil/trace.go:171","msg":"trace[1335915928] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1062; }","duration":"2.168134018s","start":"2026-07-21T06:34:34.218023Z","end":"2026-07-21T06:34:36.386157Z","steps":["trace[1335915928] 'read index received' (duration: 794.762374ms)","trace[1335915928] 'applied index is now lower than readState.Index' (duration: 1.373371124s)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:34:36.386325Z","caller":"traceutil/trace.go:171","msg":"trace[412183685] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"2.168244901s","start":"2026-07-21T06:34:34.218074Z","end":"2026-07-21T06:34:36.386318Z","steps":["trace[412183685] 'process raft request' (duration: 2.167939001s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:34:36.386391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:34:34.218065Z","time spent":"2.168277412s","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:34:36.386494Z","caller":"traceutil/trace.go:171","msg":"trace[513798800] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"2.164339403s","start":"2026-07-21T06:34:34.22215Z","end":"2026-07-21T06:34:36.386489Z","steps":["trace[513798800] 'process raft request' (duration: 2.1639803s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:34:36.386523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:34:34.222141Z","time spent":"2.164366953s","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:34:36.386663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.168636866s","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-21T06:34:36.386679Z","caller":"traceutil/trace.go:171","msg":"trace[416872052] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1027; }","duration":"2.168659656s","start":"2026-07-21T06:34:34.218015Z","end":"2026-07-21T06:34:36.386674Z","steps":["trace[416872052] 'agreement among raft nodes before linearized reading' (duration: 2.168590704s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:34:36.386692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:34:34.21801Z","time spent":"2.168679947s","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T06:34:36.386746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.164710446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-07-21T06:34:36.386758Z","caller":"traceutil/trace.go:171","msg":"trace[688620115] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1027; }","duration":"2.164750607s","start":"2026-07-21T06:34:34.222003Z","end":"2026-07-21T06:34:36.386754Z","steps":["trace[688620115] 'agreement among raft nodes before linearized reading' (duration: 2.164725456s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:34:36.386769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:34:34.22199Z","time spent":"2.164775957s","remote":"127.0.0.1:41746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":247,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" "} {"level":"warn","ts":"2026-07-21T06:34:36.386838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.728739688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:34:36.386851Z","caller":"traceutil/trace.go:171","msg":"trace[1232364015] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"1.728782949s","start":"2026-07-21T06:34:34.658064Z","end":"2026-07-21T06:34:36.386847Z","steps":["trace[1232364015] 'agreement among raft nodes before linearized reading' (duration: 1.728763109s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:34:36.386861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:34:34.658049Z","time spent":"1.72880954s","remote":"127.0.0.1:41546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:34:36.38697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.847887957s","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-21T06:34:36.387033Z","caller":"traceutil/trace.go:171","msg":"trace[2104284293] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1027; }","duration":"1.847959359s","start":"2026-07-21T06:34:34.539058Z","end":"2026-07-21T06:34:36.387017Z","steps":["trace[2104284293] 'agreement among raft nodes before linearized reading' (duration: 1.847776843s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:34:36.387074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:34:34.539036Z","time spent":"1.848030932s","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-21T06:35:08.723065Z","caller":"traceutil/trace.go:171","msg":"trace[784813541] linearizableReadLoop","detail":"{readStateIndex:1184; appliedIndex:1182; }","duration":"106.192533ms","start":"2026-07-21T06:35:08.616849Z","end":"2026-07-21T06:35:08.723041Z","steps":["trace[784813541] 'read index received' (duration: 25.991285ms)","trace[784813541] 'applied index is now lower than readState.Index' (duration: 80.200318ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:08.723199Z","caller":"traceutil/trace.go:171","msg":"trace[1530326402] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"111.071041ms","start":"2026-07-21T06:35:08.612105Z","end":"2026-07-21T06:35:08.723176Z","steps":["trace[1530326402] 'process raft request' (duration: 30.710657ms)","trace[1530326402] 'compare' (duration: 79.921928ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:08.723256Z","caller":"traceutil/trace.go:171","msg":"trace[559915738] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"110.710327ms","start":"2026-07-21T06:35:08.612535Z","end":"2026-07-21T06:35:08.723245Z","steps":["trace[559915738] 'process raft request' (duration: 110.439757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:08.723429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.560676ms","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-21T06:35:08.72383Z","caller":"traceutil/trace.go:171","msg":"trace[1266013080] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1140; }","duration":"106.981462ms","start":"2026-07-21T06:35:08.616828Z","end":"2026-07-21T06:35:08.72381Z","steps":["trace[1266013080] 'agreement among raft nodes before linearized reading' (duration: 106.473313ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:14.777365Z","caller":"traceutil/trace.go:171","msg":"trace[1752890101] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"102.459887ms","start":"2026-07-21T06:35:14.67488Z","end":"2026-07-21T06:35:14.77734Z","steps":["trace[1752890101] 'process raft request' (duration: 102.219548ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:14.888202Z","caller":"traceutil/trace.go:171","msg":"trace[905356257] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"105.943743ms","start":"2026-07-21T06:35:14.782229Z","end":"2026-07-21T06:35:14.888173Z","steps":["trace[905356257] 'process raft request' (duration: 90.704308ms)","trace[905356257] 'compare' (duration: 14.946845ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:14.888531Z","caller":"traceutil/trace.go:171","msg":"trace[1642184636] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"105.168015ms","start":"2026-07-21T06:35:14.783318Z","end":"2026-07-21T06:35:14.888486Z","steps":["trace[1642184636] 'process raft request' (duration: 104.76547ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:16.92958Z","caller":"traceutil/trace.go:171","msg":"trace[2039341382] linearizableReadLoop","detail":"{readStateIndex:1209; appliedIndex:1208; }","duration":"146.522327ms","start":"2026-07-21T06:35:16.783039Z","end":"2026-07-21T06:35:16.929561Z","steps":["trace[2039341382] 'read index received' (duration: 146.480586ms)","trace[2039341382] 'applied index is now lower than readState.Index' (duration: 40.811µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:16.929692Z","caller":"traceutil/trace.go:171","msg":"trace[1061580415] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"346.348631ms","start":"2026-07-21T06:35:16.583316Z","end":"2026-07-21T06:35:16.929665Z","steps":["trace[1061580415] 'process raft request' (duration: 346.132673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:16.92982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:16.58329Z","time spent":"346.464166ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:35:16.929905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.83916ms","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-21T06:35:16.930007Z","caller":"traceutil/trace.go:171","msg":"trace[1479738953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1163; }","duration":"146.985325ms","start":"2026-07-21T06:35:16.783Z","end":"2026-07-21T06:35:16.929985Z","steps":["trace[1479738953] 'agreement among raft nodes before linearized reading' (duration: 146.679514ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:17.129256Z","caller":"traceutil/trace.go:171","msg":"trace[101168809] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"195.437315ms","start":"2026-07-21T06:35:16.933789Z","end":"2026-07-21T06:35:17.129226Z","steps":["trace[101168809] 'process raft request' (duration: 101.506415ms)","trace[101168809] 'compare' (duration: 93.736593ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:17.129477Z","caller":"traceutil/trace.go:171","msg":"trace[331286987] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"195.550409ms","start":"2026-07-21T06:35:16.933901Z","end":"2026-07-21T06:35:17.129452Z","steps":["trace[331286987] 'process raft request' (duration: 195.265558ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:17.129859Z","caller":"traceutil/trace.go:171","msg":"trace[724967270] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"195.694564ms","start":"2026-07-21T06:35:16.934147Z","end":"2026-07-21T06:35:17.129842Z","steps":["trace[724967270] 'process raft request' (duration: 195.529968ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:17.129862Z","caller":"traceutil/trace.go:171","msg":"trace[1739537969] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"195.719445ms","start":"2026-07-21T06:35:16.934114Z","end":"2026-07-21T06:35:17.129834Z","steps":["trace[1739537969] 'process raft request' (duration: 195.268898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:17.739059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.668268ms","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-21T06:35:17.739132Z","caller":"traceutil/trace.go:171","msg":"trace[294048905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1168; }","duration":"257.801773ms","start":"2026-07-21T06:35:17.481312Z","end":"2026-07-21T06:35:17.739114Z","steps":["trace[294048905] 'range keys from in-memory index tree' (duration: 257.421669ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:17.923036Z","caller":"traceutil/trace.go:171","msg":"trace[1399981621] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"179.960361ms","start":"2026-07-21T06:35:17.743042Z","end":"2026-07-21T06:35:17.923003Z","steps":["trace[1399981621] 'process raft request' (duration: 179.790354ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:23.387696Z","caller":"traceutil/trace.go:171","msg":"trace[1259432729] transaction","detail":"{read_only:false; response_revision:1186; number_of_response:1; }","duration":"155.578843ms","start":"2026-07-21T06:35:23.232084Z","end":"2026-07-21T06:35:23.387663Z","steps":["trace[1259432729] 'process raft request' (duration: 122.636698ms)","trace[1259432729] 'compare' (duration: 32.676305ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:23.387791Z","caller":"traceutil/trace.go:171","msg":"trace[1270625752] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"154.503133ms","start":"2026-07-21T06:35:23.233257Z","end":"2026-07-21T06:35:23.38776Z","steps":["trace[1270625752] 'process raft request' (duration: 154.301986ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:27.60109Z","caller":"traceutil/trace.go:171","msg":"trace[2044691968] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1245; }","duration":"260.830737ms","start":"2026-07-21T06:35:27.340221Z","end":"2026-07-21T06:35:27.601052Z","steps":["trace[2044691968] 'read index received' (duration: 247.712946ms)","trace[2044691968] 'applied index is now lower than readState.Index' (duration: 13.116581ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:27.601285Z","caller":"traceutil/trace.go:171","msg":"trace[197668721] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"310.088312ms","start":"2026-07-21T06:35:27.291179Z","end":"2026-07-21T06:35:27.601267Z","steps":["trace[197668721] 'process raft request' (duration: 296.837356ms)","trace[197668721] 'compare' (duration: 12.865132ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:27.601439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:27.291148Z","time spent":"310.180305ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:35:27.601502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.266203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:35:27.601593Z","caller":"traceutil/trace.go:171","msg":"trace[1968780272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1198; }","duration":"261.412588ms","start":"2026-07-21T06:35:27.34016Z","end":"2026-07-21T06:35:27.601573Z","steps":["trace[1968780272] 'agreement among raft nodes before linearized reading' (duration: 261.278233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:27.602211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.60343ms","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-21T06:35:27.602253Z","caller":"traceutil/trace.go:171","msg":"trace[834719995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1198; }","duration":"194.699574ms","start":"2026-07-21T06:35:27.407543Z","end":"2026-07-21T06:35:27.602243Z","steps":["trace[834719995] 'agreement among raft nodes before linearized reading' (duration: 194.580309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:27.602865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.150907ms","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-21T06:35:27.602981Z","caller":"traceutil/trace.go:171","msg":"trace[893755264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1198; }","duration":"196.325753ms","start":"2026-07-21T06:35:27.406632Z","end":"2026-07-21T06:35:27.602958Z","steps":["trace[893755264] 'agreement among raft nodes before linearized reading' (duration: 195.345187ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:29.808248Z","caller":"traceutil/trace.go:171","msg":"trace[874918523] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"115.052328ms","start":"2026-07-21T06:35:29.693157Z","end":"2026-07-21T06:35:29.80821Z","steps":["trace[874918523] 'process raft request' (duration: 51.512049ms)","trace[874918523] 'compare' (duration: 63.310611ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:31.482985Z","caller":"traceutil/trace.go:171","msg":"trace[578610123] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"144.776201ms","start":"2026-07-21T06:35:31.338189Z","end":"2026-07-21T06:35:31.482965Z","steps":["trace[578610123] 'read index received' (duration: 144.703428ms)","trace[578610123] 'applied index is now lower than readState.Index' (duration: 71.843µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:31.483122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.912046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:35:31.483162Z","caller":"traceutil/trace.go:171","msg":"trace[867591304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1209; }","duration":"144.994989ms","start":"2026-07-21T06:35:31.338157Z","end":"2026-07-21T06:35:31.483152Z","steps":["trace[867591304] 'agreement among raft nodes before linearized reading' (duration: 144.916166ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:31.483302Z","caller":"traceutil/trace.go:171","msg":"trace[1896656485] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"269.204885ms","start":"2026-07-21T06:35:31.213883Z","end":"2026-07-21T06:35:31.483087Z","steps":["trace[1896656485] 'process raft request' (duration: 268.960797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:31.761502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.878113ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-21T06:35:31.761591Z","caller":"traceutil/trace.go:171","msg":"trace[1224811416] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1257; }","duration":"278.528758ms","start":"2026-07-21T06:35:31.483046Z","end":"2026-07-21T06:35:31.761575Z","steps":["trace[1224811416] 'read index received' (duration: 54.51492ms)","trace[1224811416] 'applied index is now lower than readState.Index' (duration: 224.012498ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:31.761771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.136948ms","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-21T06:35:31.761799Z","caller":"traceutil/trace.go:171","msg":"trace[1265659199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1209; }","duration":"358.215141ms","start":"2026-07-21T06:35:31.403576Z","end":"2026-07-21T06:35:31.761791Z","steps":["trace[1265659199] 'agreement among raft nodes before linearized reading' (duration: 358.068075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:31.761828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:31.403556Z","time spent":"358.264473ms","remote":"127.0.0.1:41836","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-21T06:35:31.91297Z","caller":"traceutil/trace.go:171","msg":"trace[2106173792] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"147.106547ms","start":"2026-07-21T06:35:31.765844Z","end":"2026-07-21T06:35:31.91295Z","steps":["trace[2106173792] 'process raft request' (duration: 146.975462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:32.407395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.336783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:35:32.40761Z","caller":"traceutil/trace.go:171","msg":"trace[549333594] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1258; }","duration":"594.358358ms","start":"2026-07-21T06:35:31.813234Z","end":"2026-07-21T06:35:32.407593Z","steps":["trace[549333594] 'read index received' (duration: 99.634545ms)","trace[549333594] 'applied index is now lower than readState.Index' (duration: 494.720263ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:32.407687Z","caller":"traceutil/trace.go:171","msg":"trace[1268493174] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"641.575281ms","start":"2026-07-21T06:35:31.766087Z","end":"2026-07-21T06:35:32.407662Z","steps":["trace[1268493174] 'process raft request' (duration: 641.396334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:32.407807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.564406ms","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-21T06:35:32.407843Z","caller":"traceutil/trace.go:171","msg":"trace[1140866335] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1212; }","duration":"594.625848ms","start":"2026-07-21T06:35:31.813206Z","end":"2026-07-21T06:35:32.407832Z","steps":["trace[1140866335] 'agreement among raft nodes before linearized reading' (duration: 594.458662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:32.407847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:31.766051Z","time spent":"641.710206ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:35:32.407882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:31.813191Z","time spent":"594.680631ms","remote":"127.0.0.1:41836","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-07-21T06:35:32.407888Z","caller":"traceutil/trace.go:171","msg":"trace[379457348] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"641.8225ms","start":"2026-07-21T06:35:31.766026Z","end":"2026-07-21T06:35:32.407848Z","steps":["trace[379457348] 'process raft request' (duration: 513.952547ms)","trace[379457348] 'compare' (duration: 127.107585ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:32.408078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:31.766018Z","time spent":"641.951474ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:35:32.592727Z","caller":"traceutil/trace.go:171","msg":"trace[131895910] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"180.207533ms","start":"2026-07-21T06:35:32.412484Z","end":"2026-07-21T06:35:32.592692Z","steps":["trace[131895910] 'process raft request' (duration: 180.147541ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:32.592785Z","caller":"traceutil/trace.go:171","msg":"trace[773996256] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1261; }","duration":"185.107113ms","start":"2026-07-21T06:35:32.407642Z","end":"2026-07-21T06:35:32.592749Z","steps":["trace[773996256] 'read index received' (duration: 127.468858ms)","trace[773996256] 'applied index is now lower than readState.Index' (duration: 57.634705ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:32.592768Z","caller":"traceutil/trace.go:171","msg":"trace[351665294] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"251.678065ms","start":"2026-07-21T06:35:32.341046Z","end":"2026-07-21T06:35:32.592724Z","steps":["trace[351665294] 'process raft request' (duration: 194.024449ms)","trace[351665294] 'compare' (duration: 57.310584ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:32.592985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.805701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:35:32.593169Z","caller":"traceutil/trace.go:171","msg":"trace[1256360935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1214; }","duration":"252.032559ms","start":"2026-07-21T06:35:32.341124Z","end":"2026-07-21T06:35:32.593156Z","steps":["trace[1256360935] 'agreement among raft nodes before linearized reading' (duration: 251.79082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:32.594392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.681258ms","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-07-21T06:35:32.594457Z","caller":"traceutil/trace.go:171","msg":"trace[2146762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1214; }","duration":"456.797102ms","start":"2026-07-21T06:35:32.137645Z","end":"2026-07-21T06:35:32.594443Z","steps":["trace[2146762] 'agreement among raft nodes before linearized reading' (duration: 455.444073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:32.594512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:32.137622Z","time spent":"456.873085ms","remote":"127.0.0.1:41836","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-21T06:35:33.04509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.792651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:35:33.045217Z","caller":"traceutil/trace.go:171","msg":"trace[545944701] linearizableReadLoop","detail":"{readStateIndex:1264; appliedIndex:1263; }","duration":"347.141498ms","start":"2026-07-21T06:35:32.698054Z","end":"2026-07-21T06:35:33.045196Z","steps":["trace[545944701] 'read index received' (duration: 177.227453ms)","trace[545944701] 'applied index is now lower than readState.Index' (duration: 169.911735ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:33.045461Z","caller":"traceutil/trace.go:171","msg":"trace[76417372] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"446.92746ms","start":"2026-07-21T06:35:32.598489Z","end":"2026-07-21T06:35:33.045416Z","steps":["trace[76417372] 'process raft request' (duration: 276.702673ms)","trace[76417372] 'compare' (duration: 169.571103ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:33.045554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.48639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"warn","ts":"2026-07-21T06:35:33.045583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:32.598465Z","time spent":"447.062335ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:35:33.04562Z","caller":"traceutil/trace.go:171","msg":"trace[77495483] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1215; }","duration":"347.582945ms","start":"2026-07-21T06:35:32.698021Z","end":"2026-07-21T06:35:33.045604Z","steps":["trace[77495483] 'agreement among raft nodes before linearized reading' (duration: 347.235742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:33.045668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:32.698006Z","time spent":"347.653097ms","remote":"127.0.0.1:41650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329971,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-07-21T06:35:33.676321Z","caller":"traceutil/trace.go:171","msg":"trace[1033023459] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"187.919197ms","start":"2026-07-21T06:35:33.488386Z","end":"2026-07-21T06:35:33.676305Z","steps":["trace[1033023459] 'process raft request' (duration: 187.821534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:34.543288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.249442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:35:34.543331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.263833ms","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-21T06:35:34.543371Z","caller":"traceutil/trace.go:171","msg":"trace[1771816716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"203.364687ms","start":"2026-07-21T06:35:34.339988Z","end":"2026-07-21T06:35:34.543352Z","steps":["trace[1771816716] 'range keys from in-memory index tree' (duration: 203.192381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:34.543421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.344926ms","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-21T06:35:34.543435Z","caller":"traceutil/trace.go:171","msg":"trace[789260061] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1216; }","duration":"130.396948ms","start":"2026-07-21T06:35:34.413034Z","end":"2026-07-21T06:35:34.543431Z","steps":["trace[789260061] 'range keys from in-memory index tree' (duration: 130.245522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:34.543532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.295291ms","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-21T06:35:34.543381Z","caller":"traceutil/trace.go:171","msg":"trace[1931062833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1216; }","duration":"130.358336ms","start":"2026-07-21T06:35:34.41301Z","end":"2026-07-21T06:35:34.543369Z","steps":["trace[1931062833] 'range keys from in-memory index tree' (duration: 130.086627ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:34.543596Z","caller":"traceutil/trace.go:171","msg":"trace[1114003986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1216; }","duration":"615.401615ms","start":"2026-07-21T06:35:33.928179Z","end":"2026-07-21T06:35:34.54358Z","steps":["trace[1114003986] 'range keys from in-memory index tree' (duration: 615.073243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:34.543617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.919454ms","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-07-21T06:35:34.543665Z","caller":"traceutil/trace.go:171","msg":"trace[373718399] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1216; }","duration":"205.008338ms","start":"2026-07-21T06:35:34.338645Z","end":"2026-07-21T06:35:34.543653Z","steps":["trace[373718399] 'count revisions from in-memory index tree' (duration: 204.844112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:34.543627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:33.928158Z","time spent":"615.462378ms","remote":"127.0.0.1:41836","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-07-21T06:35:34.804402Z","caller":"traceutil/trace.go:171","msg":"trace[1185860845] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"257.673031ms","start":"2026-07-21T06:35:34.546709Z","end":"2026-07-21T06:35:34.804382Z","steps":["trace[1185860845] 'process raft request' (duration: 257.565607ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:34.811697Z","caller":"traceutil/trace.go:171","msg":"trace[463755845] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1265; }","duration":"204.611633ms","start":"2026-07-21T06:35:34.607062Z","end":"2026-07-21T06:35:34.811674Z","steps":["trace[463755845] 'read index received' (duration: 197.149899ms)","trace[463755845] 'applied index is now lower than readState.Index' (duration: 7.460844ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:34.811741Z","caller":"traceutil/trace.go:171","msg":"trace[729876679] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"264.754281ms","start":"2026-07-21T06:35:34.546969Z","end":"2026-07-21T06:35:34.811723Z","steps":["trace[729876679] 'process raft request' (duration: 264.579865ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:34.811799Z","caller":"traceutil/trace.go:171","msg":"trace[473727492] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"264.595925ms","start":"2026-07-21T06:35:34.547194Z","end":"2026-07-21T06:35:34.81179Z","steps":["trace[473727492] 'process raft request' (duration: 264.444609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:34.811997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.917274ms","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-21T06:35:34.812027Z","caller":"traceutil/trace.go:171","msg":"trace[235693701] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1219; }","duration":"204.959825ms","start":"2026-07-21T06:35:34.607058Z","end":"2026-07-21T06:35:34.812018Z","steps":["trace[235693701] 'agreement among raft nodes before linearized reading' (duration: 204.856331ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:35.538883Z","caller":"traceutil/trace.go:171","msg":"trace[1007699606] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"422.657534ms","start":"2026-07-21T06:35:35.116202Z","end":"2026-07-21T06:35:35.538859Z","steps":["trace[1007699606] 'process raft request' (duration: 422.511139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:35.539035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:35.116181Z","time spent":"422.779308ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:35:35.828754Z","caller":"traceutil/trace.go:171","msg":"trace[464834107] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"147.056791ms","start":"2026-07-21T06:35:35.681665Z","end":"2026-07-21T06:35:35.828722Z","steps":["trace[464834107] 'process raft request' (duration: 146.686047ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:37.219655Z","caller":"traceutil/trace.go:171","msg":"trace[1941605171] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1273; }","duration":"404.238974ms","start":"2026-07-21T06:35:36.8154Z","end":"2026-07-21T06:35:37.219639Z","steps":["trace[1941605171] 'read index received' (duration: 404.075888ms)","trace[1941605171] 'applied index is now lower than readState.Index' (duration: 162.316µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:37.21991Z","caller":"traceutil/trace.go:171","msg":"trace[2092998088] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"406.751066ms","start":"2026-07-21T06:35:36.813153Z","end":"2026-07-21T06:35:37.219904Z","steps":["trace[2092998088] 'process raft request' (duration: 406.375962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:37.21998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:36.813128Z","time spent":"406.798988ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:35:37.220193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.790164ms","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-21T06:35:37.220211Z","caller":"traceutil/trace.go:171","msg":"trace[242182054] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1224; }","duration":"404.824596ms","start":"2026-07-21T06:35:36.815381Z","end":"2026-07-21T06:35:37.220205Z","steps":["trace[242182054] 'agreement among raft nodes before linearized reading' (duration: 404.693721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:37.220226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:36.815374Z","time spent":"404.849017ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T06:35:37.220315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.815465ms","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-21T06:35:37.220327Z","caller":"traceutil/trace.go:171","msg":"trace[470997999] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1224; }","duration":"404.864637ms","start":"2026-07-21T06:35:36.815459Z","end":"2026-07-21T06:35:37.220324Z","steps":["trace[470997999] 'agreement among raft nodes before linearized reading' (duration: 404.827726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:37.22034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:36.81544Z","time spent":"404.896788ms","remote":"127.0.0.1:41836","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-07-21T06:35:37.220464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.131467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:35:37.220486Z","caller":"traceutil/trace.go:171","msg":"trace[1369385455] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1224; }","duration":"150.185319ms","start":"2026-07-21T06:35:37.070293Z","end":"2026-07-21T06:35:37.220479Z","steps":["trace[1369385455] 'agreement among raft nodes before linearized reading' (duration: 150.149448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:37.220593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.410092ms","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-21T06:35:37.220607Z","caller":"traceutil/trace.go:171","msg":"trace[281243936] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1224; }","duration":"385.446704ms","start":"2026-07-21T06:35:36.835156Z","end":"2026-07-21T06:35:37.220603Z","steps":["trace[281243936] 'agreement among raft nodes before linearized reading' (duration: 385.403332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:37.220619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:36.835141Z","time spent":"385.475435ms","remote":"127.0.0.1:41836","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-07-21T06:35:37.421893Z","caller":"traceutil/trace.go:171","msg":"trace[383556690] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"197.189217ms","start":"2026-07-21T06:35:37.224671Z","end":"2026-07-21T06:35:37.42186Z","steps":["trace[383556690] 'process raft request' (duration: 188.799668ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:37.517307Z","caller":"traceutil/trace.go:171","msg":"trace[540603095] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1274; }","duration":"176.903842ms","start":"2026-07-21T06:35:37.340367Z","end":"2026-07-21T06:35:37.517271Z","steps":["trace[540603095] 'read index received' (duration: 73.119408ms)","trace[540603095] 'applied index is now lower than readState.Index' (duration: 103.783644ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:37.517351Z","caller":"traceutil/trace.go:171","msg":"trace[1786862248] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"292.334003ms","start":"2026-07-21T06:35:37.224976Z","end":"2026-07-21T06:35:37.51731Z","steps":["trace[1786862248] 'process raft request' (duration: 292.097965ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:37.517383Z","caller":"traceutil/trace.go:171","msg":"trace[1319928708] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"291.824294ms","start":"2026-07-21T06:35:37.225515Z","end":"2026-07-21T06:35:37.517339Z","steps":["trace[1319928708] 'process raft request' (duration: 291.71331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:37.517547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.147551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:35:37.517843Z","caller":"traceutil/trace.go:171","msg":"trace[1204496589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1227; }","duration":"177.486463ms","start":"2026-07-21T06:35:37.340327Z","end":"2026-07-21T06:35:37.517814Z","steps":["trace[1204496589] 'agreement among raft nodes before linearized reading' (duration: 177.096999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:37.518267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.9024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050268"} {"level":"info","ts":"2026-07-21T06:35:37.518321Z","caller":"traceutil/trace.go:171","msg":"trace[989477729] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1227; }","duration":"162.016225ms","start":"2026-07-21T06:35:37.356291Z","end":"2026-07-21T06:35:37.518307Z","steps":["trace[989477729] 'agreement among raft nodes before linearized reading' (duration: 161.124132ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:41.59058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.498846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:35:41.590684Z","caller":"traceutil/trace.go:171","msg":"trace[945242913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1237; }","duration":"253.622ms","start":"2026-07-21T06:35:41.337041Z","end":"2026-07-21T06:35:41.590663Z","steps":["trace[945242913] 'range keys from in-memory index tree' (duration: 253.366731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:41.903063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.725959ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-21T06:35:41.903173Z","caller":"traceutil/trace.go:171","msg":"trace[1305680125] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1287; }","duration":"461.210077ms","start":"2026-07-21T06:35:41.441944Z","end":"2026-07-21T06:35:41.903155Z","steps":["trace[1305680125] 'read index received' (duration: 28.386074ms)","trace[1305680125] 'applied index is now lower than readState.Index' (duration: 432.822293ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:41.903383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.435766ms","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-21T06:35:41.903428Z","caller":"traceutil/trace.go:171","msg":"trace[381217706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1237; }","duration":"461.531099ms","start":"2026-07-21T06:35:41.441884Z","end":"2026-07-21T06:35:41.903415Z","steps":["trace[381217706] 'agreement among raft nodes before linearized reading' (duration: 461.369063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:41.903472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:41.441867Z","time spent":"461.595171ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-21T06:35:42.006644Z","caller":"traceutil/trace.go:171","msg":"trace[430624727] linearizableReadLoop","detail":"{readStateIndex:1289; appliedIndex:1288; }","duration":"103.412554ms","start":"2026-07-21T06:35:41.903199Z","end":"2026-07-21T06:35:42.006611Z","steps":["trace[430624727] 'read index received' (duration: 103.215047ms)","trace[430624727] 'applied index is now lower than readState.Index' (duration: 196.397µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:42.006867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.933075ms","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-21T06:35:42.00692Z","caller":"traceutil/trace.go:171","msg":"trace[12660125] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1238; }","duration":"387.044329ms","start":"2026-07-21T06:35:41.619862Z","end":"2026-07-21T06:35:42.006907Z","steps":["trace[12660125] 'agreement among raft nodes before linearized reading' (duration: 386.881513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:42.006967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:41.619845Z","time spent":"387.109522ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T06:35:42.007044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.07735ms","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-21T06:35:42.007031Z","caller":"traceutil/trace.go:171","msg":"trace[1515047970] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"142.856426ms","start":"2026-07-21T06:35:41.864133Z","end":"2026-07-21T06:35:42.006989Z","steps":["trace[1515047970] 'process raft request' (duration: 142.290065ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:42.007111Z","caller":"traceutil/trace.go:171","msg":"trace[762942638] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1238; }","duration":"384.209475ms","start":"2026-07-21T06:35:41.622884Z","end":"2026-07-21T06:35:42.007093Z","steps":["trace[762942638] 'agreement among raft nodes before linearized reading' (duration: 383.956025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:42.007145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:41.622856Z","time spent":"384.283077ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T06:35:42.007633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.904168ms","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-21T06:35:42.007713Z","caller":"traceutil/trace.go:171","msg":"trace[83540848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1238; }","duration":"367.058754ms","start":"2026-07-21T06:35:41.640635Z","end":"2026-07-21T06:35:42.007694Z","steps":["trace[83540848] 'agreement among raft nodes before linearized reading' (duration: 366.036196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:42.007757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:41.640611Z","time spent":"367.136047ms","remote":"127.0.0.1:41836","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":"info","ts":"2026-07-21T06:35:42.082737Z","caller":"traceutil/trace.go:171","msg":"trace[1983918360] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"174.858823ms","start":"2026-07-21T06:35:41.907851Z","end":"2026-07-21T06:35:42.082709Z","steps":["trace[1983918360] 'process raft request' (duration: 174.698587ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:43.525203Z","caller":"traceutil/trace.go:171","msg":"trace[1423045327] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1294; }","duration":"187.124878ms","start":"2026-07-21T06:35:43.338051Z","end":"2026-07-21T06:35:43.525175Z","steps":["trace[1423045327] 'read index received' (duration: 186.897309ms)","trace[1423045327] 'applied index is now lower than readState.Index' (duration: 226.359µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:43.525566Z","caller":"traceutil/trace.go:171","msg":"trace[17978361] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"227.898088ms","start":"2026-07-21T06:35:43.297644Z","end":"2026-07-21T06:35:43.525542Z","steps":["trace[17978361] 'process raft request' (duration: 227.369919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:43.5256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.531612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:35:43.525666Z","caller":"traceutil/trace.go:171","msg":"trace[765226520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1244; }","duration":"187.633336ms","start":"2026-07-21T06:35:43.338006Z","end":"2026-07-21T06:35:43.525639Z","steps":["trace[765226520] 'agreement among raft nodes before linearized reading' (duration: 187.540672ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:46.446455Z","caller":"traceutil/trace.go:171","msg":"trace[1249859309] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1301; }","duration":"339.09069ms","start":"2026-07-21T06:35:46.107345Z","end":"2026-07-21T06:35:46.446436Z","steps":["trace[1249859309] 'read index received' (duration: 338.924684ms)","trace[1249859309] 'applied index is now lower than readState.Index' (duration: 165.386µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:46.446761Z","caller":"traceutil/trace.go:171","msg":"trace[1159999777] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"411.626202ms","start":"2026-07-21T06:35:46.035126Z","end":"2026-07-21T06:35:46.446752Z","steps":["trace[1159999777] 'process raft request' (duration: 411.197476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:46.446867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.035107Z","time spent":"411.680583ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:35:46.447137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.780595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-21T06:35:46.447166Z","caller":"traceutil/trace.go:171","msg":"trace[1211884211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1251; }","duration":"339.859989ms","start":"2026-07-21T06:35:46.107299Z","end":"2026-07-21T06:35:46.447159Z","steps":["trace[1211884211] 'agreement among raft nodes before linearized reading' (duration: 339.735084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:46.447186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.107279Z","time spent":"339.90235ms","remote":"127.0.0.1:41836","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-21T06:35:46.447627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.446087ms","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-07-21T06:35:46.447665Z","caller":"traceutil/trace.go:171","msg":"trace[585840852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1251; }","duration":"338.503388ms","start":"2026-07-21T06:35:46.109155Z","end":"2026-07-21T06:35:46.447659Z","steps":["trace[585840852] 'agreement among raft nodes before linearized reading' (duration: 338.140415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:46.447684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.109148Z","time spent":"338.5316ms","remote":"127.0.0.1:41836","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-21T06:35:46.447747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.079341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:35:46.447948Z","caller":"traceutil/trace.go:171","msg":"trace[771066975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1251; }","duration":"108.310539ms","start":"2026-07-21T06:35:46.33962Z","end":"2026-07-21T06:35:46.447931Z","steps":["trace[771066975] 'agreement among raft nodes before linearized reading' (duration: 108.112202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:46.448489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.691096ms","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-07-21T06:35:46.448583Z","caller":"traceutil/trace.go:171","msg":"trace[777831088] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1251; }","duration":"338.795549ms","start":"2026-07-21T06:35:46.109765Z","end":"2026-07-21T06:35:46.448561Z","steps":["trace[777831088] 'agreement among raft nodes before linearized reading' (duration: 338.54744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:46.44864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.109745Z","time spent":"338.881123ms","remote":"127.0.0.1:41836","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-21T06:35:46.448949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.624762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T06:35:46.449053Z","caller":"traceutil/trace.go:171","msg":"trace[993827687] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:1251; }","duration":"114.807249ms","start":"2026-07-21T06:35:46.334231Z","end":"2026-07-21T06:35:46.449038Z","steps":["trace[993827687] 'agreement among raft nodes before linearized reading' (duration: 114.630903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:46.44901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.733074ms","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-21T06:35:46.449275Z","caller":"traceutil/trace.go:171","msg":"trace[1745161603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1251; }","duration":"340.049765ms","start":"2026-07-21T06:35:46.109214Z","end":"2026-07-21T06:35:46.449264Z","steps":["trace[1745161603] 'agreement among raft nodes before linearized reading' (duration: 339.709903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:46.449308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.109199Z","time spent":"340.099048ms","remote":"127.0.0.1:41836","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-21T06:35:46.788903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.450049Z","time spent":"338.848761ms","remote":"127.0.0.1:41586","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-21T06:35:46.791646Z","caller":"traceutil/trace.go:171","msg":"trace[1018963968] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"338.688345ms","start":"2026-07-21T06:35:46.452939Z","end":"2026-07-21T06:35:46.791627Z","steps":["trace[1018963968] 'process raft request' (duration: 338.345412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:46.791758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.452931Z","time spent":"338.761388ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:35:46.791786Z","caller":"traceutil/trace.go:171","msg":"trace[1531353426] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"338.480258ms","start":"2026-07-21T06:35:46.453282Z","end":"2026-07-21T06:35:46.791762Z","steps":["trace[1531353426] 'process raft request' (duration: 338.257639ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:46.791844Z","caller":"traceutil/trace.go:171","msg":"trace[1339493423] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"339.669001ms","start":"2026-07-21T06:35:46.451783Z","end":"2026-07-21T06:35:46.791452Z","steps":["trace[1339493423] 'process raft request' (duration: 339.35391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:46.791908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.453276Z","time spent":"338.56184ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:35:46.792077Z","caller":"traceutil/trace.go:171","msg":"trace[1183309096] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"337.311734ms","start":"2026-07-21T06:35:46.454757Z","end":"2026-07-21T06:35:46.792069Z","steps":["trace[1183309096] 'process raft request' (duration: 337.19818ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:46.792044Z","caller":"traceutil/trace.go:171","msg":"trace[1294306654] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1302; }","duration":"105.301628ms","start":"2026-07-21T06:35:46.686729Z","end":"2026-07-21T06:35:46.792031Z","steps":["trace[1294306654] 'read index received' (duration: 97.145308ms)","trace[1294306654] 'applied index is now lower than readState.Index' (duration: 8.15523ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:46.792148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.454751Z","time spent":"337.350186ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:35:46.792202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.451955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:35:46.792232Z","caller":"traceutil/trace.go:171","msg":"trace[1796672540] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1255; }","duration":"105.532078ms","start":"2026-07-21T06:35:46.686693Z","end":"2026-07-21T06:35:46.792225Z","steps":["trace[1796672540] 'agreement among raft nodes before linearized reading' (duration: 105.456595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:46.79223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.451767Z","time spent":"340.235541ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:35:47.495803Z","caller":"traceutil/trace.go:171","msg":"trace[69751030] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"705.744179ms","start":"2026-07-21T06:35:46.79003Z","end":"2026-07-21T06:35:47.495774Z","steps":["trace[69751030] 'process raft request' (duration: 695.587305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:47.495929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:46.790009Z","time spent":"705.853793ms","remote":"127.0.0.1:41586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:35:48.743944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115839283020814,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T06:35:48.854154Z","caller":"traceutil/trace.go:171","msg":"trace[1491039816] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"768.385844ms","start":"2026-07-21T06:35:48.085745Z","end":"2026-07-21T06:35:48.854131Z","steps":["trace[1491039816] 'process raft request' (duration: 768.223589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:48.854264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:48.08573Z","time spent":"768.486038ms","remote":"127.0.0.1:41716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:35:48.866863Z","caller":"traceutil/trace.go:171","msg":"trace[1105502335] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1309; }","duration":"623.131921ms","start":"2026-07-21T06:35:48.243716Z","end":"2026-07-21T06:35:48.866848Z","steps":["trace[1105502335] 'read index received' (duration: 610.271847ms)","trace[1105502335] 'applied index is now lower than readState.Index' (duration: 12.859394ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:48.866941Z","caller":"traceutil/trace.go:171","msg":"trace[1761006697] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"413.507767ms","start":"2026-07-21T06:35:48.453406Z","end":"2026-07-21T06:35:48.866913Z","steps":["trace[1761006697] 'process raft request' (duration: 413.420614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:48.866988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.251906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:35:48.866953Z","caller":"traceutil/trace.go:171","msg":"trace[358767633] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"756.972494ms","start":"2026-07-21T06:35:48.109934Z","end":"2026-07-21T06:35:48.866907Z","steps":["trace[358767633] 'process raft request' (duration: 756.812368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:48.867054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.981358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:35:48.867046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:48.453386Z","time spent":"413.59988ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:35:48.867078Z","caller":"traceutil/trace.go:171","msg":"trace[1701449814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1260; }","duration":"575.03947ms","start":"2026-07-21T06:35:48.292032Z","end":"2026-07-21T06:35:48.867072Z","steps":["trace[1701449814] 'agreement among raft nodes before linearized reading' (duration: 575.000288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:48.867095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:48.292016Z","time spent":"575.075101ms","remote":"127.0.0.1:41546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:35:48.867155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:48.109913Z","time spent":"757.13834ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:35:48.867012Z","caller":"traceutil/trace.go:171","msg":"trace[1513601589] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1260; }","duration":"623.324119ms","start":"2026-07-21T06:35:48.243679Z","end":"2026-07-21T06:35:48.867003Z","steps":["trace[1513601589] 'agreement among raft nodes before linearized reading' (duration: 623.254157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:48.867398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:48.243662Z","time spent":"623.716883ms","remote":"127.0.0.1:46428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:35:48.931106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.851049ms","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":"warn","ts":"2026-07-21T06:35:48.931144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.889683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:35:48.931229Z","caller":"traceutil/trace.go:171","msg":"trace[371229615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1260; }","duration":"591.024359ms","start":"2026-07-21T06:35:48.340182Z","end":"2026-07-21T06:35:48.931206Z","steps":["trace[371229615] 'agreement among raft nodes before linearized reading' (duration: 527.386944ms)","trace[371229615] 'range keys from in-memory index tree' (duration: 63.49319ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:48.931265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:48.340161Z","time spent":"591.097871ms","remote":"127.0.0.1:41554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:35:48.931374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.270525ms","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-07-21T06:35:48.931405Z","caller":"traceutil/trace.go:171","msg":"trace[1429346851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1260; }","duration":"135.328827ms","start":"2026-07-21T06:35:48.796067Z","end":"2026-07-21T06:35:48.931395Z","steps":["trace[1429346851] 'agreement among raft nodes before linearized reading' (duration: 71.384821ms)","trace[1429346851] 'range keys from in-memory index tree' (duration: 63.868103ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:48.931557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.241454ms","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-07-21T06:35:48.931581Z","caller":"traceutil/trace.go:171","msg":"trace[1913521494] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1260; }","duration":"135.265174ms","start":"2026-07-21T06:35:48.796307Z","end":"2026-07-21T06:35:48.931572Z","steps":["trace[1913521494] 'agreement among raft nodes before linearized reading' (duration: 71.155202ms)","trace[1913521494] 'range keys from in-memory index tree' (duration: 64.04987ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:48.931571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.338197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-21T06:35:48.93161Z","caller":"traceutil/trace.go:171","msg":"trace[165788382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1260; }","duration":"135.403499ms","start":"2026-07-21T06:35:48.796198Z","end":"2026-07-21T06:35:48.931602Z","steps":["trace[165788382] 'agreement among raft nodes before linearized reading' (duration: 71.278197ms)","trace[165788382] 'range keys from in-memory index tree' (duration: 64.019209ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:48.93118Z","caller":"traceutil/trace.go:171","msg":"trace[1132232512] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1260; }","duration":"134.962913ms","start":"2026-07-21T06:35:48.796199Z","end":"2026-07-21T06:35:48.931161Z","steps":["trace[1132232512] 'agreement among raft nodes before linearized reading' (duration: 71.270816ms)","trace[1132232512] 'range keys from in-memory index tree' (duration: 63.513461ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:49.112099Z","caller":"traceutil/trace.go:171","msg":"trace[664384770] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"176.005247ms","start":"2026-07-21T06:35:48.936072Z","end":"2026-07-21T06:35:49.112077Z","steps":["trace[664384770] 'process raft request' (duration: 175.663044ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:49.11225Z","caller":"traceutil/trace.go:171","msg":"trace[360996106] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"175.445496ms","start":"2026-07-21T06:35:48.936794Z","end":"2026-07-21T06:35:49.11224Z","steps":["trace[360996106] 'process raft request' (duration: 175.405615ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:49.112291Z","caller":"traceutil/trace.go:171","msg":"trace[1522280540] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"175.879852ms","start":"2026-07-21T06:35:48.936398Z","end":"2026-07-21T06:35:49.112277Z","steps":["trace[1522280540] 'process raft request' (duration: 175.618703ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:51.73191Z","caller":"traceutil/trace.go:171","msg":"trace[130437434] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"100.229017ms","start":"2026-07-21T06:35:51.631657Z","end":"2026-07-21T06:35:51.731886Z","steps":["trace[130437434] 'process raft request' (duration: 96.971326ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:53.39652Z","caller":"traceutil/trace.go:171","msg":"trace[1260311155] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"264.470187ms","start":"2026-07-21T06:35:53.13202Z","end":"2026-07-21T06:35:53.39649Z","steps":["trace[1260311155] 'process raft request' (duration: 264.318241ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:53.564724Z","caller":"traceutil/trace.go:171","msg":"trace[155173453] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1342; }","duration":"225.171247ms","start":"2026-07-21T06:35:53.339531Z","end":"2026-07-21T06:35:53.564702Z","steps":["trace[155173453] 'read index received' (duration: 56.784065ms)","trace[155173453] 'applied index is now lower than readState.Index' (duration: 168.386392ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:53.564841Z","caller":"traceutil/trace.go:171","msg":"trace[734288967] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"431.338833ms","start":"2026-07-21T06:35:53.133435Z","end":"2026-07-21T06:35:53.564774Z","steps":["trace[734288967] 'process raft request' (duration: 431.217859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:53.565138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:53.133417Z","time spent":"431.638783ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:35:53.565171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.347524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:35:53.565225Z","caller":"traceutil/trace.go:171","msg":"trace[981814006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1291; }","duration":"225.725688ms","start":"2026-07-21T06:35:53.339486Z","end":"2026-07-21T06:35:53.565212Z","steps":["trace[981814006] 'agreement among raft nodes before linearized reading' (duration: 225.339834ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:53.56476Z","caller":"traceutil/trace.go:171","msg":"trace[765479415] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"431.924824ms","start":"2026-07-21T06:35:53.132801Z","end":"2026-07-21T06:35:53.564726Z","steps":["trace[765479415] 'process raft request' (duration: 412.492237ms)","trace[765479415] 'compare' (duration: 19.220359ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:53.565401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:35:53.132779Z","time spent":"432.537017ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:35:53.722437Z","caller":"traceutil/trace.go:171","msg":"trace[1935282862] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"133.526946ms","start":"2026-07-21T06:35:53.588867Z","end":"2026-07-21T06:35:53.722394Z","steps":["trace[1935282862] 'process raft request' (duration: 131.850324ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:55.718908Z","caller":"traceutil/trace.go:171","msg":"trace[740564402] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"145.696007ms","start":"2026-07-21T06:35:55.573191Z","end":"2026-07-21T06:35:55.718887Z","steps":["trace[740564402] 'process raft request' (duration: 145.558281ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:55.741116Z","caller":"traceutil/trace.go:171","msg":"trace[1600304533] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1352; }","duration":"124.13374ms","start":"2026-07-21T06:35:55.616964Z","end":"2026-07-21T06:35:55.741098Z","steps":["trace[1600304533] 'read index received' (duration: 102.037175ms)","trace[1600304533] 'applied index is now lower than readState.Index' (duration: 22.095605ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:55.741214Z","caller":"traceutil/trace.go:171","msg":"trace[1708668979] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"167.839263ms","start":"2026-07-21T06:35:55.573343Z","end":"2026-07-21T06:35:55.741183Z","steps":["trace[1708668979] 'process raft request' (duration: 167.660357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:55.741317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.347618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8d8c25e8-8fd2-45ad-9b98-d77de11d9b11\" ","response":"range_response_count:1 size:3872"} {"level":"info","ts":"2026-07-21T06:35:55.741387Z","caller":"traceutil/trace.go:171","msg":"trace[1165222847] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8d8c25e8-8fd2-45ad-9b98-d77de11d9b11; range_end:; response_count:1; response_revision:1299; }","duration":"124.466903ms","start":"2026-07-21T06:35:55.616902Z","end":"2026-07-21T06:35:55.741369Z","steps":["trace[1165222847] 'agreement among raft nodes before linearized reading' (duration: 124.294237ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:55.904773Z","caller":"traceutil/trace.go:171","msg":"trace[28425919] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1353; }","duration":"140.435073ms","start":"2026-07-21T06:35:55.764318Z","end":"2026-07-21T06:35:55.904753Z","steps":["trace[28425919] 'read index received' (duration: 140.243076ms)","trace[28425919] 'applied index is now lower than readState.Index' (duration: 191.237µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:35:55.904964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.63532ms","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-21T06:35:55.905038Z","caller":"traceutil/trace.go:171","msg":"trace[1622383753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1300; }","duration":"140.758644ms","start":"2026-07-21T06:35:55.764271Z","end":"2026-07-21T06:35:55.90503Z","steps":["trace[1622383753] 'agreement among raft nodes before linearized reading' (duration: 140.568097ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:55.905138Z","caller":"traceutil/trace.go:171","msg":"trace[1833750759] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"155.00511ms","start":"2026-07-21T06:35:55.750108Z","end":"2026-07-21T06:35:55.905114Z","steps":["trace[1833750759] 'process raft request' (duration: 154.521062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:59.468598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.098638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:35:59.468824Z","caller":"traceutil/trace.go:171","msg":"trace[1961759048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1324; }","duration":"129.40818ms","start":"2026-07-21T06:35:59.339379Z","end":"2026-07-21T06:35:59.468787Z","steps":["trace[1961759048] 'range keys from in-memory index tree' (duration: 128.994164ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:35:59.939934Z","caller":"traceutil/trace.go:171","msg":"trace[910957967] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1381; }","duration":"180.5509ms","start":"2026-07-21T06:35:59.759355Z","end":"2026-07-21T06:35:59.939906Z","steps":["trace[910957967] 'read index received' (duration: 180.2953ms)","trace[910957967] 'applied index is now lower than readState.Index' (duration: 254.21µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:35:59.940061Z","caller":"traceutil/trace.go:171","msg":"trace[1511900030] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"195.75149ms","start":"2026-07-21T06:35:59.744257Z","end":"2026-07-21T06:35:59.940009Z","steps":["trace[1511900030] 'process raft request' (duration: 195.352216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:35:59.940737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.342599ms","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-21T06:35:59.940792Z","caller":"traceutil/trace.go:171","msg":"trace[1798349548] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1326; }","duration":"181.527665ms","start":"2026-07-21T06:35:59.75925Z","end":"2026-07-21T06:35:59.940778Z","steps":["trace[1798349548] 'agreement among raft nodes before linearized reading' (duration: 180.753756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:07.659597Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115839283021055,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:36:08.160582Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500115839283021055,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:36:08.472653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.133497574s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T06:36:08.472719Z","caller":"traceutil/trace.go:171","msg":"trace[868344234] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:1354; }","duration":"2.133600648s","start":"2026-07-21T06:36:06.339102Z","end":"2026-07-21T06:36:08.472703Z","steps":["trace[868344234] 'range keys from in-memory index tree' (duration: 2.133337109s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:08.47275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:06.339083Z","time spent":"2.13365945s","remote":"127.0.0.1:41586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.242\" "} {"level":"warn","ts":"2026-07-21T06:36:08.472899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.132364233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:36:08.472926Z","caller":"traceutil/trace.go:171","msg":"trace[316459677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1354; }","duration":"2.132419895s","start":"2026-07-21T06:36:06.340499Z","end":"2026-07-21T06:36:08.472919Z","steps":["trace[316459677] 'range keys from in-memory index tree' (duration: 2.13229253s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:08.472949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:06.340483Z","time spent":"2.132460216s","remote":"127.0.0.1:41546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:36:08.485071Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.471685099s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T06:36:08.485528Z","caller":"traceutil/trace.go:171","msg":"trace[1287487339] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"1.472246201s","start":"2026-07-21T06:36:07.01326Z","end":"2026-07-21T06:36:08.485506Z","steps":["trace[1287487339] 'process raft request' (duration: 1.472131216s)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:08.485553Z","caller":"traceutil/trace.go:171","msg":"trace[997078960] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1412; }","duration":"1.326681357s","start":"2026-07-21T06:36:07.15885Z","end":"2026-07-21T06:36:08.485532Z","steps":["trace[997078960] 'read index received' (duration: 1.326664006s)","trace[997078960] 'applied index is now lower than readState.Index' (duration: 15.201µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:36:08.485751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:07.013229Z","time spent":"1.472360255s","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:36:08.48579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326919695s","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-07-21T06:36:08.485832Z","caller":"traceutil/trace.go:171","msg":"trace[850924775] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1355; }","duration":"1.327019509s","start":"2026-07-21T06:36:07.158801Z","end":"2026-07-21T06:36:08.48582Z","steps":["trace[850924775] 'agreement among raft nodes before linearized reading' (duration: 1.326872334s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:08.485864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:07.158781Z","time spent":"1.327075291s","remote":"127.0.0.1:41836","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-21T06:36:08.533241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.73145ms","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-21T06:36:08.533286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.883719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:36:08.533321Z","caller":"traceutil/trace.go:171","msg":"trace[1392127855] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1355; }","duration":"983.855426ms","start":"2026-07-21T06:36:07.549446Z","end":"2026-07-21T06:36:08.533302Z","steps":["trace[1392127855] 'agreement among raft nodes before linearized reading' (duration: 983.666889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:08.533353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.313813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:36:08.533369Z","caller":"traceutil/trace.go:171","msg":"trace[2004921851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1355; }","duration":"192.021034ms","start":"2026-07-21T06:36:08.341327Z","end":"2026-07-21T06:36:08.533348Z","steps":["trace[2004921851] 'agreement among raft nodes before linearized reading' (duration: 191.885158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:08.533383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:07.549428Z","time spent":"983.945058ms","remote":"127.0.0.1:41836","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-07-21T06:36:08.533393Z","caller":"traceutil/trace.go:171","msg":"trace[1179119600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1355; }","duration":"216.384576ms","start":"2026-07-21T06:36:08.316995Z","end":"2026-07-21T06:36:08.533379Z","steps":["trace[1179119600] 'agreement among raft nodes before linearized reading' (duration: 216.341134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:08.533305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-21T06:36:08.533621Z","caller":"traceutil/trace.go:171","msg":"trace[98499072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1355; }","duration":"389.325203ms","start":"2026-07-21T06:36:08.144286Z","end":"2026-07-21T06:36:08.533611Z","steps":["trace[98499072] 'agreement among raft nodes before linearized reading' (duration: 388.873577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:08.533657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:08.144266Z","time spent":"389.377905ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T06:36:08.533667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.686087ms","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-21T06:36:08.5337Z","caller":"traceutil/trace.go:171","msg":"trace[1076953293] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1355; }","duration":"522.745159ms","start":"2026-07-21T06:36:08.010946Z","end":"2026-07-21T06:36:08.533691Z","steps":["trace[1076953293] 'agreement among raft nodes before linearized reading' (duration: 522.651766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:08.53373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:08.010932Z","time spent":"522.788171ms","remote":"127.0.0.1:41836","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-07-21T06:36:08.533795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.170484ms","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-21T06:36:08.533867Z","caller":"traceutil/trace.go:171","msg":"trace[27263316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1355; }","duration":"530.290428ms","start":"2026-07-21T06:36:08.003563Z","end":"2026-07-21T06:36:08.533853Z","steps":["trace[27263316] 'agreement among raft nodes before linearized reading' (duration: 530.07669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:08.533958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:08.003541Z","time spent":"530.408283ms","remote":"127.0.0.1:41836","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":"info","ts":"2026-07-21T06:36:09.761084Z","caller":"traceutil/trace.go:171","msg":"trace[392969208] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"108.268486ms","start":"2026-07-21T06:36:09.652775Z","end":"2026-07-21T06:36:09.761044Z","steps":["trace[392969208] 'process raft request' (duration: 108.120371ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:18.829435Z","caller":"traceutil/trace.go:171","msg":"trace[316763147] linearizableReadLoop","detail":"{readStateIndex:1456; appliedIndex:1455; }","duration":"226.84319ms","start":"2026-07-21T06:36:18.602558Z","end":"2026-07-21T06:36:18.829402Z","steps":["trace[316763147] 'read index received' (duration: 226.283329ms)","trace[316763147] 'applied index is now lower than readState.Index' (duration: 557.891µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:36:18.830058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.313719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:disruption-controller\" ","response":"range_response_count:1 size:972"} {"level":"info","ts":"2026-07-21T06:36:18.830118Z","caller":"traceutil/trace.go:171","msg":"trace[863422957] range","detail":"{range_begin:/registry/clusterroles/system:controller:disruption-controller; range_end:; response_count:1; response_revision:1395; }","duration":"225.411261ms","start":"2026-07-21T06:36:18.604692Z","end":"2026-07-21T06:36:18.830103Z","steps":["trace[863422957] 'agreement among raft nodes before linearized reading' (duration: 225.302609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:18.830131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.175652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-21T06:36:18.83015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.580286ms","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-21T06:36:18.830177Z","caller":"traceutil/trace.go:171","msg":"trace[1935930974] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1395; }","duration":"218.249635ms","start":"2026-07-21T06:36:18.611915Z","end":"2026-07-21T06:36:18.830165Z","steps":["trace[1935930974] 'agreement among raft nodes before linearized reading' (duration: 218.083211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:18.830188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.510141ms","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-21T06:36:18.830219Z","caller":"traceutil/trace.go:171","msg":"trace[857968556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1395; }","duration":"227.701398ms","start":"2026-07-21T06:36:18.602499Z","end":"2026-07-21T06:36:18.830201Z","steps":["trace[857968556] 'agreement among raft nodes before linearized reading' (duration: 227.501324ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:18.830238Z","caller":"traceutil/trace.go:171","msg":"trace[1671611803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1395; }","duration":"221.598033ms","start":"2026-07-21T06:36:18.608624Z","end":"2026-07-21T06:36:18.830222Z","steps":["trace[1671611803] 'agreement among raft nodes before linearized reading' (duration: 221.44505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:18.830233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.632522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/local-path-provisioner\" ","response":"range_response_count:1 size:1081"} {"level":"info","ts":"2026-07-21T06:36:18.830305Z","caller":"traceutil/trace.go:171","msg":"trace[253452853] range","detail":"{range_begin:/registry/clusterrolebindings/local-path-provisioner; range_end:; response_count:1; response_revision:1395; }","duration":"173.740754ms","start":"2026-07-21T06:36:18.656543Z","end":"2026-07-21T06:36:18.830283Z","steps":["trace[253452853] 'agreement among raft nodes before linearized reading' (duration: 173.500679ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:18.829824Z","caller":"traceutil/trace.go:171","msg":"trace[1681374043] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"242.50374ms","start":"2026-07-21T06:36:18.5873Z","end":"2026-07-21T06:36:18.829803Z","steps":["trace[1681374043] 'process raft request' (duration: 241.52675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:18.830345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.159575ms","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-21T06:36:18.830876Z","caller":"traceutil/trace.go:171","msg":"trace[342680473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1395; }","duration":"223.733867ms","start":"2026-07-21T06:36:18.607114Z","end":"2026-07-21T06:36:18.830848Z","steps":["trace[342680473] 'agreement among raft nodes before linearized reading' (duration: 223.048473ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:20.634986Z","caller":"traceutil/trace.go:171","msg":"trace[399167696] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"110.781003ms","start":"2026-07-21T06:36:20.524174Z","end":"2026-07-21T06:36:20.634955Z","steps":["trace[399167696] 'process raft request' (duration: 110.527637ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:28.802455Z","caller":"traceutil/trace.go:171","msg":"trace[1201902560] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"142.808993ms","start":"2026-07-21T06:36:28.659614Z","end":"2026-07-21T06:36:28.802423Z","steps":["trace[1201902560] 'process raft request' (duration: 142.646779ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:29.2165Z","caller":"traceutil/trace.go:171","msg":"trace[1509086442] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"290.421181ms","start":"2026-07-21T06:36:28.926055Z","end":"2026-07-21T06:36:29.216476Z","steps":["trace[1509086442] 'process raft request' (duration: 287.670512ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:29.216628Z","caller":"traceutil/trace.go:171","msg":"trace[1646634054] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"290.001142ms","start":"2026-07-21T06:36:28.926615Z","end":"2026-07-21T06:36:29.216616Z","steps":["trace[1646634054] 'process raft request' (duration: 289.815348ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:29.216895Z","caller":"traceutil/trace.go:171","msg":"trace[1733750762] linearizableReadLoop","detail":"{readStateIndex:1495; appliedIndex:1492; }","duration":"286.495726ms","start":"2026-07-21T06:36:28.930357Z","end":"2026-07-21T06:36:29.216852Z","steps":["trace[1733750762] 'read index received' (duration: 23.945237ms)","trace[1733750762] 'applied index is now lower than readState.Index' (duration: 262.544819ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:36:29.216943Z","caller":"traceutil/trace.go:171","msg":"trace[1656720732] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"290.101404ms","start":"2026-07-21T06:36:28.926826Z","end":"2026-07-21T06:36:29.216928Z","steps":["trace[1656720732] 'process raft request' (duration: 289.756287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:29.21713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.766792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" ","response":"range_response_count:1 size:1111"} {"level":"warn","ts":"2026-07-21T06:36:29.217134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.381318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/kube-node-lease/\" range_end:\"/registry/cronjobs/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:36:29.217196Z","caller":"traceutil/trace.go:171","msg":"trace[1557203559] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager; range_end:; response_count:1; response_revision:1432; }","duration":"286.845124ms","start":"2026-07-21T06:36:28.930322Z","end":"2026-07-21T06:36:29.217167Z","steps":["trace[1557203559] 'agreement among raft nodes before linearized reading' (duration: 286.696871ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:29.21721Z","caller":"traceutil/trace.go:171","msg":"trace[1672820485] range","detail":"{range_begin:/registry/cronjobs/kube-node-lease/; range_end:/registry/cronjobs/kube-node-lease0; response_count:0; response_revision:1432; }","duration":"207.486121ms","start":"2026-07-21T06:36:29.009708Z","end":"2026-07-21T06:36:29.217194Z","steps":["trace[1672820485] 'agreement among raft nodes before linearized reading' (duration: 207.366078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:29.217226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.873209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/envoy-gateway-system/\" range_end:\"/registry/services/specs/envoy-gateway-system0\" limit:500 ","response":"range_response_count:1 size:1910"} {"level":"info","ts":"2026-07-21T06:36:29.21727Z","caller":"traceutil/trace.go:171","msg":"trace[416294575] range","detail":"{range_begin:/registry/services/specs/envoy-gateway-system/; range_end:/registry/services/specs/envoy-gateway-system0; response_count:1; response_revision:1432; }","duration":"270.94126ms","start":"2026-07-21T06:36:28.946314Z","end":"2026-07-21T06:36:29.217256Z","steps":["trace[416294575] 'agreement among raft nodes before linearized reading' (duration: 270.742006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:29.217553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.823741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding\" ","response":"range_response_count:1 size:1146"} {"level":"info","ts":"2026-07-21T06:36:29.217616Z","caller":"traceutil/trace.go:171","msg":"trace[1070323042] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-leader-election-rolebinding; range_end:; response_count:1; response_revision:1432; }","duration":"209.914774ms","start":"2026-07-21T06:36:29.007689Z","end":"2026-07-21T06:36:29.217604Z","steps":["trace[1070323042] 'agreement among raft nodes before linearized reading' (duration: 209.786271ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:35.34321Z","caller":"traceutil/trace.go:171","msg":"trace[2093876748] linearizableReadLoop","detail":"{readStateIndex:1515; appliedIndex:1514; }","duration":"103.324276ms","start":"2026-07-21T06:36:35.239867Z","end":"2026-07-21T06:36:35.343191Z","steps":["trace[2093876748] 'read index received' (duration: 103.137482ms)","trace[2093876748] 'applied index is now lower than readState.Index' (duration: 186.134µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:36:35.343277Z","caller":"traceutil/trace.go:171","msg":"trace[1810717966] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"104.187946ms","start":"2026-07-21T06:36:35.23907Z","end":"2026-07-21T06:36:35.343258Z","steps":["trace[1810717966] 'process raft request' (duration: 104.000672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:35.3434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.252705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-21T06:36:35.34343Z","caller":"traceutil/trace.go:171","msg":"trace[1154388809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1451; }","duration":"103.332417ms","start":"2026-07-21T06:36:35.24009Z","end":"2026-07-21T06:36:35.343423Z","steps":["trace[1154388809] 'agreement among raft nodes before linearized reading' (duration: 103.202534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:35.343451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.559322ms","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-21T06:36:35.343554Z","caller":"traceutil/trace.go:171","msg":"trace[1279263209] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1451; }","duration":"103.711695ms","start":"2026-07-21T06:36:35.23982Z","end":"2026-07-21T06:36:35.343532Z","steps":["trace[1279263209] 'agreement among raft nodes before linearized reading' (duration: 103.442449ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:35.53757Z","caller":"traceutil/trace.go:171","msg":"trace[894726356] linearizableReadLoop","detail":"{readStateIndex:1517; appliedIndex:1515; }","duration":"119.306163ms","start":"2026-07-21T06:36:35.418241Z","end":"2026-07-21T06:36:35.537547Z","steps":["trace[894726356] 'read index received' (duration: 51.732054ms)","trace[894726356] 'applied index is now lower than readState.Index' (duration: 67.573139ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:36:35.537759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.507248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kubeadm:nodes-kubeadm-config\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-21T06:36:35.537814Z","caller":"traceutil/trace.go:171","msg":"trace[1320116979] range","detail":"{range_begin:/registry/roles/kube-system/kubeadm:nodes-kubeadm-config; range_end:; response_count:1; response_revision:1453; }","duration":"119.60162ms","start":"2026-07-21T06:36:35.4182Z","end":"2026-07-21T06:36:35.537802Z","steps":["trace[1320116979] 'agreement among raft nodes before linearized reading' (duration: 119.490957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:35.537812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.334693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system::leader-locking-kube-controller-manager\" ","response":"range_response_count:1 size:688"} {"level":"info","ts":"2026-07-21T06:36:35.537881Z","caller":"traceutil/trace.go:171","msg":"trace[1928483132] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"189.141432ms","start":"2026-07-21T06:36:35.348725Z","end":"2026-07-21T06:36:35.537866Z","steps":["trace[1928483132] 'process raft request' (duration: 188.777034ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:35.537899Z","caller":"traceutil/trace.go:171","msg":"trace[1969638025] range","detail":"{range_begin:/registry/roles/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:1; response_revision:1453; }","duration":"106.456636ms","start":"2026-07-21T06:36:35.431421Z","end":"2026-07-21T06:36:35.537878Z","steps":["trace[1969638025] 'agreement among raft nodes before linearized reading' (duration: 106.306463ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:35.537888Z","caller":"traceutil/trace.go:171","msg":"trace[1155420836] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"189.859009ms","start":"2026-07-21T06:36:35.347983Z","end":"2026-07-21T06:36:35.537842Z","steps":["trace[1155420836] 'process raft request' (duration: 122.001774ms)","trace[1155420836] 'compare' (duration: 67.171769ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:36:37.231133Z","caller":"traceutil/trace.go:171","msg":"trace[1121561298] linearizableReadLoop","detail":"{readStateIndex:1522; appliedIndex:1521; }","duration":"148.400653ms","start":"2026-07-21T06:36:37.082704Z","end":"2026-07-21T06:36:37.231105Z","steps":["trace[1121561298] 'read index received' (duration: 91.949781ms)","trace[1121561298] 'applied index is now lower than readState.Index' (duration: 56.449512ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:36:37.231243Z","caller":"traceutil/trace.go:171","msg":"trace[1347961558] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"221.115961ms","start":"2026-07-21T06:36:37.010089Z","end":"2026-07-21T06:36:37.231205Z","steps":["trace[1347961558] 'process raft request' (duration: 164.565347ms)","trace[1347961558] 'compare' (duration: 56.202716ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:36:37.23134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.379427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-07-21T06:36:37.231643Z","caller":"traceutil/trace.go:171","msg":"trace[1848663239] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:1457; }","duration":"146.702644ms","start":"2026-07-21T06:36:37.084921Z","end":"2026-07-21T06:36:37.231624Z","steps":["trace[1848663239] 'agreement among raft nodes before linearized reading' (duration: 146.348916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:37.231834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.111819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-07-21T06:36:37.231935Z","caller":"traceutil/trace.go:171","msg":"trace[246363630] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:1457; }","duration":"149.227952ms","start":"2026-07-21T06:36:37.082672Z","end":"2026-07-21T06:36:37.2319Z","steps":["trace[246363630] 'agreement among raft nodes before linearized reading' (duration: 148.576687ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:36:45.766711Z","caller":"traceutil/trace.go:171","msg":"trace[827245949] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"144.273071ms","start":"2026-07-21T06:36:45.622398Z","end":"2026-07-21T06:36:45.766671Z","steps":["trace[827245949] 'process raft request' (duration: 50.978718ms)","trace[827245949] 'compare' (duration: 93.068968ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:36:46.23125Z","caller":"traceutil/trace.go:171","msg":"trace[358443893] linearizableReadLoop","detail":"{readStateIndex:1552; appliedIndex:1550; }","duration":"577.493912ms","start":"2026-07-21T06:36:45.653726Z","end":"2026-07-21T06:36:46.23122Z","steps":["trace[358443893] 'read index received' (duration: 19.663018ms)","trace[358443893] 'applied index is now lower than readState.Index' (duration: 557.811683ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:36:46.231336Z","caller":"traceutil/trace.go:171","msg":"trace[1427974621] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"607.846ms","start":"2026-07-21T06:36:45.623459Z","end":"2026-07-21T06:36:46.231305Z","steps":["trace[1427974621] 'process raft request' (duration: 561.541986ms)","trace[1427974621] 'compare' (duration: 45.878264ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:36:46.231466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:45.623445Z","time spent":"607.950792ms","remote":"127.0.0.1:41836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:36:46.231696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.955452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-sh5jh\" ","response":"range_response_count:1 size:4908"} {"level":"info","ts":"2026-07-21T06:36:46.231788Z","caller":"traceutil/trace.go:171","msg":"trace[850379457] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-sh5jh; range_end:; response_count:1; response_revision:1486; }","duration":"578.072545ms","start":"2026-07-21T06:36:45.653668Z","end":"2026-07-21T06:36:46.23174Z","steps":["trace[850379457] 'agreement among raft nodes before linearized reading' (duration: 577.674256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:46.231842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:36:45.653636Z","time spent":"578.191308ms","remote":"127.0.0.1:41734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4931,"request content":"key:\"/registry/pods/kube-system/coredns-67659f764b-sh5jh\" "} {"level":"warn","ts":"2026-07-21T06:36:46.588635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.321032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:36:46.5887Z","caller":"traceutil/trace.go:171","msg":"trace[1622901480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1486; }","duration":"251.393983ms","start":"2026-07-21T06:36:46.337291Z","end":"2026-07-21T06:36:46.588685Z","steps":["trace[1622901480] 'range keys from in-memory index tree' (duration: 251.25472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:46.588717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.749031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-zd822\" ","response":"range_response_count:1 size:4908"} {"level":"info","ts":"2026-07-21T06:36:46.588791Z","caller":"traceutil/trace.go:171","msg":"trace[653175293] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-zd822; range_end:; response_count:1; response_revision:1486; }","duration":"192.832183ms","start":"2026-07-21T06:36:46.39594Z","end":"2026-07-21T06:36:46.588773Z","steps":["trace[653175293] 'range keys from in-memory index tree' (duration: 192.581137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:36:46.589203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.977724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T06:36:46.58925Z","caller":"traceutil/trace.go:171","msg":"trace[1747707694] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:1486; }","duration":"248.066686ms","start":"2026-07-21T06:36:46.341172Z","end":"2026-07-21T06:36:46.589239Z","steps":["trace[1747707694] 'range keys from in-memory index tree' (duration: 247.752269ms)"],"step_count":1}