{"level":"warn","ts":"2026-07-03T03:19:29.102914Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-03T03:19:29.102998Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-03T03:19:29.103074Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-03T03:19:29.103081Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-07-03T03:19:29.103096Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-03T03:19:29.10386Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-07-03T03:19:29.103931Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-03T03:19:29.107933Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.420595ms"} {"level":"info","ts":"2026-07-03T03:19:29.11755Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-07-03T03:19:29.117609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-07-03T03:19:29.117624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-07-03T03:19:29.117633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-03T03:19:29.117641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-07-03T03:19:29.117666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-07-03T03:19:29.123312Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-03T03:19:29.125809Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-03T03:19:29.127Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-03T03:19:29.129092Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-03T03:19:29.131232Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-03T03:19:29.130728Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-03T03:19:29.132079Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-03T03:19:29.132102Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-03T03:19:29.132392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-07-03T03:19:29.132737Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-07-03T03:19:29.133646Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-03T03:19:29.133818Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-03T03:19:29.133851Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-03T03:19:29.13393Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-07-03T03:19:29.133944Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-07-03T03:19:29.418374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-07-03T03:19:29.418431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-07-03T03:19:29.418479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-07-03T03:19:29.418497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-07-03T03:19:29.418514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-07-03T03:19:29.418534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-07-03T03:19:29.418552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-07-03T03:19:29.41973Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T03:19:29.420657Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-03T03:19:29.420663Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-07-03T03:19:29.420769Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-03T03:19:29.421439Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-03T03:19:29.421566Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-03T03:19:29.423642Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-07-03T03:19:29.424258Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-03T03:19:29.424346Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T03:19:29.424459Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T03:19:29.424485Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-07-03T03:19:38.770652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.434731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-03T03:19:38.770795Z","caller":"traceutil/trace.go:171","msg":"trace[1883542396] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:297; }","duration":"324.671329ms","start":"2026-07-03T03:19:38.446098Z","end":"2026-07-03T03:19:38.77077Z","steps":["trace[1883542396] 'range keys from in-memory index tree' (duration: 324.298158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:38.770938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:38.446074Z","time spent":"324.845513ms","remote":"127.0.0.1:52962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-03T03:19:39.048586Z","caller":"traceutil/trace.go:171","msg":"trace[482434096] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:310; }","duration":"252.602891ms","start":"2026-07-03T03:19:38.795951Z","end":"2026-07-03T03:19:39.048553Z","steps":["trace[482434096] 'read index received' (duration: 252.353404ms)","trace[482434096] 'applied index is now lower than readState.Index' (duration: 248.497µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:19:39.048661Z","caller":"traceutil/trace.go:171","msg":"trace[1678832254] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"269.404968ms","start":"2026-07-03T03:19:38.779215Z","end":"2026-07-03T03:19:39.04862Z","steps":["trace[1678832254] 'process raft request' (duration: 269.161431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:39.049113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.132626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-03T03:19:39.049169Z","caller":"traceutil/trace.go:171","msg":"trace[1243150206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:298; }","duration":"253.269759ms","start":"2026-07-03T03:19:38.795887Z","end":"2026-07-03T03:19:39.049156Z","steps":["trace[1243150206] 'agreement among raft nodes before linearized reading' (duration: 252.797986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:39.585248Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103446572195798,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T03:19:39.959023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.65212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-07-03T03:19:39.959736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.767482ms","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-07-03T03:19:39.960129Z","caller":"traceutil/trace.go:171","msg":"trace[1471693809] linearizableReadLoop","detail":"{readStateIndex:312; appliedIndex:311; }","duration":"874.573737ms","start":"2026-07-03T03:19:39.084938Z","end":"2026-07-03T03:19:39.959511Z","steps":["trace[1471693809] 'read index received' (duration: 566.820867ms)","trace[1471693809] 'applied index is now lower than readState.Index' (duration: 307.74842ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:19:39.960395Z","caller":"traceutil/trace.go:171","msg":"trace[186026036] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"906.091593ms","start":"2026-07-03T03:19:39.053498Z","end":"2026-07-03T03:19:39.95959Z","steps":["trace[186026036] 'process raft request' (duration: 598.329803ms)","trace[186026036] 'compare' (duration: 306.493965ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:19:39.960274Z","caller":"traceutil/trace.go:171","msg":"trace[756218681] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:299; }","duration":"874.934966ms","start":"2026-07-03T03:19:39.084891Z","end":"2026-07-03T03:19:39.959826Z","steps":["trace[756218681] 'agreement among raft nodes before linearized reading' (duration: 874.732051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:39.960802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.084535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-07-03T03:19:39.960857Z","caller":"traceutil/trace.go:171","msg":"trace[1995514270] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:299; }","duration":"870.162337ms","start":"2026-07-03T03:19:39.090679Z","end":"2026-07-03T03:19:39.960841Z","steps":["trace[1995514270] 'agreement among raft nodes before linearized reading' (duration: 870.050564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:39.960892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.090667Z","time spent":"870.216778ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-07-03T03:19:39.960887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.093217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-07-03T03:19:39.960975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.068868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-07-03T03:19:39.960991Z","caller":"traceutil/trace.go:171","msg":"trace[260953673] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:299; }","duration":"707.22972ms","start":"2026-07-03T03:19:39.253705Z","end":"2026-07-03T03:19:39.960934Z","steps":["trace[260953673] 'agreement among raft nodes before linearized reading' (duration: 707.060276ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:39.960988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.094705ms","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-07-03T03:19:39.961052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.253675Z","time spent":"707.366574ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-07-03T03:19:39.961089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.099143ms","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-07-03T03:19:39.961084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.441816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-07-03T03:19:39.961136Z","caller":"traceutil/trace.go:171","msg":"trace[562916252] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:299; }","duration":"807.148325ms","start":"2026-07-03T03:19:39.153963Z","end":"2026-07-03T03:19:39.961111Z","steps":["trace[562916252] 'agreement among raft nodes before linearized reading' (duration: 807.095754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:39.961174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.153921Z","time spent":"807.246298ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-07-03T03:19:39.961202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.4354ms","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-07-03T03:19:39.961128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.458487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-07-03T03:19:39.961232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.091478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-07-03T03:19:39.961271Z","caller":"traceutil/trace.go:171","msg":"trace[1586361095] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:299; }","duration":"858.16792ms","start":"2026-07-03T03:19:39.103092Z","end":"2026-07-03T03:19:39.96126Z","steps":["trace[1586361095] 'agreement among raft nodes before linearized reading' (duration: 858.100908ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:19:39.961271Z","caller":"traceutil/trace.go:171","msg":"trace[1454104929] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:299; }","duration":"857.519373ms","start":"2026-07-03T03:19:39.103728Z","end":"2026-07-03T03:19:39.961248Z","steps":["trace[1454104929] 'agreement among raft nodes before linearized reading' (duration: 857.42548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:39.9613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.10307Z","time spent":"858.223872ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-07-03T03:19:39.961322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.103716Z","time spent":"857.593925ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-07-03T03:19:39.96137Z","caller":"traceutil/trace.go:171","msg":"trace[2109841264] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:299; }","duration":"862.604711ms","start":"2026-07-03T03:19:39.098647Z","end":"2026-07-03T03:19:39.961251Z","steps":["trace[2109841264] 'agreement among raft nodes before linearized reading' (duration: 862.426956ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:19:39.961035Z","caller":"traceutil/trace.go:171","msg":"trace[119329547] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:299; }","duration":"851.186171ms","start":"2026-07-03T03:19:39.109838Z","end":"2026-07-03T03:19:39.961024Z","steps":["trace[119329547] 'agreement among raft nodes before linearized reading' (duration: 851.047557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:39.961548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.109829Z","time spent":"851.677734ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-07-03T03:19:39.96128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.084869Z","time spent":"875.814151ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-07-03T03:19:39.961182Z","caller":"traceutil/trace.go:171","msg":"trace[2036887926] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:299; }","duration":"210.766775ms","start":"2026-07-03T03:19:39.750387Z","end":"2026-07-03T03:19:39.961154Z","steps":["trace[2036887926] 'agreement among raft nodes before linearized reading' (duration: 210.447367ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:39.961728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.687562ms","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":"warn","ts":"2026-07-03T03:19:39.960801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.14734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T03:19:39.961807Z","caller":"traceutil/trace.go:171","msg":"trace[2010911010] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:299; }","duration":"807.636838ms","start":"2026-07-03T03:19:39.154144Z","end":"2026-07-03T03:19:39.96178Z","steps":["trace[2010911010] 'agreement among raft nodes before linearized reading' (duration: 806.58328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:39.961868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.154101Z","time spent":"807.751111ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"info","ts":"2026-07-03T03:19:39.961807Z","caller":"traceutil/trace.go:171","msg":"trace[1189133405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:299; }","duration":"228.197579ms","start":"2026-07-03T03:19:39.733593Z","end":"2026-07-03T03:19:39.96179Z","steps":["trace[1189133405] 'agreement among raft nodes before linearized reading' (duration: 227.14675ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:19:39.961109Z","caller":"traceutil/trace.go:171","msg":"trace[432473319] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:299; }","duration":"803.25885ms","start":"2026-07-03T03:19:39.157826Z","end":"2026-07-03T03:19:39.961084Z","steps":["trace[432473319] 'agreement among raft nodes before linearized reading' (duration: 802.989672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:39.962008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.157745Z","time spent":"804.247576ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-07-03T03:19:39.961196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.647503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-07-03T03:19:39.960592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.053471Z","time spent":"907.022528ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T03:19:39.962087Z","caller":"traceutil/trace.go:171","msg":"trace[290395926] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:299; }","duration":"810.558527ms","start":"2026-07-03T03:19:39.151512Z","end":"2026-07-03T03:19:39.96207Z","steps":["trace[290395926] 'agreement among raft nodes before linearized reading' (duration: 809.638452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:39.961432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.09864Z","time spent":"862.775546ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-07-03T03:19:39.962337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.151501Z","time spent":"810.818405ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-07-03T03:19:39.961007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.989591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-07-03T03:19:39.962432Z","caller":"traceutil/trace.go:171","msg":"trace[2139986704] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:299; }","duration":"212.483762ms","start":"2026-07-03T03:19:39.749933Z","end":"2026-07-03T03:19:39.962416Z","steps":["trace[2139986704] 'agreement among raft nodes before linearized reading' (duration: 210.9328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:40.467193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103446572195801,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-03T03:19:40.933048Z","caller":"traceutil/trace.go:171","msg":"trace[1278986637] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:312; }","duration":"966.190955ms","start":"2026-07-03T03:19:39.966804Z","end":"2026-07-03T03:19:40.932995Z","steps":["trace[1278986637] 'read index received' (duration: 960.024558ms)","trace[1278986637] 'applied index is now lower than readState.Index' (duration: 6.164907ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:19:40.933202Z","caller":"traceutil/trace.go:171","msg":"trace[1267177103] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"966.703509ms","start":"2026-07-03T03:19:39.966469Z","end":"2026-07-03T03:19:40.933172Z","steps":["trace[1267177103] 'process raft request' (duration: 960.49377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:40.933294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.469452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-07-03T03:19:40.933343Z","caller":"traceutil/trace.go:171","msg":"trace[803789263] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:300; }","duration":"966.555645ms","start":"2026-07-03T03:19:39.966775Z","end":"2026-07-03T03:19:40.933331Z","steps":["trace[803789263] 'agreement among raft nodes before linearized reading' (duration: 966.404551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:40.93335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.966444Z","time spent":"966.820272ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T03:19:40.933348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.467213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-07-03T03:19:40.933633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.69949ms","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-07-03T03:19:40.933684Z","caller":"traceutil/trace.go:171","msg":"trace[1377393101] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:300; }","duration":"962.734631ms","start":"2026-07-03T03:19:39.970928Z","end":"2026-07-03T03:19:40.933663Z","steps":["trace[1377393101] 'agreement among raft nodes before linearized reading' (duration: 962.68321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:40.933727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.970917Z","time spent":"962.803403ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-07-03T03:19:40.933746Z","caller":"traceutil/trace.go:171","msg":"trace[1441047785] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:300; }","duration":"966.796921ms","start":"2026-07-03T03:19:39.966842Z","end":"2026-07-03T03:19:40.933639Z","steps":["trace[1441047785] 'agreement among raft nodes before linearized reading' (duration: 966.416841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:40.933905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.419841ms","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-07-03T03:19:40.933966Z","caller":"traceutil/trace.go:171","msg":"trace[1560491859] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:300; }","duration":"966.490313ms","start":"2026-07-03T03:19:39.967458Z","end":"2026-07-03T03:19:40.933948Z","steps":["trace[1560491859] 'agreement among raft nodes before linearized reading' (duration: 966.37746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:40.93401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.967452Z","time spent":"966.546404ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-07-03T03:19:40.934078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.966837Z","time spent":"967.160101ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-07-03T03:19:40.93418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.284103ms","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-07-03T03:19:40.934271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.999559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-07-03T03:19:40.934314Z","caller":"traceutil/trace.go:171","msg":"trace[1631793494] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:300; }","duration":"966.060841ms","start":"2026-07-03T03:19:39.968237Z","end":"2026-07-03T03:19:40.934298Z","steps":["trace[1631793494] 'agreement among raft nodes before linearized reading' (duration: 965.954688ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:19:40.934308Z","caller":"traceutil/trace.go:171","msg":"trace[739529585] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:300; }","duration":"964.446777ms","start":"2026-07-03T03:19:39.96984Z","end":"2026-07-03T03:19:40.934287Z","steps":["trace[739529585] 'agreement among raft nodes before linearized reading' (duration: 964.223991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:40.934349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.968224Z","time spent":"966.112412ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-07-03T03:19:40.934349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.302595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4197"} {"level":"warn","ts":"2026-07-03T03:19:40.934358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.969829Z","time spent":"964.51932ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-07-03T03:19:40.934392Z","caller":"traceutil/trace.go:171","msg":"trace[1981224770] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:300; }","duration":"967.376588ms","start":"2026-07-03T03:19:39.967002Z","end":"2026-07-03T03:19:40.934379Z","steps":["trace[1981224770] 'agreement among raft nodes before linearized reading' (duration: 967.279915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:40.934423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.966995Z","time spent":"967.421299ms","remote":"127.0.0.1:53016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4219,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-03T03:19:40.93442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.199842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-07-03T03:19:40.934463Z","caller":"traceutil/trace.go:171","msg":"trace[1342822012] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:300; }","duration":"967.250974ms","start":"2026-07-03T03:19:39.967201Z","end":"2026-07-03T03:19:40.934452Z","steps":["trace[1342822012] 'agreement among raft nodes before linearized reading' (duration: 967.178342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:40.934495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.967195Z","time spent":"967.292414ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-07-03T03:19:40.934552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.665905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-03T03:19:40.934585Z","caller":"traceutil/trace.go:171","msg":"trace[1232011334] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:300; }","duration":"967.708856ms","start":"2026-07-03T03:19:39.966865Z","end":"2026-07-03T03:19:40.934574Z","steps":["trace[1232011334] 'agreement among raft nodes before linearized reading' (duration: 967.648024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:40.934614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.966861Z","time spent":"967.746747ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-07-03T03:19:40.933377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.966766Z","time spent":"966.602346ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-07-03T03:19:40.933445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.449313ms","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-07-03T03:19:40.934694Z","caller":"traceutil/trace.go:171","msg":"trace[1840320492] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:300; }","duration":"963.716408ms","start":"2026-07-03T03:19:39.970968Z","end":"2026-07-03T03:19:40.934685Z","steps":["trace[1840320492] 'agreement among raft nodes before linearized reading' (duration: 962.446353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:40.934726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.970955Z","time spent":"963.762069ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-07-03T03:19:40.933486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.275936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-03T03:19:40.934793Z","caller":"traceutil/trace.go:171","msg":"trace[1972040104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:300; }","duration":"555.627942ms","start":"2026-07-03T03:19:40.379152Z","end":"2026-07-03T03:19:40.93478Z","steps":["trace[1972040104] 'agreement among raft nodes before linearized reading' (duration: 554.309287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:40.934851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:40.379124Z","time spent":"555.711975ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-03T03:19:40.933472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.529805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-07-03T03:19:40.93513Z","caller":"traceutil/trace.go:171","msg":"trace[1301859058] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:300; }","duration":"964.15137ms","start":"2026-07-03T03:19:39.9709Z","end":"2026-07-03T03:19:40.935051Z","steps":["trace[1301859058] 'agreement among raft nodes before linearized reading' (duration: 962.484415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:40.935851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.970894Z","time spent":"964.931261ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-07-03T03:19:40.933528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.624168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-03T03:19:40.936056Z","caller":"traceutil/trace.go:171","msg":"trace[149635615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:300; }","duration":"743.179758ms","start":"2026-07-03T03:19:40.192864Z","end":"2026-07-03T03:19:40.936043Z","steps":["trace[149635615] 'agreement among raft nodes before linearized reading' (duration: 740.638089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:40.936101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:40.192843Z","time spent":"743.245039ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T03:19:40.933591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.627868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-07-03T03:19:40.936278Z","caller":"traceutil/trace.go:171","msg":"trace[390117129] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:300; }","duration":"965.326432ms","start":"2026-07-03T03:19:39.970942Z","end":"2026-07-03T03:19:40.936268Z","steps":["trace[390117129] 'agreement among raft nodes before linearized reading' (duration: 962.617168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:19:40.936321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:19:39.970923Z","time spent":"965.388203ms","remote":"127.0.0.1:53040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-07-03T03:20:08.511359Z","caller":"traceutil/trace.go:171","msg":"trace[429965329] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"458.080463ms","start":"2026-07-03T03:20:08.053251Z","end":"2026-07-03T03:20:08.511331Z","steps":["trace[429965329] 'process raft request' (duration: 457.909278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:08.511486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:08.053234Z","time spent":"458.195306ms","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T03:20:09.264791Z","caller":"traceutil/trace.go:171","msg":"trace[557017486] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.20353124s","start":"2026-07-03T03:20:08.061235Z","end":"2026-07-03T03:20:09.264766Z","steps":["trace[557017486] 'process raft request' (duration: 1.203363965s)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:09.26502Z","caller":"traceutil/trace.go:171","msg":"trace[1950483118] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.202647436s","start":"2026-07-03T03:20:08.062275Z","end":"2026-07-03T03:20:09.264923Z","steps":["trace[1950483118] 'process raft request' (duration: 1.20240437s)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:09.265173Z","caller":"traceutil/trace.go:171","msg":"trace[1976648875] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"1.200456096s","start":"2026-07-03T03:20:08.064696Z","end":"2026-07-03T03:20:09.265152Z","steps":["trace[1976648875] 'process raft request' (duration: 1.20023555s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:09.265259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:08.062268Z","time spent":"1.202901463s","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T03:20:09.265291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:08.064691Z","time spent":"1.200521228s","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T03:20:09.264787Z","caller":"traceutil/trace.go:171","msg":"trace[605864270] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"1.209107211s","start":"2026-07-03T03:20:08.055639Z","end":"2026-07-03T03:20:09.264746Z","steps":["trace[605864270] 'process raft request' (duration: 1.20870775s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:09.265458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:08.055619Z","time spent":"1.209816021s","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T03:20:09.265465Z","caller":"traceutil/trace.go:171","msg":"trace[721093827] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.19176258s","start":"2026-07-03T03:20:08.073686Z","end":"2026-07-03T03:20:09.265448Z","steps":["trace[721093827] 'process raft request' (duration: 1.191463122s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:09.265544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:08.073643Z","time spent":"1.191860593s","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T03:20:09.265107Z","caller":"traceutil/trace.go:171","msg":"trace[1187645247] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.202015718s","start":"2026-07-03T03:20:08.063065Z","end":"2026-07-03T03:20:09.26508Z","steps":["trace[1187645247] 'process raft request' (duration: 1.201802153s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:09.265557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:08.061219Z","time spent":"1.204067495s","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T03:20:09.26568Z","caller":"traceutil/trace.go:171","msg":"trace[79723028] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.197784984s","start":"2026-07-03T03:20:08.067866Z","end":"2026-07-03T03:20:09.265651Z","steps":["trace[79723028] 'process raft request' (duration: 1.197157597s)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:09.265682Z","caller":"traceutil/trace.go:171","msg":"trace[1871781318] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:508; }","duration":"1.117316038s","start":"2026-07-03T03:20:08.148308Z","end":"2026-07-03T03:20:09.265624Z","steps":["trace[1871781318] 'read index received' (duration: 362.98668ms)","trace[1871781318] 'applied index is now lower than readState.Index' (duration: 754.327968ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:20:09.265783Z","caller":"traceutil/trace.go:171","msg":"trace[1912787405] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.182367185s","start":"2026-07-03T03:20:08.083404Z","end":"2026-07-03T03:20:09.265771Z","steps":["trace[1912787405] 'process raft request' (duration: 1.181886402s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:09.265838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:08.083391Z","time spent":"1.182427236s","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T03:20:09.265835Z","caller":"traceutil/trace.go:171","msg":"trace[1991677808] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.189743765s","start":"2026-07-03T03:20:08.076072Z","end":"2026-07-03T03:20:09.265816Z","steps":["trace[1991677808] 'process raft request' (duration: 1.189144399s)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:09.265866Z","caller":"traceutil/trace.go:171","msg":"trace[772484736] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.175056376s","start":"2026-07-03T03:20:08.090796Z","end":"2026-07-03T03:20:09.265853Z","steps":["trace[772484736] 'process raft request' (duration: 1.174568343s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:09.265921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:08.076053Z","time spent":"1.189833237s","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T03:20:09.26565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:08.063048Z","time spent":"1.202572864s","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T03:20:09.266013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:08.090773Z","time spent":"1.175131279s","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T03:20:09.266071Z","caller":"traceutil/trace.go:171","msg":"trace[1927569042] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.02976594s","start":"2026-07-03T03:20:08.236293Z","end":"2026-07-03T03:20:09.266059Z","steps":["trace[1927569042] 'process raft request' (duration: 1.029196315s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:09.265873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:08.06786Z","time spent":"1.197910887s","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T03:20:09.266128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:08.236273Z","time spent":"1.029832832s","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T03:20:09.266333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118009597s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-03T03:20:09.266406Z","caller":"traceutil/trace.go:171","msg":"trace[2123862253] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:499; }","duration":"1.11811668s","start":"2026-07-03T03:20:08.148268Z","end":"2026-07-03T03:20:09.266385Z","steps":["trace[2123862253] 'agreement among raft nodes before linearized reading' (duration: 1.117802201s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:09.266453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:08.148255Z","time spent":"1.118185911s","remote":"127.0.0.1:53088","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-07-03T03:20:09.726649Z","caller":"traceutil/trace.go:171","msg":"trace[2002948827] transaction","detail":"{read_only:false; number_of_response:1; response_revision:501; }","duration":"1.205555286s","start":"2026-07-03T03:20:08.521064Z","end":"2026-07-03T03:20:09.726619Z","steps":["trace[2002948827] 'process raft request' (duration: 1.205515795s)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:09.726748Z","caller":"traceutil/trace.go:171","msg":"trace[1997431967] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:523; }","duration":"460.919141ms","start":"2026-07-03T03:20:09.265798Z","end":"2026-07-03T03:20:09.726718Z","steps":["trace[1997431967] 'read index received' (duration: 460.90627ms)","trace[1997431967] 'applied index is now lower than readState.Index' (duration: 11.551µs)"],"step_count":2} {"level":"warn","ts":"2026-07-03T03:20:09.726823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:08.52104Z","time spent":"1.20569756s","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6936,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T03:20:09.726901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007407294s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:20:09.726949Z","caller":"traceutil/trace.go:171","msg":"trace[505755873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"1.007504677s","start":"2026-07-03T03:20:08.719433Z","end":"2026-07-03T03:20:09.726938Z","steps":["trace[505755873] 'agreement among raft nodes before linearized reading' (duration: 1.007380643s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:09.726989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:08.719417Z","time spent":"1.007563868s","remote":"127.0.0.1:52832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-03T03:20:09.727072Z","caller":"traceutil/trace.go:171","msg":"trace[436710884] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"1.205994928s","start":"2026-07-03T03:20:08.521066Z","end":"2026-07-03T03:20:09.727061Z","steps":["trace[436710884] 'process raft request' (duration: 1.205459323s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:09.727071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.694078ms","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-07-03T03:20:09.727136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:08.52105Z","time spent":"1.20605384s","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T03:20:09.727179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.564785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-03T03:20:09.727189Z","caller":"traceutil/trace.go:171","msg":"trace[1437600364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:501; }","duration":"649.870313ms","start":"2026-07-03T03:20:09.077294Z","end":"2026-07-03T03:20:09.727165Z","steps":["trace[1437600364] 'agreement among raft nodes before linearized reading' (duration: 649.636356ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:09.727236Z","caller":"traceutil/trace.go:171","msg":"trace[220869301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:501; }","duration":"649.633226ms","start":"2026-07-03T03:20:09.077585Z","end":"2026-07-03T03:20:09.727219Z","steps":["trace[220869301] 'agreement among raft nodes before linearized reading' (duration: 649.560644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:09.727261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.077277Z","time spent":"649.968826ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T03:20:09.727284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.07757Z","time spent":"649.705949ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-03T03:20:09.727328Z","caller":"traceutil/trace.go:171","msg":"trace[1975826459] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.208533357s","start":"2026-07-03T03:20:08.518781Z","end":"2026-07-03T03:20:09.727314Z","steps":["trace[1975826459] 'process raft request' (duration: 1.207598921s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:09.727081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.654469ms","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-07-03T03:20:09.727419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:08.518755Z","time spent":"1.208614149s","remote":"127.0.0.1:53336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T03:20:09.727435Z","caller":"traceutil/trace.go:171","msg":"trace[846303709] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:501; }","duration":"653.05732ms","start":"2026-07-03T03:20:09.074365Z","end":"2026-07-03T03:20:09.727422Z","steps":["trace[846303709] 'agreement among raft nodes before linearized reading' (duration: 652.590667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:09.727483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.074344Z","time spent":"653.125761ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-03T03:20:10.060379Z","caller":"traceutil/trace.go:171","msg":"trace[1588715306] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"784.844468ms","start":"2026-07-03T03:20:09.275503Z","end":"2026-07-03T03:20:10.060347Z","steps":["trace[1588715306] 'process raft request' (duration: 782.246058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:10.060633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.275485Z","time spent":"784.961452ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T03:20:10.060743Z","caller":"traceutil/trace.go:171","msg":"trace[545448360] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:523; }","duration":"331.72572ms","start":"2026-07-03T03:20:09.728994Z","end":"2026-07-03T03:20:10.06072Z","steps":["trace[545448360] 'read index received' (duration: 328.840882ms)","trace[545448360] 'applied index is now lower than readState.Index' (duration: 2.883928ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:20:10.061015Z","caller":"traceutil/trace.go:171","msg":"trace[2144372858] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"783.237215ms","start":"2026-07-03T03:20:09.27775Z","end":"2026-07-03T03:20:10.060987Z","steps":["trace[2144372858] 'process raft request' (duration: 782.584817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:10.06104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.925676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:20:10.061089Z","caller":"traceutil/trace.go:171","msg":"trace[1389132020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"332.153662ms","start":"2026-07-03T03:20:09.728921Z","end":"2026-07-03T03:20:10.061075Z","steps":["trace[1389132020] 'agreement among raft nodes before linearized reading' (duration: 331.905055ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:10.061134Z","caller":"traceutil/trace.go:171","msg":"trace[949708428] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"779.426741ms","start":"2026-07-03T03:20:09.28169Z","end":"2026-07-03T03:20:10.061117Z","steps":["trace[949708428] 'process raft request' (duration: 778.748683ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:10.061194Z","caller":"traceutil/trace.go:171","msg":"trace[1386397953] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"773.664805ms","start":"2026-07-03T03:20:09.287516Z","end":"2026-07-03T03:20:10.061181Z","steps":["trace[1386397953] 'process raft request' (duration: 773.088249ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:10.061229Z","caller":"traceutil/trace.go:171","msg":"trace[1494740740] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"739.403135ms","start":"2026-07-03T03:20:09.321778Z","end":"2026-07-03T03:20:10.061181Z","steps":["trace[1494740740] 'process raft request' (duration: 738.907161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:10.0613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.287503Z","time spent":"773.723907ms","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T03:20:10.061301Z","caller":"traceutil/trace.go:171","msg":"trace[1352404453] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"783.627296ms","start":"2026-07-03T03:20:09.277656Z","end":"2026-07-03T03:20:10.061284Z","steps":["trace[1352404453] 'process raft request' (duration: 782.634609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:10.061271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.281674Z","time spent":"779.520154ms","remote":"127.0.0.1:53336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T03:20:10.061382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.277648Z","time spent":"783.698567ms","remote":"127.0.0.1:53336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T03:20:10.061388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.32175Z","time spent":"739.559178ms","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T03:20:10.06151Z","caller":"traceutil/trace.go:171","msg":"trace[1525766726] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"781.530699ms","start":"2026-07-03T03:20:09.279945Z","end":"2026-07-03T03:20:10.061476Z","steps":["trace[1525766726] 'process raft request' (duration: 780.45256ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:10.06158Z","caller":"traceutil/trace.go:171","msg":"trace[648947619] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"781.828046ms","start":"2026-07-03T03:20:09.27974Z","end":"2026-07-03T03:20:10.061568Z","steps":["trace[648947619] 'process raft request' (duration: 780.623534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:10.06163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.27973Z","time spent":"781.875987ms","remote":"127.0.0.1:53336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T03:20:10.061639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.279924Z","time spent":"781.652342ms","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T03:20:10.061761Z","caller":"traceutil/trace.go:171","msg":"trace[1520488847] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"778.182317ms","start":"2026-07-03T03:20:09.283568Z","end":"2026-07-03T03:20:10.06175Z","steps":["trace[1520488847] 'process raft request' (duration: 776.907623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:10.061126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.277742Z","time spent":"783.334137ms","remote":"127.0.0.1:53336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T03:20:10.06114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.728905Z","time spent":"332.221263ms","remote":"127.0.0.1:52838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T03:20:10.061832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.28355Z","time spent":"778.241139ms","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T03:20:10.061059Z","caller":"traceutil/trace.go:171","msg":"trace[206696071] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"776.200244ms","start":"2026-07-03T03:20:09.284824Z","end":"2026-07-03T03:20:10.061024Z","steps":["trace[206696071] 'process raft request' (duration: 775.71914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:10.06203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.284813Z","time spent":"777.168781ms","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T03:20:10.069736Z","caller":"traceutil/trace.go:171","msg":"trace[1999747747] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"338.203006ms","start":"2026-07-03T03:20:09.73152Z","end":"2026-07-03T03:20:10.069723Z","steps":["trace[1999747747] 'process raft request' (duration: 338.009671ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:10.069823Z","caller":"traceutil/trace.go:171","msg":"trace[492848332] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"316.208438ms","start":"2026-07-03T03:20:09.753604Z","end":"2026-07-03T03:20:10.069812Z","steps":["trace[492848332] 'process raft request' (duration: 316.146417ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:10.069869Z","caller":"traceutil/trace.go:171","msg":"trace[908840946] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"337.300282ms","start":"2026-07-03T03:20:09.732562Z","end":"2026-07-03T03:20:10.069862Z","steps":["trace[908840946] 'process raft request' (duration: 337.128227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:10.069899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.753582Z","time spent":"316.279531ms","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T03:20:10.070021Z","caller":"traceutil/trace.go:171","msg":"trace[1178305097] transaction","detail":"{read_only:false; number_of_response:1; response_revision:514; }","duration":"333.754175ms","start":"2026-07-03T03:20:09.73626Z","end":"2026-07-03T03:20:10.070015Z","steps":["trace[1178305097] 'process raft request' (duration: 333.456477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:10.070064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.736243Z","time spent":"333.800437ms","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10111,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T03:20:10.069907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.732557Z","time spent":"337.332062ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T03:20:10.069832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.731503Z","time spent":"338.278718ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T03:20:10.07015Z","caller":"traceutil/trace.go:171","msg":"trace[1658851571] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"337.656051ms","start":"2026-07-03T03:20:09.732464Z","end":"2026-07-03T03:20:10.07012Z","steps":["trace[1658851571] 'process raft request' (duration: 337.177158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:10.070522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.732453Z","time spent":"337.9548ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T03:20:10.069929Z","caller":"traceutil/trace.go:171","msg":"trace[120214876] transaction","detail":"{read_only:false; number_of_response:0; response_revision:512; }","duration":"337.884747ms","start":"2026-07-03T03:20:09.73204Z","end":"2026-07-03T03:20:10.069924Z","steps":["trace[120214876] 'process raft request' (duration: 337.567469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:10.075248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:09.732028Z","time spent":"343.183351ms","remote":"127.0.0.1:53336","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T03:20:16.346871Z","caller":"traceutil/trace.go:171","msg":"trace[2005892936] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"255.819089ms","start":"2026-07-03T03:20:16.091029Z","end":"2026-07-03T03:20:16.346848Z","steps":["trace[2005892936] 'read index received' (duration: 255.11288ms)","trace[2005892936] 'applied index is now lower than readState.Index' (duration: 705.329µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:20:16.346947Z","caller":"traceutil/trace.go:171","msg":"trace[1470412378] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"458.174456ms","start":"2026-07-03T03:20:15.888734Z","end":"2026-07-03T03:20:16.346909Z","steps":["trace[1470412378] 'process raft request' (duration: 457.59728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:16.347083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.029115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-03T03:20:16.347126Z","caller":"traceutil/trace.go:171","msg":"trace[2080448106] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"256.122877ms","start":"2026-07-03T03:20:16.090992Z","end":"2026-07-03T03:20:16.347115Z","steps":["trace[2080448106] 'agreement among raft nodes before linearized reading' (duration: 255.978153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:16.347133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:15.888702Z","time spent":"458.3263ms","remote":"127.0.0.1:52950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T03:20:16.347208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.11914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-03T03:20:16.347281Z","caller":"traceutil/trace.go:171","msg":"trace[2079131010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"255.232333ms","start":"2026-07-03T03:20:16.092028Z","end":"2026-07-03T03:20:16.34726Z","steps":["trace[2079131010] 'agreement among raft nodes before linearized reading' (duration: 255.062919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:16.347311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.925708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-03T03:20:16.347406Z","caller":"traceutil/trace.go:171","msg":"trace[634531367] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"254.031311ms","start":"2026-07-03T03:20:16.093327Z","end":"2026-07-03T03:20:16.347358Z","steps":["trace[634531367] 'agreement among raft nodes before linearized reading' (duration: 253.833605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:16.347393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.140271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-03T03:20:16.347495Z","caller":"traceutil/trace.go:171","msg":"trace[821171279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"255.308945ms","start":"2026-07-03T03:20:16.092166Z","end":"2026-07-03T03:20:16.347475Z","steps":["trace[821171279] 'agreement among raft nodes before linearized reading' (duration: 255.010367ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:16.568317Z","caller":"traceutil/trace.go:171","msg":"trace[868706540] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"163.118531ms","start":"2026-07-03T03:20:16.405163Z","end":"2026-07-03T03:20:16.568282Z","steps":["trace[868706540] 'process raft request' (duration: 69.24133ms)","trace[868706540] 'compare' (duration: 93.679045ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:20:16.882112Z","caller":"traceutil/trace.go:171","msg":"trace[1854398605] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"297.318756ms","start":"2026-07-03T03:20:16.584768Z","end":"2026-07-03T03:20:16.882087Z","steps":["trace[1854398605] 'process raft request' (duration: 297.151011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:18.068309Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.082624417s","expected-duration":"1s"} {"level":"info","ts":"2026-07-03T03:20:18.074037Z","caller":"traceutil/trace.go:171","msg":"trace[2026865213] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.089516294s","start":"2026-07-03T03:20:16.984487Z","end":"2026-07-03T03:20:18.074003Z","steps":["trace[2026865213] 'process raft request' (duration: 1.085808914s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:18.074182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:16.984464Z","time spent":"1.089646017s","remote":"127.0.0.1:52950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T03:20:18.088652Z","caller":"traceutil/trace.go:171","msg":"trace[1516277108] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:630; }","duration":"368.961161ms","start":"2026-07-03T03:20:17.719634Z","end":"2026-07-03T03:20:18.088596Z","steps":["trace[1516277108] 'read index received' (duration: 348.970928ms)","trace[1516277108] 'applied index is now lower than readState.Index' (duration: 19.988963ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T03:20:18.088757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.103364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:20:18.088792Z","caller":"traceutil/trace.go:171","msg":"trace[1821654664] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"369.177537ms","start":"2026-07-03T03:20:17.719603Z","end":"2026-07-03T03:20:18.088781Z","steps":["trace[1821654664] 'agreement among raft nodes before linearized reading' (duration: 369.102015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:18.088824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:17.719588Z","time spent":"369.229268ms","remote":"127.0.0.1:52832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-03T03:20:19.839039Z","caller":"traceutil/trace.go:171","msg":"trace[1934561196] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"118.976192ms","start":"2026-07-03T03:20:19.720002Z","end":"2026-07-03T03:20:19.838978Z","steps":["trace[1934561196] 'read index received' (duration: 118.819818ms)","trace[1934561196] 'applied index is now lower than readState.Index' (duration: 155.114µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:20:19.839108Z","caller":"traceutil/trace.go:171","msg":"trace[1052384965] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"220.195202ms","start":"2026-07-03T03:20:19.61887Z","end":"2026-07-03T03:20:19.839065Z","steps":["trace[1052384965] 'process raft request' (duration: 219.962495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:19.839257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.236909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:20:19.839303Z","caller":"traceutil/trace.go:171","msg":"trace[231800139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"119.344692ms","start":"2026-07-03T03:20:19.719945Z","end":"2026-07-03T03:20:19.839289Z","steps":["trace[231800139] 'agreement among raft nodes before linearized reading' (duration: 119.229118ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:20.661097Z","caller":"traceutil/trace.go:171","msg":"trace[1277677067] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"155.506374ms","start":"2026-07-03T03:20:20.50557Z","end":"2026-07-03T03:20:20.661076Z","steps":["trace[1277677067] 'process raft request' (duration: 155.310739ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:20.865598Z","caller":"traceutil/trace.go:171","msg":"trace[839411576] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:688; }","duration":"249.521158ms","start":"2026-07-03T03:20:20.616056Z","end":"2026-07-03T03:20:20.865577Z","steps":["trace[839411576] 'read index received' (duration: 45.018813ms)","trace[839411576] 'applied index is now lower than readState.Index' (duration: 204.501405ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:20:20.865755Z","caller":"traceutil/trace.go:171","msg":"trace[241920676] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"352.67783ms","start":"2026-07-03T03:20:20.512968Z","end":"2026-07-03T03:20:20.865645Z","steps":["trace[241920676] 'process raft request' (duration: 352.033602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:20.865867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.285601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T03:20:20.865899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.793015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:20:20.865938Z","caller":"traceutil/trace.go:171","msg":"trace[1407997748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"147.395973ms","start":"2026-07-03T03:20:20.718525Z","end":"2026-07-03T03:20:20.865921Z","steps":["trace[1407997748] 'agreement among raft nodes before linearized reading' (duration: 147.231439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:20.8659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:20.512938Z","time spent":"352.896855ms","remote":"127.0.0.1:53022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17775,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T03:20:20.866125Z","caller":"traceutil/trace.go:171","msg":"trace[1547272486] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:658; }","duration":"249.937939ms","start":"2026-07-03T03:20:20.616002Z","end":"2026-07-03T03:20:20.86594Z","steps":["trace[1547272486] 'agreement among raft nodes before linearized reading' (duration: 249.701212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:21.416682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.156151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-03T03:20:21.416736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.616349ms","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-07-03T03:20:21.416811Z","caller":"traceutil/trace.go:171","msg":"trace[1720459449] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:659; }","duration":"200.739873ms","start":"2026-07-03T03:20:21.216049Z","end":"2026-07-03T03:20:21.416789Z","steps":["trace[1720459449] 'range keys from in-memory index tree' (duration: 200.383322ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:21.41676Z","caller":"traceutil/trace.go:171","msg":"trace[1913191746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:659; }","duration":"188.246193ms","start":"2026-07-03T03:20:21.228498Z","end":"2026-07-03T03:20:21.416744Z","steps":["trace[1913191746] 'range keys from in-memory index tree' (duration: 187.990647ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:21.570973Z","caller":"traceutil/trace.go:171","msg":"trace[958164486] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"179.557197ms","start":"2026-07-03T03:20:21.39138Z","end":"2026-07-03T03:20:21.570937Z","steps":["trace[958164486] 'process raft request' (duration: 179.30546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:21.571268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.045891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-07-03T03:20:21.571404Z","caller":"traceutil/trace.go:171","msg":"trace[1289731466] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:660; }","duration":"155.236026ms","start":"2026-07-03T03:20:21.416145Z","end":"2026-07-03T03:20:21.571381Z","steps":["trace[1289731466] 'agreement among raft nodes before linearized reading' (duration: 154.870636ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:21.570893Z","caller":"traceutil/trace.go:171","msg":"trace[2020184190] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:690; }","duration":"154.64107ms","start":"2026-07-03T03:20:21.416223Z","end":"2026-07-03T03:20:21.570864Z","steps":["trace[2020184190] 'read index received' (duration: 154.419014ms)","trace[2020184190] 'applied index is now lower than readState.Index' (duration: 220.746µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:20:21.622926Z","caller":"traceutil/trace.go:171","msg":"trace[791284877] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"201.602575ms","start":"2026-07-03T03:20:21.421299Z","end":"2026-07-03T03:20:21.622902Z","steps":["trace[791284877] 'process raft request' (duration: 200.764273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:21.622984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.4132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qt75f\" ","response":"range_response_count:1 size:17790"} {"level":"info","ts":"2026-07-03T03:20:21.62304Z","caller":"traceutil/trace.go:171","msg":"trace[1730306655] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qt75f; range_end:; response_count:1; response_revision:661; }","duration":"113.480602ms","start":"2026-07-03T03:20:21.509545Z","end":"2026-07-03T03:20:21.623025Z","steps":["trace[1730306655] 'agreement among raft nodes before linearized reading' (duration: 113.241935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:23.869631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.792109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:20:23.870026Z","caller":"traceutil/trace.go:171","msg":"trace[2123651928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"150.167649ms","start":"2026-07-03T03:20:23.719762Z","end":"2026-07-03T03:20:23.869929Z","steps":["trace[2123651928] 'range keys from in-memory index tree' (duration: 149.701537ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:31.762916Z","caller":"traceutil/trace.go:171","msg":"trace[1418220962] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"181.182612ms","start":"2026-07-03T03:20:31.581709Z","end":"2026-07-03T03:20:31.762892Z","steps":["trace[1418220962] 'process raft request' (duration: 180.823142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:35.680217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171358758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-03T03:20:35.680303Z","caller":"traceutil/trace.go:171","msg":"trace[664427351] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:767; }","duration":"1.171488912s","start":"2026-07-03T03:20:34.508792Z","end":"2026-07-03T03:20:35.680281Z","steps":["trace[664427351] 'range keys from in-memory index tree' (duration: 1.171190504s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:35.68035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:34.508768Z","time spent":"1.171566814s","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-03T03:20:35.680372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097123042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9szbg\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-07-03T03:20:35.680392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.018305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:20:35.680436Z","caller":"traceutil/trace.go:171","msg":"trace[568008819] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-9szbg; range_end:; response_count:1; response_revision:767; }","duration":"1.097235585s","start":"2026-07-03T03:20:34.583184Z","end":"2026-07-03T03:20:35.68042Z","steps":["trace[568008819] 'range keys from in-memory index tree' (duration: 1.096958748s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:35.680468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:34.583166Z","time spent":"1.097294976s","remote":"127.0.0.1:53022","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-9szbg\" "} {"level":"info","ts":"2026-07-03T03:20:35.68047Z","caller":"traceutil/trace.go:171","msg":"trace[1876917157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"961.136198ms","start":"2026-07-03T03:20:34.719315Z","end":"2026-07-03T03:20:35.680451Z","steps":["trace[1876917157] 'range keys from in-memory index tree' (duration: 960.930042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:35.680533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:34.719295Z","time spent":"961.229ms","remote":"127.0.0.1:52832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T03:20:35.680632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158678264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-03T03:20:35.680652Z","caller":"traceutil/trace.go:171","msg":"trace[1068149266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:767; }","duration":"1.158734595s","start":"2026-07-03T03:20:34.521911Z","end":"2026-07-03T03:20:35.680645Z","steps":["trace[1068149266] 'range keys from in-memory index tree' (duration: 1.158589652s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:35.680651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.524253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-03T03:20:35.680674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:34.521892Z","time spent":"1.158778277s","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-03T03:20:35.680687Z","caller":"traceutil/trace.go:171","msg":"trace[1872860372] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:767; }","duration":"815.606265ms","start":"2026-07-03T03:20:34.865069Z","end":"2026-07-03T03:20:35.680675Z","steps":["trace[1872860372] 'count revisions from in-memory index tree' (duration: 815.40994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:35.680717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:34.865047Z","time spent":"815.661956ms","remote":"127.0.0.1:53172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":52,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-03T03:20:35.680753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158847349s","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-07-03T03:20:35.680764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162990781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-03T03:20:35.68089Z","caller":"traceutil/trace.go:171","msg":"trace[1781381209] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:767; }","duration":"1.163146525s","start":"2026-07-03T03:20:34.517715Z","end":"2026-07-03T03:20:35.680862Z","steps":["trace[1781381209] 'range keys from in-memory index tree' (duration: 1.162783235s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:35.681019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:34.517702Z","time spent":"1.163242128s","remote":"127.0.0.1:53088","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-07-03T03:20:35.680896Z","caller":"traceutil/trace.go:171","msg":"trace[416602989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:767; }","duration":"1.159015943s","start":"2026-07-03T03:20:34.521831Z","end":"2026-07-03T03:20:35.680847Z","steps":["trace[416602989] 'range keys from in-memory index tree' (duration: 1.158651673s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:35.681103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:20:34.521823Z","time spent":"1.159264531s","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-03T03:20:35.923735Z","caller":"traceutil/trace.go:171","msg":"trace[1240423369] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"237.238633ms","start":"2026-07-03T03:20:35.686477Z","end":"2026-07-03T03:20:35.923715Z","steps":["trace[1240423369] 'process raft request' (duration: 237.12274ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:35.939375Z","caller":"traceutil/trace.go:171","msg":"trace[1404502251] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"252.250831ms","start":"2026-07-03T03:20:35.687099Z","end":"2026-07-03T03:20:35.93935Z","steps":["trace[1404502251] 'process raft request' (duration: 251.938732ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:35.939548Z","caller":"traceutil/trace.go:171","msg":"trace[114997862] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"248.943851ms","start":"2026-07-03T03:20:35.690583Z","end":"2026-07-03T03:20:35.939527Z","steps":["trace[114997862] 'process raft request' (duration: 248.717145ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:35.939668Z","caller":"traceutil/trace.go:171","msg":"trace[149742144] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"252.20529ms","start":"2026-07-03T03:20:35.687411Z","end":"2026-07-03T03:20:35.939617Z","steps":["trace[149742144] 'process raft request' (duration: 251.779558ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:35.939752Z","caller":"traceutil/trace.go:171","msg":"trace[1522990838] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"252.269121ms","start":"2026-07-03T03:20:35.687463Z","end":"2026-07-03T03:20:35.939732Z","steps":["trace[1522990838] 'process raft request' (duration: 251.766187ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:35.939948Z","caller":"traceutil/trace.go:171","msg":"trace[1524642044] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"234.268052ms","start":"2026-07-03T03:20:35.705662Z","end":"2026-07-03T03:20:35.93993Z","steps":["trace[1524642044] 'process raft request' (duration: 233.783159ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:20:35.940099Z","caller":"traceutil/trace.go:171","msg":"trace[1652540751] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:803; }","duration":"220.773396ms","start":"2026-07-03T03:20:35.719313Z","end":"2026-07-03T03:20:35.940086Z","steps":["trace[1652540751] 'read index received' (duration: 204.434462ms)","trace[1652540751] 'applied index is now lower than readState.Index' (duration: 16.337314ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T03:20:35.940206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.889138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:20:35.940241Z","caller":"traceutil/trace.go:171","msg":"trace[555035377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:773; }","duration":"220.973102ms","start":"2026-07-03T03:20:35.719259Z","end":"2026-07-03T03:20:35.940232Z","steps":["trace[555035377] 'agreement among raft nodes before linearized reading' (duration: 220.90883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:20:35.940352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.788779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-03T03:20:35.940407Z","caller":"traceutil/trace.go:171","msg":"trace[1310387983] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:773; }","duration":"159.893032ms","start":"2026-07-03T03:20:35.780502Z","end":"2026-07-03T03:20:35.940395Z","steps":["trace[1310387983] 'agreement among raft nodes before linearized reading' (duration: 159.732848ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:21:04.329762Z","caller":"traceutil/trace.go:171","msg":"trace[463360217] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"113.860171ms","start":"2026-07-03T03:21:04.215877Z","end":"2026-07-03T03:21:04.329737Z","steps":["trace[463360217] 'process raft request' (duration: 52.052274ms)","trace[463360217] 'compare' (duration: 61.650463ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:21:04.329989Z","caller":"traceutil/trace.go:171","msg":"trace[1077836846] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"113.648966ms","start":"2026-07-03T03:21:04.216321Z","end":"2026-07-03T03:21:04.32997Z","steps":["trace[1077836846] 'process raft request' (duration: 113.541633ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:21:04.330139Z","caller":"traceutil/trace.go:171","msg":"trace[22228560] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"114.081957ms","start":"2026-07-03T03:21:04.216031Z","end":"2026-07-03T03:21:04.330113Z","steps":["trace[22228560] 'process raft request' (duration: 113.669876ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:21:11.112148Z","caller":"traceutil/trace.go:171","msg":"trace[2127492679] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"165.648578ms","start":"2026-07-03T03:21:10.946481Z","end":"2026-07-03T03:21:11.112129Z","steps":["trace[2127492679] 'process raft request' (duration: 165.505284ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:21:12.356629Z","caller":"traceutil/trace.go:171","msg":"trace[1792977081] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"106.425921ms","start":"2026-07-03T03:21:12.250177Z","end":"2026-07-03T03:21:12.356603Z","steps":["trace[1792977081] 'process raft request' (duration: 106.264797ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:21:12.460114Z","caller":"traceutil/trace.go:171","msg":"trace[28088815] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1029; }","duration":"100.45893ms","start":"2026-07-03T03:21:12.359633Z","end":"2026-07-03T03:21:12.460092Z","steps":["trace[28088815] 'read index received' (duration: 99.433642ms)","trace[28088815] 'applied index is now lower than readState.Index' (duration: 1.023818ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T03:21:12.4603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.650505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-03T03:21:12.460357Z","caller":"traceutil/trace.go:171","msg":"trace[1585216222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:985; }","duration":"100.738997ms","start":"2026-07-03T03:21:12.3596Z","end":"2026-07-03T03:21:12.460339Z","steps":["trace[1585216222] 'agreement among raft nodes before linearized reading' (duration: 100.593213ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:21:19.312937Z","caller":"traceutil/trace.go:171","msg":"trace[1045914092] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"171.63813ms","start":"2026-07-03T03:21:19.141274Z","end":"2026-07-03T03:21:19.312912Z","steps":["trace[1045914092] 'process raft request' (duration: 171.494006ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:21:42.601786Z","caller":"traceutil/trace.go:171","msg":"trace[1519952160] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"232.300239ms","start":"2026-07-03T03:21:42.369467Z","end":"2026-07-03T03:21:42.601767Z","steps":["trace[1519952160] 'process raft request' (duration: 184.852867ms)","trace[1519952160] 'compare' (duration: 47.179815ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:21:44.112394Z","caller":"traceutil/trace.go:171","msg":"trace[1461891080] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"109.799068ms","start":"2026-07-03T03:21:44.002575Z","end":"2026-07-03T03:21:44.112374Z","steps":["trace[1461891080] 'process raft request' (duration: 109.586912ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:21:45.220031Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103446572197481,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-03T03:21:45.24312Z","caller":"traceutil/trace.go:171","msg":"trace[1081685559] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"523.492841ms","start":"2026-07-03T03:21:44.719608Z","end":"2026-07-03T03:21:45.243101Z","steps":["trace[1081685559] 'read index received' (duration: 522.641988ms)","trace[1081685559] 'applied index is now lower than readState.Index' (duration: 850.213µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:21:45.243336Z","caller":"traceutil/trace.go:171","msg":"trace[694509782] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"613.054372ms","start":"2026-07-03T03:21:44.630274Z","end":"2026-07-03T03:21:45.243328Z","steps":["trace[694509782] 'process raft request' (duration: 612.031894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:21:45.243413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:21:44.630253Z","time spent":"613.102943ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T03:21:45.24354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.932782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:21:45.243561Z","caller":"traceutil/trace.go:171","msg":"trace[209867533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1092; }","duration":"523.982164ms","start":"2026-07-03T03:21:44.719572Z","end":"2026-07-03T03:21:45.243554Z","steps":["trace[209867533] 'agreement among raft nodes before linearized reading' (duration: 523.950503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:21:45.24358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:21:44.719558Z","time spent":"524.018774ms","remote":"127.0.0.1:52838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T03:21:45.243714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.979711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-03T03:21:45.243731Z","caller":"traceutil/trace.go:171","msg":"trace[870620537] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1092; }","duration":"360.039552ms","start":"2026-07-03T03:21:44.883686Z","end":"2026-07-03T03:21:45.243726Z","steps":["trace[870620537] 'agreement among raft nodes before linearized reading' (duration: 359.972271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:21:45.243747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:21:44.883654Z","time spent":"360.089144ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-03T03:21:45.243897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.272456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T03:21:45.243921Z","caller":"traceutil/trace.go:171","msg":"trace[1267629874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1092; }","duration":"334.311167ms","start":"2026-07-03T03:21:44.909605Z","end":"2026-07-03T03:21:45.243916Z","steps":["trace[1267629874] 'agreement among raft nodes before linearized reading' (duration: 334.242275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:21:45.243942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:21:44.909596Z","time spent":"334.340507ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T03:21:45.244038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.871092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-03T03:21:45.244061Z","caller":"traceutil/trace.go:171","msg":"trace[1748357457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1092; }","duration":"334.913752ms","start":"2026-07-03T03:21:44.909142Z","end":"2026-07-03T03:21:45.244056Z","steps":["trace[1748357457] 'agreement among raft nodes before linearized reading' (duration: 334.861811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:21:45.244076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:21:44.909134Z","time spent":"334.938404ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T03:21:45.244611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.628583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-03T03:21:45.244696Z","caller":"traceutil/trace.go:171","msg":"trace[1274234521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1092; }","duration":"345.755196ms","start":"2026-07-03T03:21:44.898916Z","end":"2026-07-03T03:21:45.244671Z","steps":["trace[1274234521] 'agreement among raft nodes before linearized reading' (duration: 345.51658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:21:45.244735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:21:44.898893Z","time spent":"345.833938ms","remote":"127.0.0.1:53088","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-07-03T03:21:46.009254Z","caller":"traceutil/trace.go:171","msg":"trace[287479478] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1147; }","duration":"288.775636ms","start":"2026-07-03T03:21:45.720449Z","end":"2026-07-03T03:21:46.009225Z","steps":["trace[287479478] 'read index received' (duration: 288.546169ms)","trace[287479478] 'applied index is now lower than readState.Index' (duration: 228.387µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:21:46.009311Z","caller":"traceutil/trace.go:171","msg":"trace[1638584752] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"376.425005ms","start":"2026-07-03T03:21:45.632844Z","end":"2026-07-03T03:21:46.009269Z","steps":["trace[1638584752] 'process raft request' (duration: 376.220469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:21:46.00944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.968461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:21:46.009498Z","caller":"traceutil/trace.go:171","msg":"trace[1938208827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1097; }","duration":"289.087244ms","start":"2026-07-03T03:21:45.720396Z","end":"2026-07-03T03:21:46.009483Z","steps":["trace[1938208827] 'agreement among raft nodes before linearized reading' (duration: 288.981561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:21:46.009555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:21:45.63282Z","time spent":"376.570029ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T03:21:49.410848Z","caller":"traceutil/trace.go:171","msg":"trace[961175529] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"111.663329ms","start":"2026-07-03T03:21:49.299156Z","end":"2026-07-03T03:21:49.410819Z","steps":["trace[961175529] 'process raft request' (duration: 111.458143ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:21:49.411862Z","caller":"traceutil/trace.go:171","msg":"trace[1804017637] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"111.666398ms","start":"2026-07-03T03:21:49.300174Z","end":"2026-07-03T03:21:49.41184Z","steps":["trace[1804017637] 'process raft request' (duration: 110.60412ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:02.471415Z","caller":"traceutil/trace.go:171","msg":"trace[1367198722] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"124.488885ms","start":"2026-07-03T03:22:02.346856Z","end":"2026-07-03T03:22:02.471345Z","steps":["trace[1367198722] 'read index received' (duration: 124.205998ms)","trace[1367198722] 'applied index is now lower than readState.Index' (duration: 281.587µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:22:02.471644Z","caller":"traceutil/trace.go:171","msg":"trace[335037606] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"396.060566ms","start":"2026-07-03T03:22:02.075559Z","end":"2026-07-03T03:22:02.47162Z","steps":["trace[335037606] 'process raft request' (duration: 395.592033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:02.471773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:02.075535Z","time spent":"396.174829ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T03:22:02.471831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.947707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-03T03:22:02.471867Z","caller":"traceutil/trace.go:171","msg":"trace[1703546752] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:1201; }","duration":"125.04331ms","start":"2026-07-03T03:22:02.346814Z","end":"2026-07-03T03:22:02.471858Z","steps":["trace[1703546752] 'agreement among raft nodes before linearized reading' (duration: 124.687721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:05.60214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.88305567s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:22:05.602256Z","caller":"traceutil/trace.go:171","msg":"trace[890385683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1207; }","duration":"1.883269066s","start":"2026-07-03T03:22:03.718966Z","end":"2026-07-03T03:22:05.602235Z","steps":["trace[890385683] 'range keys from in-memory index tree' (duration: 1.882898886s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:05.6023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:03.718932Z","time spent":"1.883360108s","remote":"127.0.0.1:52832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T03:22:05.602914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.626760712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:22:05.602965Z","caller":"traceutil/trace.go:171","msg":"trace[239789952] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1207; }","duration":"1.626872325s","start":"2026-07-03T03:22:03.976079Z","end":"2026-07-03T03:22:05.602951Z","steps":["trace[239789952] 'count revisions from in-memory index tree' (duration: 1.6266861s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:05.602988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:03.976059Z","time spent":"1.626921577s","remote":"127.0.0.1:50892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-03T03:22:05.60729Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.130361354s","expected-duration":"1s"} {"level":"info","ts":"2026-07-03T03:22:05.607822Z","caller":"traceutil/trace.go:171","msg":"trace[1300089103] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"1.130992001s","start":"2026-07-03T03:22:04.476793Z","end":"2026-07-03T03:22:05.607785Z","steps":["trace[1300089103] 'process raft request' (duration: 1.130762695s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:05.608016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:04.476769Z","time spent":"1.131155046s","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T03:22:05.609037Z","caller":"traceutil/trace.go:171","msg":"trace[1331044797] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1265; }","duration":"223.305117ms","start":"2026-07-03T03:22:05.385697Z","end":"2026-07-03T03:22:05.609002Z","steps":["trace[1331044797] 'read index received' (duration: 222.091874ms)","trace[1331044797] 'applied index is now lower than readState.Index' (duration: 1.212233ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:22:05.609322Z","caller":"traceutil/trace.go:171","msg":"trace[1663918028] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"972.493857ms","start":"2026-07-03T03:22:04.636814Z","end":"2026-07-03T03:22:05.609308Z","steps":["trace[1663918028] 'process raft request' (duration: 972.048665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:05.60945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:04.636797Z","time spent":"972.564228ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T03:22:05.6096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.586737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-03T03:22:05.609688Z","caller":"traceutil/trace.go:171","msg":"trace[2062090912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1209; }","duration":"222.68528ms","start":"2026-07-03T03:22:05.386989Z","end":"2026-07-03T03:22:05.609674Z","steps":["trace[2062090912] 'agreement among raft nodes before linearized reading' (duration: 222.475304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:05.609806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.745195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-03T03:22:05.609825Z","caller":"traceutil/trace.go:171","msg":"trace[1513234816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1209; }","duration":"133.794277ms","start":"2026-07-03T03:22:05.476025Z","end":"2026-07-03T03:22:05.609819Z","steps":["trace[1513234816] 'agreement among raft nodes before linearized reading' (duration: 133.725675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:05.609963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.268112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-03T03:22:05.609983Z","caller":"traceutil/trace.go:171","msg":"trace[1734992096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1209; }","duration":"224.335374ms","start":"2026-07-03T03:22:05.385642Z","end":"2026-07-03T03:22:05.609977Z","steps":["trace[1734992096] 'agreement among raft nodes before linearized reading' (duration: 224.240551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:05.610035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.804037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T03:22:05.610112Z","caller":"traceutil/trace.go:171","msg":"trace[894289719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1209; }","duration":"160.91759ms","start":"2026-07-03T03:22:05.449164Z","end":"2026-07-03T03:22:05.610081Z","steps":["trace[894289719] 'agreement among raft nodes before linearized reading' (duration: 160.694224ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:07.817825Z","caller":"traceutil/trace.go:171","msg":"trace[1431055679] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"188.238308ms","start":"2026-07-03T03:22:07.629556Z","end":"2026-07-03T03:22:07.817794Z","steps":["trace[1431055679] 'process raft request' (duration: 188.018882ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:07.818035Z","caller":"traceutil/trace.go:171","msg":"trace[1588480301] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"188.29928ms","start":"2026-07-03T03:22:07.629707Z","end":"2026-07-03T03:22:07.818006Z","steps":["trace[1588480301] 'process raft request' (duration: 187.910699ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:07.818247Z","caller":"traceutil/trace.go:171","msg":"trace[342503352] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"186.424979ms","start":"2026-07-03T03:22:07.631811Z","end":"2026-07-03T03:22:07.818236Z","steps":["trace[342503352] 'process raft request' (duration: 185.837923ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:07.818353Z","caller":"traceutil/trace.go:171","msg":"trace[390635876] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"188.911927ms","start":"2026-07-03T03:22:07.629413Z","end":"2026-07-03T03:22:07.818325Z","steps":["trace[390635876] 'process raft request' (duration: 149.554193ms)","trace[390635876] 'compare' (duration: 38.449439ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:22:07.818392Z","caller":"traceutil/trace.go:171","msg":"trace[590322981] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"171.506206ms","start":"2026-07-03T03:22:07.646876Z","end":"2026-07-03T03:22:07.818382Z","steps":["trace[590322981] 'process raft request' (duration: 170.813387ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:12.580893Z","caller":"traceutil/trace.go:171","msg":"trace[306846157] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"126.501469ms","start":"2026-07-03T03:22:12.454361Z","end":"2026-07-03T03:22:12.580862Z","steps":["trace[306846157] 'process raft request' (duration: 97.830234ms)","trace[306846157] 'compare' (duration: 28.530951ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:22:18.428768Z","caller":"traceutil/trace.go:171","msg":"trace[1272734863] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1310; }","duration":"397.700951ms","start":"2026-07-03T03:22:18.031042Z","end":"2026-07-03T03:22:18.428743Z","steps":["trace[1272734863] 'read index received' (duration: 397.245658ms)","trace[1272734863] 'applied index is now lower than readState.Index' (duration: 454.213µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:22:18.428856Z","caller":"traceutil/trace.go:171","msg":"trace[982377198] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"399.717085ms","start":"2026-07-03T03:22:18.02911Z","end":"2026-07-03T03:22:18.428827Z","steps":["trace[982377198] 'process raft request' (duration: 399.310644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:18.429004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:18.029097Z","time spent":"399.825457ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T03:22:18.429084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.027569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-03T03:22:18.429129Z","caller":"traceutil/trace.go:171","msg":"trace[103458764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1251; }","duration":"398.146673ms","start":"2026-07-03T03:22:18.030972Z","end":"2026-07-03T03:22:18.429119Z","steps":["trace[103458764] 'agreement among raft nodes before linearized reading' (duration: 397.906656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:18.429165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:18.030919Z","time spent":"398.238515ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T03:22:18.734201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.545306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T03:22:18.734377Z","caller":"traceutil/trace.go:171","msg":"trace[1053126268] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"540.692006ms","start":"2026-07-03T03:22:18.193668Z","end":"2026-07-03T03:22:18.73436Z","steps":["trace[1053126268] 'process raft request' (duration: 540.632324ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:18.734454Z","caller":"traceutil/trace.go:171","msg":"trace[334689937] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"700.68863ms","start":"2026-07-03T03:22:18.03375Z","end":"2026-07-03T03:22:18.734439Z","steps":["trace[334689937] 'process raft request' (duration: 578.814546ms)","trace[334689937] 'compare' (duration: 121.33502ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T03:22:18.734472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:18.193647Z","time spent":"540.779008ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T03:22:18.734514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:18.033702Z","time spent":"700.784493ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T03:22:18.819555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.067895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:22:18.819603Z","caller":"traceutil/trace.go:171","msg":"trace[635048624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1254; }","duration":"100.170228ms","start":"2026-07-03T03:22:18.719423Z","end":"2026-07-03T03:22:18.819593Z","steps":["trace[635048624] 'agreement among raft nodes before linearized reading' (duration: 100.080945ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:18.819546Z","caller":"traceutil/trace.go:171","msg":"trace[1162587880] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"386.034775ms","start":"2026-07-03T03:22:18.433488Z","end":"2026-07-03T03:22:18.819523Z","steps":["trace[1162587880] 'process raft request' (duration: 385.832349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:18.819759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:18.433472Z","time spent":"386.192309ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T03:22:21.223003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103446572197861,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-03T03:22:21.385349Z","caller":"traceutil/trace.go:171","msg":"trace[98675456] linearizableReadLoop","detail":"{readStateIndex:1319; appliedIndex:1318; }","duration":"662.543259ms","start":"2026-07-03T03:22:20.72279Z","end":"2026-07-03T03:22:21.385333Z","steps":["trace[98675456] 'read index received' (duration: 662.376165ms)","trace[98675456] 'applied index is now lower than readState.Index' (duration: 166.144µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:22:21.385423Z","caller":"traceutil/trace.go:171","msg":"trace[2054243723] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"665.654553ms","start":"2026-07-03T03:22:20.719716Z","end":"2026-07-03T03:22:21.385371Z","steps":["trace[2054243723] 'process raft request' (duration: 665.496879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:21.385542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.906599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-03T03:22:21.385567Z","caller":"traceutil/trace.go:171","msg":"trace[939616911] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1259; }","duration":"645.970141ms","start":"2026-07-03T03:22:20.739589Z","end":"2026-07-03T03:22:21.385559Z","steps":["trace[939616911] 'agreement among raft nodes before linearized reading' (duration: 645.851208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:21.385587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:20.739575Z","time spent":"646.007492ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-03T03:22:21.385553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.728174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T03:22:21.385612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.806916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:22:21.385658Z","caller":"traceutil/trace.go:171","msg":"trace[1972981492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1259; }","duration":"662.846977ms","start":"2026-07-03T03:22:20.722785Z","end":"2026-07-03T03:22:21.385632Z","steps":["trace[1972981492] 'agreement among raft nodes before linearized reading' (duration: 662.631152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:21.385709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.351154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-03T03:22:21.385725Z","caller":"traceutil/trace.go:171","msg":"trace[1964353051] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1259; }","duration":"433.991091ms","start":"2026-07-03T03:22:20.951709Z","end":"2026-07-03T03:22:21.3857Z","steps":["trace[1964353051] 'agreement among raft nodes before linearized reading' (duration: 433.769875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:21.385732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:20.722758Z","time spent":"662.960811ms","remote":"127.0.0.1:52832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T03:22:21.385767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.001027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:22:21.3858Z","caller":"traceutil/trace.go:171","msg":"trace[641596489] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1259; }","duration":"614.073579ms","start":"2026-07-03T03:22:20.771719Z","end":"2026-07-03T03:22:21.385793Z","steps":["trace[641596489] 'agreement among raft nodes before linearized reading' (duration: 614.032878ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:21.3858Z","caller":"traceutil/trace.go:171","msg":"trace[1479113040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1259; }","duration":"561.465657ms","start":"2026-07-03T03:22:20.824294Z","end":"2026-07-03T03:22:21.38576Z","steps":["trace[1479113040] 'agreement among raft nodes before linearized reading' (duration: 561.22064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:21.385806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:20.951694Z","time spent":"434.088473ms","remote":"127.0.0.1:59734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-07-03T03:22:21.385862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:20.824282Z","time spent":"561.56285ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T03:22:21.385739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.348242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T03:22:21.385914Z","caller":"traceutil/trace.go:171","msg":"trace[1175191210] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1259; }","duration":"575.565878ms","start":"2026-07-03T03:22:20.810343Z","end":"2026-07-03T03:22:21.385909Z","steps":["trace[1175191210] 'agreement among raft nodes before linearized reading' (duration: 575.365222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:21.385936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:20.810323Z","time spent":"575.608809ms","remote":"127.0.0.1:52950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":11,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-07-03T03:22:21.386026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.445217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:22:21.386045Z","caller":"traceutil/trace.go:171","msg":"trace[1313616807] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1259; }","duration":"453.497958ms","start":"2026-07-03T03:22:20.932542Z","end":"2026-07-03T03:22:21.38604Z","steps":["trace[1313616807] 'agreement among raft nodes before linearized reading' (duration: 453.466538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:21.386059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:20.932523Z","time spent":"453.53214ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-07-03T03:22:21.385819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:20.771698Z","time spent":"614.11484ms","remote":"127.0.0.1:50882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-03T03:22:21.386196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.169765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T03:22:21.386241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.885888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T03:22:21.386263Z","caller":"traceutil/trace.go:171","msg":"trace[64495478] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1259; }","duration":"312.9586ms","start":"2026-07-03T03:22:21.073298Z","end":"2026-07-03T03:22:21.386257Z","steps":["trace[64495478] 'agreement among raft nodes before linearized reading' (duration: 312.917029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:21.386279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:21.073278Z","time spent":"312.997131ms","remote":"127.0.0.1:53210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-07-03T03:22:21.386265Z","caller":"traceutil/trace.go:171","msg":"trace[682054400] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1259; }","duration":"357.35451ms","start":"2026-07-03T03:22:21.028888Z","end":"2026-07-03T03:22:21.386243Z","steps":["trace[682054400] 'agreement among raft nodes before linearized reading' (duration: 357.238157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:21.386317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:21.028852Z","time spent":"357.448252ms","remote":"127.0.0.1:59672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-03T03:22:21.386482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:20.7197Z","time spent":"666.693191ms","remote":"127.0.0.1:52924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":839,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T03:22:22.318017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:22.010287Z","time spent":"307.724828ms","remote":"127.0.0.1:37860","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-03T03:22:22.318655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.554137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T03:22:22.318873Z","caller":"traceutil/trace.go:171","msg":"trace[691941816] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"928.274263ms","start":"2026-07-03T03:22:21.390559Z","end":"2026-07-03T03:22:22.318833Z","steps":["trace[691941816] 'process raft request' (duration: 510.275724ms)","trace[691941816] 'compare' (duration: 417.120475ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:22:22.318876Z","caller":"traceutil/trace.go:171","msg":"trace[1944061155] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1319; }","duration":"555.056313ms","start":"2026-07-03T03:22:21.763804Z","end":"2026-07-03T03:22:22.31886Z","steps":["trace[1944061155] 'read index received' (duration: 136.947531ms)","trace[1944061155] 'applied index is now lower than readState.Index' (duration: 418.107972ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T03:22:22.318969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:21.390543Z","time spent":"928.361114ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T03:22:22.319093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.288279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T03:22:22.319115Z","caller":"traceutil/trace.go:171","msg":"trace[2032023474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1262; }","duration":"555.339091ms","start":"2026-07-03T03:22:21.763768Z","end":"2026-07-03T03:22:22.319107Z","steps":["trace[2032023474] 'agreement among raft nodes before linearized reading' (duration: 555.256109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:22.319134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:21.763752Z","time spent":"555.376603ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-03T03:22:22.319234Z","caller":"traceutil/trace.go:171","msg":"trace[323020709] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"928.145449ms","start":"2026-07-03T03:22:21.391082Z","end":"2026-07-03T03:22:22.319228Z","steps":["trace[323020709] 'process raft request' (duration: 927.666216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:22.319271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:21.391062Z","time spent":"928.18745ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T03:22:22.319295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.744958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-03T03:22:22.319336Z","caller":"traceutil/trace.go:171","msg":"trace[20643986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1262; }","duration":"405.82025ms","start":"2026-07-03T03:22:21.913505Z","end":"2026-07-03T03:22:22.319325Z","steps":["trace[20643986] 'agreement among raft nodes before linearized reading' (duration: 405.662956ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:22.31935Z","caller":"traceutil/trace.go:171","msg":"trace[1131638100] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"623.582476ms","start":"2026-07-03T03:22:21.695762Z","end":"2026-07-03T03:22:22.319344Z","steps":["trace[1131638100] 'process raft request' (duration: 623.065222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:22.319373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:21.913489Z","time spent":"405.875461ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-03T03:22:22.319385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:21.69574Z","time spent":"623.625697ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T03:22:22.319511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.617819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:22:22.319541Z","caller":"traceutil/trace.go:171","msg":"trace[973763678] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1262; }","duration":"171.68103ms","start":"2026-07-03T03:22:22.147849Z","end":"2026-07-03T03:22:22.31953Z","steps":["trace[973763678] 'agreement among raft nodes before linearized reading' (duration: 171.633499ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:22.473217Z","caller":"traceutil/trace.go:171","msg":"trace[1992830885] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1322; }","duration":"125.927714ms","start":"2026-07-03T03:22:22.347255Z","end":"2026-07-03T03:22:22.473183Z","steps":["trace[1992830885] 'read index received' (duration: 38.017297ms)","trace[1992830885] 'applied index is now lower than readState.Index' (duration: 87.908767ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:22:22.473381Z","caller":"traceutil/trace.go:171","msg":"trace[2099415971] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"149.027008ms","start":"2026-07-03T03:22:22.32434Z","end":"2026-07-03T03:22:22.473367Z","steps":["trace[2099415971] 'process raft request' (duration: 123.531508ms)","trace[2099415971] 'compare' (duration: 25.193152ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T03:22:22.473667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.378206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-03T03:22:22.473698Z","caller":"traceutil/trace.go:171","msg":"trace[1719896144] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:1264; }","duration":"126.476789ms","start":"2026-07-03T03:22:22.347212Z","end":"2026-07-03T03:22:22.473689Z","steps":["trace[1719896144] 'agreement among raft nodes before linearized reading' (duration: 126.332635ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:22.64034Z","caller":"traceutil/trace.go:171","msg":"trace[2055575212] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"162.938014ms","start":"2026-07-03T03:22:22.477357Z","end":"2026-07-03T03:22:22.640295Z","steps":["trace[2055575212] 'process raft request' (duration: 156.226743ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:22.673784Z","caller":"traceutil/trace.go:171","msg":"trace[1837660050] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"172.040171ms","start":"2026-07-03T03:22:22.501728Z","end":"2026-07-03T03:22:22.673768Z","steps":["trace[1837660050] 'process raft request' (duration: 171.954229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:23.970127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.611234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:22:23.970197Z","caller":"traceutil/trace.go:171","msg":"trace[380479770] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1269; }","duration":"725.713427ms","start":"2026-07-03T03:22:23.244464Z","end":"2026-07-03T03:22:23.970177Z","steps":["trace[380479770] 'range keys from in-memory index tree' (duration: 725.556073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:23.970234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:23.24445Z","time spent":"725.774509ms","remote":"127.0.0.1:53196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"warn","ts":"2026-07-03T03:22:23.970904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.714228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T03:22:23.97102Z","caller":"traceutil/trace.go:171","msg":"trace[280977065] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1269; }","duration":"389.278732ms","start":"2026-07-03T03:22:23.581717Z","end":"2026-07-03T03:22:23.970996Z","steps":["trace[280977065] 'count revisions from in-memory index tree' (duration: 388.568254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:23.97108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:23.581699Z","time spent":"389.364255ms","remote":"127.0.0.1:53326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":105,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-07-03T03:22:24.467734Z","caller":"traceutil/trace.go:171","msg":"trace[658326174] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"142.400859ms","start":"2026-07-03T03:22:24.325308Z","end":"2026-07-03T03:22:24.467709Z","steps":["trace[658326174] 'process raft request' (duration: 142.249775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:24.625108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.045848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T03:22:24.625402Z","caller":"traceutil/trace.go:171","msg":"trace[813489655] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"297.324597ms","start":"2026-07-03T03:22:24.328039Z","end":"2026-07-03T03:22:24.625364Z","steps":["trace[813489655] 'process raft request' (duration: 177.848937ms)","trace[813489655] 'compare' (duration: 118.874494ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:22:24.625448Z","caller":"traceutil/trace.go:171","msg":"trace[338618360] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1330; }","duration":"236.485393ms","start":"2026-07-03T03:22:24.388941Z","end":"2026-07-03T03:22:24.625426Z","steps":["trace[338618360] 'read index received' (duration: 78.671987ms)","trace[338618360] 'applied index is now lower than readState.Index' (duration: 157.811896ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T03:22:24.625798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.844812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-03T03:22:24.625833Z","caller":"traceutil/trace.go:171","msg":"trace[186199762] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1272; }","duration":"236.933084ms","start":"2026-07-03T03:22:24.388893Z","end":"2026-07-03T03:22:24.625826Z","steps":["trace[186199762] 'agreement among raft nodes before linearized reading' (duration: 236.78464ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:24.625838Z","caller":"traceutil/trace.go:171","msg":"trace[699581737] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"295.888028ms","start":"2026-07-03T03:22:24.329921Z","end":"2026-07-03T03:22:24.625809Z","steps":["trace[699581737] 'process raft request' (duration: 295.407395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:24.625925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.576154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T03:22:24.625966Z","caller":"traceutil/trace.go:171","msg":"trace[640156734] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1272; }","duration":"142.659357ms","start":"2026-07-03T03:22:24.483297Z","end":"2026-07-03T03:22:24.625957Z","steps":["trace[640156734] 'agreement among raft nodes before linearized reading' (duration: 142.592265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:24.625863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.267081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T03:22:24.626013Z","caller":"traceutil/trace.go:171","msg":"trace[1204732163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1272; }","duration":"147.485917ms","start":"2026-07-03T03:22:24.478518Z","end":"2026-07-03T03:22:24.626004Z","steps":["trace[1204732163] 'agreement among raft nodes before linearized reading' (duration: 147.186289ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:24.840761Z","caller":"traceutil/trace.go:171","msg":"trace[1144637792] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1333; }","duration":"196.699157ms","start":"2026-07-03T03:22:24.644024Z","end":"2026-07-03T03:22:24.840724Z","steps":["trace[1144637792] 'read index received' (duration: 15.098648ms)","trace[1144637792] 'applied index is now lower than readState.Index' (duration: 181.599109ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:22:24.841006Z","caller":"traceutil/trace.go:171","msg":"trace[1644244780] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"211.026704ms","start":"2026-07-03T03:22:24.629952Z","end":"2026-07-03T03:22:24.840978Z","steps":["trace[1644244780] 'process raft request' (duration: 129.607773ms)","trace[1644244780] 'compare' (duration: 81.027411ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T03:22:24.841123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.503474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:22:24.841231Z","caller":"traceutil/trace.go:171","msg":"trace[2035772837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1274; }","duration":"121.671929ms","start":"2026-07-03T03:22:24.719534Z","end":"2026-07-03T03:22:24.841206Z","steps":["trace[2035772837] 'agreement among raft nodes before linearized reading' (duration: 121.487104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:24.841239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.19196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-03T03:22:24.841315Z","caller":"traceutil/trace.go:171","msg":"trace[757197999] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1274; }","duration":"197.340294ms","start":"2026-07-03T03:22:24.643959Z","end":"2026-07-03T03:22:24.8413Z","steps":["trace[757197999] 'agreement among raft nodes before linearized reading' (duration: 197.085527ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:25.630024Z","caller":"traceutil/trace.go:171","msg":"trace[269080496] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"452.022109ms","start":"2026-07-03T03:22:25.177969Z","end":"2026-07-03T03:22:25.629991Z","steps":["trace[269080496] 'process raft request' (duration: 451.842834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:25.63031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:25.177947Z","time spent":"452.261796ms","remote":"127.0.0.1:52924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":784,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T03:22:32.045414Z","caller":"traceutil/trace.go:171","msg":"trace[810922838] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"150.92471ms","start":"2026-07-03T03:22:31.894456Z","end":"2026-07-03T03:22:32.045381Z","steps":["trace[810922838] 'process raft request' (duration: 108.710639ms)","trace[810922838] 'compare' (duration: 42.073617ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:22:32.126795Z","caller":"traceutil/trace.go:171","msg":"trace[425654641] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"227.650663ms","start":"2026-07-03T03:22:31.899111Z","end":"2026-07-03T03:22:32.126761Z","steps":["trace[425654641] 'process raft request' (duration: 227.492339ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:34.553306Z","caller":"traceutil/trace.go:171","msg":"trace[13540331] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"144.797694ms","start":"2026-07-03T03:22:34.408481Z","end":"2026-07-03T03:22:34.553279Z","steps":["trace[13540331] 'process raft request' (duration: 144.698421ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:34.553376Z","caller":"traceutil/trace.go:171","msg":"trace[1572897742] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"147.726234ms","start":"2026-07-03T03:22:34.40561Z","end":"2026-07-03T03:22:34.553336Z","steps":["trace[1572897742] 'process raft request' (duration: 147.416805ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:34.554395Z","caller":"traceutil/trace.go:171","msg":"trace[42489063] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"121.496944ms","start":"2026-07-03T03:22:34.432865Z","end":"2026-07-03T03:22:34.554362Z","steps":["trace[42489063] 'process raft request' (duration: 121.041872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:35.356341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.533699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:22:35.356405Z","caller":"traceutil/trace.go:171","msg":"trace[241136715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1341; }","duration":"635.645682ms","start":"2026-07-03T03:22:34.720744Z","end":"2026-07-03T03:22:35.35639Z","steps":["trace[241136715] 'range keys from in-memory index tree' (duration: 635.447936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:35.356433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:34.720718Z","time spent":"635.709444ms","remote":"127.0.0.1:52838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T03:22:35.356526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.930625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-03T03:22:35.356601Z","caller":"traceutil/trace.go:171","msg":"trace[1153789273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1341; }","duration":"341.08101ms","start":"2026-07-03T03:22:35.015501Z","end":"2026-07-03T03:22:35.356582Z","steps":["trace[1153789273] 'range keys from in-memory index tree' (duration: 340.702129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:35.356645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:35.015458Z","time spent":"341.177562ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-03T03:22:35.3567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.337088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-03T03:22:35.356771Z","caller":"traceutil/trace.go:171","msg":"trace[2141884333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1341; }","duration":"620.460851ms","start":"2026-07-03T03:22:34.736291Z","end":"2026-07-03T03:22:35.356752Z","steps":["trace[2141884333] 'range keys from in-memory index tree' (duration: 620.127322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:35.356733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.308008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-03T03:22:35.356839Z","caller":"traceutil/trace.go:171","msg":"trace[1975349565] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1341; }","duration":"320.445991ms","start":"2026-07-03T03:22:35.036384Z","end":"2026-07-03T03:22:35.35683Z","steps":["trace[1975349565] 'range keys from in-memory index tree' (duration: 319.911437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:35.356852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:34.736271Z","time spent":"620.571945ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T03:22:35.356835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.223159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-03T03:22:35.356925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.260533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T03:22:35.35697Z","caller":"traceutil/trace.go:171","msg":"trace[1145260554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1341; }","duration":"584.323785ms","start":"2026-07-03T03:22:34.772617Z","end":"2026-07-03T03:22:35.356941Z","steps":["trace[1145260554] 'range keys from in-memory index tree' (duration: 583.721849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:35.356989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:34.772596Z","time spent":"584.389197ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-03T03:22:35.356942Z","caller":"traceutil/trace.go:171","msg":"trace[1190094453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1341; }","duration":"585.401894ms","start":"2026-07-03T03:22:34.771515Z","end":"2026-07-03T03:22:35.356917Z","steps":["trace[1190094453] 'range keys from in-memory index tree' (duration: 584.934992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:35.357026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:34.771485Z","time spent":"585.530647ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-03T03:22:35.356862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:35.036361Z","time spent":"320.494733ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-03T03:22:36.219863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103446572198039,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T03:22:36.465852Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.104780273s","expected-duration":"1s"} {"level":"info","ts":"2026-07-03T03:22:36.466602Z","caller":"traceutil/trace.go:171","msg":"trace[469261190] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"1.105698188s","start":"2026-07-03T03:22:35.360877Z","end":"2026-07-03T03:22:36.466576Z","steps":["trace[469261190] 'process raft request' (duration: 1.105533224s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:36.466811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:35.36085Z","time spent":"1.105870873s","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T03:22:36.472747Z","caller":"traceutil/trace.go:171","msg":"trace[594216037] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1405; }","duration":"753.887488ms","start":"2026-07-03T03:22:35.718822Z","end":"2026-07-03T03:22:36.47271Z","steps":["trace[594216037] 'read index received' (duration: 747.937157ms)","trace[594216037] 'applied index is now lower than readState.Index' (duration: 5.948591ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:22:36.473162Z","caller":"traceutil/trace.go:171","msg":"trace[1938746250] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"1.111839544s","start":"2026-07-03T03:22:35.361313Z","end":"2026-07-03T03:22:36.473153Z","steps":["trace[1938746250] 'process raft request' (duration: 1.111249208s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:36.473156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.332891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T03:22:36.473214Z","caller":"traceutil/trace.go:171","msg":"trace[272808450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"754.436533ms","start":"2026-07-03T03:22:35.718766Z","end":"2026-07-03T03:22:36.473203Z","steps":["trace[272808450] 'agreement among raft nodes before linearized reading' (duration: 754.282999ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:36.473202Z","caller":"traceutil/trace.go:171","msg":"trace[1012368082] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"1.112130522s","start":"2026-07-03T03:22:35.361055Z","end":"2026-07-03T03:22:36.473186Z","steps":["trace[1012368082] 'process raft request' (duration: 1.111255428s)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:36.473107Z","caller":"traceutil/trace.go:171","msg":"trace[2044612500] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"1.111177176s","start":"2026-07-03T03:22:35.361879Z","end":"2026-07-03T03:22:36.473057Z","steps":["trace[2044612500] 'process raft request' (duration: 1.110728734s)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:36.473065Z","caller":"traceutil/trace.go:171","msg":"trace[1990917182] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"1.111787793s","start":"2026-07-03T03:22:35.361241Z","end":"2026-07-03T03:22:36.473029Z","steps":["trace[1990917182] 'process raft request' (duration: 1.111270089s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:36.473271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:35.361295Z","time spent":"1.111909226s","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T03:22:36.473252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:35.718745Z","time spent":"754.501344ms","remote":"127.0.0.1:52832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T03:22:36.473304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:35.361871Z","time spent":"1.111400382s","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T03:22:36.473123Z","caller":"traceutil/trace.go:171","msg":"trace[1468433439] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"148.262337ms","start":"2026-07-03T03:22:36.324835Z","end":"2026-07-03T03:22:36.473098Z","steps":["trace[1468433439] 'process raft request' (duration: 147.820036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:36.473404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:35.36122Z","time spent":"1.112068831s","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T03:22:36.473446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:22:35.361031Z","time spent":"1.112342628s","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T03:22:44.279553Z","caller":"traceutil/trace.go:171","msg":"trace[563712875] linearizableReadLoop","detail":"{readStateIndex:1461; appliedIndex:1460; }","duration":"153.336495ms","start":"2026-07-03T03:22:44.126195Z","end":"2026-07-03T03:22:44.279531Z","steps":["trace[563712875] 'read index received' (duration: 153.16485ms)","trace[563712875] 'applied index is now lower than readState.Index' (duration: 170.515µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:22:44.279676Z","caller":"traceutil/trace.go:171","msg":"trace[749960403] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"175.429592ms","start":"2026-07-03T03:22:44.104197Z","end":"2026-07-03T03:22:44.279626Z","steps":["trace[749960403] 'process raft request' (duration: 175.156195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:22:44.279787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.575782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:471"} {"level":"info","ts":"2026-07-03T03:22:44.279821Z","caller":"traceutil/trace.go:171","msg":"trace[360197992] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1396; }","duration":"153.653203ms","start":"2026-07-03T03:22:44.126159Z","end":"2026-07-03T03:22:44.279812Z","steps":["trace[360197992] 'agreement among raft nodes before linearized reading' (duration: 153.507229ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:49.476331Z","caller":"traceutil/trace.go:171","msg":"trace[980242084] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"126.64114ms","start":"2026-07-03T03:22:49.349653Z","end":"2026-07-03T03:22:49.476294Z","steps":["trace[980242084] 'process raft request' (duration: 126.483655ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:49.478546Z","caller":"traceutil/trace.go:171","msg":"trace[218776754] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"114.665916ms","start":"2026-07-03T03:22:49.363854Z","end":"2026-07-03T03:22:49.47852Z","steps":["trace[218776754] 'process raft request' (duration: 114.582473ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:52.507758Z","caller":"traceutil/trace.go:171","msg":"trace[1270580393] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"143.585887ms","start":"2026-07-03T03:22:52.364138Z","end":"2026-07-03T03:22:52.507724Z","steps":["trace[1270580393] 'process raft request' (duration: 141.295415ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:52.508265Z","caller":"traceutil/trace.go:171","msg":"trace[2051390386] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"141.029038ms","start":"2026-07-03T03:22:52.367213Z","end":"2026-07-03T03:22:52.508242Z","steps":["trace[2051390386] 'process raft request' (duration: 140.796052ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:22:59.944427Z","caller":"traceutil/trace.go:171","msg":"trace[747328830] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"140.098333ms","start":"2026-07-03T03:22:59.804302Z","end":"2026-07-03T03:22:59.944401Z","steps":["trace[747328830] 'process raft request' (duration: 139.890817ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:24:27.458124Z","caller":"traceutil/trace.go:171","msg":"trace[2017157909] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"153.166645ms","start":"2026-07-03T03:24:27.304931Z","end":"2026-07-03T03:24:27.458098Z","steps":["trace[2017157909] 'process raft request' (duration: 70.509534ms)","trace[2017157909] 'compare' (duration: 82.443495ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:24:27.458498Z","caller":"traceutil/trace.go:171","msg":"trace[521132546] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"152.518797ms","start":"2026-07-03T03:24:27.305949Z","end":"2026-07-03T03:24:27.458468Z","steps":["trace[521132546] 'process raft request' (duration: 152.110006ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:24:27.769099Z","caller":"traceutil/trace.go:171","msg":"trace[1275366092] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"209.292049ms","start":"2026-07-03T03:24:27.559784Z","end":"2026-07-03T03:24:27.769076Z","steps":["trace[1275366092] 'process raft request' (duration: 209.095564ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:24:27.769518Z","caller":"traceutil/trace.go:171","msg":"trace[26415033] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"209.626048ms","start":"2026-07-03T03:24:27.559868Z","end":"2026-07-03T03:24:27.769494Z","steps":["trace[26415033] 'process raft request' (duration: 209.393902ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:24:27.769447Z","caller":"traceutil/trace.go:171","msg":"trace[1394132792] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"209.545166ms","start":"2026-07-03T03:24:27.559871Z","end":"2026-07-03T03:24:27.769416Z","steps":["trace[1394132792] 'process raft request' (duration: 209.157266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:25:17.726917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.96989ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-03T03:25:17.727422Z","caller":"traceutil/trace.go:171","msg":"trace[375207021] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"198.052516ms","start":"2026-07-03T03:25:17.529351Z","end":"2026-07-03T03:25:17.727403Z","steps":["trace[375207021] 'process raft request' (duration: 197.925972ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:25:56.025592Z","caller":"traceutil/trace.go:171","msg":"trace[534871536] transaction","detail":"{read_only:false; response_revision:2274; number_of_response:1; }","duration":"154.02593ms","start":"2026-07-03T03:25:55.871541Z","end":"2026-07-03T03:25:56.025567Z","steps":["trace[534871536] 'process raft request' (duration: 153.761193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:26:32.716821Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103446572200783,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-03T03:26:32.907003Z","caller":"traceutil/trace.go:171","msg":"trace[635491217] linearizableReadLoop","detail":"{readStateIndex:2577; appliedIndex:2576; }","duration":"690.917523ms","start":"2026-07-03T03:26:32.216037Z","end":"2026-07-03T03:26:32.906955Z","steps":["trace[635491217] 'read index received' (duration: 690.746459ms)","trace[635491217] 'applied index is now lower than readState.Index' (duration: 169.754µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T03:26:32.907302Z","caller":"traceutil/trace.go:171","msg":"trace[1631519165] transaction","detail":"{read_only:false; response_revision:2466; number_of_response:1; }","duration":"760.695793ms","start":"2026-07-03T03:26:32.146531Z","end":"2026-07-03T03:26:32.907226Z","steps":["trace[1631519165] 'process raft request' (duration: 760.245601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:26:32.90745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.87043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-03T03:26:32.907486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.486275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-03T03:26:32.907527Z","caller":"traceutil/trace.go:171","msg":"trace[2129863678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2466; }","duration":"387.998743ms","start":"2026-07-03T03:26:32.519512Z","end":"2026-07-03T03:26:32.907511Z","steps":["trace[2129863678] 'agreement among raft nodes before linearized reading' (duration: 387.789108ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:26:32.907535Z","caller":"traceutil/trace.go:171","msg":"trace[246246684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2466; }","duration":"532.543237ms","start":"2026-07-03T03:26:32.374975Z","end":"2026-07-03T03:26:32.907519Z","steps":["trace[246246684] 'agreement among raft nodes before linearized reading' (duration: 532.470655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:26:32.907563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:26:32.519491Z","time spent":"388.064034ms","remote":"127.0.0.1:53088","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-07-03T03:26:32.907562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:26:32.374965Z","time spent":"532.591998ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T03:26:32.907628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.953807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-07-03T03:26:32.907745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.265478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-03T03:26:32.90777Z","caller":"traceutil/trace.go:171","msg":"trace[1483609260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2466; }","duration":"689.347601ms","start":"2026-07-03T03:26:32.218415Z","end":"2026-07-03T03:26:32.907763Z","steps":["trace[1483609260] 'agreement among raft nodes before linearized reading' (duration: 688.839447ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:26:32.907764Z","caller":"traceutil/trace.go:171","msg":"trace[796916866] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2466; }","duration":"687.089841ms","start":"2026-07-03T03:26:32.220625Z","end":"2026-07-03T03:26:32.907715Z","steps":["trace[796916866] 'agreement among raft nodes before linearized reading' (duration: 686.756402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:26:32.907788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:26:32.21837Z","time spent":"689.413533ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-03T03:26:32.90781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:26:32.220616Z","time spent":"687.185383ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-03T03:26:32.907409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.834715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-03T03:26:32.907897Z","caller":"traceutil/trace.go:171","msg":"trace[1390597044] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:2466; }","duration":"543.377429ms","start":"2026-07-03T03:26:32.364509Z","end":"2026-07-03T03:26:32.907886Z","steps":["trace[1390597044] 'agreement among raft nodes before linearized reading' (duration: 542.772963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:26:32.907893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.799828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-03T03:26:32.907931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:26:32.364455Z","time spent":"543.467071ms","remote":"127.0.0.1:52876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"info","ts":"2026-07-03T03:26:32.90794Z","caller":"traceutil/trace.go:171","msg":"trace[777949253] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2466; }","duration":"691.941871ms","start":"2026-07-03T03:26:32.215986Z","end":"2026-07-03T03:26:32.907928Z","steps":["trace[777949253] 'agreement among raft nodes before linearized reading' (duration: 691.12952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:26:32.907985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:26:32.215966Z","time spent":"692.010513ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-03T03:26:32.908204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.90068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-03T03:26:32.908249Z","caller":"traceutil/trace.go:171","msg":"trace[1808112658] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2466; }","duration":"656.011353ms","start":"2026-07-03T03:26:32.252224Z","end":"2026-07-03T03:26:32.908235Z","steps":["trace[1808112658] 'agreement among raft nodes before linearized reading' (duration: 655.15476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T03:26:32.908271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:26:32.252199Z","time spent":"656.066054ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-03T03:26:32.908222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T03:26:32.146494Z","time spent":"760.983611ms","remote":"127.0.0.1:53088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T03:26:33.029019Z","caller":"traceutil/trace.go:171","msg":"trace[119007563] transaction","detail":"{read_only:false; response_revision:2467; number_of_response:1; }","duration":"116.424317ms","start":"2026-07-03T03:26:32.91257Z","end":"2026-07-03T03:26:33.028995Z","steps":["trace[119007563] 'process raft request' (duration: 116.16519ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:26:33.029103Z","caller":"traceutil/trace.go:171","msg":"trace[788808865] transaction","detail":"{read_only:false; response_revision:2468; number_of_response:1; }","duration":"116.463087ms","start":"2026-07-03T03:26:32.912622Z","end":"2026-07-03T03:26:33.029086Z","steps":["trace[788808865] 'process raft request' (duration: 116.256192ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:26:33.029715Z","caller":"traceutil/trace.go:171","msg":"trace[698367713] transaction","detail":"{read_only:false; response_revision:2471; number_of_response:1; }","duration":"116.287343ms","start":"2026-07-03T03:26:32.913418Z","end":"2026-07-03T03:26:33.029706Z","steps":["trace[698367713] 'process raft request' (duration: 116.1636ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:26:33.029774Z","caller":"traceutil/trace.go:171","msg":"trace[275924176] transaction","detail":"{read_only:false; response_revision:2470; number_of_response:1; }","duration":"116.677994ms","start":"2026-07-03T03:26:32.913082Z","end":"2026-07-03T03:26:33.02976Z","steps":["trace[275924176] 'process raft request' (duration: 116.438087ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:26:33.02987Z","caller":"traceutil/trace.go:171","msg":"trace[1077590557] transaction","detail":"{read_only:false; response_revision:2472; number_of_response:1; }","duration":"116.282843ms","start":"2026-07-03T03:26:32.913554Z","end":"2026-07-03T03:26:33.029837Z","steps":["trace[1077590557] 'process raft request' (duration: 116.058207ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T03:26:33.030038Z","caller":"traceutil/trace.go:171","msg":"trace[1795899443] transaction","detail":"{read_only:false; response_revision:2469; number_of_response:1; }","duration":"117.373092ms","start":"2026-07-03T03:26:32.912657Z","end":"2026-07-03T03:26:33.030031Z","steps":["trace[1795899443] 'process raft request' (duration: 116.255802ms)"],"step_count":1}