{"level":"warn","ts":"2026-06-05T22:02:40.309816Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T22:02:40.309883Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.78: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.78:2380","--initial-cluster=instance=https://199.204.45.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.78: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-06-05T22:02:40.310004Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-05T22:02:40.310015Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-06-05T22:02:40.310031Z","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-06-05T22:02:40.310644Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"]} {"level":"info","ts":"2026-06-05T22:02:40.310735Z","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.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.78: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-06-05T22:02:40.332017Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"21.069888ms"} {"level":"info","ts":"2026-06-05T22:02:40.421482Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6eb8766b1ec86e92","cluster-id":"9bbfe9e5d45d3718"} {"level":"info","ts":"2026-06-05T22:02:40.421652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=()"} {"level":"info","ts":"2026-06-05T22:02:40.421724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 0"} {"level":"info","ts":"2026-06-05T22:02:40.421774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6eb8766b1ec86e92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-05T22:02:40.42182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 1"} {"level":"info","ts":"2026-06-05T22:02:40.421902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"warn","ts":"2026-06-05T22:02:40.430746Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-05T22:02:40.432202Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-05T22:02:40.433391Z","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-06-05T22:02:40.436044Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6eb8766b1ec86e92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-05T22:02:40.43629Z","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-06-05T22:02:40.43642Z","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-06-05T22:02:40.436436Z","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-06-05T22:02:40.436742Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6eb8766b1ec86e92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-05T22:02:40.438894Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"info","ts":"2026-06-05T22:02:40.439404Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","added-peer-id":"6eb8766b1ec86e92","added-peer-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-06-05T22:02:40.440852Z","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-06-05T22:02:40.441005Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-06-05T22:02:40.441056Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-06-05T22:02:40.441319Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6eb8766b1ec86e92","initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-05T22:02:40.441409Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-05T22:02:41.422246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 is starting a new election at term 1"} {"level":"info","ts":"2026-06-05T22:02:41.422296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-05T22:02:41.422329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgPreVoteResp from 6eb8766b1ec86e92 at term 1"} {"level":"info","ts":"2026-06-05T22:02:41.422344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became candidate at term 2"} {"level":"info","ts":"2026-06-05T22:02:41.422353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgVoteResp from 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-06-05T22:02:41.422367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became leader at term 2"} {"level":"info","ts":"2026-06-05T22:02:41.422379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6eb8766b1ec86e92 elected leader 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-06-05T22:02:41.423304Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T22:02:41.423735Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6eb8766b1ec86e92","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.78:2379]}","request-path":"/0/members/6eb8766b1ec86e92/attributes","cluster-id":"9bbfe9e5d45d3718","publish-timeout":"7s"} {"level":"info","ts":"2026-06-05T22:02:41.423763Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T22:02:41.423747Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-05T22:02:41.42401Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-05T22:02:41.424074Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-05T22:02:41.424365Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T22:02:41.424506Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T22:02:41.42455Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-05T22:02:41.426284Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-05T22:02:41.427809Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.78:2379"} {"level":"info","ts":"2026-06-05T22:02:44.832627Z","caller":"traceutil/trace.go:171","msg":"trace[1105530225] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"126.377622ms","start":"2026-06-05T22:02:44.706227Z","end":"2026-06-05T22:02:44.832604Z","steps":["trace[1105530225] 'process raft request' (duration: 126.252401ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:02:55.0479Z","caller":"traceutil/trace.go:171","msg":"trace[2102837075] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"103.165777ms","start":"2026-06-05T22:02:54.944712Z","end":"2026-06-05T22:02:55.047877Z","steps":["trace[2102837075] 'process raft request' (duration: 102.801185ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:02:55.388387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.621274ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T22:02:55.388501Z","caller":"traceutil/trace.go:171","msg":"trace[864249769] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:417; }","duration":"268.144655ms","start":"2026-06-05T22:02:55.12034Z","end":"2026-06-05T22:02:55.388485Z","steps":["trace[864249769] 'read index received' (duration: 101.847276ms)","trace[864249769] 'applied index is now lower than readState.Index' (duration: 166.296209ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:02:55.388589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.245256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-05T22:02:55.388622Z","caller":"traceutil/trace.go:171","msg":"trace[938623683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:405; }","duration":"268.312716ms","start":"2026-06-05T22:02:55.120301Z","end":"2026-06-05T22:02:55.388613Z","steps":["trace[938623683] 'agreement among raft nodes before linearized reading' (duration: 268.229926ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:02:55.389035Z","caller":"traceutil/trace.go:171","msg":"trace[412755132] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"336.562218ms","start":"2026-06-05T22:02:55.052457Z","end":"2026-06-05T22:02:55.389019Z","steps":["trace[412755132] 'process raft request' (duration: 169.737645ms)","trace[412755132] 'compare' (duration: 165.497234ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:02:55.38911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:02:55.052446Z","time spent":"336.629779ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:03:18.448831Z","caller":"traceutil/trace.go:171","msg":"trace[165644364] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"143.258303ms","start":"2026-06-05T22:03:18.305533Z","end":"2026-06-05T22:03:18.448791Z","steps":["trace[165644364] 'process raft request' (duration: 134.746251ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:18.709247Z","caller":"traceutil/trace.go:171","msg":"trace[2031525187] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"100.769717ms","start":"2026-06-05T22:03:18.608458Z","end":"2026-06-05T22:03:18.709228Z","steps":["trace[2031525187] 'read index received' (duration: 100.571788ms)","trace[2031525187] 'applied index is now lower than readState.Index' (duration: 197.349µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:03:18.709403Z","caller":"traceutil/trace.go:171","msg":"trace[1395776299] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"115.797363ms","start":"2026-06-05T22:03:18.593578Z","end":"2026-06-05T22:03:18.709375Z","steps":["trace[1395776299] 'process raft request' (duration: 115.443916ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:18.709535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.03699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-06-05T22:03:18.709595Z","caller":"traceutil/trace.go:171","msg":"trace[1375771302] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:622; }","duration":"101.151065ms","start":"2026-06-05T22:03:18.60843Z","end":"2026-06-05T22:03:18.709581Z","steps":["trace[1375771302] 'agreement among raft nodes before linearized reading' (duration: 100.955926ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:26.221095Z","caller":"traceutil/trace.go:171","msg":"trace[16298857] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"645.055248ms","start":"2026-06-05T22:03:25.575958Z","end":"2026-06-05T22:03:26.221013Z","steps":["trace[16298857] 'process raft request' (duration: 644.907102ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:26.221259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:03:25.575937Z","time spent":"645.242476ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:03:26.626153Z","caller":"traceutil/trace.go:171","msg":"trace[2114707949] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:718; }","duration":"862.006792ms","start":"2026-06-05T22:03:25.764122Z","end":"2026-06-05T22:03:26.626129Z","steps":["trace[2114707949] 'read index received' (duration: 456.795347ms)","trace[2114707949] 'applied index is now lower than readState.Index' (duration: 405.210465ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:03:26.626466Z","caller":"traceutil/trace.go:171","msg":"trace[1395481991] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"1.050465491s","start":"2026-06-05T22:03:25.575975Z","end":"2026-06-05T22:03:26.626441Z","steps":["trace[1395481991] 'process raft request' (duration: 998.843963ms)","trace[1395481991] 'compare' (duration: 50.397344ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:03:26.626605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:03:25.57596Z","time spent":"1.050564055s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:03:26.626953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.829157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-05T22:03:26.627001Z","caller":"traceutil/trace.go:171","msg":"trace[1690642068] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:691; }","duration":"862.917031ms","start":"2026-06-05T22:03:25.764071Z","end":"2026-06-05T22:03:26.626988Z","steps":["trace[1690642068] 'agreement among raft nodes before linearized reading' (duration: 862.758424ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:26.627043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:03:25.764048Z","time spent":"862.985664ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-05T22:03:26.627289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.77905ms","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-06-05T22:03:26.62734Z","caller":"traceutil/trace.go:171","msg":"trace[101672860] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:691; }","duration":"825.848313ms","start":"2026-06-05T22:03:25.801477Z","end":"2026-06-05T22:03:26.627325Z","steps":["trace[101672860] 'agreement among raft nodes before linearized reading' (duration: 825.737648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:26.627388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:03:25.801466Z","time spent":"825.911906ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-05T22:03:40.349926Z","caller":"traceutil/trace.go:171","msg":"trace[1705628106] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"379.242607ms","start":"2026-06-05T22:03:39.970657Z","end":"2026-06-05T22:03:40.3499Z","steps":["trace[1705628106] 'read index received' (duration: 379.009589ms)","trace[1705628106] 'applied index is now lower than readState.Index' (duration: 231.708µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:03:40.350014Z","caller":"traceutil/trace.go:171","msg":"trace[826407461] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"675.786645ms","start":"2026-06-05T22:03:39.674187Z","end":"2026-06-05T22:03:40.349973Z","steps":["trace[826407461] 'process raft request' (duration: 675.538196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:40.35014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.455486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:03:40.35023Z","caller":"traceutil/trace.go:171","msg":"trace[445160957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"379.595421ms","start":"2026-06-05T22:03:39.970617Z","end":"2026-06-05T22:03:40.350212Z","steps":["trace[445160957] 'agreement among raft nodes before linearized reading' (duration: 379.452576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:40.350266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:03:39.674174Z","time spent":"675.920841ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5034,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:03:40.350281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:03:39.970602Z","time spent":"379.664433ms","remote":"127.0.0.1:51554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T22:03:40.526656Z","caller":"traceutil/trace.go:171","msg":"trace[1071849657] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"170.409499ms","start":"2026-06-05T22:03:40.356221Z","end":"2026-06-05T22:03:40.52663Z","steps":["trace[1071849657] 'process raft request' (duration: 170.217272ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:40.557854Z","caller":"traceutil/trace.go:171","msg":"trace[535881193] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"200.830866ms","start":"2026-06-05T22:03:40.356989Z","end":"2026-06-05T22:03:40.55782Z","steps":["trace[535881193] 'process raft request' (duration: 200.551366ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:40.558088Z","caller":"traceutil/trace.go:171","msg":"trace[870992244] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"193.159169ms","start":"2026-06-05T22:03:40.364859Z","end":"2026-06-05T22:03:40.558019Z","steps":["trace[870992244] 'process raft request' (duration: 192.937851ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:40.557906Z","caller":"traceutil/trace.go:171","msg":"trace[844219956] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"199.776376ms","start":"2026-06-05T22:03:40.358102Z","end":"2026-06-05T22:03:40.557879Z","steps":["trace[844219956] 'process raft request' (duration: 199.636921ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:44.237708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605074216987882,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T22:03:44.659983Z","caller":"traceutil/trace.go:171","msg":"trace[1217337971] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"971.565605ms","start":"2026-06-05T22:03:43.688389Z","end":"2026-06-05T22:03:44.659955Z","steps":["trace[1217337971] 'process raft request' (duration: 971.41668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:44.660155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:03:43.688375Z","time spent":"971.695659ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:03:44.660311Z","caller":"traceutil/trace.go:171","msg":"trace[1402282248] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:841; }","duration":"923.210886ms","start":"2026-06-05T22:03:43.737083Z","end":"2026-06-05T22:03:44.660294Z","steps":["trace[1402282248] 'read index received' (duration: 923.202756ms)","trace[1402282248] 'applied index is now lower than readState.Index' (duration: 6.48µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:03:44.6605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.405223ms","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-06-05T22:03:44.660556Z","caller":"traceutil/trace.go:171","msg":"trace[173607034] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:807; }","duration":"923.520737ms","start":"2026-06-05T22:03:43.73702Z","end":"2026-06-05T22:03:44.660541Z","steps":["trace[173607034] 'agreement among raft nodes before linearized reading' (duration: 923.357461ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:44.660583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:03:43.736989Z","time spent":"923.58825ms","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":94,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-06-05T22:03:44.664997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.702202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:03:44.665067Z","caller":"traceutil/trace.go:171","msg":"trace[10228388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"778.804216ms","start":"2026-06-05T22:03:43.886242Z","end":"2026-06-05T22:03:44.665047Z","steps":["trace[10228388] 'agreement among raft nodes before linearized reading' (duration: 778.682482ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:44.665165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:03:43.886222Z","time spent":"778.93268ms","remote":"127.0.0.1:51554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T22:03:44.665161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.88949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-05T22:03:44.665215Z","caller":"traceutil/trace.go:171","msg":"trace[629067387] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:808; }","duration":"313.980494ms","start":"2026-06-05T22:03:44.351219Z","end":"2026-06-05T22:03:44.6652Z","steps":["trace[629067387] 'agreement among raft nodes before linearized reading' (duration: 313.789947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:44.66525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:03:44.351201Z","time spent":"314.037325ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"info","ts":"2026-06-05T22:03:44.665364Z","caller":"traceutil/trace.go:171","msg":"trace[1596334761] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"426.159343ms","start":"2026-06-05T22:03:44.239167Z","end":"2026-06-05T22:03:44.665326Z","steps":["trace[1596334761] 'process raft request' (duration: 425.674876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:44.665423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.963785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:03:44.665462Z","caller":"traceutil/trace.go:171","msg":"trace[1123777763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"695.025948ms","start":"2026-06-05T22:03:43.970424Z","end":"2026-06-05T22:03:44.66545Z","steps":["trace[1123777763] 'agreement among raft nodes before linearized reading' (duration: 694.962885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:44.665493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:03:43.970402Z","time spent":"695.0851ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T22:03:44.665548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:03:44.239134Z","time spent":"426.307868ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:03:48.877933Z","caller":"traceutil/trace.go:171","msg":"trace[945502567] linearizableReadLoop","detail":"{readStateIndex:864; appliedIndex:863; }","duration":"119.667735ms","start":"2026-06-05T22:03:48.758223Z","end":"2026-06-05T22:03:48.877891Z","steps":["trace[945502567] 'read index received' (duration: 102.339703ms)","trace[945502567] 'applied index is now lower than readState.Index' (duration: 17.326802ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:03:48.878203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.951604ms","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-06-05T22:03:48.878024Z","caller":"traceutil/trace.go:171","msg":"trace[1652175335] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"124.888814ms","start":"2026-06-05T22:03:48.753105Z","end":"2026-06-05T22:03:48.877994Z","steps":["trace[1652175335] 'process raft request' (duration: 107.388555ms)","trace[1652175335] 'compare' (duration: 17.273961ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:03:48.878186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.199756ms","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-06-05T22:03:48.878262Z","caller":"traceutil/trace.go:171","msg":"trace[2026738456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:829; }","duration":"120.087239ms","start":"2026-06-05T22:03:48.758161Z","end":"2026-06-05T22:03:48.878248Z","steps":["trace[2026738456] 'agreement among raft nodes before linearized reading' (duration: 119.873132ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:48.878275Z","caller":"traceutil/trace.go:171","msg":"trace[1317653957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:829; }","duration":"111.33717ms","start":"2026-06-05T22:03:48.766925Z","end":"2026-06-05T22:03:48.878263Z","steps":["trace[1317653957] 'agreement among raft nodes before linearized reading' (duration: 111.117212ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:48.878316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.013829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-05T22:03:48.878392Z","caller":"traceutil/trace.go:171","msg":"trace[50798486] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:829; }","duration":"104.143854ms","start":"2026-06-05T22:03:48.774229Z","end":"2026-06-05T22:03:48.878373Z","steps":["trace[50798486] 'agreement among raft nodes before linearized reading' (duration: 103.892165ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:49.32704Z","caller":"traceutil/trace.go:171","msg":"trace[2045606741] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"108.583386ms","start":"2026-06-05T22:03:49.218431Z","end":"2026-06-05T22:03:49.327015Z","steps":["trace[2045606741] 'process raft request' (duration: 108.421481ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:49.798863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.609433ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-05T22:03:49.799486Z","caller":"traceutil/trace.go:171","msg":"trace[1139790911] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"283.660123ms","start":"2026-06-05T22:03:49.515784Z","end":"2026-06-05T22:03:49.799444Z","steps":["trace[1139790911] 'process raft request' (duration: 283.236848ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:49.799826Z","caller":"traceutil/trace.go:171","msg":"trace[639418364] linearizableReadLoop","detail":"{readStateIndex:884; appliedIndex:880; }","duration":"230.290006ms","start":"2026-06-05T22:03:49.569513Z","end":"2026-06-05T22:03:49.799803Z","steps":["trace[639418364] 'read index received' (duration: 25.645468ms)","trace[639418364] 'applied index is now lower than readState.Index' (duration: 204.643218ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:03:49.799946Z","caller":"traceutil/trace.go:171","msg":"trace[243547914] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"283.88588ms","start":"2026-06-05T22:03:49.516045Z","end":"2026-06-05T22:03:49.799931Z","steps":["trace[243547914] 'process raft request' (duration: 283.557629ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:49.800037Z","caller":"traceutil/trace.go:171","msg":"trace[1666467650] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"282.145892ms","start":"2026-06-05T22:03:49.51787Z","end":"2026-06-05T22:03:49.800016Z","steps":["trace[1666467650] 'process raft request' (duration: 281.877303ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:03:49.800347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.819983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T22:03:49.80039Z","caller":"traceutil/trace.go:171","msg":"trace[1921380251] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:848; }","duration":"230.913757ms","start":"2026-06-05T22:03:49.569465Z","end":"2026-06-05T22:03:49.800379Z","steps":["trace[1921380251] 'agreement among raft nodes before linearized reading' (duration: 230.780682ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:03:51.559518Z","caller":"traceutil/trace.go:171","msg":"trace[1913957514] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"133.38453ms","start":"2026-06-05T22:03:51.426098Z","end":"2026-06-05T22:03:51.559483Z","steps":["trace[1913957514] 'process raft request' (duration: 36.78741ms)","trace[1913957514] 'compare' (duration: 96.427265ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:04:05.175471Z","caller":"traceutil/trace.go:171","msg":"trace[1124939534] linearizableReadLoop","detail":"{readStateIndex:1179; appliedIndex:1178; }","duration":"205.849838ms","start":"2026-06-05T22:04:04.969599Z","end":"2026-06-05T22:04:05.175449Z","steps":["trace[1124939534] 'read index received' (duration: 205.709934ms)","trace[1124939534] 'applied index is now lower than readState.Index' (duration: 139.044µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:04:05.175544Z","caller":"traceutil/trace.go:171","msg":"trace[2095832514] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"212.517833ms","start":"2026-06-05T22:04:04.962975Z","end":"2026-06-05T22:04:05.175493Z","steps":["trace[2095832514] 'process raft request' (duration: 212.345297ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:04:05.175707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.098357ms","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-06-05T22:04:05.17575Z","caller":"traceutil/trace.go:171","msg":"trace[117443388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1140; }","duration":"206.19649ms","start":"2026-06-05T22:04:04.969543Z","end":"2026-06-05T22:04:05.175739Z","steps":["trace[117443388] 'agreement among raft nodes before linearized reading' (duration: 206.024275ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:04:05.402402Z","caller":"traceutil/trace.go:171","msg":"trace[849830576] linearizableReadLoop","detail":"{readStateIndex:1181; appliedIndex:1179; }","duration":"226.82036ms","start":"2026-06-05T22:04:05.175544Z","end":"2026-06-05T22:04:05.402365Z","steps":["trace[849830576] 'read index received' (duration: 226.620824ms)","trace[849830576] 'applied index is now lower than readState.Index' (duration: 198.556µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:04:05.402539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.060163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:04:05.402573Z","caller":"traceutil/trace.go:171","msg":"trace[1581270443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1142; }","duration":"431.146545ms","start":"2026-06-05T22:04:04.971413Z","end":"2026-06-05T22:04:05.40256Z","steps":["trace[1581270443] 'agreement among raft nodes before linearized reading' (duration: 431.067573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:04:05.402601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.769074ms","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-06-05T22:04:05.402679Z","caller":"traceutil/trace.go:171","msg":"trace[2060102288] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1142; }","duration":"354.879777ms","start":"2026-06-05T22:04:05.04778Z","end":"2026-06-05T22:04:05.40266Z","steps":["trace[2060102288] 'agreement among raft nodes before linearized reading' (duration: 354.6045ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:04:05.402723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:04:05.047771Z","time spent":"354.943869ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-05T22:04:05.402873Z","caller":"traceutil/trace.go:171","msg":"trace[1963929928] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"266.928973ms","start":"2026-06-05T22:04:05.135928Z","end":"2026-06-05T22:04:05.402857Z","steps":["trace[1963929928] 'process raft request' (duration: 266.389377ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:04:05.403029Z","caller":"traceutil/trace.go:171","msg":"trace[652069974] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"419.794076ms","start":"2026-06-05T22:04:04.98322Z","end":"2026-06-05T22:04:05.403014Z","steps":["trace[652069974] 'process raft request' (duration: 418.928871ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:04:05.403146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:04:04.983205Z","time spent":"419.852589ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:04:05.403125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.387147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-05T22:04:05.403263Z","caller":"traceutil/trace.go:171","msg":"trace[338812842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1142; }","duration":"359.592183ms","start":"2026-06-05T22:04:05.043648Z","end":"2026-06-05T22:04:05.40324Z","steps":["trace[338812842] 'agreement among raft nodes before linearized reading' (duration: 359.190281ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:04:05.403287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:04:04.971397Z","time spent":"431.209767ms","remote":"127.0.0.1:51554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T22:04:05.403367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:04:05.043631Z","time spent":"359.700386ms","remote":"127.0.0.1:51810","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-06-05T22:04:05.417594Z","caller":"traceutil/trace.go:171","msg":"trace[708775842] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"235.213742ms","start":"2026-06-05T22:04:05.182358Z","end":"2026-06-05T22:04:05.417572Z","steps":["trace[708775842] 'process raft request' (duration: 235.007706ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:04:05.417694Z","caller":"traceutil/trace.go:171","msg":"trace[511679201] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"231.367202ms","start":"2026-06-05T22:04:05.18631Z","end":"2026-06-05T22:04:05.417677Z","steps":["trace[511679201] 'process raft request' (duration: 231.180127ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:04:16.219233Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605074216988636,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T22:04:16.537456Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.054515963s","expected-duration":"1s"} {"level":"info","ts":"2026-06-05T22:04:16.53786Z","caller":"traceutil/trace.go:171","msg":"trace[1848701053] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"1.061897457s","start":"2026-06-05T22:04:15.475938Z","end":"2026-06-05T22:04:16.537835Z","steps":["trace[1848701053] 'process raft request' (duration: 1.061853136s)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:04:16.537995Z","caller":"traceutil/trace.go:171","msg":"trace[39110914] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"1.062269447s","start":"2026-06-05T22:04:15.475704Z","end":"2026-06-05T22:04:16.537974Z","steps":["trace[39110914] 'process raft request' (duration: 1.061957548s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:04:16.538021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:04:15.475909Z","time spent":"1.062030271s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:04:16.538093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:04:15.475696Z","time spent":"1.062347569s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:04:16.758566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.955557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T22:04:16.758748Z","caller":"traceutil/trace.go:171","msg":"trace[1625279499] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1259; }","duration":"1.040194327s","start":"2026-06-05T22:04:15.71853Z","end":"2026-06-05T22:04:16.758725Z","steps":["trace[1625279499] 'read index received' (duration: 819.462582ms)","trace[1625279499] 'applied index is now lower than readState.Index' (duration: 220.729865ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:04:16.759207Z","caller":"traceutil/trace.go:171","msg":"trace[497289704] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"1.231335293s","start":"2026-06-05T22:04:15.527833Z","end":"2026-06-05T22:04:16.759169Z","steps":["trace[497289704] 'process raft request' (duration: 1.102677788s)","trace[497289704] 'compare' (duration: 127.794173ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:04:16.759274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.040736501s","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-06-05T22:04:16.75931Z","caller":"traceutil/trace.go:171","msg":"trace[544644686] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1219; }","duration":"1.040809983s","start":"2026-06-05T22:04:15.718489Z","end":"2026-06-05T22:04:16.759299Z","steps":["trace[544644686] 'agreement among raft nodes before linearized reading' (duration: 1.040677279s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:04:16.759242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.678486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T22:04:16.759346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:04:15.71847Z","time spent":"1.040867484s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T22:04:16.759367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:04:15.527817Z","time spent":"1.231465718s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:04:16.759375Z","caller":"traceutil/trace.go:171","msg":"trace[1413746007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1219; }","duration":"788.891861ms","start":"2026-06-05T22:04:15.970456Z","end":"2026-06-05T22:04:16.759347Z","steps":["trace[1413746007] 'agreement among raft nodes before linearized reading' (duration: 788.626553ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:04:16.759439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:04:15.970439Z","time spent":"788.989904ms","remote":"127.0.0.1:51554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T22:04:24.682618Z","caller":"traceutil/trace.go:171","msg":"trace[704539533] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"105.663653ms","start":"2026-06-05T22:04:24.576929Z","end":"2026-06-05T22:04:24.682593Z","steps":["trace[704539533] 'process raft request' (duration: 105.527659ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:04:24.689763Z","caller":"traceutil/trace.go:171","msg":"trace[1660179288] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"111.480032ms","start":"2026-06-05T22:04:24.578256Z","end":"2026-06-05T22:04:24.689736Z","steps":["trace[1660179288] 'process raft request' (duration: 111.373509ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:04:28.380883Z","caller":"traceutil/trace.go:171","msg":"trace[83168438] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"180.987639ms","start":"2026-06-05T22:04:28.199877Z","end":"2026-06-05T22:04:28.380865Z","steps":["trace[83168438] 'process raft request' (duration: 180.889006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:04:29.976224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.962068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-admission\" ","response":"range_response_count:1 size:1071"} {"level":"info","ts":"2026-06-05T22:04:29.97633Z","caller":"traceutil/trace.go:171","msg":"trace[439646668] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-admission; range_end:; response_count:1; response_revision:1342; }","duration":"111.128493ms","start":"2026-06-05T22:04:29.865176Z","end":"2026-06-05T22:04:29.976304Z","steps":["trace[439646668] 'range keys from in-memory index tree' (duration: 110.754613ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:04:34.052488Z","caller":"traceutil/trace.go:171","msg":"trace[1334389311] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1408; }","duration":"405.122877ms","start":"2026-06-05T22:04:33.647342Z","end":"2026-06-05T22:04:34.052465Z","steps":["trace[1334389311] 'read index received' (duration: 405.013714ms)","trace[1334389311] 'applied index is now lower than readState.Index' (duration: 108.133µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:04:34.052522Z","caller":"traceutil/trace.go:171","msg":"trace[1448407024] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"439.755718ms","start":"2026-06-05T22:04:33.61274Z","end":"2026-06-05T22:04:34.052496Z","steps":["trace[1448407024] 'process raft request' (duration: 439.582264ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:04:34.052648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:04:33.612719Z","time spent":"439.861901ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:04:34.052656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.766669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T22:04:34.052749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.404482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-05T22:04:34.052789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.90737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-rlt7j\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-06-05T22:04:34.0528Z","caller":"traceutil/trace.go:171","msg":"trace[559939532] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1360; }","duration":"405.490355ms","start":"2026-06-05T22:04:33.647296Z","end":"2026-06-05T22:04:34.052786Z","steps":["trace[559939532] 'agreement among raft nodes before linearized reading' (duration: 405.29686ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:04:34.052797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.826394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:04:34.052827Z","caller":"traceutil/trace.go:171","msg":"trace[1115130507] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-rlt7j; range_end:; response_count:1; response_revision:1360; }","duration":"160.979942ms","start":"2026-06-05T22:04:33.891835Z","end":"2026-06-05T22:04:34.052815Z","steps":["trace[1115130507] 'agreement among raft nodes before linearized reading' (duration: 160.89949ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:04:34.052857Z","caller":"traceutil/trace.go:171","msg":"trace[1774331516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1360; }","duration":"167.920356ms","start":"2026-06-05T22:04:33.884916Z","end":"2026-06-05T22:04:34.052837Z","steps":["trace[1774331516] 'agreement among raft nodes before linearized reading' (duration: 167.831434ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:04:34.052851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:04:33.647273Z","time spent":"405.562977ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-05T22:04:34.053214Z","caller":"traceutil/trace.go:171","msg":"trace[1477434470] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1360; }","duration":"365.893502ms","start":"2026-06-05T22:04:33.686817Z","end":"2026-06-05T22:04:34.052711Z","steps":["trace[1477434470] 'agreement among raft nodes before linearized reading' (duration: 365.78554ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:04:34.053284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:04:33.686801Z","time spent":"366.466904ms","remote":"127.0.0.1:52054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":30,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-06-05T22:04:42.566516Z","caller":"traceutil/trace.go:171","msg":"trace[638698919] linearizableReadLoop","detail":"{readStateIndex:1539; appliedIndex:1538; }","duration":"479.487981ms","start":"2026-06-05T22:04:42.087Z","end":"2026-06-05T22:04:42.566488Z","steps":["trace[638698919] 'read index received' (duration: 479.289127ms)","trace[638698919] 'applied index is now lower than readState.Index' (duration: 198.024µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:04:42.566654Z","caller":"traceutil/trace.go:171","msg":"trace[62633760] transaction","detail":"{read_only:false; response_revision:1488; number_of_response:1; }","duration":"493.181866ms","start":"2026-06-05T22:04:42.073438Z","end":"2026-06-05T22:04:42.56662Z","steps":["trace[62633760] 'process raft request' (duration: 492.90688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:04:42.566706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.686265ms","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-06-05T22:04:42.566744Z","caller":"traceutil/trace.go:171","msg":"trace[1166347722] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1488; }","duration":"479.768326ms","start":"2026-06-05T22:04:42.086965Z","end":"2026-06-05T22:04:42.566734Z","steps":["trace[1166347722] 'agreement among raft nodes before linearized reading' (duration: 479.626003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:04:42.566786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:04:42.086928Z","time spent":"479.849338ms","remote":"127.0.0.1:51810","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-06-05T22:04:42.566852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:04:42.07341Z","time spent":"493.322508ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:04:43.022713Z","caller":"traceutil/trace.go:171","msg":"trace[223803358] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"227.81554ms","start":"2026-06-05T22:04:42.794846Z","end":"2026-06-05T22:04:43.022661Z","steps":["trace[223803358] 'process raft request' (duration: 227.592935ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:04:54.610506Z","caller":"traceutil/trace.go:171","msg":"trace[1664893277] transaction","detail":"{read_only:false; response_revision:1723; number_of_response:1; }","duration":"105.507709ms","start":"2026-06-05T22:04:54.504965Z","end":"2026-06-05T22:04:54.610473Z","steps":["trace[1664893277] 'process raft request' (duration: 105.372756ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:00.819447Z","caller":"traceutil/trace.go:171","msg":"trace[672376495] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"177.635731ms","start":"2026-06-05T22:05:00.641754Z","end":"2026-06-05T22:05:00.81939Z","steps":["trace[672376495] 'process raft request' (duration: 177.07439ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:07.650624Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605074216990005,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T22:05:07.876537Z","caller":"traceutil/trace.go:171","msg":"trace[1219027211] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"796.549678ms","start":"2026-06-05T22:05:07.079941Z","end":"2026-06-05T22:05:07.876491Z","steps":["trace[1219027211] 'process raft request' (duration: 796.324064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:07.87677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:07.079914Z","time spent":"796.730322ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:05:09.08182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029574622s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T22:05:09.082209Z","caller":"traceutil/trace.go:171","msg":"trace[1835369833] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"1.454228222s","start":"2026-06-05T22:05:07.62792Z","end":"2026-06-05T22:05:09.082148Z","steps":["trace[1835369833] 'process raft request' (duration: 424.197092ms)","trace[1835369833] 'compare' (duration: 1.029397339s)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:05:09.082478Z","caller":"traceutil/trace.go:171","msg":"trace[450889935] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"853.7002ms","start":"2026-06-05T22:05:08.228749Z","end":"2026-06-05T22:05:09.082449Z","steps":["trace[450889935] 'process raft request' (duration: 853.536607ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:09.082502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:07.627889Z","time spent":"1.454407595s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:05:09.082763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:08.228715Z","time spent":"853.991275ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:05:09.082961Z","caller":"traceutil/trace.go:171","msg":"trace[1825073970] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"921.992708ms","start":"2026-06-05T22:05:08.160952Z","end":"2026-06-05T22:05:09.082945Z","steps":["trace[1825073970] 'process raft request' (duration: 921.129653ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:09.083047Z","caller":"traceutil/trace.go:171","msg":"trace[1709521201] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"1.23028409s","start":"2026-06-05T22:05:07.852725Z","end":"2026-06-05T22:05:09.083009Z","steps":["trace[1709521201] 'process raft request' (duration: 1.229243983s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:09.083093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:08.160919Z","time spent":"922.128181ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:05:09.082909Z","caller":"traceutil/trace.go:171","msg":"trace[1072435077] linearizableReadLoop","detail":"{readStateIndex:1979; appliedIndex:1977; }","duration":"1.932762351s","start":"2026-06-05T22:05:07.150072Z","end":"2026-06-05T22:05:09.082834Z","steps":["trace[1072435077] 'read index received' (duration: 726.192856ms)","trace[1072435077] 'applied index is now lower than readState.Index' (duration: 1.206560045s)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:05:09.083228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:07.852637Z","time spent":"1.230501275s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:05:09.08346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.933387494s","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-06-05T22:05:09.083508Z","caller":"traceutil/trace.go:171","msg":"trace[976989936] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1903; }","duration":"1.933496735s","start":"2026-06-05T22:05:07.149998Z","end":"2026-06-05T22:05:09.083494Z","steps":["trace[976989936] 'agreement among raft nodes before linearized reading' (duration: 1.933272832s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:09.083574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:07.149971Z","time spent":"1.933592477s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-05T22:05:09.084082Z","caller":"traceutil/trace.go:171","msg":"trace[2135488136] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"237.907353ms","start":"2026-06-05T22:05:08.846156Z","end":"2026-06-05T22:05:09.084063Z","steps":["trace[2135488136] 'process raft request' (duration: 237.025718ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:09.138544Z","caller":"traceutil/trace.go:171","msg":"trace[1250519906] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"192.427044ms","start":"2026-06-05T22:05:08.946064Z","end":"2026-06-05T22:05:09.138491Z","steps":["trace[1250519906] 'process raft request' (duration: 191.791074ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:09.138949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.611393972s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-67625b93-8380-4252-abf1-2c397ac981f5\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-06-05T22:05:09.139195Z","caller":"traceutil/trace.go:171","msg":"trace[481054982] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-67625b93-8380-4252-abf1-2c397ac981f5; range_end:; response_count:1; response_revision:1906; }","duration":"1.611713429s","start":"2026-06-05T22:05:07.527455Z","end":"2026-06-05T22:05:09.139169Z","steps":["trace[481054982] 'agreement among raft nodes before linearized reading' (duration: 1.611289551s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:09.139157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168644339s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:05:09.140321Z","caller":"traceutil/trace.go:171","msg":"trace[602687675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1906; }","duration":"1.169926872s","start":"2026-06-05T22:05:07.970373Z","end":"2026-06-05T22:05:09.140299Z","steps":["trace[602687675] 'agreement among raft nodes before linearized reading' (duration: 1.168622759s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:09.140472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:07.970348Z","time spent":"1.170108146s","remote":"127.0.0.1:51554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T22:05:09.139003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.660110215s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-05T22:05:09.140791Z","caller":"traceutil/trace.go:171","msg":"trace[19740403] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1906; }","duration":"1.661946577s","start":"2026-06-05T22:05:07.478826Z","end":"2026-06-05T22:05:09.140772Z","steps":["trace[19740403] 'agreement among raft nodes before linearized reading' (duration: 1.660089904s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:09.140842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:07.478796Z","time spent":"1.662030968s","remote":"127.0.0.1:55266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-05T22:05:09.139129Z","caller":"traceutil/trace.go:171","msg":"trace[1774540876] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"161.327653ms","start":"2026-06-05T22:05:08.977745Z","end":"2026-06-05T22:05:09.139073Z","steps":["trace[1774540876] 'process raft request' (duration: 160.288276ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:09.139008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.047725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T22:05:09.139335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.835564238s","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-06-05T22:05:09.141297Z","caller":"traceutil/trace.go:171","msg":"trace[1663475193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1907; }","duration":"1.837582813s","start":"2026-06-05T22:05:07.303699Z","end":"2026-06-05T22:05:09.141281Z","steps":["trace[1663475193] 'agreement among raft nodes before linearized reading' (duration: 1.835385594s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:09.141427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:07.30365Z","time spent":"1.837747385s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-05T22:05:09.139444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.163447ms","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-06-05T22:05:09.141628Z","caller":"traceutil/trace.go:171","msg":"trace[1194450486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1907; }","duration":"292.424655ms","start":"2026-06-05T22:05:08.849187Z","end":"2026-06-05T22:05:09.141612Z","steps":["trace[1194450486] 'agreement among raft nodes before linearized reading' (duration: 289.971723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:09.139452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:07.527429Z","time spent":"1.611955843s","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-67625b93-8380-4252-abf1-2c397ac981f5\" "} {"level":"info","ts":"2026-06-05T22:05:09.14163Z","caller":"traceutil/trace.go:171","msg":"trace[1973107756] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1906; }","duration":"958.264234ms","start":"2026-06-05T22:05:08.182884Z","end":"2026-06-05T22:05:09.141148Z","steps":["trace[1973107756] 'agreement among raft nodes before linearized reading' (duration: 955.972404ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:09.13951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.882458119s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:05:09.142089Z","caller":"traceutil/trace.go:171","msg":"trace[1969189482] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1907; }","duration":"1.885066565s","start":"2026-06-05T22:05:07.256983Z","end":"2026-06-05T22:05:09.14205Z","steps":["trace[1969189482] 'agreement among raft nodes before linearized reading' (duration: 1.882417889s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:09.142219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:07.256959Z","time spent":"1.885215357s","remote":"127.0.0.1:33552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-05T22:05:09.139604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.981422572s","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-06-05T22:05:09.142314Z","caller":"traceutil/trace.go:171","msg":"trace[1251362771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1907; }","duration":"1.98417072s","start":"2026-06-05T22:05:07.15813Z","end":"2026-06-05T22:05:09.142301Z","steps":["trace[1251362771] 'agreement among raft nodes before linearized reading' (duration: 1.981367541s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:09.142364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:07.15811Z","time spent":"1.984241031s","remote":"127.0.0.1:51810","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-06-05T22:05:09.139688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.96475727s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-05T22:05:09.142472Z","caller":"traceutil/trace.go:171","msg":"trace[1393337498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1907; }","duration":"1.967585719s","start":"2026-06-05T22:05:07.174868Z","end":"2026-06-05T22:05:09.142453Z","steps":["trace[1393337498] 'agreement among raft nodes before linearized reading' (duration: 1.96471356s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:09.142536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:07.17484Z","time spent":"1.96767896s","remote":"127.0.0.1:51810","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":"warn","ts":"2026-06-05T22:05:09.139784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.350400012s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-05T22:05:09.142671Z","caller":"traceutil/trace.go:171","msg":"trace[1659684565] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1907; }","duration":"1.353341043s","start":"2026-06-05T22:05:07.789315Z","end":"2026-06-05T22:05:09.142656Z","steps":["trace[1659684565] 'agreement among raft nodes before linearized reading' (duration: 1.349876943s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:09.142725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:07.789281Z","time spent":"1.353430505s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-06-05T22:05:09.143451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:08.182867Z","time spent":"960.427562ms","remote":"127.0.0.1:33644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-06-05T22:05:16.782302Z","caller":"traceutil/trace.go:171","msg":"trace[823187660] transaction","detail":"{read_only:false; response_revision:1981; number_of_response:1; }","duration":"191.99586ms","start":"2026-06-05T22:05:16.590272Z","end":"2026-06-05T22:05:16.782268Z","steps":["trace[823187660] 'process raft request' (duration: 191.790267ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:23.16391Z","caller":"traceutil/trace.go:171","msg":"trace[1594250087] linearizableReadLoop","detail":"{readStateIndex:2101; appliedIndex:2100; }","duration":"193.285825ms","start":"2026-06-05T22:05:22.970569Z","end":"2026-06-05T22:05:23.163855Z","steps":["trace[1594250087] 'read index received' (duration: 193.027791ms)","trace[1594250087] 'applied index is now lower than readState.Index' (duration: 256.784µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:05:23.163959Z","caller":"traceutil/trace.go:171","msg":"trace[296184827] transaction","detail":"{read_only:false; response_revision:2019; number_of_response:1; }","duration":"582.68419ms","start":"2026-06-05T22:05:22.581242Z","end":"2026-06-05T22:05:23.163926Z","steps":["trace[296184827] 'process raft request' (duration: 582.387296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:23.164181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:22.581219Z","time spent":"582.827772ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5625,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:05:23.16434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.739063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:05:23.164511Z","caller":"traceutil/trace.go:171","msg":"trace[140936305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2019; }","duration":"193.956366ms","start":"2026-06-05T22:05:22.970511Z","end":"2026-06-05T22:05:23.164467Z","steps":["trace[140936305] 'agreement among raft nodes before linearized reading' (duration: 193.671181ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:32.220018Z","caller":"traceutil/trace.go:171","msg":"trace[1766370126] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"149.578831ms","start":"2026-06-05T22:05:32.070403Z","end":"2026-06-05T22:05:32.219982Z","steps":["trace[1766370126] 'process raft request' (duration: 149.393798ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:39.415826Z","caller":"traceutil/trace.go:171","msg":"trace[356170423] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"240.000779ms","start":"2026-06-05T22:05:39.175798Z","end":"2026-06-05T22:05:39.415799Z","steps":["trace[356170423] 'process raft request' (duration: 239.866177ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:39.661811Z","caller":"traceutil/trace.go:171","msg":"trace[1919425983] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"357.820958ms","start":"2026-06-05T22:05:39.303944Z","end":"2026-06-05T22:05:39.661765Z","steps":["trace[1919425983] 'process raft request' (duration: 357.33364ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:39.661878Z","caller":"traceutil/trace.go:171","msg":"trace[1930883974] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"355.801808ms","start":"2026-06-05T22:05:39.306037Z","end":"2026-06-05T22:05:39.661839Z","steps":["trace[1930883974] 'process raft request' (duration: 355.615875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:39.661969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:39.30391Z","time spent":"357.980049ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:05:39.662031Z","caller":"traceutil/trace.go:171","msg":"trace[572028] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"336.523052ms","start":"2026-06-05T22:05:39.325452Z","end":"2026-06-05T22:05:39.661975Z","steps":["trace[572028] 'process raft request' (duration: 336.290558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:39.662078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:39.305996Z","time spent":"355.967909ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:05:39.662213Z","caller":"traceutil/trace.go:171","msg":"trace[403664900] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"336.241757ms","start":"2026-06-05T22:05:39.325962Z","end":"2026-06-05T22:05:39.662203Z","steps":["trace[403664900] 'process raft request' (duration: 335.869891ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:39.662249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:39.325439Z","time spent":"336.696634ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:05:39.66227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:39.325946Z","time spent":"336.297067ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:05:39.662261Z","caller":"traceutil/trace.go:171","msg":"trace[1567528241] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"335.793581ms","start":"2026-06-05T22:05:39.326455Z","end":"2026-06-05T22:05:39.662249Z","steps":["trace[1567528241] 'process raft request' (duration: 335.591088ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:39.662229Z","caller":"traceutil/trace.go:171","msg":"trace[1077639049] linearizableReadLoop","detail":"{readStateIndex:2214; appliedIndex:2209; }","duration":"285.464204ms","start":"2026-06-05T22:05:39.376708Z","end":"2026-06-05T22:05:39.662172Z","steps":["trace[1077639049] 'read index received' (duration: 39.511387ms)","trace[1077639049] 'applied index is now lower than readState.Index' (duration: 245.950667ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:05:39.662436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:39.326441Z","time spent":"335.946143ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:05:39.66258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.91318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-05T22:05:39.662634Z","caller":"traceutil/trace.go:171","msg":"trace[792835437] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2129; }","duration":"285.968241ms","start":"2026-06-05T22:05:39.376653Z","end":"2026-06-05T22:05:39.662622Z","steps":["trace[792835437] 'agreement among raft nodes before linearized reading' (duration: 285.809759ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:39.675573Z","caller":"traceutil/trace.go:171","msg":"trace[575627074] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"122.477027ms","start":"2026-06-05T22:05:39.553065Z","end":"2026-06-05T22:05:39.675542Z","steps":["trace[575627074] 'process raft request' (duration: 122.134112ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:39.815059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.737663ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-05T22:05:39.815358Z","caller":"traceutil/trace.go:171","msg":"trace[205737060] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"145.371597ms","start":"2026-06-05T22:05:39.669959Z","end":"2026-06-05T22:05:39.815331Z","steps":["trace[205737060] 'process raft request' (duration: 145.218784ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:39.815642Z","caller":"traceutil/trace.go:171","msg":"trace[2144137225] linearizableReadLoop","detail":"{readStateIndex:2216; appliedIndex:2214; }","duration":"153.153622ms","start":"2026-06-05T22:05:39.662471Z","end":"2026-06-05T22:05:39.815625Z","steps":["trace[2144137225] 'read index received' (duration: 12.675078ms)","trace[2144137225] 'applied index is now lower than readState.Index' (duration: 140.476764ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:05:39.81589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.883509ms","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-06-05T22:05:39.815932Z","caller":"traceutil/trace.go:171","msg":"trace[1795300798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2131; }","duration":"438.982661ms","start":"2026-06-05T22:05:39.376938Z","end":"2026-06-05T22:05:39.815921Z","steps":["trace[1795300798] 'agreement among raft nodes before linearized reading' (duration: 438.768738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:39.81626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.039585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-05T22:05:39.816339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:39.376917Z","time spent":"439.405498ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T22:05:39.816428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.373544ms","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-06-05T22:05:39.816516Z","caller":"traceutil/trace.go:171","msg":"trace[409856761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2131; }","duration":"422.497855ms","start":"2026-06-05T22:05:39.393999Z","end":"2026-06-05T22:05:39.816497Z","steps":["trace[409856761] 'agreement among raft nodes before linearized reading' (duration: 422.259872ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:39.816564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:39.393988Z","time spent":"422.567546ms","remote":"127.0.0.1:51810","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-06-05T22:05:39.816796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.987864ms","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-06-05T22:05:39.816833Z","caller":"traceutil/trace.go:171","msg":"trace[356431303] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2131; }","duration":"417.045686ms","start":"2026-06-05T22:05:39.399777Z","end":"2026-06-05T22:05:39.816822Z","steps":["trace[356431303] 'agreement among raft nodes before linearized reading' (duration: 416.947184ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:39.816862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:39.399759Z","time spent":"417.095397ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-05T22:05:39.817776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.864578ms","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-06-05T22:05:39.817845Z","caller":"traceutil/trace.go:171","msg":"trace[1054253820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2131; }","duration":"417.98766ms","start":"2026-06-05T22:05:39.399841Z","end":"2026-06-05T22:05:39.817828Z","steps":["trace[1054253820] 'agreement among raft nodes before linearized reading' (duration: 415.97238ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:39.81789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:39.399818Z","time spent":"418.06086ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-05T22:05:39.816327Z","caller":"traceutil/trace.go:171","msg":"trace[1988639872] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2131; }","duration":"421.130936ms","start":"2026-06-05T22:05:39.395171Z","end":"2026-06-05T22:05:39.816302Z","steps":["trace[1988639872] 'agreement among raft nodes before linearized reading' (duration: 420.980493ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:39.819217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:39.39516Z","time spent":"423.999678ms","remote":"127.0.0.1:55266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-05T22:05:40.430927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.841969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T22:05:40.431243Z","caller":"traceutil/trace.go:171","msg":"trace[1549262377] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"609.123427ms","start":"2026-06-05T22:05:39.822094Z","end":"2026-06-05T22:05:40.431217Z","steps":["trace[1549262377] 'process raft request' (duration: 608.948424ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:40.431267Z","caller":"traceutil/trace.go:171","msg":"trace[1352067403] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"609.391641ms","start":"2026-06-05T22:05:39.821826Z","end":"2026-06-05T22:05:40.431218Z","steps":["trace[1352067403] 'process raft request' (duration: 181.134446ms)","trace[1352067403] 'compare' (duration: 427.355012ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:05:40.431639Z","caller":"traceutil/trace.go:171","msg":"trace[1325262533] linearizableReadLoop","detail":"{readStateIndex:2222; appliedIndex:2219; }","duration":"460.613534ms","start":"2026-06-05T22:05:39.971014Z","end":"2026-06-05T22:05:40.431628Z","steps":["trace[1325262533] 'read index received' (duration: 31.804661ms)","trace[1325262533] 'applied index is now lower than readState.Index' (duration: 428.807733ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:05:40.431659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:39.821809Z","time spent":"609.644265ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:05:40.431831Z","caller":"traceutil/trace.go:171","msg":"trace[728419569] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"608.828533ms","start":"2026-06-05T22:05:39.822986Z","end":"2026-06-05T22:05:40.431815Z","steps":["trace[728419569] 'process raft request' (duration: 608.185743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:40.431914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:39.82298Z","time spent":"608.892003ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:05:40.432073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.06755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:05:40.43211Z","caller":"traceutil/trace.go:171","msg":"trace[654722791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2136; }","duration":"461.149482ms","start":"2026-06-05T22:05:39.97095Z","end":"2026-06-05T22:05:40.4321Z","steps":["trace[654722791] 'agreement among raft nodes before linearized reading' (duration: 461.093001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:40.43214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:39.970932Z","time spent":"461.201832ms","remote":"127.0.0.1:51554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T22:05:40.432213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.76753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T22:05:40.431392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:39.82208Z","time spent":"609.26362ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:05:40.432288Z","caller":"traceutil/trace.go:171","msg":"trace[1352468941] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2136; }","duration":"350.905162ms","start":"2026-06-05T22:05:40.081365Z","end":"2026-06-05T22:05:40.43227Z","steps":["trace[1352468941] 'agreement among raft nodes before linearized reading' (duration: 350.78199ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:40.43235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:40.081331Z","time spent":"351.002624ms","remote":"127.0.0.1:35914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":2,"response size":30,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-06-05T22:05:40.858748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.413326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T22:05:40.85881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.948459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:05:40.858859Z","caller":"traceutil/trace.go:171","msg":"trace[432867166] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:2136; }","duration":"321.615698ms","start":"2026-06-05T22:05:40.537223Z","end":"2026-06-05T22:05:40.858838Z","steps":["trace[432867166] 'count revisions from in-memory index tree' (duration: 321.272134ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:40.858904Z","caller":"traceutil/trace.go:171","msg":"trace[233752787] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:2136; }","duration":"254.110842ms","start":"2026-06-05T22:05:40.604772Z","end":"2026-06-05T22:05:40.858883Z","steps":["trace[233752787] 'count revisions from in-memory index tree' (duration: 253.818468ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:40.858928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:40.537199Z","time spent":"321.700911ms","remote":"127.0.0.1:51656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":9,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-06-05T22:05:40.971064Z","caller":"traceutil/trace.go:171","msg":"trace[303918160] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"109.603759ms","start":"2026-06-05T22:05:40.861418Z","end":"2026-06-05T22:05:40.971022Z","steps":["trace[303918160] 'process raft request' (duration: 109.327204ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:42.295899Z","caller":"traceutil/trace.go:171","msg":"trace[1066777845] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"624.349312ms","start":"2026-06-05T22:05:41.671517Z","end":"2026-06-05T22:05:42.295866Z","steps":["trace[1066777845] 'process raft request' (duration: 624.052928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:42.296085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:41.671491Z","time spent":"624.493094ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:05:42.342895Z","caller":"traceutil/trace.go:171","msg":"trace[262025336] linearizableReadLoop","detail":"{readStateIndex:2227; appliedIndex:2223; }","duration":"521.724501ms","start":"2026-06-05T22:05:41.821125Z","end":"2026-06-05T22:05:42.342849Z","steps":["trace[262025336] 'read index received' (duration: 474.57573ms)","trace[262025336] 'applied index is now lower than readState.Index' (duration: 47.147671ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:05:42.34308Z","caller":"traceutil/trace.go:171","msg":"trace[47413272] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"670.940375ms","start":"2026-06-05T22:05:41.672083Z","end":"2026-06-05T22:05:42.343023Z","steps":["trace[47413272] 'process raft request' (duration: 670.685332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:42.34324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.069472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T22:05:42.343274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:41.672074Z","time spent":"671.097507ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:05:42.3433Z","caller":"traceutil/trace.go:171","msg":"trace[1079748617] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:2141; }","duration":"468.142303ms","start":"2026-06-05T22:05:41.875143Z","end":"2026-06-05T22:05:42.343285Z","steps":["trace[1079748617] 'agreement among raft nodes before linearized reading' (duration: 468.033791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:42.343305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.153587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-05T22:05:42.343342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:41.875086Z","time spent":"468.246325ms","remote":"127.0.0.1:33554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-06-05T22:05:42.343347Z","caller":"traceutil/trace.go:171","msg":"trace[1189723993] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2141; }","duration":"522.222068ms","start":"2026-06-05T22:05:41.821114Z","end":"2026-06-05T22:05:42.343336Z","steps":["trace[1189723993] 'agreement among raft nodes before linearized reading' (duration: 522.036805ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:42.343386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:41.821061Z","time spent":"522.316409ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-05T22:05:42.3431Z","caller":"traceutil/trace.go:171","msg":"trace[1447445924] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"670.957195ms","start":"2026-06-05T22:05:41.672129Z","end":"2026-06-05T22:05:42.343086Z","steps":["trace[1447445924] 'process raft request' (duration: 670.682271ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:42.343382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.776592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-05T22:05:42.343468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:41.672123Z","time spent":"671.29803ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:05:42.343509Z","caller":"traceutil/trace.go:171","msg":"trace[1772053233] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2141; }","duration":"506.958684ms","start":"2026-06-05T22:05:41.836519Z","end":"2026-06-05T22:05:42.343478Z","steps":["trace[1772053233] 'agreement among raft nodes before linearized reading' (duration: 506.644949ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:42.343564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:41.836502Z","time spent":"507.052636ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T22:05:42.343613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.42785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-05T22:05:42.343616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.908997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:05:42.343674Z","caller":"traceutil/trace.go:171","msg":"trace[1260167278] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2141; }","duration":"477.488321ms","start":"2026-06-05T22:05:41.866146Z","end":"2026-06-05T22:05:42.343634Z","steps":["trace[1260167278] 'agreement among raft nodes before linearized reading' (duration: 477.396089ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:42.343698Z","caller":"traceutil/trace.go:171","msg":"trace[1629533890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2141; }","duration":"374.03778ms","start":"2026-06-05T22:05:41.969649Z","end":"2026-06-05T22:05:42.343686Z","steps":["trace[1629533890] 'agreement among raft nodes before linearized reading' (duration: 373.914277ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:42.343701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:41.866129Z","time spent":"477.565912ms","remote":"127.0.0.1:55266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-05T22:05:42.34373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:41.969633Z","time spent":"374.09069ms","remote":"127.0.0.1:51554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T22:05:42.343857Z","caller":"traceutil/trace.go:171","msg":"trace[1426205947] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"671.88945ms","start":"2026-06-05T22:05:41.671949Z","end":"2026-06-05T22:05:42.343839Z","steps":["trace[1426205947] 'process raft request' (duration: 670.683842ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:42.343935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:41.671928Z","time spent":"671.967941ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:05:42.491624Z","caller":"traceutil/trace.go:171","msg":"trace[73219353] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"142.571828ms","start":"2026-06-05T22:05:42.349021Z","end":"2026-06-05T22:05:42.491593Z","steps":["trace[73219353] 'process raft request' (duration: 124.060517ms)","trace[73219353] 'compare' (duration: 18.338218ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:05:44.521442Z","caller":"traceutil/trace.go:171","msg":"trace[456085910] linearizableReadLoop","detail":"{readStateIndex:2233; appliedIndex:2232; }","duration":"206.702562ms","start":"2026-06-05T22:05:44.314715Z","end":"2026-06-05T22:05:44.521418Z","steps":["trace[456085910] 'read index received' (duration: 206.60243ms)","trace[456085910] 'applied index is now lower than readState.Index' (duration: 98.752µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:05:44.521616Z","caller":"traceutil/trace.go:171","msg":"trace[1277988965] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"219.192013ms","start":"2026-06-05T22:05:44.302411Z","end":"2026-06-05T22:05:44.521603Z","steps":["trace[1277988965] 'process raft request' (duration: 218.847349ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:44.521991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.255709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T22:05:44.522042Z","caller":"traceutil/trace.go:171","msg":"trace[84415226] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:2147; }","duration":"207.383832ms","start":"2026-06-05T22:05:44.314646Z","end":"2026-06-05T22:05:44.52203Z","steps":["trace[84415226] 'agreement among raft nodes before linearized reading' (duration: 207.086097ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:44.744284Z","caller":"traceutil/trace.go:171","msg":"trace[1072290852] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"393.485215ms","start":"2026-06-05T22:05:44.350773Z","end":"2026-06-05T22:05:44.744258Z","steps":["trace[1072290852] 'process raft request' (duration: 393.354183ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:44.744442Z","caller":"traceutil/trace.go:171","msg":"trace[2006270306] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"393.259992ms","start":"2026-06-05T22:05:44.351166Z","end":"2026-06-05T22:05:44.744426Z","steps":["trace[2006270306] 'process raft request' (duration: 393.053908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:44.744553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:44.350765Z","time spent":"393.656468ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:05:44.744574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:44.351159Z","time spent":"393.327833ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:05:44.744732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.566786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-05T22:05:44.744761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.563044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-05T22:05:44.744709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.823011ms","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-06-05T22:05:44.744815Z","caller":"traceutil/trace.go:171","msg":"trace[1976527401] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2150; }","duration":"247.720648ms","start":"2026-06-05T22:05:44.497077Z","end":"2026-06-05T22:05:44.744797Z","steps":["trace[1976527401] 'agreement among raft nodes before linearized reading' (duration: 247.313403ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:44.744814Z","caller":"traceutil/trace.go:171","msg":"trace[800271677] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2150; }","duration":"386.650085ms","start":"2026-06-05T22:05:44.358149Z","end":"2026-06-05T22:05:44.744799Z","steps":["trace[800271677] 'agreement among raft nodes before linearized reading' (duration: 386.465183ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:44.744851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:44.358119Z","time spent":"386.725466ms","remote":"127.0.0.1:55266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-06-05T22:05:44.744852Z","caller":"traceutil/trace.go:171","msg":"trace[1209954350] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2150; }","duration":"381.034414ms","start":"2026-06-05T22:05:44.363798Z","end":"2026-06-05T22:05:44.744833Z","steps":["trace[1209954350] 'agreement among raft nodes before linearized reading' (duration: 380.636438ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:44.744879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.537285ms","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-06-05T22:05:44.744928Z","caller":"traceutil/trace.go:171","msg":"trace[965301981] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2150; }","duration":"227.635957ms","start":"2026-06-05T22:05:44.517279Z","end":"2026-06-05T22:05:44.744915Z","steps":["trace[965301981] 'agreement among raft nodes before linearized reading' (duration: 227.480835ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:44.744728Z","caller":"traceutil/trace.go:171","msg":"trace[138178325] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"394.477299ms","start":"2026-06-05T22:05:44.350231Z","end":"2026-06-05T22:05:44.744708Z","steps":["trace[138178325] 'process raft request' (duration: 387.67861ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:44.745284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.214877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-05T22:05:44.745364Z","caller":"traceutil/trace.go:171","msg":"trace[1065638043] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:2150; }","duration":"390.334598ms","start":"2026-06-05T22:05:44.355015Z","end":"2026-06-05T22:05:44.74535Z","steps":["trace[1065638043] 'agreement among raft nodes before linearized reading' (duration: 390.138845ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:05:44.744344Z","caller":"traceutil/trace.go:171","msg":"trace[2135150001] linearizableReadLoop","detail":"{readStateIndex:2236; appliedIndex:2233; }","duration":"222.749355ms","start":"2026-06-05T22:05:44.521569Z","end":"2026-06-05T22:05:44.744318Z","steps":["trace[2135150001] 'read index received' (duration: 216.340232ms)","trace[2135150001] 'applied index is now lower than readState.Index' (duration: 6.408183ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:05:44.745598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.461607ms","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-06-05T22:05:44.745658Z","caller":"traceutil/trace.go:171","msg":"trace[2018954051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2150; }","duration":"199.533748ms","start":"2026-06-05T22:05:44.546093Z","end":"2026-06-05T22:05:44.745626Z","steps":["trace[2018954051] 'agreement among raft nodes before linearized reading' (duration: 199.432157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:44.745837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.498427ms","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-06-05T22:05:44.745862Z","caller":"traceutil/trace.go:171","msg":"trace[886253766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2150; }","duration":"199.585978ms","start":"2026-06-05T22:05:44.54627Z","end":"2026-06-05T22:05:44.745856Z","steps":["trace[886253766] 'agreement among raft nodes before linearized reading' (duration: 199.522308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:44.745916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:44.355002Z","time spent":"390.896577ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"warn","ts":"2026-06-05T22:05:44.745964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.121505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T22:05:44.745983Z","caller":"traceutil/trace.go:171","msg":"trace[1155516363] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2150; }","duration":"182.170056ms","start":"2026-06-05T22:05:44.563808Z","end":"2026-06-05T22:05:44.745978Z","steps":["trace[1155516363] 'agreement among raft nodes before linearized reading' (duration: 182.127386ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:05:44.74504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:44.363788Z","time spent":"381.129275ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-05T22:05:44.746932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:05:44.350205Z","time spent":"396.63239ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:08:00.113456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.041235ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-05T22:08:00.113934Z","caller":"traceutil/trace.go:171","msg":"trace[1722387987] linearizableReadLoop","detail":"{readStateIndex:3093; appliedIndex:3092; }","duration":"233.350418ms","start":"2026-06-05T22:07:59.880562Z","end":"2026-06-05T22:08:00.113912Z","steps":["trace[1722387987] 'read index received' (duration: 58.972µs)","trace[1722387987] 'applied index is now lower than readState.Index' (duration: 233.289526ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:08:00.11423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.288001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:08:00.1143Z","caller":"traceutil/trace.go:171","msg":"trace[116949967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2976; }","duration":"143.415506ms","start":"2026-06-05T22:07:59.970867Z","end":"2026-06-05T22:08:00.114282Z","steps":["trace[116949967] 'agreement among raft nodes before linearized reading' (duration: 143.284831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:08:00.114369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.802646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18b64eb92b8558eb\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-06-05T22:08:00.114493Z","caller":"traceutil/trace.go:171","msg":"trace[610099062] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18b64eb92b8558eb; range_end:; response_count:1; response_revision:2976; }","duration":"233.936011ms","start":"2026-06-05T22:07:59.880543Z","end":"2026-06-05T22:08:00.114479Z","steps":["trace[610099062] 'agreement among raft nodes before linearized reading' (duration: 233.564347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:08:00.722633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.966044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-06-05T22:08:00.72275Z","caller":"traceutil/trace.go:171","msg":"trace[151443842] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2977; }","duration":"137.14427ms","start":"2026-06-05T22:08:00.585578Z","end":"2026-06-05T22:08:00.722722Z","steps":["trace[151443842] 'range keys from in-memory index tree' (duration: 136.732974ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:08:01.194307Z","caller":"traceutil/trace.go:171","msg":"trace[567951900] transaction","detail":"{read_only:false; response_revision:2978; number_of_response:1; }","duration":"117.961705ms","start":"2026-06-05T22:08:01.076322Z","end":"2026-06-05T22:08:01.194284Z","steps":["trace[567951900] 'process raft request' (duration: 117.819869ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:09:47.862136Z","caller":"traceutil/trace.go:171","msg":"trace[739877295] transaction","detail":"{read_only:false; response_revision:3618; number_of_response:1; }","duration":"120.971573ms","start":"2026-06-05T22:09:47.741143Z","end":"2026-06-05T22:09:47.862114Z","steps":["trace[739877295] 'process raft request' (duration: 120.816019ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:09:47.93423Z","caller":"traceutil/trace.go:171","msg":"trace[642122096] transaction","detail":"{read_only:false; response_revision:3619; number_of_response:1; }","duration":"159.916314ms","start":"2026-06-05T22:09:47.774299Z","end":"2026-06-05T22:09:47.934216Z","steps":["trace[642122096] 'process raft request' (duration: 159.789261ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:09:47.93451Z","caller":"traceutil/trace.go:171","msg":"trace[820289175] transaction","detail":"{read_only:false; response_revision:3621; number_of_response:1; }","duration":"147.575739ms","start":"2026-06-05T22:09:47.786918Z","end":"2026-06-05T22:09:47.934494Z","steps":["trace[820289175] 'process raft request' (duration: 147.475307ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:09:47.934543Z","caller":"traceutil/trace.go:171","msg":"trace[2065704532] transaction","detail":"{read_only:false; response_revision:3620; number_of_response:1; }","duration":"151.989112ms","start":"2026-06-05T22:09:47.78252Z","end":"2026-06-05T22:09:47.934509Z","steps":["trace[2065704532] 'process raft request' (duration: 151.659083ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:24.375332Z","caller":"traceutil/trace.go:171","msg":"trace[818876183] transaction","detail":"{read_only:false; response_revision:4213; number_of_response:1; }","duration":"122.937202ms","start":"2026-06-05T22:11:24.252371Z","end":"2026-06-05T22:11:24.375308Z","steps":["trace[818876183] 'process raft request' (duration: 89.100005ms)","trace[818876183] 'compare' (duration: 33.675864ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:24.375724Z","caller":"traceutil/trace.go:171","msg":"trace[1900919726] transaction","detail":"{read_only:false; response_revision:4214; number_of_response:1; }","duration":"122.412731ms","start":"2026-06-05T22:11:24.253301Z","end":"2026-06-05T22:11:24.375714Z","steps":["trace[1900919726] 'process raft request' (duration: 121.965183ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:24.376474Z","caller":"traceutil/trace.go:171","msg":"trace[422326247] transaction","detail":"{read_only:false; response_revision:4215; number_of_response:1; }","duration":"121.523553ms","start":"2026-06-05T22:11:24.254925Z","end":"2026-06-05T22:11:24.376448Z","steps":["trace[422326247] 'process raft request' (duration: 120.604675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:36.098961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.32864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:36.099048Z","caller":"traceutil/trace.go:171","msg":"trace[800394871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4643; }","duration":"128.430312ms","start":"2026-06-05T22:11:35.9706Z","end":"2026-06-05T22:11:36.099031Z","steps":["trace[800394871] 'range keys from in-memory index tree' (duration: 128.240089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:39.724784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.825069ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-05T22:11:39.724906Z","caller":"traceutil/trace.go:171","msg":"trace[713443805] linearizableReadLoop","detail":"{readStateIndex:4935; appliedIndex:4934; }","duration":"149.153894ms","start":"2026-06-05T22:11:39.575737Z","end":"2026-06-05T22:11:39.724891Z","steps":["trace[713443805] 'read index received' (duration: 24.225434ms)","trace[713443805] 'applied index is now lower than readState.Index' (duration: 124.92713ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:39.725804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.052741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-06-05T22:11:39.725878Z","caller":"traceutil/trace.go:171","msg":"trace[681952450] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4750; }","duration":"150.153052ms","start":"2026-06-05T22:11:39.575713Z","end":"2026-06-05T22:11:39.725866Z","steps":["trace[681952450] 'agreement among raft nodes before linearized reading' (duration: 149.253745ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:39.953526Z","caller":"traceutil/trace.go:171","msg":"trace[438199743] transaction","detail":"{read_only:false; response_revision:4752; number_of_response:1; }","duration":"102.363358ms","start":"2026-06-05T22:11:39.851136Z","end":"2026-06-05T22:11:39.953499Z","steps":["trace[438199743] 'process raft request' (duration: 102.133634ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:40.314721Z","caller":"traceutil/trace.go:171","msg":"trace[1557166308] linearizableReadLoop","detail":"{readStateIndex:4939; appliedIndex:4938; }","duration":"336.014939ms","start":"2026-06-05T22:11:39.978679Z","end":"2026-06-05T22:11:40.314694Z","steps":["trace[1557166308] 'read index received' (duration: 335.789093ms)","trace[1557166308] 'applied index is now lower than readState.Index' (duration: 224.696µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:40.314807Z","caller":"traceutil/trace.go:171","msg":"trace[196430304] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"355.829497ms","start":"2026-06-05T22:11:39.958947Z","end":"2026-06-05T22:11:40.314776Z","steps":["trace[196430304] 'process raft request' (duration: 355.547901ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:40.314957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:39.958918Z","time spent":"355.95402ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3878,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:40.314989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.682599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:56729"} {"level":"info","ts":"2026-06-05T22:11:40.31504Z","caller":"traceutil/trace.go:171","msg":"trace[883082342] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4754; }","duration":"351.76039ms","start":"2026-06-05T22:11:39.963268Z","end":"2026-06-05T22:11:40.315029Z","steps":["trace[883082342] 'agreement among raft nodes before linearized reading' (duration: 351.538756ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:40.315069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:39.963262Z","time spent":"351.803081ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":6,"response size":56752,"request content":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" "} {"level":"warn","ts":"2026-06-05T22:11:40.315231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.658896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-06-05T22:11:40.315285Z","caller":"traceutil/trace.go:171","msg":"trace[56977331] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4754; }","duration":"353.762298ms","start":"2026-06-05T22:11:39.961509Z","end":"2026-06-05T22:11:40.315271Z","steps":["trace[56977331] 'agreement among raft nodes before linearized reading' (duration: 353.30506ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:40.315331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:39.961489Z","time spent":"353.833459ms","remote":"127.0.0.1:51918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":1147,"request content":"key:\"/registry/storageclasses/general\" "} {"level":"info","ts":"2026-06-05T22:11:40.345351Z","caller":"traceutil/trace.go:171","msg":"trace[1367475528] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"284.976342ms","start":"2026-06-05T22:11:40.060354Z","end":"2026-06-05T22:11:40.345331Z","steps":["trace[1367475528] 'process raft request' (duration: 284.86663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:40.345528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.570022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:40.345601Z","caller":"traceutil/trace.go:171","msg":"trace[240105166] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:4755; }","duration":"332.699203ms","start":"2026-06-05T22:11:40.012883Z","end":"2026-06-05T22:11:40.345582Z","steps":["trace[240105166] 'agreement among raft nodes before linearized reading' (duration: 332.565541ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:40.345649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:40.012862Z","time spent":"332.777005ms","remote":"127.0.0.1:33592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-06-05T22:11:40.599852Z","caller":"traceutil/trace.go:171","msg":"trace[347353320] linearizableReadLoop","detail":"{readStateIndex:4941; appliedIndex:4940; }","duration":"254.416438ms","start":"2026-06-05T22:11:40.345413Z","end":"2026-06-05T22:11:40.599829Z","steps":["trace[347353320] 'read index received' (duration: 254.273276ms)","trace[347353320] 'applied index is now lower than readState.Index' (duration: 142.042µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:40.59992Z","caller":"traceutil/trace.go:171","msg":"trace[1455621154] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"271.098836ms","start":"2026-06-05T22:11:40.328785Z","end":"2026-06-05T22:11:40.599884Z","steps":["trace[1455621154] 'process raft request' (duration: 270.886662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:40.600094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.34969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9c6a932a-8eee-418e-a2b7-26a780f43cf5\" ","response":"range_response_count:1 size:3759"} {"level":"info","ts":"2026-06-05T22:11:40.600139Z","caller":"traceutil/trace.go:171","msg":"trace[1065581430] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9c6a932a-8eee-418e-a2b7-26a780f43cf5; range_end:; response_count:1; response_revision:4756; }","duration":"273.411011ms","start":"2026-06-05T22:11:40.326715Z","end":"2026-06-05T22:11:40.600126Z","steps":["trace[1065581430] 'agreement among raft nodes before linearized reading' (duration: 273.236778ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:40.87606Z","caller":"traceutil/trace.go:171","msg":"trace[283381281] transaction","detail":"{read_only:false; response_revision:4757; number_of_response:1; }","duration":"521.195143ms","start":"2026-06-05T22:11:40.354838Z","end":"2026-06-05T22:11:40.876033Z","steps":["trace[283381281] 'process raft request' (duration: 487.202244ms)","trace[283381281] 'compare' (duration: 33.716304ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:40.876203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:40.35482Z","time spent":"521.310555ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:11:40.876457Z","caller":"traceutil/trace.go:171","msg":"trace[1859669958] linearizableReadLoop","detail":"{readStateIndex:4945; appliedIndex:4941; }","duration":"276.508541ms","start":"2026-06-05T22:11:40.599923Z","end":"2026-06-05T22:11:40.876432Z","steps":["trace[1859669958] 'read index received' (duration: 242.040683ms)","trace[1859669958] 'applied index is now lower than readState.Index' (duration: 34.466608ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:40.876471Z","caller":"traceutil/trace.go:171","msg":"trace[1956104364] transaction","detail":"{read_only:false; response_revision:4759; number_of_response:1; }","duration":"425.282112ms","start":"2026-06-05T22:11:40.451176Z","end":"2026-06-05T22:11:40.876458Z","steps":["trace[1956104364] 'process raft request' (duration: 424.963696ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:40.876759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:40.451167Z","time spent":"425.478286ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:40.877172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.738542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-88d700b2-e826-41df-b5b5-0021408dfdd9\" ","response":"range_response_count:1 size:3893"} {"level":"info","ts":"2026-06-05T22:11:40.876506Z","caller":"traceutil/trace.go:171","msg":"trace[1867941647] transaction","detail":"{read_only:false; response_revision:4760; number_of_response:1; }","duration":"425.260782ms","start":"2026-06-05T22:11:40.451228Z","end":"2026-06-05T22:11:40.876489Z","steps":["trace[1867941647] 'process raft request' (duration: 425.091839ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:40.877241Z","caller":"traceutil/trace.go:171","msg":"trace[368319298] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-88d700b2-e826-41df-b5b5-0021408dfdd9; range_end:; response_count:1; response_revision:4760; }","duration":"145.813954ms","start":"2026-06-05T22:11:40.731406Z","end":"2026-06-05T22:11:40.87722Z","steps":["trace[368319298] 'agreement among raft nodes before linearized reading' (duration: 145.694332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:40.877271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:40.451102Z","time spent":"426.126518ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:40.877077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.240319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-05T22:11:40.877412Z","caller":"traceutil/trace.go:171","msg":"trace[1487945108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4760; }","duration":"117.572475ms","start":"2026-06-05T22:11:40.759829Z","end":"2026-06-05T22:11:40.877402Z","steps":["trace[1487945108] 'agreement among raft nodes before linearized reading' (duration: 117.171487ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:40.876938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.203076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-05T22:11:40.877497Z","caller":"traceutil/trace.go:171","msg":"trace[1525207608] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4760; }","duration":"152.863289ms","start":"2026-06-05T22:11:40.724627Z","end":"2026-06-05T22:11:40.87749Z","steps":["trace[1525207608] 'agreement among raft nodes before linearized reading' (duration: 152.111594ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:40.876408Z","caller":"traceutil/trace.go:171","msg":"trace[942497839] transaction","detail":"{read_only:false; response_revision:4758; number_of_response:1; }","duration":"426.538406ms","start":"2026-06-05T22:11:40.449846Z","end":"2026-06-05T22:11:40.876384Z","steps":["trace[942497839] 'process raft request' (duration: 426.049647ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:40.878258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:40.44982Z","time spent":"428.372541ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:40.878213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.879896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-06-05T22:11:40.878494Z","caller":"traceutil/trace.go:171","msg":"trace[807577212] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4760; }","duration":"313.187281ms","start":"2026-06-05T22:11:40.565281Z","end":"2026-06-05T22:11:40.878469Z","steps":["trace[807577212] 'agreement among raft nodes before linearized reading' (duration: 311.449497ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:40.87854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:40.565268Z","time spent":"313.256272ms","remote":"127.0.0.1:51652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2206989,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-06-05T22:11:41.243877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.706499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T22:11:41.243983Z","caller":"traceutil/trace.go:171","msg":"trace[1531838970] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"362.494455ms","start":"2026-06-05T22:11:40.881467Z","end":"2026-06-05T22:11:41.243961Z","steps":["trace[1531838970] 'process raft request' (duration: 132.614132ms)","trace[1531838970] 'compare' (duration: 229.504356ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:41.244041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:40.881446Z","time spent":"362.569196ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:42.287447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.183247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-05T22:11:42.287673Z","caller":"traceutil/trace.go:171","msg":"trace[209583063] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"1.405611412s","start":"2026-06-05T22:11:40.882013Z","end":"2026-06-05T22:11:42.287625Z","steps":["trace[209583063] 'process raft request' (duration: 694.165929ms)","trace[209583063] 'compare' (duration: 710.588466ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:42.287719Z","caller":"traceutil/trace.go:171","msg":"trace[1813617749] transaction","detail":"{read_only:false; response_revision:4763; number_of_response:1; }","duration":"1.405781115s","start":"2026-06-05T22:11:40.88191Z","end":"2026-06-05T22:11:42.287692Z","steps":["trace[1813617749] 'process raft request' (duration: 1.405629532s)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:42.287716Z","caller":"traceutil/trace.go:171","msg":"trace[1992036790] transaction","detail":"{read_only:false; response_revision:4764; number_of_response:1; }","duration":"1.405673333s","start":"2026-06-05T22:11:40.882026Z","end":"2026-06-05T22:11:42.287699Z","steps":["trace[1992036790] 'process raft request' (duration: 1.405605272s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:42.28786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:40.882001Z","time spent":"1.405771964s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:42.287889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:40.881999Z","time spent":"1.405843766s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:11:42.287915Z","caller":"traceutil/trace.go:171","msg":"trace[286183512] transaction","detail":"{read_only:false; response_revision:4765; number_of_response:1; }","duration":"1.405811365s","start":"2026-06-05T22:11:40.882087Z","end":"2026-06-05T22:11:42.287898Z","steps":["trace[286183512] 'process raft request' (duration: 1.40557812s)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:42.287896Z","caller":"traceutil/trace.go:171","msg":"trace[663161806] linearizableReadLoop","detail":"{readStateIndex:4950; appliedIndex:4945; }","duration":"1.376903012s","start":"2026-06-05T22:11:40.910878Z","end":"2026-06-05T22:11:42.287781Z","steps":["trace[663161806] 'read index received' (duration: 103.19266ms)","trace[663161806] 'applied index is now lower than readState.Index' (duration: 1.273708602s)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:42.288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:40.882063Z","time spent":"1.405897297s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:42.288026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:40.881895Z","time spent":"1.405882796s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:42.288155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37728412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-06-05T22:11:42.288222Z","caller":"traceutil/trace.go:171","msg":"trace[830329811] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4765; }","duration":"1.377388101s","start":"2026-06-05T22:11:40.910811Z","end":"2026-06-05T22:11:42.288199Z","steps":["trace[830329811] 'agreement among raft nodes before linearized reading' (duration: 1.377212958s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:42.288258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13513133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:42.28832Z","caller":"traceutil/trace.go:171","msg":"trace[1141061506] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:4765; }","duration":"1.135233031s","start":"2026-06-05T22:11:41.15307Z","end":"2026-06-05T22:11:42.288303Z","steps":["trace[1141061506] 'agreement among raft nodes before linearized reading' (duration: 1.135131329s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:42.288237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.317982186s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T22:11:42.288323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:40.910786Z","time spent":"1.377502494s","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1840,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-06-05T22:11:42.288375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:41.153062Z","time spent":"1.135299133s","remote":"127.0.0.1:33694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-06-05T22:11:42.288393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.140022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-06-05T22:11:42.288428Z","caller":"traceutil/trace.go:171","msg":"trace[857072933] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4765; }","duration":"104.215063ms","start":"2026-06-05T22:11:42.184203Z","end":"2026-06-05T22:11:42.288418Z","steps":["trace[857072933] 'agreement among raft nodes before linearized reading' (duration: 104.095991ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:42.288509Z","caller":"traceutil/trace.go:171","msg":"trace[1332896700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4765; }","duration":"1.31821201s","start":"2026-06-05T22:11:40.970154Z","end":"2026-06-05T22:11:42.288366Z","steps":["trace[1332896700] 'agreement among raft nodes before linearized reading' (duration: 1.318000236s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:42.28859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.672848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T22:11:42.288705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.735174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-88d700b2-e826-41df-b5b5-0021408dfdd9\" ","response":"range_response_count:1 size:3893"} {"level":"warn","ts":"2026-06-05T22:11:42.288649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:40.970134Z","time spent":"1.318418944s","remote":"127.0.0.1:51554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-05T22:11:42.288746Z","caller":"traceutil/trace.go:171","msg":"trace[1942597776] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-88d700b2-e826-41df-b5b5-0021408dfdd9; range_end:; response_count:1; response_revision:4765; }","duration":"406.813764ms","start":"2026-06-05T22:11:41.881922Z","end":"2026-06-05T22:11:42.288736Z","steps":["trace[1942597776] 'agreement among raft nodes before linearized reading' (duration: 406.693922ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:42.288792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:41.881899Z","time spent":"406.886566ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3916,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-88d700b2-e826-41df-b5b5-0021408dfdd9\" "} {"level":"warn","ts":"2026-06-05T22:11:42.28827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.719103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9c6a932a-8eee-418e-a2b7-26a780f43cf5\" ","response":"range_response_count:1 size:3759"} {"level":"warn","ts":"2026-06-05T22:11:42.288865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255410733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:42.288898Z","caller":"traceutil/trace.go:171","msg":"trace[1484912291] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9c6a932a-8eee-418e-a2b7-26a780f43cf5; range_end:; response_count:1; response_revision:4765; }","duration":"684.392146ms","start":"2026-06-05T22:11:41.604497Z","end":"2026-06-05T22:11:42.288889Z","steps":["trace[1484912291] 'agreement among raft nodes before linearized reading' (duration: 683.669233ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:42.28892Z","caller":"traceutil/trace.go:171","msg":"trace[530257626] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:4765; }","duration":"1.255504034s","start":"2026-06-05T22:11:41.033399Z","end":"2026-06-05T22:11:42.288903Z","steps":["trace[530257626] 'agreement among raft nodes before linearized reading' (duration: 1.255422213s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:42.288978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:41.033376Z","time spent":"1.255588087s","remote":"127.0.0.1:33154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"warn","ts":"2026-06-05T22:11:42.288928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:41.604477Z","time spent":"684.444158ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3782,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9c6a932a-8eee-418e-a2b7-26a780f43cf5\" "} {"level":"info","ts":"2026-06-05T22:11:42.288647Z","caller":"traceutil/trace.go:171","msg":"trace[1234233244] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4765; }","duration":"698.764689ms","start":"2026-06-05T22:11:41.589869Z","end":"2026-06-05T22:11:42.288634Z","steps":["trace[1234233244] 'agreement among raft nodes before linearized reading' (duration: 698.678508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:42.289175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:41.589851Z","time spent":"699.3028ms","remote":"127.0.0.1:55266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-06-05T22:11:42.546318Z","caller":"traceutil/trace.go:171","msg":"trace[351032605] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"194.45854ms","start":"2026-06-05T22:11:42.351828Z","end":"2026-06-05T22:11:42.546287Z","steps":["trace[351032605] 'process raft request' (duration: 194.118204ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:42.546534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.503857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:42.546635Z","caller":"traceutil/trace.go:171","msg":"trace[2141373374] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4766; }","duration":"177.694641ms","start":"2026-06-05T22:11:42.368921Z","end":"2026-06-05T22:11:42.546616Z","steps":["trace[2141373374] 'agreement among raft nodes before linearized reading' (duration: 177.415476ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:42.546853Z","caller":"traceutil/trace.go:171","msg":"trace[2122017830] linearizableReadLoop","detail":"{readStateIndex:4951; appliedIndex:4950; }","duration":"177.253723ms","start":"2026-06-05T22:11:42.368954Z","end":"2026-06-05T22:11:42.546208Z","steps":["trace[2122017830] 'read index received' (duration: 176.885896ms)","trace[2122017830] 'applied index is now lower than readState.Index' (duration: 366.557µs)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:43.109413Z","caller":"traceutil/trace.go:171","msg":"trace[1843155973] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"177.631656ms","start":"2026-06-05T22:11:42.931749Z","end":"2026-06-05T22:11:43.109381Z","steps":["trace[1843155973] 'process raft request' (duration: 160.084132ms)","trace[1843155973] 'compare' (duration: 17.417791ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:43.673617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.07858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9c6a932a-8eee-418e-a2b7-26a780f43cf5\" ","response":"range_response_count:1 size:3879"} {"level":"info","ts":"2026-06-05T22:11:43.673692Z","caller":"traceutil/trace.go:171","msg":"trace[1429091601] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9c6a932a-8eee-418e-a2b7-26a780f43cf5; range_end:; response_count:1; response_revision:4780; }","duration":"379.205983ms","start":"2026-06-05T22:11:43.294467Z","end":"2026-06-05T22:11:43.673673Z","steps":["trace[1429091601] 'range keys from in-memory index tree' (duration: 378.967758ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:43.673735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:43.294443Z","time spent":"379.282534ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3902,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9c6a932a-8eee-418e-a2b7-26a780f43cf5\" "} {"level":"warn","ts":"2026-06-05T22:11:43.673744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.587323ms","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-06-05T22:11:43.67383Z","caller":"traceutil/trace.go:171","msg":"trace[2001593447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4780; }","duration":"425.727826ms","start":"2026-06-05T22:11:43.248081Z","end":"2026-06-05T22:11:43.673808Z","steps":["trace[2001593447] 'range keys from in-memory index tree' (duration: 425.348159ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:43.673817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.953408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-88d700b2-e826-41df-b5b5-0021408dfdd9\" ","response":"range_response_count:1 size:4079"} {"level":"warn","ts":"2026-06-05T22:11:43.673875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:43.248055Z","time spent":"425.811657ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-05T22:11:43.67391Z","caller":"traceutil/trace.go:171","msg":"trace[2105966867] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-88d700b2-e826-41df-b5b5-0021408dfdd9; range_end:; response_count:1; response_revision:4780; }","duration":"379.0692ms","start":"2026-06-05T22:11:43.294826Z","end":"2026-06-05T22:11:43.673895Z","steps":["trace[2105966867] 'range keys from in-memory index tree' (duration: 378.770484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:43.674048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:43.294815Z","time spent":"379.182303ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4102,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-88d700b2-e826-41df-b5b5-0021408dfdd9\" "} {"level":"info","ts":"2026-06-05T22:11:44.800501Z","caller":"traceutil/trace.go:171","msg":"trace[2092685268] transaction","detail":"{read_only:false; response_revision:4814; number_of_response:1; }","duration":"111.055306ms","start":"2026-06-05T22:11:44.689416Z","end":"2026-06-05T22:11:44.800471Z","steps":["trace[2092685268] 'process raft request' (duration: 106.740514ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:45.101489Z","caller":"traceutil/trace.go:171","msg":"trace[1908282274] linearizableReadLoop","detail":"{readStateIndex:5012; appliedIndex:5011; }","duration":"130.596182ms","start":"2026-06-05T22:11:44.970867Z","end":"2026-06-05T22:11:45.101463Z","steps":["trace[1908282274] 'read index received' (duration: 130.420279ms)","trace[1908282274] 'applied index is now lower than readState.Index' (duration: 174.803µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:45.101686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.795166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:45.101762Z","caller":"traceutil/trace.go:171","msg":"trace[1039315860] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-1; range_end:; response_count:0; response_revision:4824; }","duration":"130.896748ms","start":"2026-06-05T22:11:44.970852Z","end":"2026-06-05T22:11:45.101749Z","steps":["trace[1039315860] 'agreement among raft nodes before linearized reading' (duration: 130.737395ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:45.101582Z","caller":"traceutil/trace.go:171","msg":"trace[871596008] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"130.703404ms","start":"2026-06-05T22:11:44.970836Z","end":"2026-06-05T22:11:45.101539Z","steps":["trace[871596008] 'process raft request' (duration: 130.49218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:45.105492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.566328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-06-05T22:11:45.105574Z","caller":"traceutil/trace.go:171","msg":"trace[169803628] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4825; }","duration":"134.667889ms","start":"2026-06-05T22:11:44.970888Z","end":"2026-06-05T22:11:45.105556Z","steps":["trace[169803628] 'agreement among raft nodes before linearized reading' (duration: 134.449175ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:45.105986Z","caller":"traceutil/trace.go:171","msg":"trace[1596728197] transaction","detail":"{read_only:false; response_revision:4825; number_of_response:1; }","duration":"132.806305ms","start":"2026-06-05T22:11:44.973113Z","end":"2026-06-05T22:11:45.105919Z","steps":["trace[1596728197] 'process raft request' (duration: 132.111332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:45.106064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.156059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:45.106105Z","caller":"traceutil/trace.go:171","msg":"trace[543727996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4825; }","duration":"134.209251ms","start":"2026-06-05T22:11:44.971884Z","end":"2026-06-05T22:11:45.106094Z","steps":["trace[543727996] 'agreement among raft nodes before linearized reading' (duration: 133.975427ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:45.513731Z","caller":"traceutil/trace.go:171","msg":"trace[701777052] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"159.743313ms","start":"2026-06-05T22:11:45.35396Z","end":"2026-06-05T22:11:45.513703Z","steps":["trace[701777052] 'process raft request' (duration: 159.335605ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:45.513855Z","caller":"traceutil/trace.go:171","msg":"trace[365710914] linearizableReadLoop","detail":"{readStateIndex:5019; appliedIndex:5016; }","duration":"145.255397ms","start":"2026-06-05T22:11:45.368578Z","end":"2026-06-05T22:11:45.513834Z","steps":["trace[365710914] 'read index received' (duration: 12.270231ms)","trace[365710914] 'applied index is now lower than readState.Index' (duration: 132.984256ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:45.514228Z","caller":"traceutil/trace.go:171","msg":"trace[1828334872] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"158.760625ms","start":"2026-06-05T22:11:45.355446Z","end":"2026-06-05T22:11:45.514207Z","steps":["trace[1828334872] 'process raft request' (duration: 158.210814ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:45.514311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.4705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-alertmanager.18b64eef4025fb7d\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-06-05T22:11:45.514397Z","caller":"traceutil/trace.go:171","msg":"trace[1245583445] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-alertmanager.18b64eef4025fb7d; range_end:; response_count:1; response_revision:4831; }","duration":"130.605692ms","start":"2026-06-05T22:11:45.383773Z","end":"2026-06-05T22:11:45.514379Z","steps":["trace[1245583445] 'agreement among raft nodes before linearized reading' (duration: 130.349967ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:45.518515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.906597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206966"} {"level":"info","ts":"2026-06-05T22:11:45.518597Z","caller":"traceutil/trace.go:171","msg":"trace[975283466] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4831; }","duration":"150.022339ms","start":"2026-06-05T22:11:45.368559Z","end":"2026-06-05T22:11:45.518581Z","steps":["trace[975283466] 'agreement among raft nodes before linearized reading' (duration: 145.542534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:46.872654Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605074216997042,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-05T22:11:46.911743Z","caller":"traceutil/trace.go:171","msg":"trace[208383469] transaction","detail":"{read_only:false; response_revision:4847; number_of_response:1; }","duration":"573.606966ms","start":"2026-06-05T22:11:46.338109Z","end":"2026-06-05T22:11:46.911716Z","steps":["trace[208383469] 'process raft request' (duration: 573.441633ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:46.911906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:46.338089Z","time spent":"573.736989ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:11:46.949122Z","caller":"traceutil/trace.go:171","msg":"trace[290354036] linearizableReadLoop","detail":"{readStateIndex:5041; appliedIndex:5035; }","duration":"577.038951ms","start":"2026-06-05T22:11:46.372053Z","end":"2026-06-05T22:11:46.949092Z","steps":["trace[290354036] 'read index received' (duration: 539.602984ms)","trace[290354036] 'applied index is now lower than readState.Index' (duration: 37.434727ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:46.949503Z","caller":"traceutil/trace.go:171","msg":"trace[1512289411] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"610.886821ms","start":"2026-06-05T22:11:46.338597Z","end":"2026-06-05T22:11:46.949483Z","steps":["trace[1512289411] 'process raft request' (duration: 608.310502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:46.949649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:46.338577Z","time spent":"610.985112ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:11:46.949892Z","caller":"traceutil/trace.go:171","msg":"trace[1622315929] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"611.067644ms","start":"2026-06-05T22:11:46.338808Z","end":"2026-06-05T22:11:46.949875Z","steps":["trace[1622315929] 'process raft request' (duration: 608.330462ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:46.949987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:46.338799Z","time spent":"611.140735ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:11:46.950182Z","caller":"traceutil/trace.go:171","msg":"trace[437364342] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"610.081085ms","start":"2026-06-05T22:11:46.340085Z","end":"2026-06-05T22:11:46.950166Z","steps":["trace[437364342] 'process raft request' (duration: 608.69255ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:46.950276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:46.340078Z","time spent":"610.150677ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:46.950606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.509769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T22:11:46.950658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.736466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9c6a932a-8eee-418e-a2b7-26a780f43cf5\" ","response":"range_response_count:1 size:4067"} {"level":"info","ts":"2026-06-05T22:11:46.950697Z","caller":"traceutil/trace.go:171","msg":"trace[29308940] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9c6a932a-8eee-418e-a2b7-26a780f43cf5; range_end:; response_count:1; response_revision:4853; }","duration":"260.778747ms","start":"2026-06-05T22:11:46.689906Z","end":"2026-06-05T22:11:46.950685Z","steps":["trace[29308940] 'agreement among raft nodes before linearized reading' (duration: 260.651365ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:46.950698Z","caller":"traceutil/trace.go:171","msg":"trace[1347243053] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:4853; }","duration":"578.647802ms","start":"2026-06-05T22:11:46.372017Z","end":"2026-06-05T22:11:46.950665Z","steps":["trace[1347243053] 'agreement among raft nodes before linearized reading' (duration: 578.486909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:46.950749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:46.372Z","time spent":"578.738413ms","remote":"127.0.0.1:33144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"info","ts":"2026-06-05T22:11:46.950735Z","caller":"traceutil/trace.go:171","msg":"trace[1311411252] transaction","detail":"{read_only:false; response_revision:4852; number_of_response:1; }","duration":"164.867814ms","start":"2026-06-05T22:11:46.785824Z","end":"2026-06-05T22:11:46.950692Z","steps":["trace[1311411252] 'process raft request' (duration: 163.116842ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:46.950788Z","caller":"traceutil/trace.go:171","msg":"trace[2034294387] transaction","detail":"{read_only:false; response_revision:4851; number_of_response:1; }","duration":"281.825074ms","start":"2026-06-05T22:11:46.668942Z","end":"2026-06-05T22:11:46.950767Z","steps":["trace[2034294387] 'process raft request' (duration: 279.925538ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:51.882455Z","caller":"traceutil/trace.go:171","msg":"trace[976589040] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"131.164117ms","start":"2026-06-05T22:11:51.751268Z","end":"2026-06-05T22:11:51.882432Z","steps":["trace[976589040] 'process raft request' (duration: 130.8085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:52.999471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028774093s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:52.999539Z","caller":"traceutil/trace.go:171","msg":"trace[819130433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4956; }","duration":"1.028885686s","start":"2026-06-05T22:11:51.970638Z","end":"2026-06-05T22:11:52.999524Z","steps":["trace[819130433] 'range keys from in-memory index tree' (duration: 1.028676462s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:52.999568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:51.970617Z","time spent":"1.028944536s","remote":"127.0.0.1:51554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T22:11:52.999783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011945377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-8fb65c47f-wcjrb\" ","response":"range_response_count:1 size:11251"} {"level":"info","ts":"2026-06-05T22:11:52.99981Z","caller":"traceutil/trace.go:171","msg":"trace[1921469359] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-8fb65c47f-wcjrb; range_end:; response_count:1; response_revision:4956; }","duration":"1.012003789s","start":"2026-06-05T22:11:51.987799Z","end":"2026-06-05T22:11:52.999803Z","steps":["trace[1921469359] 'range keys from in-memory index tree' (duration: 1.011811195s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:52.999828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:51.98779Z","time spent":"1.01203408s","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":11274,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-8fb65c47f-wcjrb\" "} {"level":"warn","ts":"2026-06-05T22:11:53.000079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.728356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-06-05T22:11:53.000106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.426991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T22:11:53.000189Z","caller":"traceutil/trace.go:171","msg":"trace[1860050617] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:4956; }","duration":"181.591164ms","start":"2026-06-05T22:11:52.818574Z","end":"2026-06-05T22:11:53.000165Z","steps":["trace[1860050617] 'count revisions from in-memory index tree' (duration: 181.283469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:53.000333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.858191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-05T22:11:53.00034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.295548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-ml6x8.18b64ef239b85945\" ","response":"range_response_count:1 size:962"} {"level":"info","ts":"2026-06-05T22:11:53.000364Z","caller":"traceutil/trace.go:171","msg":"trace[900487731] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:4956; }","duration":"900.927822ms","start":"2026-06-05T22:11:52.099428Z","end":"2026-06-05T22:11:53.000356Z","steps":["trace[900487731] 'count revisions from in-memory index tree' (duration: 900.80302ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:53.000375Z","caller":"traceutil/trace.go:171","msg":"trace[930161741] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-ml6x8.18b64ef239b85945; range_end:; response_count:1; response_revision:4956; }","duration":"542.354079ms","start":"2026-06-05T22:11:52.45801Z","end":"2026-06-05T22:11:53.000364Z","steps":["trace[930161741] 'range keys from in-memory index tree' (duration: 542.136566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:53.00039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:52.099409Z","time spent":"900.972853ms","remote":"127.0.0.1:51656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-06-05T22:11:53.000397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:52.457999Z","time spent":"542.39277ms","remote":"127.0.0.1:51616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":985,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-ml6x8.18b64ef239b85945\" "} {"level":"warn","ts":"2026-06-05T22:11:53.000643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.015122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T22:11:53.000686Z","caller":"traceutil/trace.go:171","msg":"trace[153478604] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:4956; }","duration":"757.059583ms","start":"2026-06-05T22:11:52.243619Z","end":"2026-06-05T22:11:53.000679Z","steps":["trace[153478604] 'count revisions from in-memory index tree' (duration: 756.944251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:53.000706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:52.243544Z","time spent":"757.155484ms","remote":"127.0.0.1:51912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-05T22:11:53.001233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.493314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-06-05T22:11:53.001339Z","caller":"traceutil/trace.go:171","msg":"trace[487309446] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4956; }","duration":"257.661447ms","start":"2026-06-05T22:11:52.743664Z","end":"2026-06-05T22:11:53.001326Z","steps":["trace[487309446] 'range keys from in-memory index tree' (duration: 257.27959ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:53.000138Z","caller":"traceutil/trace.go:171","msg":"trace[169441335] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4956; }","duration":"702.817889ms","start":"2026-06-05T22:11:52.297307Z","end":"2026-06-05T22:11:53.000125Z","steps":["trace[169441335] 'range keys from in-memory index tree' (duration: 702.610795ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:53.001437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:52.297297Z","time spent":"704.120192ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-06-05T22:11:53.00228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.852397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f5e11a49-aac5-4784-a3b8-be3a57b455fe\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-06-05T22:11:53.002349Z","caller":"traceutil/trace.go:171","msg":"trace[2138648109] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f5e11a49-aac5-4784-a3b8-be3a57b455fe; range_end:; response_count:1; response_revision:4956; }","duration":"114.973579ms","start":"2026-06-05T22:11:52.887358Z","end":"2026-06-05T22:11:53.002332Z","steps":["trace[2138648109] 'range keys from in-memory index tree' (duration: 112.996512ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:53.58538Z","caller":"traceutil/trace.go:171","msg":"trace[712491557] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"596.370902ms","start":"2026-06-05T22:11:52.988969Z","end":"2026-06-05T22:11:53.58534Z","steps":["trace[712491557] 'process raft request' (duration: 505.622297ms)","trace[712491557] 'compare' (duration: 90.412828ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:53.585438Z","caller":"traceutil/trace.go:171","msg":"trace[721273226] linearizableReadLoop","detail":"{readStateIndex:5152; appliedIndex:5149; }","duration":"583.46573ms","start":"2026-06-05T22:11:53.001943Z","end":"2026-06-05T22:11:53.585409Z","steps":["trace[721273226] 'read index received' (duration: 492.791647ms)","trace[721273226] 'applied index is now lower than readState.Index' (duration: 90.672893ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:53.585571Z","caller":"traceutil/trace.go:171","msg":"trace[1866874563] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"595.906303ms","start":"2026-06-05T22:11:52.989653Z","end":"2026-06-05T22:11:53.58556Z","steps":["trace[1866874563] 'process raft request' (duration: 595.706419ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:53.585699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:52.989631Z","time spent":"595.963354ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:11:53.585449Z","caller":"traceutil/trace.go:171","msg":"trace[662684578] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"596.058106ms","start":"2026-06-05T22:11:52.989361Z","end":"2026-06-05T22:11:53.585419Z","steps":["trace[662684578] 'process raft request' (duration: 595.894163ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:53.585563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:52.988946Z","time spent":"596.515144ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:53.585849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:52.98935Z","time spent":"596.392682ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:53.585861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.698693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-05T22:11:53.585909Z","caller":"traceutil/trace.go:171","msg":"trace[2088427460] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4960; }","duration":"607.819396ms","start":"2026-06-05T22:11:52.978075Z","end":"2026-06-05T22:11:53.585894Z","steps":["trace[2088427460] 'agreement among raft nodes before linearized reading' (duration: 607.624642ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:53.585947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:52.978023Z","time spent":"607.916328ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-05T22:11:53.645349Z","caller":"traceutil/trace.go:171","msg":"trace[1854425675] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"643.342667ms","start":"2026-06-05T22:11:53.001969Z","end":"2026-06-05T22:11:53.645311Z","steps":["trace[1854425675] 'process raft request' (duration: 643.060792ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:53.645539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:53.001958Z","time spent":"643.47334ms","remote":"127.0.0.1:51616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:53.645678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.212768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:53.64577Z","caller":"traceutil/trace.go:171","msg":"trace[1303500664] transaction","detail":"{read_only:false; response_revision:4964; number_of_response:1; }","duration":"637.741883ms","start":"2026-06-05T22:11:53.008003Z","end":"2026-06-05T22:11:53.645745Z","steps":["trace[1303500664] 'process raft request' (duration: 637.326385ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:53.645789Z","caller":"traceutil/trace.go:171","msg":"trace[1688053989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4966; }","duration":"641.366631ms","start":"2026-06-05T22:11:53.004402Z","end":"2026-06-05T22:11:53.645768Z","steps":["trace[1688053989] 'agreement among raft nodes before linearized reading' (duration: 641.144147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:53.645884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:53.004362Z","time spent":"641.510944ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T22:11:53.645885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:53.00799Z","time spent":"637.838054ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:11:53.645938Z","caller":"traceutil/trace.go:171","msg":"trace[39804653] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"638.491607ms","start":"2026-06-05T22:11:53.007433Z","end":"2026-06-05T22:11:53.645925Z","steps":["trace[39804653] 'process raft request' (duration: 637.857926ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:53.646013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:53.007379Z","time spent":"638.597669ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:11:53.646047Z","caller":"traceutil/trace.go:171","msg":"trace[1942322303] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"638.579499ms","start":"2026-06-05T22:11:53.00733Z","end":"2026-06-05T22:11:53.645909Z","steps":["trace[1942322303] 'process raft request' (duration: 637.900216ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:53.64626Z","caller":"traceutil/trace.go:171","msg":"trace[466514730] transaction","detail":"{read_only:false; response_revision:4966; number_of_response:1; }","duration":"634.618176ms","start":"2026-06-05T22:11:53.011625Z","end":"2026-06-05T22:11:53.646243Z","steps":["trace[466514730] 'process raft request' (duration: 633.854361ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:53.646377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:53.011615Z","time spent":"634.698677ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11827,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:11:53.646281Z","caller":"traceutil/trace.go:171","msg":"trace[309216383] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"637.697092ms","start":"2026-06-05T22:11:53.008572Z","end":"2026-06-05T22:11:53.646269Z","steps":["trace[309216383] 'process raft request' (duration: 636.799715ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:53.646389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:53.007312Z","time spent":"638.887514ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:11:53.646386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.125519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-06-05T22:11:53.646484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:53.008561Z","time spent":"637.893216ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:11:53.646515Z","caller":"traceutil/trace.go:171","msg":"trace[1315591856] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4966; }","duration":"639.269723ms","start":"2026-06-05T22:11:53.007234Z","end":"2026-06-05T22:11:53.646504Z","steps":["trace[1315591856] 'agreement among raft nodes before linearized reading' (duration: 639.028528ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:53.64658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:53.007227Z","time spent":"639.326994ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-05T22:11:54.540991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.757727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-8fb65c47f-wcjrb.18b64ef3fc58d23e\" ","response":"range_response_count:1 size:895"} {"level":"info","ts":"2026-06-05T22:11:54.541085Z","caller":"traceutil/trace.go:171","msg":"trace[756342672] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-8fb65c47f-wcjrb.18b64ef3fc58d23e; range_end:; response_count:1; response_revision:4973; }","duration":"540.91679ms","start":"2026-06-05T22:11:54.000136Z","end":"2026-06-05T22:11:54.541053Z","steps":["trace[756342672] 'range keys from in-memory index tree' (duration: 540.503613ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:54.541129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:54.000103Z","time spent":"541.016582ms","remote":"127.0.0.1:51616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":918,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-8fb65c47f-wcjrb.18b64ef3fc58d23e\" "} {"level":"warn","ts":"2026-06-05T22:11:54.541111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.021324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-05T22:11:54.541191Z","caller":"traceutil/trace.go:171","msg":"trace[1165208121] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:4973; }","duration":"167.182237ms","start":"2026-06-05T22:11:54.37399Z","end":"2026-06-05T22:11:54.541173Z","steps":["trace[1165208121] 'range keys from in-memory index tree' (duration: 166.716098ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:54.5414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.90596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f5e11a49-aac5-4784-a3b8-be3a57b455fe\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-06-05T22:11:54.541514Z","caller":"traceutil/trace.go:171","msg":"trace[294462192] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f5e11a49-aac5-4784-a3b8-be3a57b455fe; range_end:; response_count:1; response_revision:4973; }","duration":"533.078884ms","start":"2026-06-05T22:11:54.008409Z","end":"2026-06-05T22:11:54.541488Z","steps":["trace[294462192] 'range keys from in-memory index tree' (duration: 532.647276ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:54.541579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:54.008391Z","time spent":"533.169375ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3788,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f5e11a49-aac5-4784-a3b8-be3a57b455fe\" "} {"level":"info","ts":"2026-06-05T22:11:54.944027Z","caller":"traceutil/trace.go:171","msg":"trace[23713264] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"114.84236ms","start":"2026-06-05T22:11:54.829151Z","end":"2026-06-05T22:11:54.943994Z","steps":["trace[23713264] 'process raft request' (duration: 114.586866ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:55.162816Z","caller":"traceutil/trace.go:171","msg":"trace[273831880] transaction","detail":"{read_only:false; response_revision:4977; number_of_response:1; }","duration":"153.480385ms","start":"2026-06-05T22:11:55.00928Z","end":"2026-06-05T22:11:55.16276Z","steps":["trace[273831880] 'process raft request' (duration: 153.234951ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:55.796708Z","caller":"traceutil/trace.go:171","msg":"trace[332129299] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"114.104033ms","start":"2026-06-05T22:11:55.682584Z","end":"2026-06-05T22:11:55.796688Z","steps":["trace[332129299] 'process raft request' (duration: 113.970301ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:55.796963Z","caller":"traceutil/trace.go:171","msg":"trace[714404077] transaction","detail":"{read_only:false; response_revision:4983; number_of_response:1; }","duration":"115.526719ms","start":"2026-06-05T22:11:55.681422Z","end":"2026-06-05T22:11:55.796949Z","steps":["trace[714404077] 'process raft request' (duration: 83.85085ms)","trace[714404077] 'compare' (duration: 30.571769ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:55.797317Z","caller":"traceutil/trace.go:171","msg":"trace[1982382486] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"111.796019ms","start":"2026-06-05T22:11:55.685504Z","end":"2026-06-05T22:11:55.7973Z","steps":["trace[1982382486] 'process raft request' (duration: 111.519824ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:55.797396Z","caller":"traceutil/trace.go:171","msg":"trace[360677303] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"114.058221ms","start":"2026-06-05T22:11:55.683328Z","end":"2026-06-05T22:11:55.797386Z","steps":["trace[360677303] 'process raft request' (duration: 113.305437ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:56.513468Z","caller":"traceutil/trace.go:171","msg":"trace[2072786504] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"220.978634ms","start":"2026-06-05T22:11:56.292449Z","end":"2026-06-05T22:11:56.513428Z","steps":["trace[2072786504] 'process raft request' (duration: 182.055231ms)","trace[2072786504] 'compare' (duration: 38.570606ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:11:57.173001Z","caller":"traceutil/trace.go:171","msg":"trace[1943008736] linearizableReadLoop","detail":"{readStateIndex:5186; appliedIndex:5185; }","duration":"135.841958ms","start":"2026-06-05T22:11:57.037129Z","end":"2026-06-05T22:11:57.172971Z","steps":["trace[1943008736] 'read index received' (duration: 135.585633ms)","trace[1943008736] 'applied index is now lower than readState.Index' (duration: 254.515µs)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:11:57.173237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.092933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:11:57.173314Z","caller":"traceutil/trace.go:171","msg":"trace[1003391883] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:4993; }","duration":"136.240516ms","start":"2026-06-05T22:11:57.037054Z","end":"2026-06-05T22:11:57.173295Z","steps":["trace[1003391883] 'agreement among raft nodes before linearized reading' (duration: 136.089913ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:57.17328Z","caller":"traceutil/trace.go:171","msg":"trace[1770813607] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"139.413284ms","start":"2026-06-05T22:11:57.033771Z","end":"2026-06-05T22:11:57.173184Z","steps":["trace[1770813607] 'process raft request' (duration: 139.012046ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:57.177715Z","caller":"traceutil/trace.go:171","msg":"trace[1374773579] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"132.206961ms","start":"2026-06-05T22:11:57.045492Z","end":"2026-06-05T22:11:57.177699Z","steps":["trace[1374773579] 'process raft request' (duration: 131.576949ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:57.811887Z","caller":"traceutil/trace.go:171","msg":"trace[818168387] transaction","detail":"{read_only:false; response_revision:5005; number_of_response:1; }","duration":"128.506512ms","start":"2026-06-05T22:11:57.683356Z","end":"2026-06-05T22:11:57.811863Z","steps":["trace[818168387] 'process raft request' (duration: 127.88717ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:57.813156Z","caller":"traceutil/trace.go:171","msg":"trace[2050836716] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"129.372588ms","start":"2026-06-05T22:11:57.683755Z","end":"2026-06-05T22:11:57.813127Z","steps":["trace[2050836716] 'process raft request' (duration: 128.551404ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:11:59.660991Z","caller":"traceutil/trace.go:171","msg":"trace[998604688] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"474.726023ms","start":"2026-06-05T22:11:59.18624Z","end":"2026-06-05T22:11:59.660966Z","steps":["trace[998604688] 'process raft request' (duration: 474.328395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:11:59.661964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:11:59.186201Z","time spent":"474.879775ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:12:04.69229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.240584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-05T22:12:04.693647Z","caller":"traceutil/trace.go:171","msg":"trace[1330720596] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:5121; }","duration":"318.388987ms","start":"2026-06-05T22:12:04.373975Z","end":"2026-06-05T22:12:04.692364Z","steps":["trace[1330720596] 'range keys from in-memory index tree' (duration: 317.412298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:12:04.693694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:04.373955Z","time spent":"319.728801ms","remote":"127.0.0.1:51578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"warn","ts":"2026-06-05T22:12:09.747564Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605074216997770,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T22:12:10.248325Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605074216997770,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T22:12:10.748919Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967605074216997770,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-05T22:12:10.911377Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.74587072s","expected-duration":"1s"} {"level":"info","ts":"2026-06-05T22:12:10.911921Z","caller":"traceutil/trace.go:171","msg":"trace[437845224] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"1.74647683s","start":"2026-06-05T22:12:09.16542Z","end":"2026-06-05T22:12:10.911897Z","steps":["trace[437845224] 'process raft request' (duration: 1.746297997s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:12:10.912153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:09.165408Z","time spent":"1.746575821s","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17218,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:12:11.139089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.735859ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-05T22:12:11.139915Z","caller":"traceutil/trace.go:171","msg":"trace[2048158436] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"1.443780344s","start":"2026-06-05T22:12:09.69611Z","end":"2026-06-05T22:12:11.13989Z","steps":["trace[2048158436] 'process raft request' (duration: 1.443077902s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:12:11.140133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:09.69609Z","time spent":"1.443895046s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:12:11.195694Z","caller":"traceutil/trace.go:171","msg":"trace[1317014487] linearizableReadLoop","detail":"{readStateIndex:5364; appliedIndex:5357; }","duration":"1.948298437s","start":"2026-06-05T22:12:09.247371Z","end":"2026-06-05T22:12:11.19567Z","steps":["trace[1317014487] 'read index received' (duration: 1.664350876s)","trace[1317014487] 'applied index is now lower than readState.Index' (duration: 283.946381ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:12:11.195823Z","caller":"traceutil/trace.go:171","msg":"trace[1529196557] transaction","detail":"{read_only:false; response_revision:5163; number_of_response:1; }","duration":"1.348727763s","start":"2026-06-05T22:12:09.847057Z","end":"2026-06-05T22:12:11.195784Z","steps":["trace[1529196557] 'process raft request' (duration: 1.348493188s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:12:11.195976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:09.847035Z","time spent":"1.348856835s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:12:11.196205Z","caller":"traceutil/trace.go:171","msg":"trace[733714837] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"1.496542691s","start":"2026-06-05T22:12:09.699645Z","end":"2026-06-05T22:12:11.196188Z","steps":["trace[733714837] 'process raft request' (duration: 1.44022762s)","trace[733714837] 'compare' (duration: 55.555777ms)"],"step_count":2} {"level":"warn","ts":"2026-06-05T22:12:11.19622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328422634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-06-05T22:12:11.196272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:09.699636Z","time spent":"1.496603152s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:12:11.196371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263869869s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T22:12:11.196428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314332967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-05T22:12:11.196461Z","caller":"traceutil/trace.go:171","msg":"trace[86057286] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:5165; }","duration":"1.263998701s","start":"2026-06-05T22:12:09.93244Z","end":"2026-06-05T22:12:11.196439Z","steps":["trace[86057286] 'agreement among raft nodes before linearized reading' (duration: 1.263832838s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:12:11.196423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17811893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-05T22:12:11.196469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.207517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T22:12:11.196532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.172006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"warn","ts":"2026-06-05T22:12:11.19657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.226841495s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-05T22:12:11.196531Z","caller":"traceutil/trace.go:171","msg":"trace[2003897392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5165; }","duration":"1.178230292s","start":"2026-06-05T22:12:10.018285Z","end":"2026-06-05T22:12:11.196515Z","steps":["trace[2003897392] 'agreement among raft nodes before linearized reading' (duration: 1.177924606s)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:12:11.196575Z","caller":"traceutil/trace.go:171","msg":"trace[1810654876] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:5165; }","duration":"783.34424ms","start":"2026-06-05T22:12:10.413207Z","end":"2026-06-05T22:12:11.196551Z","steps":["trace[1810654876] 'agreement among raft nodes before linearized reading' (duration: 783.188067ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:12:11.196581Z","caller":"traceutil/trace.go:171","msg":"trace[271801986] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5165; }","duration":"278.282217ms","start":"2026-06-05T22:12:10.918283Z","end":"2026-06-05T22:12:11.196566Z","steps":["trace[271801986] 'agreement among raft nodes before linearized reading' (duration: 278.125285ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:12:11.196544Z","caller":"traceutil/trace.go:171","msg":"trace[2071257953] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5165; }","duration":"1.314449409s","start":"2026-06-05T22:12:09.882085Z","end":"2026-06-05T22:12:11.196534Z","steps":["trace[2071257953] 'agreement among raft nodes before linearized reading' (duration: 1.314265016s)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:12:11.196605Z","caller":"traceutil/trace.go:171","msg":"trace[838570924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5165; }","duration":"1.226890567s","start":"2026-06-05T22:12:09.969704Z","end":"2026-06-05T22:12:11.196595Z","steps":["trace[838570924] 'agreement among raft nodes before linearized reading' (duration: 1.226843396s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:12:11.196625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:10.413197Z","time spent":"783.416501ms","remote":"127.0.0.1:33138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-06-05T22:12:11.196639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:09.969684Z","time spent":"1.226951028s","remote":"127.0.0.1:51554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-05T22:12:11.196632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:10.018224Z","time spent":"1.178394974s","remote":"127.0.0.1:51810","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-06-05T22:12:11.196637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:09.882046Z","time spent":"1.314579202s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-05T22:12:11.19673Z","caller":"traceutil/trace.go:171","msg":"trace[1067927656] transaction","detail":"{read_only:false; response_revision:5165; number_of_response:1; }","duration":"799.583385ms","start":"2026-06-05T22:12:10.39697Z","end":"2026-06-05T22:12:11.196553Z","steps":["trace[1067927656] 'process raft request' (duration: 798.660209ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:12:11.196847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.97935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-05T22:12:11.196275Z","caller":"traceutil/trace.go:171","msg":"trace[831616782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5165; }","duration":"1.328530795s","start":"2026-06-05T22:12:09.867728Z","end":"2026-06-05T22:12:11.196259Z","steps":["trace[831616782] 'agreement among raft nodes before linearized reading' (duration: 1.328300361s)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:12:11.196893Z","caller":"traceutil/trace.go:171","msg":"trace[419985416] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5165; }","duration":"997.057592ms","start":"2026-06-05T22:12:10.199825Z","end":"2026-06-05T22:12:11.196883Z","steps":["trace[419985416] 'agreement among raft nodes before linearized reading' (duration: 996.982471ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:12:11.19693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:10.199812Z","time spent":"997.109853ms","remote":"127.0.0.1:55186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-06-05T22:12:11.196938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.972025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-05T22:12:11.196965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.248055292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-05T22:12:11.196973Z","caller":"traceutil/trace.go:171","msg":"trace[958182587] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:5165; }","duration":"712.062847ms","start":"2026-06-05T22:12:10.484899Z","end":"2026-06-05T22:12:11.196962Z","steps":["trace[958182587] 'agreement among raft nodes before linearized reading' (duration: 712.008736ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:12:11.197Z","caller":"traceutil/trace.go:171","msg":"trace[565331282] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5165; }","duration":"1.248129074s","start":"2026-06-05T22:12:09.948859Z","end":"2026-06-05T22:12:11.196988Z","steps":["trace[565331282] 'agreement among raft nodes before linearized reading' (duration: 1.247994092s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:12:11.196962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:09.867709Z","time spent":"1.329232579s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-05T22:12:11.197038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:09.948838Z","time spent":"1.248193795s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-06-05T22:12:11.197033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.318916291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-06-05T22:12:11.196518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:09.932431Z","time spent":"1.264075132s","remote":"127.0.0.1:58904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true "} {"level":"info","ts":"2026-06-05T22:12:11.197103Z","caller":"traceutil/trace.go:171","msg":"trace[387585331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5165; }","duration":"1.319005092s","start":"2026-06-05T22:12:09.878086Z","end":"2026-06-05T22:12:11.197091Z","steps":["trace[387585331] 'agreement among raft nodes before linearized reading' (duration: 1.318866169s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:12:11.197135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.485297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"warn","ts":"2026-06-05T22:12:11.197147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:09.878079Z","time spent":"1.319058763s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-05T22:12:11.197174Z","caller":"traceutil/trace.go:171","msg":"trace[1530978648] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5165; }","duration":"808.545288ms","start":"2026-06-05T22:12:10.388619Z","end":"2026-06-05T22:12:11.197165Z","steps":["trace[1530978648] 'agreement among raft nodes before linearized reading' (duration: 808.444346ms)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:12:11.197203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:10.388608Z","time spent":"808.58834ms","remote":"127.0.0.1:33084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3339,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-06-05T22:12:11.1963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.948913407s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-05T22:12:11.197247Z","caller":"traceutil/trace.go:171","msg":"trace[261429184] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5165; }","duration":"1.949868125s","start":"2026-06-05T22:12:09.247368Z","end":"2026-06-05T22:12:11.197236Z","steps":["trace[261429184] 'agreement among raft nodes before linearized reading' (duration: 1.948624472s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:12:11.197288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:09.247345Z","time spent":"1.949934387s","remote":"127.0.0.1:51698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-06-05T22:12:11.197068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:10.396953Z","time spent":"799.83141ms","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-05T22:12:11.197003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:10.484873Z","time spent":"712.122478ms","remote":"127.0.0.1:58962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true "} {"level":"warn","ts":"2026-06-05T22:12:11.197131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.320143263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-05T22:12:11.197487Z","caller":"traceutil/trace.go:171","msg":"trace[735027192] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5165; }","duration":"1.320541251s","start":"2026-06-05T22:12:09.876933Z","end":"2026-06-05T22:12:11.197474Z","steps":["trace[735027192] 'agreement among raft nodes before linearized reading' (duration: 1.320104223s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:12:11.197535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:09.876902Z","time spent":"1.320619521s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-05T22:12:11.196487Z","caller":"traceutil/trace.go:171","msg":"trace[16461498] transaction","detail":"{read_only:false; response_revision:5164; number_of_response:1; }","duration":"1.347757735s","start":"2026-06-05T22:12:09.848716Z","end":"2026-06-05T22:12:11.196474Z","steps":["trace[16461498] 'process raft request' (duration: 1.346885489s)"],"step_count":1} {"level":"warn","ts":"2026-06-05T22:12:11.197698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-05T22:12:09.848696Z","time spent":"1.348945077s","remote":"127.0.0.1:51810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-05T22:12:11.435622Z","caller":"traceutil/trace.go:171","msg":"trace[947263102] transaction","detail":"{read_only:false; response_revision:5174; number_of_response:1; }","duration":"173.448895ms","start":"2026-06-05T22:12:11.262149Z","end":"2026-06-05T22:12:11.435598Z","steps":["trace[947263102] 'process raft request' (duration: 84.587824ms)","trace[947263102] 'compare' (duration: 88.711469ms)"],"step_count":2} {"level":"info","ts":"2026-06-05T22:12:11.436167Z","caller":"traceutil/trace.go:171","msg":"trace[1716534970] transaction","detail":"{read_only:false; response_revision:5175; number_of_response:1; }","duration":"173.953904ms","start":"2026-06-05T22:12:11.262176Z","end":"2026-06-05T22:12:11.43613Z","steps":["trace[1716534970] 'process raft request' (duration: 173.361963ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:12:11.436349Z","caller":"traceutil/trace.go:171","msg":"trace[193810467] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"168.25534ms","start":"2026-06-05T22:12:11.268072Z","end":"2026-06-05T22:12:11.436327Z","steps":["trace[193810467] 'process raft request' (duration: 168.189089ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:12:11.436382Z","caller":"traceutil/trace.go:171","msg":"trace[892544836] transaction","detail":"{read_only:false; response_revision:5176; number_of_response:1; }","duration":"174.114877ms","start":"2026-06-05T22:12:11.26225Z","end":"2026-06-05T22:12:11.436365Z","steps":["trace[892544836] 'process raft request' (duration: 173.907003ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:12:41.453617Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2881} {"level":"info","ts":"2026-06-05T22:12:41.530631Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2881,"took":"76.010362ms","hash":2527062893,"current-db-size-bytes":52740096,"current-db-size":"53 MB","current-db-size-in-use-bytes":28266496,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-05T22:12:41.53072Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2527062893,"revision":2881,"compact-revision":-1} {"level":"info","ts":"2026-06-05T22:12:52.766823Z","caller":"traceutil/trace.go:171","msg":"trace[967803874] transaction","detail":"{read_only:false; response_revision:5511; number_of_response:1; }","duration":"135.931354ms","start":"2026-06-05T22:12:52.630864Z","end":"2026-06-05T22:12:52.766796Z","steps":["trace[967803874] 'process raft request' (duration: 135.747111ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:15:56.273857Z","caller":"traceutil/trace.go:171","msg":"trace[294298599] transaction","detail":"{read_only:false; response_revision:6782; number_of_response:1; }","duration":"102.209976ms","start":"2026-06-05T22:15:56.17163Z","end":"2026-06-05T22:15:56.27384Z","steps":["trace[294298599] 'process raft request' (duration: 102.161375ms)"],"step_count":1} {"level":"info","ts":"2026-06-05T22:17:41.465786Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5439} {"level":"info","ts":"2026-06-05T22:17:41.546637Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5439,"took":"79.267148ms","hash":2920321282,"current-db-size-bytes":52740096,"current-db-size":"53 MB","current-db-size-in-use-bytes":18452480,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-06-05T22:17:41.546717Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2920321282,"revision":5439,"compact-revision":2881}