{"level":"warn","ts":"2026-05-20T11:22:48.622752Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T11:22:48.622859Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.91:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.91:2380","--initial-cluster=instance=https://199.19.213.91:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.91:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.91:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-20T11:22:48.62293Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T11:22:48.622942Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.91:2380"]} {"level":"info","ts":"2026-05-20T11:22:48.622962Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-20T11:22:48.623688Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"]} {"level":"info","ts":"2026-05-20T11:22:48.623793Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.91:2380"],"listen-peer-urls":["https://199.19.213.91:2380"],"advertise-client-urls":["https://199.19.213.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.91:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-20T11:22:48.628284Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.247911ms"} {"level":"info","ts":"2026-05-20T11:22:48.63979Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2c24222821500990","cluster-id":"f38faa6ba493daad"} {"level":"info","ts":"2026-05-20T11:22:48.639953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=()"} {"level":"info","ts":"2026-05-20T11:22:48.639979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became follower at term 0"} {"level":"info","ts":"2026-05-20T11:22:48.639988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2c24222821500990 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-20T11:22:48.640002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became follower at term 1"} {"level":"info","ts":"2026-05-20T11:22:48.640034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=(3180704792583342480)"} {"level":"warn","ts":"2026-05-20T11:22:48.662917Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-20T11:22:48.664967Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-20T11:22:48.666507Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-20T11:22:48.669343Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2c24222821500990","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-20T11:22:48.669501Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-20T11:22:48.669554Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-20T11:22:48.669565Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-20T11:22:48.669892Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2c24222821500990","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-20T11:22:48.675529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 switched to configuration voters=(3180704792583342480)"} {"level":"info","ts":"2026-05-20T11:22:48.675725Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f38faa6ba493daad","local-member-id":"2c24222821500990","added-peer-id":"2c24222821500990","added-peer-peer-urls":["https://199.19.213.91:2380"]} {"level":"info","ts":"2026-05-20T11:22:48.676078Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-20T11:22:48.676218Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.91:2380"} {"level":"info","ts":"2026-05-20T11:22:48.676258Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.91:2380"} {"level":"info","ts":"2026-05-20T11:22:48.676463Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2c24222821500990","initial-advertise-peer-urls":["https://199.19.213.91:2380"],"listen-peer-urls":["https://199.19.213.91:2380"],"advertise-client-urls":["https://199.19.213.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-20T11:22:48.676507Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-20T11:22:49.140601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 is starting a new election at term 1"} {"level":"info","ts":"2026-05-20T11:22:49.140656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-20T11:22:49.140678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 received MsgPreVoteResp from 2c24222821500990 at term 1"} {"level":"info","ts":"2026-05-20T11:22:49.140696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became candidate at term 2"} {"level":"info","ts":"2026-05-20T11:22:49.140714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 received MsgVoteResp from 2c24222821500990 at term 2"} {"level":"info","ts":"2026-05-20T11:22:49.140749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2c24222821500990 became leader at term 2"} {"level":"info","ts":"2026-05-20T11:22:49.140764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2c24222821500990 elected leader 2c24222821500990 at term 2"} {"level":"info","ts":"2026-05-20T11:22:49.218253Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T11:22:49.228011Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T11:22:49.228017Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2c24222821500990","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.91:2379]}","request-path":"/0/members/2c24222821500990/attributes","cluster-id":"f38faa6ba493daad","publish-timeout":"7s"} {"level":"info","ts":"2026-05-20T11:22:49.228122Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T11:22:49.228727Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-20T11:22:49.228759Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-20T11:22:49.23075Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-20T11:22:49.236221Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f38faa6ba493daad","local-member-id":"2c24222821500990","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T11:22:49.236393Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T11:22:49.236436Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T11:22:49.23668Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.91:2379"} {"level":"info","ts":"2026-05-20T11:22:55.42364Z","caller":"traceutil/trace.go:171","msg":"trace[251918870] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:273; }","duration":"121.925683ms","start":"2026-05-20T11:22:55.301686Z","end":"2026-05-20T11:22:55.423612Z","steps":["trace[251918870] 'read index received' (duration: 121.855833ms)","trace[251918870] 'applied index is now lower than readState.Index' (duration: 68.6µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:22:55.423651Z","caller":"traceutil/trace.go:171","msg":"trace[809805817] transaction","detail":"{read_only:false; number_of_response:0; response_revision:264; }","duration":"123.840376ms","start":"2026-05-20T11:22:55.299792Z","end":"2026-05-20T11:22:55.423633Z","steps":["trace[809805817] 'process raft request' (duration: 123.716156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:22:55.423839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.095974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5892"} {"level":"info","ts":"2026-05-20T11:22:55.423917Z","caller":"traceutil/trace.go:171","msg":"trace[718856123] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:264; }","duration":"122.244595ms","start":"2026-05-20T11:22:55.301655Z","end":"2026-05-20T11:22:55.4239Z","steps":["trace[718856123] 'agreement among raft nodes before linearized reading' (duration: 122.039224ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:22:55.589415Z","caller":"traceutil/trace.go:171","msg":"trace[1760577983] transaction","detail":"{read_only:false; number_of_response:0; response_revision:264; }","duration":"285.695196ms","start":"2026-05-20T11:22:55.303687Z","end":"2026-05-20T11:22:55.589382Z","steps":["trace[1760577983] 'process raft request' (duration: 285.551175ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:22:55.589441Z","caller":"traceutil/trace.go:171","msg":"trace[530014177] linearizableReadLoop","detail":"{readStateIndex:278; appliedIndex:274; }","duration":"164.654449ms","start":"2026-05-20T11:22:55.424757Z","end":"2026-05-20T11:22:55.589412Z","steps":["trace[530014177] 'read index received' (duration: 164.396667ms)","trace[530014177] 'applied index is now lower than readState.Index' (duration: 256.742µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:22:55.589425Z","caller":"traceutil/trace.go:171","msg":"trace[973494581] transaction","detail":"{read_only:false; number_of_response:0; response_revision:264; }","duration":"287.527909ms","start":"2026-05-20T11:22:55.301838Z","end":"2026-05-20T11:22:55.589366Z","steps":["trace[973494581] 'process raft request' (duration: 287.307267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:22:55.589586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.80614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-05-20T11:22:55.589623Z","caller":"traceutil/trace.go:171","msg":"trace[285577432] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:265; }","duration":"164.8977ms","start":"2026-05-20T11:22:55.424715Z","end":"2026-05-20T11:22:55.589612Z","steps":["trace[285577432] 'agreement among raft nodes before linearized reading' (duration: 164.79467ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:22:55.58969Z","caller":"traceutil/trace.go:171","msg":"trace[588183646] transaction","detail":"{read_only:false; number_of_response:0; response_revision:264; }","duration":"285.724296ms","start":"2026-05-20T11:22:55.303944Z","end":"2026-05-20T11:22:55.589668Z","steps":["trace[588183646] 'process raft request' (duration: 285.327893ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:22:55.589831Z","caller":"traceutil/trace.go:171","msg":"trace[1696890915] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"250.540614ms","start":"2026-05-20T11:22:55.339269Z","end":"2026-05-20T11:22:55.589809Z","steps":["trace[1696890915] 'process raft request' (duration: 250.03045ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:22:55.633308Z","caller":"traceutil/trace.go:171","msg":"trace[1656806207] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"191.419871ms","start":"2026-05-20T11:22:55.441837Z","end":"2026-05-20T11:22:55.633257Z","steps":["trace[1656806207] 'process raft request' (duration: 191.22151ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:22:55.798457Z","caller":"traceutil/trace.go:171","msg":"trace[40946069] linearizableReadLoop","detail":"{readStateIndex:281; appliedIndex:279; }","duration":"144.396595ms","start":"2026-05-20T11:22:55.654035Z","end":"2026-05-20T11:22:55.798431Z","steps":["trace[40946069] 'read index received' (duration: 11.389742ms)","trace[40946069] 'applied index is now lower than readState.Index' (duration: 133.005753ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:22:55.798574Z","caller":"traceutil/trace.go:171","msg":"trace[1976373253] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"152.981086ms","start":"2026-05-20T11:22:55.645564Z","end":"2026-05-20T11:22:55.798545Z","steps":["trace[1976373253] 'process raft request' (duration: 94.596498ms)","trace[1976373253] 'compare' (duration: 58.096806ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:22:55.798632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.578666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-20T11:22:55.798687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.170984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T11:22:55.798753Z","caller":"traceutil/trace.go:171","msg":"trace[365545083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:268; }","duration":"140.278114ms","start":"2026-05-20T11:22:55.658459Z","end":"2026-05-20T11:22:55.798737Z","steps":["trace[365545083] 'agreement among raft nodes before linearized reading' (duration: 140.145223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:22:55.798762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.528187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-20T11:22:55.798794Z","caller":"traceutil/trace.go:171","msg":"trace[1375746073] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:268; }","duration":"129.614357ms","start":"2026-05-20T11:22:55.669173Z","end":"2026-05-20T11:22:55.798788Z","steps":["trace[1375746073] 'agreement among raft nodes before linearized reading' (duration: 129.559897ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:22:55.798704Z","caller":"traceutil/trace.go:171","msg":"trace[1040118036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:268; }","duration":"144.705537ms","start":"2026-05-20T11:22:55.653981Z","end":"2026-05-20T11:22:55.798686Z","steps":["trace[1040118036] 'agreement among raft nodes before linearized reading' (duration: 144.579476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:22:56.032586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.525299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:22:56.032691Z","caller":"traceutil/trace.go:171","msg":"trace[35761343] linearizableReadLoop","detail":"{readStateIndex:286; appliedIndex:285; }","duration":"144.572373ms","start":"2026-05-20T11:22:55.888102Z","end":"2026-05-20T11:22:56.032675Z","steps":["trace[35761343] 'read index received' (duration: 33.243568ms)","trace[35761343] 'applied index is now lower than readState.Index' (duration: 111.327685ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:22:56.032748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.644004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:22:56.03278Z","caller":"traceutil/trace.go:171","msg":"trace[1398796750] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:273; }","duration":"144.712344ms","start":"2026-05-20T11:22:55.888058Z","end":"2026-05-20T11:22:56.03277Z","steps":["trace[1398796750] 'agreement among raft nodes before linearized reading' (duration: 144.660113ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:22:56.032952Z","caller":"traceutil/trace.go:171","msg":"trace[1991302074] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"181.280826ms","start":"2026-05-20T11:22:55.851632Z","end":"2026-05-20T11:22:56.032913Z","steps":["trace[1991302074] 'process raft request' (duration: 69.78264ms)","trace[1991302074] 'compare' (duration: 110.402528ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:22:56.278057Z","caller":"traceutil/trace.go:171","msg":"trace[571278564] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"203.547181ms","start":"2026-05-20T11:22:56.074472Z","end":"2026-05-20T11:22:56.278019Z","steps":["trace[571278564] 'process raft request' (duration: 119.28397ms)","trace[571278564] 'compare' (duration: 83.915789ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:22:56.27814Z","caller":"traceutil/trace.go:171","msg":"trace[1571478869] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"189.17658ms","start":"2026-05-20T11:22:56.088932Z","end":"2026-05-20T11:22:56.278108Z","steps":["trace[1571478869] 'process raft request' (duration: 188.990918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:22:59.784468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.67102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:22:59.784598Z","caller":"traceutil/trace.go:171","msg":"trace[54270572] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:317; }","duration":"496.348448ms","start":"2026-05-20T11:22:59.288229Z","end":"2026-05-20T11:22:59.784577Z","steps":["trace[54270572] 'read index received' (duration: 28.910316ms)","trace[54270572] 'applied index is now lower than readState.Index' (duration: 467.436712ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:22:59.784858Z","caller":"traceutil/trace.go:171","msg":"trace[716715625] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"532.692726ms","start":"2026-05-20T11:22:59.252132Z","end":"2026-05-20T11:22:59.784824Z","steps":["trace[716715625] 'process raft request' (duration: 128.563188ms)","trace[716715625] 'compare' (duration: 403.44074ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:22:59.785098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:22:59.252098Z","time spent":"532.905495ms","remote":"127.0.0.1:49548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T11:22:59.785185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.204179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-20T11:22:59.785424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.000827ms","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-05-20T11:22:59.785442Z","caller":"traceutil/trace.go:171","msg":"trace[610349157] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:306; }","duration":"447.47708ms","start":"2026-05-20T11:22:59.337942Z","end":"2026-05-20T11:22:59.785419Z","steps":["trace[610349157] 'agreement among raft nodes before linearized reading' (duration: 447.153247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:22:59.785452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.988702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-05-20T11:22:59.785435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.75048ms","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-05-20T11:22:59.785481Z","caller":"traceutil/trace.go:171","msg":"trace[783828989] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:306; }","duration":"397.056289ms","start":"2026-05-20T11:22:59.38839Z","end":"2026-05-20T11:22:59.785446Z","steps":["trace[783828989] 'agreement among raft nodes before linearized reading' (duration: 397.010428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:22:59.78549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.58869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-05-20T11:22:59.785527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:22:59.388374Z","time spent":"397.145893ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-05-20T11:22:59.785515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:22:59.337927Z","time spent":"447.571325ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-05-20T11:22:59.785572Z","caller":"traceutil/trace.go:171","msg":"trace[409050362] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:306; }","duration":"296.855104ms","start":"2026-05-20T11:22:59.488658Z","end":"2026-05-20T11:22:59.785513Z","steps":["trace[409050362] 'agreement among raft nodes before linearized reading' (duration: 296.622315ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:22:59.785592Z","caller":"traceutil/trace.go:171","msg":"trace[264441759] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:306; }","duration":"247.762717ms","start":"2026-05-20T11:22:59.537794Z","end":"2026-05-20T11:22:59.785556Z","steps":["trace[264441759] 'agreement among raft nodes before linearized reading' (duration: 247.466325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:22:59.785343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.040031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-20T11:22:59.785638Z","caller":"traceutil/trace.go:171","msg":"trace[477191265] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:306; }","duration":"147.393815ms","start":"2026-05-20T11:22:59.638233Z","end":"2026-05-20T11:22:59.785627Z","steps":["trace[477191265] 'agreement among raft nodes before linearized reading' (duration: 147.041941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:22:59.785355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.798405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-20T11:22:59.785706Z","caller":"traceutil/trace.go:171","msg":"trace[76620879] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:306; }","duration":"348.188832ms","start":"2026-05-20T11:22:59.43751Z","end":"2026-05-20T11:22:59.785699Z","steps":["trace[76620879] 'agreement among raft nodes before linearized reading' (duration: 347.751974ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:22:59.785519Z","caller":"traceutil/trace.go:171","msg":"trace[1489278786] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:306; }","duration":"196.080246ms","start":"2026-05-20T11:22:59.58942Z","end":"2026-05-20T11:22:59.7855Z","steps":["trace[1489278786] 'agreement among raft nodes before linearized reading' (duration: 195.990662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:22:59.78573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:22:59.437489Z","time spent":"348.231754ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-05-20T11:22:59.791635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.374213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-05-20T11:22:59.791762Z","caller":"traceutil/trace.go:171","msg":"trace[94037171] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:306; }","duration":"503.579502ms","start":"2026-05-20T11:22:59.288154Z","end":"2026-05-20T11:22:59.791734Z","steps":["trace[94037171] 'agreement among raft nodes before linearized reading' (duration: 496.712933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:22:59.791825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:22:59.288128Z","time spent":"503.684646ms","remote":"127.0.0.1:49628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-05-20T11:23:00.044519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.168996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:23:00.044909Z","caller":"traceutil/trace.go:171","msg":"trace[1191937750] linearizableReadLoop","detail":"{readStateIndex:325; appliedIndex:319; }","duration":"204.647335ms","start":"2026-05-20T11:22:59.840241Z","end":"2026-05-20T11:23:00.044888Z","steps":["trace[1191937750] 'read index received' (duration: 90.139395ms)","trace[1191937750] 'applied index is now lower than readState.Index' (duration: 114.50643ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:23:00.045027Z","caller":"traceutil/trace.go:171","msg":"trace[65286447] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"248.889095ms","start":"2026-05-20T11:22:59.796125Z","end":"2026-05-20T11:23:00.045014Z","steps":["trace[65286447] 'process raft request' (duration: 248.659036ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:00.045033Z","caller":"traceutil/trace.go:171","msg":"trace[459677949] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"248.967519ms","start":"2026-05-20T11:22:59.79604Z","end":"2026-05-20T11:23:00.045008Z","steps":["trace[459677949] 'process raft request' (duration: 248.637465ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:00.045198Z","caller":"traceutil/trace.go:171","msg":"trace[1428078925] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"249.25943ms","start":"2026-05-20T11:22:59.795911Z","end":"2026-05-20T11:23:00.045171Z","steps":["trace[1428078925] 'process raft request' (duration: 134.382596ms)","trace[1428078925] 'compare' (duration: 114.03982ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:23:00.045293Z","caller":"traceutil/trace.go:171","msg":"trace[812494479] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"249.152045ms","start":"2026-05-20T11:22:59.796128Z","end":"2026-05-20T11:23:00.04528Z","steps":["trace[812494479] 'process raft request' (duration: 248.612074ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:00.045462Z","caller":"traceutil/trace.go:171","msg":"trace[388442629] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"249.440897ms","start":"2026-05-20T11:22:59.79601Z","end":"2026-05-20T11:23:00.045451Z","steps":["trace[388442629] 'process raft request' (duration: 248.612303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:00.045453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.987758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-20T11:23:00.045641Z","caller":"traceutil/trace.go:171","msg":"trace[1422280333] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:312; }","duration":"205.118564ms","start":"2026-05-20T11:22:59.840441Z","end":"2026-05-20T11:23:00.045559Z","steps":["trace[1422280333] 'agreement among raft nodes before linearized reading' (duration: 204.934906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:00.045703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.467588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T11:23:00.045735Z","caller":"traceutil/trace.go:171","msg":"trace[412734311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:312; }","duration":"205.551152ms","start":"2026-05-20T11:22:59.840175Z","end":"2026-05-20T11:23:00.045727Z","steps":["trace[412734311] 'agreement among raft nodes before linearized reading' (duration: 205.4986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:00.045656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.364978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-05-20T11:23:00.045842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.078618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T11:23:00.045879Z","caller":"traceutil/trace.go:171","msg":"trace[751555975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:312; }","duration":"198.156121ms","start":"2026-05-20T11:22:59.847713Z","end":"2026-05-20T11:23:00.045869Z","steps":["trace[751555975] 'agreement among raft nodes before linearized reading' (duration: 198.073448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:00.045936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.521098ms","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-05-20T11:23:00.045997Z","caller":"traceutil/trace.go:171","msg":"trace[1398757734] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:312; }","duration":"157.630632ms","start":"2026-05-20T11:22:59.888352Z","end":"2026-05-20T11:23:00.045982Z","steps":["trace[1398757734] 'agreement among raft nodes before linearized reading' (duration: 157.525488ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:00.045644Z","caller":"traceutil/trace.go:171","msg":"trace[1905628303] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"248.336082ms","start":"2026-05-20T11:22:59.797293Z","end":"2026-05-20T11:23:00.045629Z","steps":["trace[1905628303] 'process raft request' (duration: 247.546231ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:00.046327Z","caller":"traceutil/trace.go:171","msg":"trace[1105937463] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:312; }","duration":"106.616547ms","start":"2026-05-20T11:22:59.939241Z","end":"2026-05-20T11:23:00.045858Z","steps":["trace[1105937463] 'agreement among raft nodes before linearized reading' (duration: 106.355007ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:01.478766Z","caller":"traceutil/trace.go:171","msg":"trace[2087205844] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"154.756977ms","start":"2026-05-20T11:23:01.323967Z","end":"2026-05-20T11:23:01.478724Z","steps":["trace[2087205844] 'process raft request' (duration: 154.431134ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:02.254982Z","caller":"traceutil/trace.go:171","msg":"trace[138734993] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"164.409424ms","start":"2026-05-20T11:23:02.090545Z","end":"2026-05-20T11:23:02.254954Z","steps":["trace[138734993] 'process raft request' (duration: 164.209857ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:02.265642Z","caller":"traceutil/trace.go:171","msg":"trace[1050921876] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"174.936748ms","start":"2026-05-20T11:23:02.090689Z","end":"2026-05-20T11:23:02.265625Z","steps":["trace[1050921876] 'process raft request' (duration: 174.849404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:02.554312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.193946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.91\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-20T11:23:02.554416Z","caller":"traceutil/trace.go:171","msg":"trace[200197537] range","detail":"{range_begin:/registry/masterleases/199.19.213.91; range_end:; response_count:1; response_revision:356; }","duration":"124.345581ms","start":"2026-05-20T11:23:02.430042Z","end":"2026-05-20T11:23:02.554388Z","steps":["trace[200197537] 'range keys from in-memory index tree' (duration: 124.072221ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:04.093061Z","caller":"traceutil/trace.go:171","msg":"trace[1080671505] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"167.645523ms","start":"2026-05-20T11:23:03.925391Z","end":"2026-05-20T11:23:04.093036Z","steps":["trace[1080671505] 'process raft request' (duration: 167.476866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:04.520791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.974445ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:23:04.520917Z","caller":"traceutil/trace.go:171","msg":"trace[456441145] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:375; }","duration":"196.972935ms","start":"2026-05-20T11:23:04.323921Z","end":"2026-05-20T11:23:04.520894Z","steps":["trace[456441145] 'read index received' (duration: 5.696098ms)","trace[456441145] 'applied index is now lower than readState.Index' (duration: 191.275777ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:23:04.520996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.11564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-05-20T11:23:04.520979Z","caller":"traceutil/trace.go:171","msg":"trace[1819782511] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"231.80709ms","start":"2026-05-20T11:23:04.289132Z","end":"2026-05-20T11:23:04.520939Z","steps":["trace[1819782511] 'process raft request' (duration: 113.603497ms)","trace[1819782511] 'compare' (duration: 117.85085ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:23:04.52103Z","caller":"traceutil/trace.go:171","msg":"trace[112670543] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:362; }","duration":"197.188053ms","start":"2026-05-20T11:23:04.323832Z","end":"2026-05-20T11:23:04.52102Z","steps":["trace[112670543] 'agreement among raft nodes before linearized reading' (duration: 197.117191ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:04.820131Z","caller":"traceutil/trace.go:171","msg":"trace[1409332307] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"192.595598ms","start":"2026-05-20T11:23:04.627503Z","end":"2026-05-20T11:23:04.820099Z","steps":["trace[1409332307] 'process raft request' (duration: 169.564335ms)","trace[1409332307] 'compare' (duration: 22.712831ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:23:07.567964Z","caller":"traceutil/trace.go:171","msg":"trace[1773208975] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"187.734116ms","start":"2026-05-20T11:23:07.380208Z","end":"2026-05-20T11:23:07.567942Z","steps":["trace[1773208975] 'process raft request' (duration: 187.606041ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:09.484455Z","caller":"traceutil/trace.go:171","msg":"trace[1005691140] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"262.379371ms","start":"2026-05-20T11:23:09.22205Z","end":"2026-05-20T11:23:09.48443Z","steps":["trace[1005691140] 'process raft request' (duration: 262.207105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:09.862254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.524929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:23:09.862469Z","caller":"traceutil/trace.go:171","msg":"trace[1216641586] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"235.111014ms","start":"2026-05-20T11:23:09.627337Z","end":"2026-05-20T11:23:09.862448Z","steps":["trace[1216641586] 'process raft request' (duration: 235.035971ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:09.862603Z","caller":"traceutil/trace.go:171","msg":"trace[1611429536] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:405; }","duration":"235.916083ms","start":"2026-05-20T11:23:09.626668Z","end":"2026-05-20T11:23:09.862584Z","steps":["trace[1611429536] 'read index received' (duration: 74.02644ms)","trace[1611429536] 'applied index is now lower than readState.Index' (duration: 161.888173ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:23:09.862829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.684756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-05-20T11:23:09.862857Z","caller":"traceutil/trace.go:171","msg":"trace[1318556756] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:391; }","duration":"232.774028ms","start":"2026-05-20T11:23:09.630075Z","end":"2026-05-20T11:23:09.86285Z","steps":["trace[1318556756] 'agreement among raft nodes before linearized reading' (duration: 232.653244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:09.862842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.163222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-pdcsb\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-05-20T11:23:09.862902Z","caller":"traceutil/trace.go:171","msg":"trace[379225854] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-pdcsb; range_end:; response_count:1; response_revision:391; }","duration":"236.249175ms","start":"2026-05-20T11:23:09.626635Z","end":"2026-05-20T11:23:09.862884Z","steps":["trace[379225854] 'agreement among raft nodes before linearized reading' (duration: 236.075399ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:09.862637Z","caller":"traceutil/trace.go:171","msg":"trace[1765463379] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"243.780678ms","start":"2026-05-20T11:23:09.618801Z","end":"2026-05-20T11:23:09.862582Z","steps":["trace[1765463379] 'process raft request' (duration: 81.829813ms)","trace[1765463379] 'compare' (duration: 161.24699ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:23:10.080733Z","caller":"traceutil/trace.go:171","msg":"trace[258728496] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"192.986378ms","start":"2026-05-20T11:23:09.887721Z","end":"2026-05-20T11:23:10.080708Z","steps":["trace[258728496] 'process raft request' (duration: 188.133604ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:10.08074Z","caller":"traceutil/trace.go:171","msg":"trace[1930141148] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"189.420038ms","start":"2026-05-20T11:23:09.891294Z","end":"2026-05-20T11:23:10.080714Z","steps":["trace[1930141148] 'process raft request' (duration: 189.306504ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:10.081853Z","caller":"traceutil/trace.go:171","msg":"trace[2096309121] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"165.481541ms","start":"2026-05-20T11:23:09.916346Z","end":"2026-05-20T11:23:10.081827Z","steps":["trace[2096309121] 'process raft request' (duration: 165.130728ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:10.082231Z","caller":"traceutil/trace.go:171","msg":"trace[1330053558] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"165.751812ms","start":"2026-05-20T11:23:09.916471Z","end":"2026-05-20T11:23:10.082223Z","steps":["trace[1330053558] 'process raft request' (duration: 165.140189ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:10.082388Z","caller":"traceutil/trace.go:171","msg":"trace[1938649074] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"165.755011ms","start":"2026-05-20T11:23:09.916621Z","end":"2026-05-20T11:23:10.082376Z","steps":["trace[1938649074] 'process raft request' (duration: 165.544563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:12.931781Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224762710147989,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-20T11:23:13.332357Z","caller":"traceutil/trace.go:171","msg":"trace[458027618] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"900.969561ms","start":"2026-05-20T11:23:12.431366Z","end":"2026-05-20T11:23:13.332335Z","steps":["trace[458027618] 'read index received' (duration: 900.764844ms)","trace[458027618] 'applied index is now lower than readState.Index' (duration: 203.757µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:23:13.332479Z","caller":"traceutil/trace.go:171","msg":"trace[846490810] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"965.167899ms","start":"2026-05-20T11:23:12.367292Z","end":"2026-05-20T11:23:13.33246Z","steps":["trace[846490810] 'process raft request' (duration: 964.92085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:13.332572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.19124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.91\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-20T11:23:13.332613Z","caller":"traceutil/trace.go:171","msg":"trace[136657635] range","detail":"{range_begin:/registry/masterleases/199.19.213.91; range_end:; response_count:1; response_revision:414; }","duration":"901.281523ms","start":"2026-05-20T11:23:12.431319Z","end":"2026-05-20T11:23:13.3326Z","steps":["trace[136657635] 'agreement among raft nodes before linearized reading' (duration: 901.161888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:13.332648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:12.431298Z","time spent":"901.341665ms","remote":"127.0.0.1:49500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.91\" "} {"level":"warn","ts":"2026-05-20T11:23:13.332666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:12.36728Z","time spent":"965.316574ms","remote":"127.0.0.1:49708","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-05-20T11:23:13.332836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.407851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-20T11:23:13.333042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.484475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T11:23:13.33308Z","caller":"traceutil/trace.go:171","msg":"trace[1461867622] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:414; }","duration":"417.547988ms","start":"2026-05-20T11:23:12.91552Z","end":"2026-05-20T11:23:13.333068Z","steps":["trace[1461867622] 'agreement among raft nodes before linearized reading' (duration: 417.488586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:13.333147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:12.915474Z","time spent":"417.661112ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-20T11:23:13.333985Z","caller":"traceutil/trace.go:171","msg":"trace[683571825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:414; }","duration":"780.623499ms","start":"2026-05-20T11:23:12.552254Z","end":"2026-05-20T11:23:13.332878Z","steps":["trace[683571825] 'agreement among raft nodes before linearized reading' (duration: 780.406092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:13.334035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:12.552231Z","time spent":"781.792358ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T11:23:13.92095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:13.333753Z","time spent":"587.192347ms","remote":"127.0.0.1:49500","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-20T11:23:13.936469Z","caller":"traceutil/trace.go:171","msg":"trace[1399397341] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:430; }","duration":"428.887307ms","start":"2026-05-20T11:23:13.507553Z","end":"2026-05-20T11:23:13.93644Z","steps":["trace[1399397341] 'read index received' (duration: 374.442443ms)","trace[1399397341] 'applied index is now lower than readState.Index' (duration: 54.443834ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:23:13.936625Z","caller":"traceutil/trace.go:171","msg":"trace[1103758206] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"600.323711ms","start":"2026-05-20T11:23:13.336286Z","end":"2026-05-20T11:23:13.936609Z","steps":["trace[1103758206] 'process raft request' (duration: 599.917147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:13.936929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:13.336273Z","time spent":"600.551198ms","remote":"127.0.0.1:49708","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-05-20T11:23:13.93669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.134375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:23:13.937295Z","caller":"traceutil/trace.go:171","msg":"trace[793851011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"429.723976ms","start":"2026-05-20T11:23:13.507539Z","end":"2026-05-20T11:23:13.937263Z","steps":["trace[793851011] 'agreement among raft nodes before linearized reading' (duration: 429.121034ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:13.936656Z","caller":"traceutil/trace.go:171","msg":"trace[1379355381] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"598.889771ms","start":"2026-05-20T11:23:13.337747Z","end":"2026-05-20T11:23:13.936637Z","steps":["trace[1379355381] 'process raft request' (duration: 598.597721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:13.937512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:13.337727Z","time spent":"599.71552ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T11:23:13.937368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:13.507446Z","time spent":"429.902642ms","remote":"127.0.0.1:49452","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-05-20T11:23:13.957927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.567986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-20T11:23:13.958001Z","caller":"traceutil/trace.go:171","msg":"trace[233861918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:417; }","duration":"322.67914ms","start":"2026-05-20T11:23:13.635304Z","end":"2026-05-20T11:23:13.957983Z","steps":["trace[233861918] 'agreement among raft nodes before linearized reading' (duration: 322.38003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:13.958071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:13.635287Z","time spent":"322.747652ms","remote":"127.0.0.1:49708","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":"info","ts":"2026-05-20T11:23:16.161452Z","caller":"traceutil/trace.go:171","msg":"trace[223650112] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"216.969677ms","start":"2026-05-20T11:23:15.944457Z","end":"2026-05-20T11:23:16.161427Z","steps":["trace[223650112] 'process raft request' (duration: 216.823002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:16.359436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.93144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:23:16.359534Z","caller":"traceutil/trace.go:171","msg":"trace[1726690873] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:437; }","duration":"354.026904ms","start":"2026-05-20T11:23:16.005493Z","end":"2026-05-20T11:23:16.35952Z","steps":["trace[1726690873] 'read index received' (duration: 155.805067ms)","trace[1726690873] 'applied index is now lower than readState.Index' (duration: 198.220997ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:23:16.359622Z","caller":"traceutil/trace.go:171","msg":"trace[715031803] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"415.07471ms","start":"2026-05-20T11:23:15.944535Z","end":"2026-05-20T11:23:16.35961Z","steps":["trace[715031803] 'process raft request' (duration: 293.904553ms)","trace[715031803] 'compare' (duration: 120.838736ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:23:16.359696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:15.944516Z","time spent":"415.136361ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T11:23:16.359825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.340394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-20T11:23:16.359843Z","caller":"traceutil/trace.go:171","msg":"trace[865637040] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"354.400016ms","start":"2026-05-20T11:23:16.005436Z","end":"2026-05-20T11:23:16.359836Z","steps":["trace[865637040] 'agreement among raft nodes before linearized reading' (duration: 354.115757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:16.359862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:16.005415Z","time spent":"354.442737ms","remote":"127.0.0.1:49708","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":"info","ts":"2026-05-20T11:23:18.487284Z","caller":"traceutil/trace.go:171","msg":"trace[419621936] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"105.404383ms","start":"2026-05-20T11:23:18.381852Z","end":"2026-05-20T11:23:18.487256Z","steps":["trace[419621936] 'process raft request' (duration: 85.068197ms)","trace[419621936] 'compare' (duration: 20.205752ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:23:19.713847Z","caller":"traceutil/trace.go:171","msg":"trace[519295241] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:445; }","duration":"206.516522ms","start":"2026-05-20T11:23:19.507308Z","end":"2026-05-20T11:23:19.713824Z","steps":["trace[519295241] 'read index received' (duration: 206.305405ms)","trace[519295241] 'applied index is now lower than readState.Index' (duration: 209.857µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:23:19.713949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.637327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:23:19.713932Z","caller":"traceutil/trace.go:171","msg":"trace[130985685] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"322.281883ms","start":"2026-05-20T11:23:19.391622Z","end":"2026-05-20T11:23:19.713904Z","steps":["trace[130985685] 'process raft request' (duration: 322.071906ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:19.713977Z","caller":"traceutil/trace.go:171","msg":"trace[135719699] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"206.714959ms","start":"2026-05-20T11:23:19.507254Z","end":"2026-05-20T11:23:19.713969Z","steps":["trace[135719699] 'agreement among raft nodes before linearized reading' (duration: 206.651637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:19.714084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:19.391596Z","time spent":"322.405267ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:23:20.292886Z","caller":"traceutil/trace.go:171","msg":"trace[1871884108] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"114.108737ms","start":"2026-05-20T11:23:20.178758Z","end":"2026-05-20T11:23:20.292867Z","steps":["trace[1871884108] 'process raft request' (duration: 113.999804ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:23.788074Z","caller":"traceutil/trace.go:171","msg":"trace[458022799] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"676.24417ms","start":"2026-05-20T11:23:23.111803Z","end":"2026-05-20T11:23:23.788047Z","steps":["trace[458022799] 'process raft request' (duration: 676.112346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:23.788279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.111784Z","time spent":"676.377925ms","remote":"127.0.0.1:49526","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-05-20T11:23:23.798106Z","caller":"traceutil/trace.go:171","msg":"trace[1636794520] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"670.256226ms","start":"2026-05-20T11:23:23.127827Z","end":"2026-05-20T11:23:23.798083Z","steps":["trace[1636794520] 'process raft request' (duration: 670.088011ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:23.798228Z","caller":"traceutil/trace.go:171","msg":"trace[1752807998] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"668.630875ms","start":"2026-05-20T11:23:23.129544Z","end":"2026-05-20T11:23:23.798175Z","steps":["trace[1752807998] 'process raft request' (duration: 668.445749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:23.798227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.127807Z","time spent":"670.357959ms","remote":"127.0.0.1:49526","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-05-20T11:23:23.798271Z","caller":"traceutil/trace.go:171","msg":"trace[700715538] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"651.07696ms","start":"2026-05-20T11:23:23.147149Z","end":"2026-05-20T11:23:23.798226Z","steps":["trace[700715538] 'process raft request' (duration: 650.871534ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:23.798324Z","caller":"traceutil/trace.go:171","msg":"trace[957778804] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"650.090409ms","start":"2026-05-20T11:23:23.148204Z","end":"2026-05-20T11:23:23.79829Z","steps":["trace[957778804] 'process raft request' (duration: 649.859821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:23.798426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.14713Z","time spent":"651.222314ms","remote":"127.0.0.1:49526","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-05-20T11:23:23.798445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.148195Z","time spent":"650.179352ms","remote":"127.0.0.1:49526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T11:23:23.798621Z","caller":"traceutil/trace.go:171","msg":"trace[1760463256] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"649.45551ms","start":"2026-05-20T11:23:23.149138Z","end":"2026-05-20T11:23:23.798594Z","steps":["trace[1760463256] 'process raft request' (duration: 649.166401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:23.798701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.149131Z","time spent":"649.537412ms","remote":"127.0.0.1:49526","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-05-20T11:23:23.799387Z","caller":"traceutil/trace.go:171","msg":"trace[635939148] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"643.810904ms","start":"2026-05-20T11:23:23.155564Z","end":"2026-05-20T11:23:23.799375Z","steps":["trace[635939148] 'process raft request' (duration: 643.673739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:23.799445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.155534Z","time spent":"643.886616ms","remote":"127.0.0.1:49526","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-05-20T11:23:23.799553Z","caller":"traceutil/trace.go:171","msg":"trace[966342469] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"637.900271ms","start":"2026-05-20T11:23:23.16164Z","end":"2026-05-20T11:23:23.79954Z","steps":["trace[966342469] 'process raft request' (duration: 637.645673ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:23.799543Z","caller":"traceutil/trace.go:171","msg":"trace[1708583133] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"648.703265ms","start":"2026-05-20T11:23:23.150817Z","end":"2026-05-20T11:23:23.79952Z","steps":["trace[1708583133] 'process raft request' (duration: 647.768046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:23.799603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.161622Z","time spent":"637.960592ms","remote":"127.0.0.1:49526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T11:23:23.79963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.15081Z","time spent":"648.784237ms","remote":"127.0.0.1:49526","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-05-20T11:23:23.799613Z","caller":"traceutil/trace.go:171","msg":"trace[1026717706] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:491; }","duration":"289.764733ms","start":"2026-05-20T11:23:23.509796Z","end":"2026-05-20T11:23:23.799561Z","steps":["trace[1026717706] 'read index received' (duration: 278.130183ms)","trace[1026717706] 'applied index is now lower than readState.Index' (duration: 11.63385ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:23:23.799691Z","caller":"traceutil/trace.go:171","msg":"trace[1494929262] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"618.069944ms","start":"2026-05-20T11:23:23.181611Z","end":"2026-05-20T11:23:23.799681Z","steps":["trace[1494929262] 'process raft request' (duration: 617.753774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:23.799739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.181602Z","time spent":"618.117265ms","remote":"127.0.0.1:49526","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-05-20T11:23:23.799734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.129527Z","time spent":"670.158531ms","remote":"127.0.0.1:49526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T11:23:23.799786Z","caller":"traceutil/trace.go:171","msg":"trace[1526277367] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"523.462998ms","start":"2026-05-20T11:23:23.276289Z","end":"2026-05-20T11:23:23.799752Z","steps":["trace[1526277367] 'process raft request' (duration: 523.218451ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:23.799851Z","caller":"traceutil/trace.go:171","msg":"trace[975431130] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"627.690594ms","start":"2026-05-20T11:23:23.172079Z","end":"2026-05-20T11:23:23.799769Z","steps":["trace[975431130] 'process raft request' (duration: 627.23868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:23.799875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.078734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T11:23:23.799865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.276252Z","time spent":"523.570711ms","remote":"127.0.0.1:49526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T11:23:23.799931Z","caller":"traceutil/trace.go:171","msg":"trace[1515174482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:484; }","duration":"290.159746ms","start":"2026-05-20T11:23:23.509748Z","end":"2026-05-20T11:23:23.799908Z","steps":["trace[1515174482] 'agreement among raft nodes before linearized reading' (duration: 290.052233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:23.800048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.172055Z","time spent":"627.838958ms","remote":"127.0.0.1:49526","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-05-20T11:23:24.384703Z","caller":"traceutil/trace.go:171","msg":"trace[886271038] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"569.752899ms","start":"2026-05-20T11:23:23.814927Z","end":"2026-05-20T11:23:24.38468Z","steps":["trace[886271038] 'process raft request' (duration: 569.675197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:24.384851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.814893Z","time spent":"569.885533ms","remote":"127.0.0.1:49526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:23:24.384928Z","caller":"traceutil/trace.go:171","msg":"trace[1625520957] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"572.001499ms","start":"2026-05-20T11:23:23.812891Z","end":"2026-05-20T11:23:24.384893Z","steps":["trace[1625520957] 'process raft request' (duration: 571.673809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:24.385058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.812885Z","time spent":"572.117382ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T11:23:24.385189Z","caller":"traceutil/trace.go:171","msg":"trace[1016462400] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"572.398572ms","start":"2026-05-20T11:23:23.81275Z","end":"2026-05-20T11:23:24.385149Z","steps":["trace[1016462400] 'process raft request' (duration: 570.460872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:24.385351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.812737Z","time spent":"572.550826ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T11:23:24.395873Z","caller":"traceutil/trace.go:171","msg":"trace[1657833372] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"579.796938ms","start":"2026-05-20T11:23:23.816043Z","end":"2026-05-20T11:23:24.39584Z","steps":["trace[1657833372] 'process raft request' (duration: 579.583422ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:24.396033Z","caller":"traceutil/trace.go:171","msg":"trace[2118836452] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"562.723278ms","start":"2026-05-20T11:23:23.833283Z","end":"2026-05-20T11:23:24.396006Z","steps":["trace[2118836452] 'process raft request' (duration: 562.578924ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:24.396159Z","caller":"traceutil/trace.go:171","msg":"trace[1455316699] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"574.757501ms","start":"2026-05-20T11:23:23.821364Z","end":"2026-05-20T11:23:24.396121Z","steps":["trace[1455316699] 'process raft request' (duration: 574.455272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:24.396181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.833266Z","time spent":"562.839811ms","remote":"127.0.0.1:49526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:23:24.396136Z","caller":"traceutil/trace.go:171","msg":"trace[200989635] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"579.492448ms","start":"2026-05-20T11:23:23.816609Z","end":"2026-05-20T11:23:24.396101Z","steps":["trace[200989635] 'process raft request' (duration: 579.128217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:24.396252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.821351Z","time spent":"574.861474ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T11:23:24.396329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.816601Z","time spent":"579.635523ms","remote":"127.0.0.1:49526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:23:24.396351Z","caller":"traceutil/trace.go:171","msg":"trace[890628319] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"562.283464ms","start":"2026-05-20T11:23:23.834059Z","end":"2026-05-20T11:23:24.396342Z","steps":["trace[890628319] 'process raft request' (duration: 561.880672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:24.396408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.834028Z","time spent":"562.343906ms","remote":"127.0.0.1:49526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:23:24.3965Z","caller":"traceutil/trace.go:171","msg":"trace[1149210130] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"554.936546ms","start":"2026-05-20T11:23:23.841544Z","end":"2026-05-20T11:23:24.39648Z","steps":["trace[1149210130] 'process raft request' (duration: 554.530813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:24.396568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.816033Z","time spent":"579.942472ms","remote":"127.0.0.1:58560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T11:23:24.396873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.841525Z","time spent":"555.024789ms","remote":"127.0.0.1:49526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:23:24.396583Z","caller":"traceutil/trace.go:171","msg":"trace[1483322893] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"544.767849ms","start":"2026-05-20T11:23:23.851801Z","end":"2026-05-20T11:23:24.396569Z","steps":["trace[1483322893] 'process raft request' (duration: 544.342976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:24.397015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.851779Z","time spent":"545.189572ms","remote":"127.0.0.1:49526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:23:24.397713Z","caller":"traceutil/trace.go:171","msg":"trace[974038833] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"534.828012ms","start":"2026-05-20T11:23:23.862869Z","end":"2026-05-20T11:23:24.397697Z","steps":["trace[974038833] 'process raft request' (duration: 533.331345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:24.397809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:23.862852Z","time spent":"534.911034ms","remote":"127.0.0.1:49526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T11:23:30.80828Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224762710148341,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-20T11:23:31.149421Z","caller":"traceutil/trace.go:171","msg":"trace[165131030] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"841.156785ms","start":"2026-05-20T11:23:30.308233Z","end":"2026-05-20T11:23:31.149389Z","steps":["trace[165131030] 'read index received' (duration: 840.425624ms)","trace[165131030] 'applied index is now lower than readState.Index' (duration: 730.041µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:23:31.149445Z","caller":"traceutil/trace.go:171","msg":"trace[996150918] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"842.287517ms","start":"2026-05-20T11:23:30.307135Z","end":"2026-05-20T11:23:31.149423Z","steps":["trace[996150918] 'process raft request' (duration: 841.776443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:31.14963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.637766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:23:31.14969Z","caller":"traceutil/trace.go:171","msg":"trace[623537039] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:579; }","duration":"783.726058ms","start":"2026-05-20T11:23:30.365946Z","end":"2026-05-20T11:23:31.149672Z","steps":["trace[623537039] 'agreement among raft nodes before linearized reading' (duration: 783.636906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:31.149729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.80218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T11:23:31.149743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.365932Z","time spent":"783.79486ms","remote":"127.0.0.1:37408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-20T11:23:31.149794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.946818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T11:23:31.149719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.866605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:23:31.149789Z","caller":"traceutil/trace.go:171","msg":"trace[1986576656] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:579; }","duration":"783.885472ms","start":"2026-05-20T11:23:30.36589Z","end":"2026-05-20T11:23:31.149775Z","steps":["trace[1986576656] 'agreement among raft nodes before linearized reading' (duration: 783.79293ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:31.149834Z","caller":"traceutil/trace.go:171","msg":"trace[964154011] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:579; }","duration":"807.00162ms","start":"2026-05-20T11:23:30.342818Z","end":"2026-05-20T11:23:31.14982Z","steps":["trace[964154011] 'agreement among raft nodes before linearized reading' (duration: 806.959218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:31.14985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.365878Z","time spent":"783.957385ms","remote":"127.0.0.1:37408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-05-20T11:23:31.14987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.342805Z","time spent":"807.057051ms","remote":"127.0.0.1:37400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-20T11:23:31.149966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.726241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:23:31.149955Z","caller":"traceutil/trace.go:171","msg":"trace[1404170253] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:579; }","duration":"807.103162ms","start":"2026-05-20T11:23:30.342807Z","end":"2026-05-20T11:23:31.149911Z","steps":["trace[1404170253] 'agreement among raft nodes before linearized reading' (duration: 806.807314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:31.150008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.338821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:23:31.150013Z","caller":"traceutil/trace.go:171","msg":"trace[1308786644] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:579; }","duration":"841.796434ms","start":"2026-05-20T11:23:30.308204Z","end":"2026-05-20T11:23:31.150001Z","steps":["trace[1308786644] 'agreement among raft nodes before linearized reading' (duration: 841.729612ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:31.150057Z","caller":"traceutil/trace.go:171","msg":"trace[876070217] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:579; }","duration":"814.428384ms","start":"2026-05-20T11:23:30.335613Z","end":"2026-05-20T11:23:31.150041Z","steps":["trace[876070217] 'agreement among raft nodes before linearized reading' (duration: 814.336891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:31.150088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.3356Z","time spent":"814.478265ms","remote":"127.0.0.1:37386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-20T11:23:31.150062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.308187Z","time spent":"841.862235ms","remote":"127.0.0.1:37374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 "} {"level":"warn","ts":"2026-05-20T11:23:31.150052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.342796Z","time spent":"807.235625ms","remote":"127.0.0.1:37400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-05-20T11:23:31.150243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.348297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-20T11:23:31.150277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.574678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T11:23:31.150282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.632969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:23:31.150292Z","caller":"traceutil/trace.go:171","msg":"trace[1088000057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"608.434149ms","start":"2026-05-20T11:23:30.541843Z","end":"2026-05-20T11:23:31.150278Z","steps":["trace[1088000057] 'agreement among raft nodes before linearized reading' (duration: 608.276595ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:31.150318Z","caller":"traceutil/trace.go:171","msg":"trace[685185198] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:579; }","duration":"814.618199ms","start":"2026-05-20T11:23:30.335691Z","end":"2026-05-20T11:23:31.150309Z","steps":["trace[685185198] 'agreement among raft nodes before linearized reading' (duration: 814.556438ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:31.150323Z","caller":"traceutil/trace.go:171","msg":"trace[1978635887] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:579; }","duration":"814.708831ms","start":"2026-05-20T11:23:30.335604Z","end":"2026-05-20T11:23:31.150313Z","steps":["trace[1978635887] 'agreement among raft nodes before linearized reading' (duration: 814.64606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:31.150334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.541821Z","time spent":"608.502542ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T11:23:31.15035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.335584Z","time spent":"814.758093ms","remote":"127.0.0.1:37386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-05-20T11:23:31.150451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.140972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:23:31.150492Z","caller":"traceutil/trace.go:171","msg":"trace[1853255254] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:579; }","duration":"842.188053ms","start":"2026-05-20T11:23:30.308292Z","end":"2026-05-20T11:23:31.15048Z","steps":["trace[1853255254] 'agreement among raft nodes before linearized reading' (duration: 842.134102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:31.150521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.308289Z","time spent":"842.222884ms","remote":"127.0.0.1:37374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-05-20T11:23:31.150559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.221663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T11:23:31.150545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.179671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T11:23:31.150349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.33566Z","time spent":"814.678971ms","remote":"127.0.0.1:37386","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"info","ts":"2026-05-20T11:23:31.150612Z","caller":"traceutil/trace.go:171","msg":"trace[2144885238] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:579; }","duration":"765.302145ms","start":"2026-05-20T11:23:30.385296Z","end":"2026-05-20T11:23:31.150598Z","steps":["trace[2144885238] 'agreement among raft nodes before linearized reading' (duration: 765.222513ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:31.150622Z","caller":"traceutil/trace.go:171","msg":"trace[1567870933] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:579; }","duration":"765.287905ms","start":"2026-05-20T11:23:30.38532Z","end":"2026-05-20T11:23:31.150608Z","steps":["trace[1567870933] 'agreement among raft nodes before linearized reading' (duration: 765.179852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:31.150652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.333862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-20T11:23:31.150658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.385281Z","time spent":"765.362937ms","remote":"127.0.0.1:37422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-05-20T11:23:31.150684Z","caller":"traceutil/trace.go:171","msg":"trace[751954720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"717.406284ms","start":"2026-05-20T11:23:30.433268Z","end":"2026-05-20T11:23:31.150675Z","steps":["trace[751954720] 'agreement among raft nodes before linearized reading' (duration: 717.28605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:31.150713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.433249Z","time spent":"717.457185ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T11:23:31.150533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.30711Z","time spent":"843.06839ms","remote":"127.0.0.1:49538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T11:23:31.150798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.635114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ngmtb\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-20T11:23:31.150847Z","caller":"traceutil/trace.go:171","msg":"trace[604330006] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ngmtb; range_end:; response_count:1; response_revision:579; }","duration":"758.696225ms","start":"2026-05-20T11:23:30.392138Z","end":"2026-05-20T11:23:31.150835Z","steps":["trace[604330006] 'agreement among raft nodes before linearized reading' (duration: 758.564871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:31.150887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.392132Z","time spent":"758.742706ms","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-ngmtb\" "} {"level":"warn","ts":"2026-05-20T11:23:31.150911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.119816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-20T11:23:31.149722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.387452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T11:23:31.150946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.675938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:23:31.150983Z","caller":"traceutil/trace.go:171","msg":"trace[1106519469] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:579; }","duration":"763.7451ms","start":"2026-05-20T11:23:30.387229Z","end":"2026-05-20T11:23:31.150974Z","steps":["trace[1106519469] 'agreement among raft nodes before linearized reading' (duration: 763.689258ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:31.150989Z","caller":"traceutil/trace.go:171","msg":"trace[505881319] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:579; }","duration":"807.688288ms","start":"2026-05-20T11:23:30.343282Z","end":"2026-05-20T11:23:31.150971Z","steps":["trace[505881319] 'agreement among raft nodes before linearized reading' (duration: 806.365451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:31.15102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.387208Z","time spent":"763.802971ms","remote":"127.0.0.1:37408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-05-20T11:23:31.151049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.343272Z","time spent":"807.755951ms","remote":"127.0.0.1:37400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-05-20T11:23:31.150679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.385305Z","time spent":"765.355597ms","remote":"127.0.0.1:37422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-05-20T11:23:31.150943Z","caller":"traceutil/trace.go:171","msg":"trace[1048164735] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"717.175087ms","start":"2026-05-20T11:23:30.433759Z","end":"2026-05-20T11:23:31.150934Z","steps":["trace[1048164735] 'agreement among raft nodes before linearized reading' (duration: 717.083654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:31.151248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.433744Z","time spent":"717.495206ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T11:23:31.149622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.297098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:23:31.15254Z","caller":"traceutil/trace.go:171","msg":"trace[113592670] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:579; }","duration":"844.248834ms","start":"2026-05-20T11:23:30.308275Z","end":"2026-05-20T11:23:31.152524Z","steps":["trace[113592670] 'agreement among raft nodes before linearized reading' (duration: 841.277469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:31.15262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:30.30827Z","time spent":"844.333026ms","remote":"127.0.0.1:37374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 "} {"level":"warn","ts":"2026-05-20T11:23:32.108193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.162476ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:23:32.108322Z","caller":"traceutil/trace.go:171","msg":"trace[1295087574] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:579; }","duration":"463.31269ms","start":"2026-05-20T11:23:31.644989Z","end":"2026-05-20T11:23:32.108301Z","steps":["trace[1295087574] 'range keys from in-memory index tree' (duration: 463.138065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:32.108791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.84882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:23:32.108886Z","caller":"traceutil/trace.go:171","msg":"trace[633532802] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"951.026908ms","start":"2026-05-20T11:23:31.157839Z","end":"2026-05-20T11:23:32.108866Z","steps":["trace[633532802] 'process raft request' (duration: 504.020223ms)","trace[633532802] 'compare' (duration: 446.728397ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:23:32.109425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:31.157825Z","time spent":"951.10635ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T11:23:33.018497Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.356671824s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T11:23:33.018907Z","caller":"traceutil/trace.go:171","msg":"trace[350628862] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.859443282s","start":"2026-05-20T11:23:31.159446Z","end":"2026-05-20T11:23:33.018889Z","steps":["trace[350628862] 'process raft request' (duration: 1.859187645s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:33.019041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:31.159428Z","time spent":"1.859512885s","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:23:33.019226Z","caller":"traceutil/trace.go:171","msg":"trace[1332056670] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.856118236s","start":"2026-05-20T11:23:31.163098Z","end":"2026-05-20T11:23:33.019216Z","steps":["trace[1332056670] 'process raft request' (duration: 1.855758226s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:33.019303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:31.163065Z","time spent":"1.856215739s","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:23:33.019349Z","caller":"traceutil/trace.go:171","msg":"trace[13142553] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.83981414s","start":"2026-05-20T11:23:31.179515Z","end":"2026-05-20T11:23:33.01933Z","steps":["trace[13142553] 'process raft request' (duration: 1.839572644s)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:33.019364Z","caller":"traceutil/trace.go:171","msg":"trace[1979461928] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:602; }","duration":"1.509634493s","start":"2026-05-20T11:23:31.509701Z","end":"2026-05-20T11:23:33.019336Z","steps":["trace[1979461928] 'read index received' (duration: 152.170717ms)","trace[1979461928] 'applied index is now lower than readState.Index' (duration: 1.357462826s)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:23:33.019493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:31.179497Z","time spent":"1.839907705s","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:23:33.019548Z","caller":"traceutil/trace.go:171","msg":"trace[1614085271] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.41220624s","start":"2026-05-20T11:23:31.607333Z","end":"2026-05-20T11:23:33.019539Z","steps":["trace[1614085271] 'process raft request' (duration: 1.411900092s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:33.019616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:31.607313Z","time spent":"1.412275292s","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:23:33.019721Z","caller":"traceutil/trace.go:171","msg":"trace[83893796] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.809840955s","start":"2026-05-20T11:23:31.209873Z","end":"2026-05-20T11:23:33.019714Z","steps":["trace[83893796] 'process raft request' (duration: 1.809317941s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:33.019755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:31.209852Z","time spent":"1.809885027s","remote":"127.0.0.1:58534","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T11:23:33.019807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.510060754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:23:33.019969Z","caller":"traceutil/trace.go:171","msg":"trace[444562330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"1.510246139s","start":"2026-05-20T11:23:31.509653Z","end":"2026-05-20T11:23:33.019899Z","steps":["trace[444562330] 'agreement among raft nodes before linearized reading' (duration: 1.509992802s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:33.020059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.457921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.91\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-20T11:23:33.02006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:31.509635Z","time spent":"1.510412475s","remote":"127.0.0.1:49452","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-05-20T11:23:33.020104Z","caller":"traceutil/trace.go:171","msg":"trace[544257630] range","detail":"{range_begin:/registry/masterleases/199.19.213.91; range_end:; response_count:1; response_revision:585; }","duration":"587.553474ms","start":"2026-05-20T11:23:32.432542Z","end":"2026-05-20T11:23:33.020096Z","steps":["trace[544257630] 'agreement among raft nodes before linearized reading' (duration: 587.421319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:33.02016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:32.432523Z","time spent":"587.616386ms","remote":"127.0.0.1:49500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.91\" "} {"level":"warn","ts":"2026-05-20T11:23:33.020254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14573459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T11:23:33.020363Z","caller":"traceutil/trace.go:171","msg":"trace[661582601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"1.145872303s","start":"2026-05-20T11:23:31.874471Z","end":"2026-05-20T11:23:33.020343Z","steps":["trace[661582601] 'agreement among raft nodes before linearized reading' (duration: 1.145480812s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:33.020465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:31.874448Z","time spent":"1.146001157s","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T11:23:34.614124Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224762710148362,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T11:23:35.114816Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224762710148362,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T11:23:35.61533Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224762710148362,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T11:23:35.953336Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.427003029s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T11:23:35.954416Z","caller":"traceutil/trace.go:171","msg":"trace[1330162297] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"1.840998601s","start":"2026-05-20T11:23:34.113394Z","end":"2026-05-20T11:23:35.954393Z","steps":["trace[1330162297] 'read index received' (duration: 1.840170509s)","trace[1330162297] 'applied index is now lower than readState.Index' (duration: 826.992µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:23:35.954644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.841236347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-20T11:23:35.954591Z","caller":"traceutil/trace.go:171","msg":"trace[1163695679] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"2.429682473s","start":"2026-05-20T11:23:33.524869Z","end":"2026-05-20T11:23:35.954551Z","steps":["trace[1163695679] 'process raft request' (duration: 2.428863191s)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:35.954694Z","caller":"traceutil/trace.go:171","msg":"trace[493933509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"1.84133656s","start":"2026-05-20T11:23:34.113346Z","end":"2026-05-20T11:23:35.954682Z","steps":["trace[493933509] 'agreement among raft nodes before linearized reading' (duration: 1.841165535s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:35.95485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:34.113324Z","time spent":"1.841515135s","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T11:23:35.954855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:33.524842Z","time spent":"2.429899619s","remote":"127.0.0.1:49538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T11:23:35.987841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.477825971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:23:35.987846Z","caller":"traceutil/trace.go:171","msg":"trace[1407411689] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"341.829906ms","start":"2026-05-20T11:23:35.645978Z","end":"2026-05-20T11:23:35.987808Z","steps":["trace[1407411689] 'process raft request' (duration: 311.029541ms)","trace[1407411689] 'compare' (duration: 30.527717ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:23:35.987899Z","caller":"traceutil/trace.go:171","msg":"trace[376991310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"1.477917164s","start":"2026-05-20T11:23:34.509969Z","end":"2026-05-20T11:23:35.987886Z","steps":["trace[376991310] 'agreement among raft nodes before linearized reading' (duration: 1.477826041s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:35.987988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:35.645952Z","time spent":"341.95928ms","remote":"127.0.0.1:49708","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-05-20T11:23:35.988163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.623721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T11:23:35.988231Z","caller":"traceutil/trace.go:171","msg":"trace[1044984211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"901.768965ms","start":"2026-05-20T11:23:35.086443Z","end":"2026-05-20T11:23:35.988212Z","steps":["trace[1044984211] 'agreement among raft nodes before linearized reading' (duration: 901.484567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:35.989287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:35.08642Z","time spent":"901.844316ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T11:23:35.989489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.414532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T11:23:35.989509Z","caller":"traceutil/trace.go:171","msg":"trace[1228284453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"964.480345ms","start":"2026-05-20T11:23:35.025023Z","end":"2026-05-20T11:23:35.989504Z","steps":["trace[1228284453] 'agreement among raft nodes before linearized reading' (duration: 964.426642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:35.989525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:35.025Z","time spent":"964.521206ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T11:23:35.989742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.65527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T11:23:35.989819Z","caller":"traceutil/trace.go:171","msg":"trace[129197865] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"964.748462ms","start":"2026-05-20T11:23:35.025038Z","end":"2026-05-20T11:23:35.989786Z","steps":["trace[129197865] 'agreement among raft nodes before linearized reading' (duration: 964.496396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:35.989927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:35.025018Z","time spent":"964.891096ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T11:23:35.987932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:34.509949Z","time spent":"1.477969575s","remote":"127.0.0.1:49452","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-05-20T11:23:38.093605Z","caller":"traceutil/trace.go:171","msg":"trace[875163079] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"535.652741ms","start":"2026-05-20T11:23:37.557925Z","end":"2026-05-20T11:23:38.093578Z","steps":["trace[875163079] 'process raft request' (duration: 535.433955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:38.093752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:37.557902Z","time spent":"535.773503ms","remote":"127.0.0.1:49526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T11:23:38.10705Z","caller":"traceutil/trace.go:171","msg":"trace[198261528] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"349.397568ms","start":"2026-05-20T11:23:37.757632Z","end":"2026-05-20T11:23:38.107029Z","steps":["trace[198261528] 'process raft request' (duration: 349.076559ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:38.107099Z","caller":"traceutil/trace.go:171","msg":"trace[1942474455] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:648; }","duration":"112.717074ms","start":"2026-05-20T11:23:37.994374Z","end":"2026-05-20T11:23:38.107091Z","steps":["trace[1942474455] 'read index received' (duration: 99.080832ms)","trace[1942474455] 'applied index is now lower than readState.Index' (duration: 13.635392ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:23:38.107163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:37.757601Z","time spent":"349.508081ms","remote":"127.0.0.1:49526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T11:23:38.107071Z","caller":"traceutil/trace.go:171","msg":"trace[98887130] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"149.78523ms","start":"2026-05-20T11:23:37.957276Z","end":"2026-05-20T11:23:38.107062Z","steps":["trace[98887130] 'process raft request' (duration: 149.553714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:38.107296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.918699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-20T11:23:38.107329Z","caller":"traceutil/trace.go:171","msg":"trace[865043862] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"112.992091ms","start":"2026-05-20T11:23:37.994326Z","end":"2026-05-20T11:23:38.107318Z","steps":["trace[865043862] 'agreement among raft nodes before linearized reading' (duration: 112.870258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:38.107609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.697976ms","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-05-20T11:23:38.10765Z","caller":"traceutil/trace.go:171","msg":"trace[196793374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"105.786908ms","start":"2026-05-20T11:23:38.001852Z","end":"2026-05-20T11:23:38.107639Z","steps":["trace[196793374] 'agreement among raft nodes before linearized reading' (duration: 105.688715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:38.10772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.352539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-20T11:23:38.107744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.01392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-20T11:23:38.107761Z","caller":"traceutil/trace.go:171","msg":"trace[1165751454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"104.394491ms","start":"2026-05-20T11:23:38.003358Z","end":"2026-05-20T11:23:38.107752Z","steps":["trace[1165751454] 'agreement among raft nodes before linearized reading' (duration: 104.303218ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:38.107796Z","caller":"traceutil/trace.go:171","msg":"trace[28024854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"104.070731ms","start":"2026-05-20T11:23:38.003712Z","end":"2026-05-20T11:23:38.107782Z","steps":["trace[28024854] 'agreement among raft nodes before linearized reading' (duration: 103.907987ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:38.924322Z","caller":"traceutil/trace.go:171","msg":"trace[681591874] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"119.236124ms","start":"2026-05-20T11:23:38.805063Z","end":"2026-05-20T11:23:38.924299Z","steps":["trace[681591874] 'process raft request' (duration: 116.711887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:39.638288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.459163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ngmtb\" ","response":"range_response_count:1 size:17786"} {"level":"info","ts":"2026-05-20T11:23:39.638361Z","caller":"traceutil/trace.go:171","msg":"trace[1081032674] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ngmtb; range_end:; response_count:1; response_revision:651; }","duration":"187.573186ms","start":"2026-05-20T11:23:39.450767Z","end":"2026-05-20T11:23:39.63834Z","steps":["trace[1081032674] 'range keys from in-memory index tree' (duration: 187.300669ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:39.71563Z","caller":"traceutil/trace.go:171","msg":"trace[1169475457] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:676; }","duration":"206.699421ms","start":"2026-05-20T11:23:39.508911Z","end":"2026-05-20T11:23:39.71561Z","steps":["trace[1169475457] 'read index received' (duration: 206.540926ms)","trace[1169475457] 'applied index is now lower than readState.Index' (duration: 157.695µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:23:39.715694Z","caller":"traceutil/trace.go:171","msg":"trace[163092501] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"263.875089ms","start":"2026-05-20T11:23:39.451781Z","end":"2026-05-20T11:23:39.715657Z","steps":["trace[163092501] 'process raft request' (duration: 263.628612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:39.715834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.904116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:23:39.716189Z","caller":"traceutil/trace.go:171","msg":"trace[2138243718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"207.261145ms","start":"2026-05-20T11:23:39.508877Z","end":"2026-05-20T11:23:39.716138Z","steps":["trace[2138243718] 'agreement among raft nodes before linearized reading' (duration: 206.853935ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:39.798228Z","caller":"traceutil/trace.go:171","msg":"trace[1477240117] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"143.635097ms","start":"2026-05-20T11:23:39.654543Z","end":"2026-05-20T11:23:39.798178Z","steps":["trace[1477240117] 'process raft request' (duration: 143.378071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:39.798397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.116804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-05-20T11:23:39.798486Z","caller":"traceutil/trace.go:171","msg":"trace[562236165] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:653; }","duration":"242.246107ms","start":"2026-05-20T11:23:39.556216Z","end":"2026-05-20T11:23:39.798462Z","steps":["trace[562236165] 'agreement among raft nodes before linearized reading' (duration: 241.98635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:39.853926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.593482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:23:39.854094Z","caller":"traceutil/trace.go:171","msg":"trace[1239808200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"136.789177ms","start":"2026-05-20T11:23:39.717285Z","end":"2026-05-20T11:23:39.854074Z","steps":["trace[1239808200] 'agreement among raft nodes before linearized reading' (duration: 136.563301ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:40.033276Z","caller":"traceutil/trace.go:171","msg":"trace[96311201] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"164.330746ms","start":"2026-05-20T11:23:39.868918Z","end":"2026-05-20T11:23:40.033249Z","steps":["trace[96311201] 'process raft request' (duration: 164.195443ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:40.302637Z","caller":"traceutil/trace.go:171","msg":"trace[1104975276] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"186.288334ms","start":"2026-05-20T11:23:40.116309Z","end":"2026-05-20T11:23:40.302597Z","steps":["trace[1104975276] 'read index received' (duration: 134.983984ms)","trace[1104975276] 'applied index is now lower than readState.Index' (duration: 51.30338ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:23:40.302984Z","caller":"traceutil/trace.go:171","msg":"trace[879284059] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"235.095639ms","start":"2026-05-20T11:23:40.067856Z","end":"2026-05-20T11:23:40.302952Z","steps":["trace[879284059] 'process raft request' (duration: 183.592534ms)","trace[879284059] 'compare' (duration: 51.043623ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:23:40.303017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.687445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-20T11:23:40.303087Z","caller":"traceutil/trace.go:171","msg":"trace[801419433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"186.796497ms","start":"2026-05-20T11:23:40.116271Z","end":"2026-05-20T11:23:40.303067Z","steps":["trace[801419433] 'agreement among raft nodes before linearized reading' (duration: 186.580341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:40.303106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.51467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-20T11:23:40.303183Z","caller":"traceutil/trace.go:171","msg":"trace[1221128675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:656; }","duration":"186.591972ms","start":"2026-05-20T11:23:40.116547Z","end":"2026-05-20T11:23:40.303139Z","steps":["trace[1221128675] 'agreement among raft nodes before linearized reading' (duration: 186.420057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:40.303194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.545317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-20T11:23:40.303232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.555601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-20T11:23:40.303232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.947546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-05-20T11:23:40.303256Z","caller":"traceutil/trace.go:171","msg":"trace[1661063188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:656; }","duration":"182.744672ms","start":"2026-05-20T11:23:40.120494Z","end":"2026-05-20T11:23:40.303239Z","steps":["trace[1661063188] 'agreement among raft nodes before linearized reading' (duration: 182.482445ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:40.303293Z","caller":"traceutil/trace.go:171","msg":"trace[1539284573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:656; }","duration":"186.654893ms","start":"2026-05-20T11:23:40.116622Z","end":"2026-05-20T11:23:40.303277Z","steps":["trace[1539284573] 'agreement among raft nodes before linearized reading' (duration: 186.488959ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:40.303299Z","caller":"traceutil/trace.go:171","msg":"trace[1287090353] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:656; }","duration":"147.07932ms","start":"2026-05-20T11:23:40.156203Z","end":"2026-05-20T11:23:40.303283Z","steps":["trace[1287090353] 'agreement among raft nodes before linearized reading' (duration: 146.811653ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:23:40.588978Z","caller":"traceutil/trace.go:171","msg":"trace[1050706204] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"171.707343ms","start":"2026-05-20T11:23:40.41724Z","end":"2026-05-20T11:23:40.588948Z","steps":["trace[1050706204] 'process raft request' (duration: 171.493488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:41.847941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.58874ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:23:41.848071Z","caller":"traceutil/trace.go:171","msg":"trace[1935647348] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:673; }","duration":"202.728174ms","start":"2026-05-20T11:23:41.645325Z","end":"2026-05-20T11:23:41.848054Z","steps":["trace[1935647348] 'range keys from in-memory index tree' (duration: 202.57466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:54.010385Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224762710148690,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T11:23:54.510763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224762710148690,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T11:23:55.011578Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":689224762710148690,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T11:23:55.109366Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.631369117s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T11:23:55.109663Z","caller":"traceutil/trace.go:171","msg":"trace[227806242] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"1.640215242s","start":"2026-05-20T11:23:53.46943Z","end":"2026-05-20T11:23:55.109645Z","steps":["trace[227806242] 'process raft request' (duration: 1.640107769s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:55.109793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:53.469404Z","time spent":"1.640315784s","remote":"127.0.0.1:49708","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-05-20T11:23:55.113338Z","caller":"traceutil/trace.go:171","msg":"trace[32498038] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:785; }","duration":"1.603219764s","start":"2026-05-20T11:23:53.510088Z","end":"2026-05-20T11:23:55.113308Z","steps":["trace[32498038] 'read index received' (duration: 1.599457149s)","trace[32498038] 'applied index is now lower than readState.Index' (duration: 3.761215ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:23:55.113571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.603457789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:23:55.11422Z","caller":"traceutil/trace.go:171","msg":"trace[204963915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"1.604156925s","start":"2026-05-20T11:23:53.510042Z","end":"2026-05-20T11:23:55.114199Z","steps":["trace[204963915] 'agreement among raft nodes before linearized reading' (duration: 1.603442689s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:55.114326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:53.51002Z","time spent":"1.604293507s","remote":"127.0.0.1:49452","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-05-20T11:23:55.113796Z","caller":"traceutil/trace.go:171","msg":"trace[1798044213] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"1.480117114s","start":"2026-05-20T11:23:53.633664Z","end":"2026-05-20T11:23:55.113781Z","steps":["trace[1798044213] 'process raft request' (duration: 1.479543121s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:55.113818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.583845774s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jptsl\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-20T11:23:55.114671Z","caller":"traceutil/trace.go:171","msg":"trace[2074303650] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jptsl; range_end:; response_count:1; response_revision:758; }","duration":"1.584728436s","start":"2026-05-20T11:23:53.529924Z","end":"2026-05-20T11:23:55.114653Z","steps":["trace[2074303650] 'agreement among raft nodes before linearized reading' (duration: 1.583729292s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:55.114686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:53.633628Z","time spent":"1.480882041s","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T11:23:55.114713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:53.529911Z","time spent":"1.584790987s","remote":"127.0.0.1:49612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jptsl\" "} {"level":"warn","ts":"2026-05-20T11:23:55.113939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.266101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T11:23:55.115085Z","caller":"traceutil/trace.go:171","msg":"trace[1472269964] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:758; }","duration":"674.431368ms","start":"2026-05-20T11:23:54.440641Z","end":"2026-05-20T11:23:55.115072Z","steps":["trace[1472269964] 'agreement among raft nodes before linearized reading' (duration: 673.21548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:55.115149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:54.44063Z","time spent":"674.506029ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T11:23:55.113954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.246451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:23:55.115249Z","caller":"traceutil/trace.go:171","msg":"trace[277070948] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:758; }","duration":"528.572822ms","start":"2026-05-20T11:23:54.586664Z","end":"2026-05-20T11:23:55.115237Z","steps":["trace[277070948] 'agreement among raft nodes before linearized reading' (duration: 527.272592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:55.115295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:54.586643Z","time spent":"528.639224ms","remote":"127.0.0.1:49730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-20T11:23:55.11401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.480069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T11:23:55.115424Z","caller":"traceutil/trace.go:171","msg":"trace[1294068810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:758; }","duration":"743.920622ms","start":"2026-05-20T11:23:54.371496Z","end":"2026-05-20T11:23:55.115417Z","steps":["trace[1294068810] 'agreement among raft nodes before linearized reading' (duration: 742.479829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:55.115445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:54.371479Z","time spent":"743.960293ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T11:23:55.114015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.191201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T11:23:55.115617Z","caller":"traceutil/trace.go:171","msg":"trace[1817627356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:758; }","duration":"675.816699ms","start":"2026-05-20T11:23:54.439778Z","end":"2026-05-20T11:23:55.115595Z","steps":["trace[1817627356] 'agreement among raft nodes before linearized reading' (duration: 674.181461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:55.11576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:54.439735Z","time spent":"676.009213ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T11:23:55.113906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.668947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-20T11:23:55.115974Z","caller":"traceutil/trace.go:171","msg":"trace[1383418841] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:758; }","duration":"674.753105ms","start":"2026-05-20T11:23:54.441209Z","end":"2026-05-20T11:23:55.115962Z","steps":["trace[1383418841] 'agreement among raft nodes before linearized reading' (duration: 672.617746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:23:55.116034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:23:54.441196Z","time spent":"674.827076ms","remote":"127.0.0.1:49708","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":"info","ts":"2026-05-20T11:24:02.621004Z","caller":"traceutil/trace.go:171","msg":"trace[694859985] linearizableReadLoop","detail":"{readStateIndex:870; appliedIndex:869; }","duration":"113.429218ms","start":"2026-05-20T11:24:02.50755Z","end":"2026-05-20T11:24:02.62098Z","steps":["trace[694859985] 'read index received' (duration: 113.224994ms)","trace[694859985] 'applied index is now lower than readState.Index' (duration: 203.354µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:24:02.621189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.622983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:24:02.621222Z","caller":"traceutil/trace.go:171","msg":"trace[1775667234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:838; }","duration":"113.711965ms","start":"2026-05-20T11:24:02.507498Z","end":"2026-05-20T11:24:02.62121Z","steps":["trace[1775667234] 'agreement among raft nodes before linearized reading' (duration: 113.576472ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:24:02.621519Z","caller":"traceutil/trace.go:171","msg":"trace[1952240331] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"144.846711ms","start":"2026-05-20T11:24:02.476662Z","end":"2026-05-20T11:24:02.621508Z","steps":["trace[1952240331] 'process raft request' (duration: 144.173047ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:24:11.352888Z","caller":"traceutil/trace.go:171","msg":"trace[251351111] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"108.864568ms","start":"2026-05-20T11:24:11.243996Z","end":"2026-05-20T11:24:11.352861Z","steps":["trace[251351111] 'process raft request' (duration: 103.294707ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:24:11.353104Z","caller":"traceutil/trace.go:171","msg":"trace[1998199329] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"108.796757ms","start":"2026-05-20T11:24:11.244274Z","end":"2026-05-20T11:24:11.353071Z","steps":["trace[1998199329] 'process raft request' (duration: 108.719776ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:24:11.353254Z","caller":"traceutil/trace.go:171","msg":"trace[600685722] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"109.146303ms","start":"2026-05-20T11:24:11.244093Z","end":"2026-05-20T11:24:11.353239Z","steps":["trace[600685722] 'process raft request' (duration: 108.736735ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:24:25.884562Z","caller":"traceutil/trace.go:171","msg":"trace[1426238148] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"129.594868ms","start":"2026-05-20T11:24:25.754942Z","end":"2026-05-20T11:24:25.884537Z","steps":["trace[1426238148] 'process raft request' (duration: 129.390535ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:24:27.678653Z","caller":"traceutil/trace.go:171","msg":"trace[1204092712] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"235.366551ms","start":"2026-05-20T11:24:27.443262Z","end":"2026-05-20T11:24:27.678629Z","steps":["trace[1204092712] 'read index received' (duration: 196.123735ms)","trace[1204092712] 'applied index is now lower than readState.Index' (duration: 39.241816ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:24:27.678903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.225724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-20T11:24:27.678942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.158283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T11:24:27.678987Z","caller":"traceutil/trace.go:171","msg":"trace[304972130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:992; }","duration":"246.256004ms","start":"2026-05-20T11:24:27.432718Z","end":"2026-05-20T11:24:27.678974Z","steps":["trace[304972130] 'agreement among raft nodes before linearized reading' (duration: 245.998079ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:24:27.678982Z","caller":"traceutil/trace.go:171","msg":"trace[313641481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:992; }","duration":"246.347956ms","start":"2026-05-20T11:24:27.432615Z","end":"2026-05-20T11:24:27.678963Z","steps":["trace[313641481] 'agreement among raft nodes before linearized reading' (duration: 246.112642ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:24:27.678701Z","caller":"traceutil/trace.go:171","msg":"trace[1730102595] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"307.392548ms","start":"2026-05-20T11:24:27.371273Z","end":"2026-05-20T11:24:27.678665Z","steps":["trace[1730102595] 'process raft request' (duration: 268.162482ms)","trace[1730102595] 'compare' (duration: 39.054102ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:24:27.679305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:24:27.371248Z","time spent":"307.925327ms","remote":"127.0.0.1:49708","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-05-20T11:24:27.679767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.986576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-20T11:24:27.679823Z","caller":"traceutil/trace.go:171","msg":"trace[109373690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:992; }","duration":"252.84773ms","start":"2026-05-20T11:24:27.42696Z","end":"2026-05-20T11:24:27.679808Z","steps":["trace[109373690] 'agreement among raft nodes before linearized reading' (duration: 251.786792ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:24:27.723388Z","caller":"traceutil/trace.go:171","msg":"trace[650006675] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"275.611544ms","start":"2026-05-20T11:24:27.447747Z","end":"2026-05-20T11:24:27.723358Z","steps":["trace[650006675] 'process raft request' (duration: 275.460562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:24:27.723679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.92644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:24:27.723797Z","caller":"traceutil/trace.go:171","msg":"trace[1004284158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:993; }","duration":"214.150115ms","start":"2026-05-20T11:24:27.509621Z","end":"2026-05-20T11:24:27.723771Z","steps":["trace[1004284158] 'agreement among raft nodes before linearized reading' (duration: 213.760238ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:24:28.449391Z","caller":"traceutil/trace.go:171","msg":"trace[1206638222] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"432.948581ms","start":"2026-05-20T11:24:28.016417Z","end":"2026-05-20T11:24:28.449365Z","steps":["trace[1206638222] 'process raft request' (duration: 432.729757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:24:28.44955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:24:28.016401Z","time spent":"433.064253ms","remote":"127.0.0.1:49680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2914,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:24:29.557289Z","caller":"traceutil/trace.go:171","msg":"trace[974675072] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"106.830788ms","start":"2026-05-20T11:24:29.450439Z","end":"2026-05-20T11:24:29.55727Z","steps":["trace[974675072] 'process raft request' (duration: 106.501392ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:24:29.893536Z","caller":"traceutil/trace.go:171","msg":"trace[371609028] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"137.081517ms","start":"2026-05-20T11:24:29.756424Z","end":"2026-05-20T11:24:29.893505Z","steps":["trace[371609028] 'read index received' (duration: 136.988714ms)","trace[371609028] 'applied index is now lower than readState.Index' (duration: 91.653µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:24:29.89366Z","caller":"traceutil/trace.go:171","msg":"trace[698234857] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"163.352857ms","start":"2026-05-20T11:24:29.730261Z","end":"2026-05-20T11:24:29.893614Z","steps":["trace[698234857] 'process raft request' (duration: 163.071171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:24:29.893842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.392812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-20T11:24:29.893881Z","caller":"traceutil/trace.go:171","msg":"trace[1979357082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1003; }","duration":"137.489864ms","start":"2026-05-20T11:24:29.756379Z","end":"2026-05-20T11:24:29.893869Z","steps":["trace[1979357082] 'agreement among raft nodes before linearized reading' (duration: 137.303321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:24:29.893876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.330321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T11:24:29.893931Z","caller":"traceutil/trace.go:171","msg":"trace[902537204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1003; }","duration":"137.456713ms","start":"2026-05-20T11:24:29.756458Z","end":"2026-05-20T11:24:29.893915Z","steps":["trace[902537204] 'agreement among raft nodes before linearized reading' (duration: 137.30492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:24:29.894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.441833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T11:24:29.894097Z","caller":"traceutil/trace.go:171","msg":"trace[118327643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1003; }","duration":"137.609636ms","start":"2026-05-20T11:24:29.756469Z","end":"2026-05-20T11:24:29.894079Z","steps":["trace[118327643] 'agreement among raft nodes before linearized reading' (duration: 137.372712ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:24:37.726367Z","caller":"traceutil/trace.go:171","msg":"trace[543648077] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"131.977813ms","start":"2026-05-20T11:24:37.594364Z","end":"2026-05-20T11:24:37.726342Z","steps":["trace[543648077] 'process raft request' (duration: 131.81619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:24:38.471369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.212856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T11:24:38.471485Z","caller":"traceutil/trace.go:171","msg":"trace[1940502055] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"536.663425ms","start":"2026-05-20T11:24:37.934781Z","end":"2026-05-20T11:24:38.471444Z","steps":["trace[1940502055] 'process raft request' (duration: 76.309086ms)","trace[1940502055] 'compare' (duration: 459.231099ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:24:38.471526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:24:37.934764Z","time spent":"536.743897ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:24:38.47743Z","caller":"traceutil/trace.go:171","msg":"trace[1238129104] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1068; }","duration":"504.474079ms","start":"2026-05-20T11:24:37.972936Z","end":"2026-05-20T11:24:38.47741Z","steps":["trace[1238129104] 'read index received' (duration: 38.147652ms)","trace[1238129104] 'applied index is now lower than readState.Index' (duration: 466.325667ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:24:38.477466Z","caller":"traceutil/trace.go:171","msg":"trace[1369234276] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"505.953124ms","start":"2026-05-20T11:24:37.97148Z","end":"2026-05-20T11:24:38.477433Z","steps":["trace[1369234276] 'process raft request' (duration: 505.827622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:24:38.477635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.687752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-20T11:24:38.477639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:24:37.971436Z","time spent":"506.107127ms","remote":"127.0.0.1:49708","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-05-20T11:24:38.477665Z","caller":"traceutil/trace.go:171","msg":"trace[630625210] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1031; }","duration":"504.759024ms","start":"2026-05-20T11:24:37.972898Z","end":"2026-05-20T11:24:38.477657Z","steps":["trace[630625210] 'agreement among raft nodes before linearized reading' (duration: 504.622002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:24:38.477691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:24:37.972889Z","time spent":"504.795844ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T11:24:38.477707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.87123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T11:24:38.477761Z","caller":"traceutil/trace.go:171","msg":"trace[155574986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1031; }","duration":"494.927561ms","start":"2026-05-20T11:24:37.98282Z","end":"2026-05-20T11:24:38.477747Z","steps":["trace[155574986] 'agreement among raft nodes before linearized reading' (duration: 494.768149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:24:38.477803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:24:37.982787Z","time spent":"495.007562ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T11:24:38.477879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.414719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-20T11:24:38.477912Z","caller":"traceutil/trace.go:171","msg":"trace[1521490631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1031; }","duration":"463.49021ms","start":"2026-05-20T11:24:38.014413Z","end":"2026-05-20T11:24:38.477903Z","steps":["trace[1521490631] 'agreement among raft nodes before linearized reading' (duration: 463.361828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:24:38.477932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:24:38.014392Z","time spent":"463.536431ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-20T11:24:40.28183Z","caller":"traceutil/trace.go:171","msg":"trace[485963927] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"546.616489ms","start":"2026-05-20T11:24:39.735192Z","end":"2026-05-20T11:24:40.281809Z","steps":["trace[485963927] 'process raft request' (duration: 546.503797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:24:40.281969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:24:39.735165Z","time spent":"546.734071ms","remote":"127.0.0.1:49708","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-05-20T11:24:44.680498Z","caller":"traceutil/trace.go:171","msg":"trace[1654810721] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"121.034697ms","start":"2026-05-20T11:24:44.559442Z","end":"2026-05-20T11:24:44.680476Z","steps":["trace[1654810721] 'process raft request' (duration: 120.891895ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:24:44.808853Z","caller":"traceutil/trace.go:171","msg":"trace[1054063746] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1088; }","duration":"214.450625ms","start":"2026-05-20T11:24:44.59438Z","end":"2026-05-20T11:24:44.80883Z","steps":["trace[1054063746] 'read index received' (duration: 86.01983ms)","trace[1054063746] 'applied index is now lower than readState.Index' (duration: 128.429505ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:24:44.808896Z","caller":"traceutil/trace.go:171","msg":"trace[835003748] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"246.84705ms","start":"2026-05-20T11:24:44.562033Z","end":"2026-05-20T11:24:44.80888Z","steps":["trace[835003748] 'process raft request' (duration: 161.752974ms)","trace[835003748] 'compare' (duration: 84.822982ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:24:44.809059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.935202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-20T11:24:44.80906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.914547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T11:24:44.809085Z","caller":"traceutil/trace.go:171","msg":"trace[1892003045] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1050; }","duration":"210.001914ms","start":"2026-05-20T11:24:44.599076Z","end":"2026-05-20T11:24:44.809077Z","steps":["trace[1892003045] 'agreement among raft nodes before linearized reading' (duration: 209.877822ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:24:44.809088Z","caller":"traceutil/trace.go:171","msg":"trace[1595514809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1050; }","duration":"208.981648ms","start":"2026-05-20T11:24:44.6001Z","end":"2026-05-20T11:24:44.809082Z","steps":["trace[1595514809] 'agreement among raft nodes before linearized reading' (duration: 208.884107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:24:44.809224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.820471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T11:24:44.809497Z","caller":"traceutil/trace.go:171","msg":"trace[628157727] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1050; }","duration":"215.146236ms","start":"2026-05-20T11:24:44.594333Z","end":"2026-05-20T11:24:44.809479Z","steps":["trace[628157727] 'agreement among raft nodes before linearized reading' (duration: 214.583337ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:24:44.946081Z","caller":"traceutil/trace.go:171","msg":"trace[1927360192] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"133.354063ms","start":"2026-05-20T11:24:44.812698Z","end":"2026-05-20T11:24:44.946052Z","steps":["trace[1927360192] 'process raft request' (duration: 106.676418ms)","trace[1927360192] 'compare' (duration: 26.31464ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:24:44.947793Z","caller":"traceutil/trace.go:171","msg":"trace[755167075] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"134.481831ms","start":"2026-05-20T11:24:44.813297Z","end":"2026-05-20T11:24:44.947779Z","steps":["trace[755167075] 'process raft request' (duration: 132.715174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:25:01.168573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.725155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-20T11:25:01.168601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.996382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-20T11:25:01.168597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.159512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-20T11:25:01.168748Z","caller":"traceutil/trace.go:171","msg":"trace[1175857736] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1102; }","duration":"150.164185ms","start":"2026-05-20T11:25:01.018567Z","end":"2026-05-20T11:25:01.168731Z","steps":["trace[1175857736] 'range keys from in-memory index tree' (duration: 149.78589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:25:01.168607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.383012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T11:25:01.168761Z","caller":"traceutil/trace.go:171","msg":"trace[1716626609] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1102; }","duration":"195.377215ms","start":"2026-05-20T11:25:00.973366Z","end":"2026-05-20T11:25:01.168743Z","steps":["trace[1716626609] 'range keys from in-memory index tree' (duration: 194.962469ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:25:01.168812Z","caller":"traceutil/trace.go:171","msg":"trace[1876925416] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1102; }","duration":"307.626695ms","start":"2026-05-20T11:25:00.861176Z","end":"2026-05-20T11:25:01.168803Z","steps":["trace[1876925416] 'count revisions from in-memory index tree' (duration: 307.302561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:25:01.168837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:25:00.861117Z","time spent":"307.709847ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-05-20T11:25:01.168668Z","caller":"traceutil/trace.go:171","msg":"trace[651219012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1102; }","duration":"146.899787ms","start":"2026-05-20T11:25:01.021747Z","end":"2026-05-20T11:25:01.168647Z","steps":["trace[651219012] 'range keys from in-memory index tree' (duration: 146.467322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:25:01.169157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.527266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-20T11:25:01.169219Z","caller":"traceutil/trace.go:171","msg":"trace[1911628132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1102; }","duration":"197.615788ms","start":"2026-05-20T11:25:00.971591Z","end":"2026-05-20T11:25:01.169206Z","steps":["trace[1911628132] 'range keys from in-memory index tree' (duration: 197.355294ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:25:05.587043Z","caller":"traceutil/trace.go:171","msg":"trace[1792292646] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"271.87302ms","start":"2026-05-20T11:25:05.315147Z","end":"2026-05-20T11:25:05.58702Z","steps":["trace[1792292646] 'process raft request' (duration: 271.715468ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:25:05.623078Z","caller":"traceutil/trace.go:171","msg":"trace[1318746245] linearizableReadLoop","detail":"{readStateIndex:1163; appliedIndex:1161; }","duration":"114.89371ms","start":"2026-05-20T11:25:05.508157Z","end":"2026-05-20T11:25:05.623051Z","steps":["trace[1318746245] 'read index received' (duration: 78.723023ms)","trace[1318746245] 'applied index is now lower than readState.Index' (duration: 36.169637ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:25:05.623203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.028502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T11:25:05.623235Z","caller":"traceutil/trace.go:171","msg":"trace[1319726400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1119; }","duration":"115.114513ms","start":"2026-05-20T11:25:05.50811Z","end":"2026-05-20T11:25:05.623225Z","steps":["trace[1319726400] 'agreement among raft nodes before linearized reading' (duration: 115.039322ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:25:05.623203Z","caller":"traceutil/trace.go:171","msg":"trace[166063713] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"307.940046ms","start":"2026-05-20T11:25:05.315213Z","end":"2026-05-20T11:25:05.623153Z","steps":["trace[166063713] 'process raft request' (duration: 307.689963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:25:05.623713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:25:05.315197Z","time spent":"308.133299ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T11:25:06.90896Z","caller":"traceutil/trace.go:171","msg":"trace[904736572] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"118.2476ms","start":"2026-05-20T11:25:06.790691Z","end":"2026-05-20T11:25:06.908939Z","steps":["trace[904736572] 'process raft request' (duration: 118.104878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:25:08.05895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.856449ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-20T11:25:09.851098Z","caller":"traceutil/trace.go:171","msg":"trace[393633146] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"135.506039ms","start":"2026-05-20T11:25:09.715563Z","end":"2026-05-20T11:25:09.851069Z","steps":["trace[393633146] 'process raft request' (duration: 92.380945ms)","trace[393633146] 'compare' (duration: 42.919881ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T11:25:12.857502Z","caller":"traceutil/trace.go:171","msg":"trace[1189570796] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"185.900081ms","start":"2026-05-20T11:25:12.671579Z","end":"2026-05-20T11:25:12.857479Z","steps":["trace[1189570796] 'process raft request' (duration: 185.750279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:25:19.875671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.560375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-20T11:25:19.875768Z","caller":"traceutil/trace.go:171","msg":"trace[1707447561] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1164; }","duration":"124.693817ms","start":"2026-05-20T11:25:19.751051Z","end":"2026-05-20T11:25:19.875745Z","steps":["trace[1707447561] 'range keys from in-memory index tree' (duration: 124.029728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:25:28.034779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.081276ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-20T11:25:28.034882Z","caller":"traceutil/trace.go:171","msg":"trace[267401032] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1238; }","duration":"122.013789ms","start":"2026-05-20T11:25:27.912848Z","end":"2026-05-20T11:25:28.034861Z","steps":["trace[267401032] 'read index received' (duration: 7.789601ms)","trace[267401032] 'applied index is now lower than readState.Index' (duration: 114.222648ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T11:25:28.035126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.272732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-20T11:25:28.035205Z","caller":"traceutil/trace.go:171","msg":"trace[832993366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1190; }","duration":"122.402375ms","start":"2026-05-20T11:25:27.912782Z","end":"2026-05-20T11:25:28.035185Z","steps":["trace[832993366] 'agreement among raft nodes before linearized reading' (duration: 122.140781ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:25:41.657605Z","caller":"traceutil/trace.go:171","msg":"trace[124057983] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"189.438149ms","start":"2026-05-20T11:25:41.468141Z","end":"2026-05-20T11:25:41.657579Z","steps":["trace[124057983] 'process raft request' (duration: 189.271567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:25:41.900641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.407696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T11:25:41.900727Z","caller":"traceutil/trace.go:171","msg":"trace[1197375557] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1268; }","duration":"159.547117ms","start":"2026-05-20T11:25:41.741161Z","end":"2026-05-20T11:25:41.900708Z","steps":["trace[1197375557] 'range keys from in-memory index tree' (duration: 159.241903ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:25:49.539301Z","caller":"traceutil/trace.go:171","msg":"trace[1155805587] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"277.411436ms","start":"2026-05-20T11:25:49.261864Z","end":"2026-05-20T11:25:49.539276Z","steps":["trace[1155805587] 'process raft request' (duration: 277.265335ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:25:49.550062Z","caller":"traceutil/trace.go:171","msg":"trace[958097467] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"263.986883ms","start":"2026-05-20T11:25:49.28605Z","end":"2026-05-20T11:25:49.550037Z","steps":["trace[958097467] 'process raft request' (duration: 263.552707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:25:50.726926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.757127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:126638"} {"level":"info","ts":"2026-05-20T11:25:50.726998Z","caller":"traceutil/trace.go:171","msg":"trace[1249625978] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1301; }","duration":"172.892138ms","start":"2026-05-20T11:25:50.554089Z","end":"2026-05-20T11:25:50.726981Z","steps":["trace[1249625978] 'range keys from in-memory index tree' (duration: 172.517214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:25:50.727128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.081187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T11:25:50.72722Z","caller":"traceutil/trace.go:171","msg":"trace[1634132544] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1301; }","duration":"792.240428ms","start":"2026-05-20T11:25:49.934969Z","end":"2026-05-20T11:25:50.72721Z","steps":["trace[1634132544] 'range keys from in-memory index tree' (duration: 791.963675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:25:50.727363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.810291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-20T11:25:50.727397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.060412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-20T11:25:50.727439Z","caller":"traceutil/trace.go:171","msg":"trace[379735983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1301; }","duration":"557.947793ms","start":"2026-05-20T11:25:50.169472Z","end":"2026-05-20T11:25:50.727419Z","steps":["trace[379735983] 'range keys from in-memory index tree' (duration: 557.624539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:25:50.726942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.723107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-20T11:25:50.727523Z","caller":"traceutil/trace.go:171","msg":"trace[909390986] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1301; }","duration":"548.146304ms","start":"2026-05-20T11:25:50.179298Z","end":"2026-05-20T11:25:50.727444Z","steps":["trace[909390986] 'range keys from in-memory index tree' (duration: 547.943401ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T11:25:50.727571Z","caller":"traceutil/trace.go:171","msg":"trace[1907729368] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1301; }","duration":"173.374414ms","start":"2026-05-20T11:25:50.55417Z","end":"2026-05-20T11:25:50.727545Z","steps":["trace[1907729368] 'range keys from in-memory index tree' (duration: 172.633236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T11:25:50.72759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:25:50.179287Z","time spent":"548.287705ms","remote":"127.0.0.1:49708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T11:25:50.727507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:25:50.169443Z","time spent":"558.052693ms","remote":"127.0.0.1:49708","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-05-20T11:25:50.728083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T11:25:49.934951Z","time spent":"793.118928ms","remote":"127.0.0.1:49708","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\" "}