{"level":"warn","ts":"2026-06-09T22:23:24.571631Z","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-09T22:23:24.571748Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-09T22:23:24.571832Z","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-09T22:23:24.57185Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-06-09T22:23:24.571878Z","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-09T22:23:24.572764Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-06-09T22:23:24.572873Z","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":16,"max-cpu-available":16,"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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-09T22:23:24.57695Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.770869ms"} {"level":"info","ts":"2026-06-09T22:23:24.590177Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-06-09T22:23:24.590282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-06-09T22:23:24.590313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-06-09T22:23:24.590341Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-09T22:23:24.590362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-06-09T22:23:24.590415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-06-09T22:23:24.598009Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-09T22:23:24.599443Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-09T22:23:24.600564Z","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-09T22:23:24.602708Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-09T22:23:24.602841Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-09T22:23:24.602905Z","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-09T22:23:24.602985Z","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-09T22:23:24.602996Z","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-09T22:23:24.60377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-06-09T22:23:24.605241Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-06-09T22:23:24.608245Z","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-09T22:23:24.608371Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-06-09T22:23:24.608649Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-06-09T22:23:24.608744Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-09T22:23:24.608791Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-09T22:23:24.990652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-06-09T22:23:24.990747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-09T22:23:24.990772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-06-09T22:23:24.990788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-06-09T22:23:24.990794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-06-09T22:23:24.990803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-06-09T22:23:24.990814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-06-09T22:23:25.000619Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T22:23:25.00292Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T22:23:25.00293Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-06-09T22:23:25.003521Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T22:23:25.003703Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T22:23:25.00375Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T22:23:25.003772Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T22:23:25.0045Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-09T22:23:25.004611Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-09T22:23:25.00625Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-09T22:23:25.007479Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"warn","ts":"2026-06-09T22:23:46.890204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.66636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:23:46.890342Z","caller":"traceutil/trace.go:171","msg":"trace[792168832] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"152.03432ms","start":"2026-06-09T22:23:46.73829Z","end":"2026-06-09T22:23:46.890325Z","steps":["trace[792168832] 'process raft request' (duration: 42.699459ms)","trace[792168832] 'compare' (duration: 108.495916ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:07.174604Z","caller":"traceutil/trace.go:171","msg":"trace[743700476] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"142.514248ms","start":"2026-06-09T22:24:07.032069Z","end":"2026-06-09T22:24:07.174583Z","steps":["trace[743700476] 'process raft request' (duration: 142.410185ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:07.720124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.441789ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:24:07.720382Z","caller":"traceutil/trace.go:171","msg":"trace[1272757513] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:588; }","duration":"213.691226ms","start":"2026-06-09T22:24:07.506635Z","end":"2026-06-09T22:24:07.720327Z","steps":["trace[1272757513] 'range keys from in-memory index tree' (duration: 213.421719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:07.720904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.553264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:24:07.721091Z","caller":"traceutil/trace.go:171","msg":"trace[357570566] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"299.22109ms","start":"2026-06-09T22:24:07.421838Z","end":"2026-06-09T22:24:07.721059Z","steps":["trace[357570566] 'process raft request' (duration: 150.019387ms)","trace[357570566] 'compare' (duration: 148.367589ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:07.764039Z","caller":"traceutil/trace.go:171","msg":"trace[467825735] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"185.042644ms","start":"2026-06-09T22:24:07.578942Z","end":"2026-06-09T22:24:07.763985Z","steps":["trace[467825735] 'process raft request' (duration: 179.277077ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:07.771027Z","caller":"traceutil/trace.go:171","msg":"trace[622823444] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"168.39919ms","start":"2026-06-09T22:24:07.602592Z","end":"2026-06-09T22:24:07.770991Z","steps":["trace[622823444] 'process raft request' (duration: 168.133383ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:08.064217Z","caller":"traceutil/trace.go:171","msg":"trace[456872167] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"280.395633ms","start":"2026-06-09T22:24:07.783787Z","end":"2026-06-09T22:24:08.064183Z","steps":["trace[456872167] 'process raft request' (duration: 279.693063ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:08.256296Z","caller":"traceutil/trace.go:171","msg":"trace[909017674] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:615; }","duration":"237.510909ms","start":"2026-06-09T22:24:08.018754Z","end":"2026-06-09T22:24:08.256265Z","steps":["trace[909017674] 'read index received' (duration: 44.74881ms)","trace[909017674] 'applied index is now lower than readState.Index' (duration: 192.761059ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:08.256454Z","caller":"traceutil/trace.go:171","msg":"trace[109970536] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"430.141454ms","start":"2026-06-09T22:24:07.826265Z","end":"2026-06-09T22:24:08.256406Z","steps":["trace[109970536] 'process raft request' (duration: 403.330085ms)","trace[109970536] 'compare' (duration: 26.5197ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:24:08.256577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.86543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:24:08.256627Z","caller":"traceutil/trace.go:171","msg":"trace[893828774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"227.973412ms","start":"2026-06-09T22:24:08.028642Z","end":"2026-06-09T22:24:08.256615Z","steps":["trace[893828774] 'agreement among raft nodes before linearized reading' (duration: 227.849829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:08.256567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:24:07.826225Z","time spent":"430.283398ms","remote":"127.0.0.1:53724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T22:24:08.256627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.844998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-09T22:24:08.256716Z","caller":"traceutil/trace.go:171","msg":"trace[764969101] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:593; }","duration":"238.005283ms","start":"2026-06-09T22:24:08.018686Z","end":"2026-06-09T22:24:08.256691Z","steps":["trace[764969101] 'agreement among raft nodes before linearized reading' (duration: 237.777007ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:23.143027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.166156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:24:23.143123Z","caller":"traceutil/trace.go:171","msg":"trace[2064515916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"114.282889ms","start":"2026-06-09T22:24:23.028821Z","end":"2026-06-09T22:24:23.143104Z","steps":["trace[2064515916] 'range keys from in-memory index tree' (duration: 114.088074ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:25:45.38636Z","caller":"traceutil/trace.go:171","msg":"trace[1530511592] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"190.983734ms","start":"2026-06-09T22:25:45.195346Z","end":"2026-06-09T22:25:45.386329Z","steps":["trace[1530511592] 'process raft request' (duration: 190.600343ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:25:45.401984Z","caller":"traceutil/trace.go:171","msg":"trace[1169917408] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"102.325467ms","start":"2026-06-09T22:25:45.299614Z","end":"2026-06-09T22:25:45.401939Z","steps":["trace[1169917408] 'process raft request' (duration: 102.168233ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:29.7888Z","caller":"traceutil/trace.go:171","msg":"trace[267470685] transaction","detail":"{read_only:false; response_revision:2132; number_of_response:1; }","duration":"120.620033ms","start":"2026-06-09T22:26:29.66814Z","end":"2026-06-09T22:26:29.78876Z","steps":["trace[267470685] 'process raft request' (duration: 88.340586ms)","trace[267470685] 'compare' (duration: 32.112023ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:26:30.200839Z","caller":"traceutil/trace.go:171","msg":"trace[236085408] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"144.142909ms","start":"2026-06-09T22:26:30.056663Z","end":"2026-06-09T22:26:30.200806Z","steps":["trace[236085408] 'process raft request' (duration: 143.802319ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:30.644244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.147197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-09T22:26:30.64424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.182671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T22:26:30.644356Z","caller":"traceutil/trace.go:171","msg":"trace[1868124030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2136; }","duration":"239.292621ms","start":"2026-06-09T22:26:30.405021Z","end":"2026-06-09T22:26:30.644314Z","steps":["trace[1868124030] 'range keys from in-memory index tree' (duration: 238.872778ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:30.644383Z","caller":"traceutil/trace.go:171","msg":"trace[1501722523] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2136; }","duration":"159.361666ms","start":"2026-06-09T22:26:30.484971Z","end":"2026-06-09T22:26:30.644333Z","steps":["trace[1501722523] 'count revisions from in-memory index tree' (duration: 159.011947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:30.644246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.593604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:26:30.644454Z","caller":"traceutil/trace.go:171","msg":"trace[1233821189] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2136; }","duration":"228.849101ms","start":"2026-06-09T22:26:30.415584Z","end":"2026-06-09T22:26:30.644434Z","steps":["trace[1233821189] 'count revisions from in-memory index tree' (duration: 228.506201ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:27:59.339766Z","caller":"traceutil/trace.go:171","msg":"trace[6482282] transaction","detail":"{read_only:false; response_revision:2811; number_of_response:1; }","duration":"102.03612ms","start":"2026-06-09T22:27:59.237692Z","end":"2026-06-09T22:27:59.339728Z","steps":["trace[6482282] 'process raft request' (duration: 101.817414ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:32:06.651058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.529703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:32:06.65119Z","caller":"traceutil/trace.go:171","msg":"trace[573817607] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"333.210534ms","start":"2026-06-09T22:32:06.317957Z","end":"2026-06-09T22:32:06.651168Z","steps":["trace[573817607] 'process raft request' (duration: 169.477815ms)","trace[573817607] 'compare' (duration: 163.3994ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:32:06.651255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:32:06.317937Z","time spent":"333.289457ms","remote":"127.0.0.1:53408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4934,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:32:06.667882Z","caller":"traceutil/trace.go:171","msg":"trace[1821948193] linearizableReadLoop","detail":"{readStateIndex:5238; appliedIndex:5235; }","duration":"329.070355ms","start":"2026-06-09T22:32:06.338772Z","end":"2026-06-09T22:32:06.667842Z","steps":["trace[1821948193] 'read index received' (duration: 148.816043ms)","trace[1821948193] 'applied index is now lower than readState.Index' (duration: 180.252642ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:32:06.667915Z","caller":"traceutil/trace.go:171","msg":"trace[90123589] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"346.873655ms","start":"2026-06-09T22:32:06.321011Z","end":"2026-06-09T22:32:06.667884Z","steps":["trace[90123589] 'process raft request' (duration: 346.758862ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:32:06.668107Z","caller":"traceutil/trace.go:171","msg":"trace[527640919] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"349.639294ms","start":"2026-06-09T22:32:06.318441Z","end":"2026-06-09T22:32:06.66808Z","steps":["trace[527640919] 'process raft request' (duration: 349.169141ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:32:06.668163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.363944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-pq56k-tls\" ","response":"range_response_count:1 size:2184"} {"level":"info","ts":"2026-06-09T22:32:06.668217Z","caller":"traceutil/trace.go:171","msg":"trace[1311972204] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-pq56k-tls; range_end:; response_count:1; response_revision:5049; }","duration":"329.493117ms","start":"2026-06-09T22:32:06.338709Z","end":"2026-06-09T22:32:06.668202Z","steps":["trace[1311972204] 'agreement among raft nodes before linearized reading' (duration: 329.284252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:32:06.668221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:32:06.318433Z","time spent":"349.739288ms","remote":"127.0.0.1:53318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T22:32:06.668265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:32:06.338685Z","time spent":"329.56952ms","remote":"127.0.0.1:49920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":2208,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-pq56k-tls\" "} {"level":"warn","ts":"2026-06-09T22:32:06.668375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:32:06.321003Z","time spent":"347.125462ms","remote":"127.0.0.1:53694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5006,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:32:06.680932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.184972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T22:32:06.680973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.20892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-36c1d050-c92a-4d7b-9fac-406115a0c2f5\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-06-09T22:32:06.680998Z","caller":"traceutil/trace.go:171","msg":"trace[368733109] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:5050; }","duration":"271.281345ms","start":"2026-06-09T22:32:06.4097Z","end":"2026-06-09T22:32:06.680981Z","steps":["trace[368733109] 'agreement among raft nodes before linearized reading' (duration: 271.138561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:32:06.681002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.770338ms","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-09T22:32:06.681023Z","caller":"traceutil/trace.go:171","msg":"trace[488037697] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-36c1d050-c92a-4d7b-9fac-406115a0c2f5; range_end:; response_count:1; response_revision:5050; }","duration":"259.326563ms","start":"2026-06-09T22:32:06.421681Z","end":"2026-06-09T22:32:06.681008Z","steps":["trace[488037697] 'agreement among raft nodes before linearized reading' (duration: 259.141988ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:32:06.68106Z","caller":"traceutil/trace.go:171","msg":"trace[1322659795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5050; }","duration":"286.86054ms","start":"2026-06-09T22:32:06.394187Z","end":"2026-06-09T22:32:06.681047Z","steps":["trace[1322659795] 'agreement among raft nodes before linearized reading' (duration: 286.707126ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:32:06.681264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.714598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786872"} {"level":"info","ts":"2026-06-09T22:32:06.681303Z","caller":"traceutil/trace.go:171","msg":"trace[1956783845] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5050; }","duration":"313.81084ms","start":"2026-06-09T22:32:06.367482Z","end":"2026-06-09T22:32:06.681293Z","steps":["trace[1956783845] 'agreement among raft nodes before linearized reading' (duration: 313.44958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:32:06.681327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:32:06.367439Z","time spent":"313.882182ms","remote":"127.0.0.1:53346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":25,"response size":786896,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-06-09T22:32:19.2385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.895358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-tbr9j\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-06-09T22:32:19.238589Z","caller":"traceutil/trace.go:171","msg":"trace[1508064626] range","detail":"{range_begin:/registry/pods/openstack/keepalived-tbr9j; range_end:; response_count:1; response_revision:5289; }","duration":"133.018761ms","start":"2026-06-09T22:32:19.105548Z","end":"2026-06-09T22:32:19.238567Z","steps":["trace[1508064626] 'range keys from in-memory index tree' (duration: 132.760994ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:32:27.090473Z","caller":"traceutil/trace.go:171","msg":"trace[421859304] linearizableReadLoop","detail":"{readStateIndex:5658; appliedIndex:5657; }","duration":"255.915325ms","start":"2026-06-09T22:32:26.834534Z","end":"2026-06-09T22:32:27.090449Z","steps":["trace[421859304] 'read index received' (duration: 255.801531ms)","trace[421859304] 'applied index is now lower than readState.Index' (duration: 112.484µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:32:27.090539Z","caller":"traceutil/trace.go:171","msg":"trace[940269655] transaction","detail":"{read_only:false; response_revision:5457; number_of_response:1; }","duration":"269.842913ms","start":"2026-06-09T22:32:26.820666Z","end":"2026-06-09T22:32:27.090509Z","steps":["trace[940269655] 'process raft request' (duration: 269.643538ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:32:27.090732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.180822ms","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-09T22:32:27.091004Z","caller":"traceutil/trace.go:171","msg":"trace[1223566663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5457; }","duration":"256.395748ms","start":"2026-06-09T22:32:26.834504Z","end":"2026-06-09T22:32:27.0909Z","steps":["trace[1223566663] 'agreement among raft nodes before linearized reading' (duration: 256.068329ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:33:25.034939Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2953} {"level":"info","ts":"2026-06-09T22:33:25.107926Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2953,"took":"70.19571ms","hash":3219890731,"current-db-size-bytes":58343424,"current-db-size":"58 MB","current-db-size-in-use-bytes":33718272,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-09T22:33:25.107984Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3219890731,"revision":2953,"compact-revision":-1} {"level":"info","ts":"2026-06-09T22:38:25.041212Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6021} {"level":"info","ts":"2026-06-09T22:38:25.139889Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6021,"took":"96.014973ms","hash":1092671699,"current-db-size-bytes":58343424,"current-db-size":"58 MB","current-db-size-in-use-bytes":21880832,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-09T22:38:25.139947Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1092671699,"revision":6021,"compact-revision":2953} {"level":"warn","ts":"2026-06-09T22:38:31.0889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.276779ms","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-09T22:38:31.088963Z","caller":"traceutil/trace.go:171","msg":"trace[124454658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8283; }","duration":"250.386633ms","start":"2026-06-09T22:38:30.838561Z","end":"2026-06-09T22:38:31.088948Z","steps":["trace[124454658] 'range keys from in-memory index tree' (duration: 250.042713ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:38:31.089019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.691618ms","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-09T22:38:31.089104Z","caller":"traceutil/trace.go:171","msg":"trace[521974735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8283; }","duration":"120.828902ms","start":"2026-06-09T22:38:30.968258Z","end":"2026-06-09T22:38:31.089087Z","steps":["trace[521974735] 'range keys from in-memory index tree' (duration: 120.521223ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:38:42.715998Z","caller":"traceutil/trace.go:171","msg":"trace[1110025530] linearizableReadLoop","detail":"{readStateIndex:8636; appliedIndex:8635; }","duration":"236.95397ms","start":"2026-06-09T22:38:42.479016Z","end":"2026-06-09T22:38:42.71597Z","steps":["trace[1110025530] 'read index received' (duration: 236.884868ms)","trace[1110025530] 'applied index is now lower than readState.Index' (duration: 65.582µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:38:42.71611Z","caller":"traceutil/trace.go:171","msg":"trace[1225164654] transaction","detail":"{read_only:false; response_revision:8344; number_of_response:1; }","duration":"241.595122ms","start":"2026-06-09T22:38:42.474483Z","end":"2026-06-09T22:38:42.716079Z","steps":["trace[1225164654] 'process raft request' (duration: 241.302844ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:38:42.716242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.211457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-6b8ffc7678-728wm.18b78ab87a0a2f24\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-06-09T22:38:42.716292Z","caller":"traceutil/trace.go:171","msg":"trace[1116887366] range","detail":"{range_begin:/registry/events/openstack/barbican-api-6b8ffc7678-728wm.18b78ab87a0a2f24; range_end:; response_count:1; response_revision:8344; }","duration":"237.307319ms","start":"2026-06-09T22:38:42.478969Z","end":"2026-06-09T22:38:42.716276Z","steps":["trace[1116887366] 'agreement among raft nodes before linearized reading' (duration: 237.140505ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:38:42.81514Z","caller":"traceutil/trace.go:171","msg":"trace[564616659] transaction","detail":"{read_only:false; response_revision:8345; number_of_response:1; }","duration":"178.077247ms","start":"2026-06-09T22:38:42.63703Z","end":"2026-06-09T22:38:42.815107Z","steps":["trace[564616659] 'process raft request' (duration: 134.956285ms)","trace[564616659] 'compare' (duration: 42.802793ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:38:42.815252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.400154ms","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":"info","ts":"2026-06-09T22:38:42.815317Z","caller":"traceutil/trace.go:171","msg":"trace[1243599675] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:8345; }","duration":"117.512187ms","start":"2026-06-09T22:38:42.697787Z","end":"2026-06-09T22:38:42.815299Z","steps":["trace[1243599675] 'agreement among raft nodes before linearized reading' (duration: 117.294871ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:38:43.71048Z","caller":"traceutil/trace.go:171","msg":"trace[1966618177] linearizableReadLoop","detail":"{readStateIndex:8643; appliedIndex:8642; }","duration":"138.787205ms","start":"2026-06-09T22:38:43.571663Z","end":"2026-06-09T22:38:43.71045Z","steps":["trace[1966618177] 'read index received' (duration: 138.565389ms)","trace[1966618177] 'applied index is now lower than readState.Index' (duration: 220.626µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:38:43.710643Z","caller":"traceutil/trace.go:171","msg":"trace[37451687] transaction","detail":"{read_only:false; response_revision:8349; number_of_response:1; }","duration":"210.840983ms","start":"2026-06-09T22:38:43.49975Z","end":"2026-06-09T22:38:43.710591Z","steps":["trace[37451687] 'process raft request' (duration: 210.499834ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:38:43.710778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.090963ms","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":"info","ts":"2026-06-09T22:38:43.710858Z","caller":"traceutil/trace.go:171","msg":"trace[1977564724] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8349; }","duration":"139.188557ms","start":"2026-06-09T22:38:43.571645Z","end":"2026-06-09T22:38:43.710834Z","steps":["trace[1977564724] 'agreement among raft nodes before linearized reading' (duration: 138.93103ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:38:44.192759Z","caller":"traceutil/trace.go:171","msg":"trace[138197338] linearizableReadLoop","detail":"{readStateIndex:8646; appliedIndex:8645; }","duration":"125.116695ms","start":"2026-06-09T22:38:44.067617Z","end":"2026-06-09T22:38:44.192734Z","steps":["trace[138197338] 'read index received' (duration: 124.906539ms)","trace[138197338] 'applied index is now lower than readState.Index' (duration: 209.216µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:38:44.192821Z","caller":"traceutil/trace.go:171","msg":"trace[358139975] transaction","detail":"{read_only:false; response_revision:8352; number_of_response:1; }","duration":"142.641455ms","start":"2026-06-09T22:38:44.050145Z","end":"2026-06-09T22:38:44.192786Z","steps":["trace[358139975] 'process raft request' (duration: 142.407618ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:38:44.193407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.764042ms","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-09T22:38:44.193493Z","caller":"traceutil/trace.go:171","msg":"trace[1414452822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8352; }","duration":"125.869845ms","start":"2026-06-09T22:38:44.067604Z","end":"2026-06-09T22:38:44.193474Z","steps":["trace[1414452822] 'agreement among raft nodes before linearized reading' (duration: 125.279389ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:38:44.322088Z","caller":"traceutil/trace.go:171","msg":"trace[844067406] transaction","detail":"{read_only:false; response_revision:8354; number_of_response:1; }","duration":"124.994511ms","start":"2026-06-09T22:38:44.197072Z","end":"2026-06-09T22:38:44.322067Z","steps":["trace[844067406] 'process raft request' (duration: 90.017002ms)","trace[844067406] 'compare' (duration: 34.883346ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:38:44.822839Z","caller":"traceutil/trace.go:171","msg":"trace[1166461200] transaction","detail":"{read_only:false; response_revision:8355; number_of_response:1; }","duration":"100.745128ms","start":"2026-06-09T22:38:44.722068Z","end":"2026-06-09T22:38:44.822814Z","steps":["trace[1166461200] 'process raft request' (duration: 100.586664ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:39:11.042212Z","caller":"traceutil/trace.go:171","msg":"trace[1407778863] transaction","detail":"{read_only:false; response_revision:8550; number_of_response:1; }","duration":"131.022509ms","start":"2026-06-09T22:39:10.911174Z","end":"2026-06-09T22:39:11.042196Z","steps":["trace[1407778863] 'process raft request' (duration: 130.928896ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:39:11.259404Z","caller":"traceutil/trace.go:171","msg":"trace[1238750843] linearizableReadLoop","detail":"{readStateIndex:8852; appliedIndex:8851; }","duration":"230.896476ms","start":"2026-06-09T22:39:11.028477Z","end":"2026-06-09T22:39:11.259373Z","steps":["trace[1238750843] 'read index received' (duration: 13.799429ms)","trace[1238750843] 'applied index is now lower than readState.Index' (duration: 217.095857ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:39:11.25962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.118392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:39:11.259832Z","caller":"traceutil/trace.go:171","msg":"trace[1238182055] transaction","detail":"{read_only:false; response_revision:8551; number_of_response:1; }","duration":"270.780301ms","start":"2026-06-09T22:39:10.989026Z","end":"2026-06-09T22:39:11.259806Z","steps":["trace[1238182055] 'process raft request' (duration: 266.300931ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:39:11.259929Z","caller":"traceutil/trace.go:171","msg":"trace[193737164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8551; }","duration":"231.416349ms","start":"2026-06-09T22:39:11.028449Z","end":"2026-06-09T22:39:11.259865Z","steps":["trace[193737164] 'agreement among raft nodes before linearized reading' (duration: 231.08612ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:39:24.113399Z","caller":"traceutil/trace.go:171","msg":"trace[700221643] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"132.092026ms","start":"2026-06-09T22:39:23.981278Z","end":"2026-06-09T22:39:24.11337Z","steps":["trace[700221643] 'process raft request' (duration: 101.286254ms)","trace[700221643] 'compare' (duration: 30.630378ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:39:24.785191Z","caller":"traceutil/trace.go:171","msg":"trace[2073357089] transaction","detail":"{read_only:false; response_revision:8666; number_of_response:1; }","duration":"110.559312ms","start":"2026-06-09T22:39:24.674615Z","end":"2026-06-09T22:39:24.785174Z","steps":["trace[2073357089] 'process raft request' (duration: 110.451069ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:39:24.951907Z","caller":"traceutil/trace.go:171","msg":"trace[996807418] transaction","detail":"{read_only:false; response_revision:8667; number_of_response:1; }","duration":"161.688776ms","start":"2026-06-09T22:39:24.79018Z","end":"2026-06-09T22:39:24.951868Z","steps":["trace[996807418] 'process raft request' (duration: 116.299585ms)","trace[996807418] 'compare' (duration: 45.088653ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:39:25.550383Z","caller":"traceutil/trace.go:171","msg":"trace[1939387633] transaction","detail":"{read_only:false; response_revision:8673; number_of_response:1; }","duration":"105.069435ms","start":"2026-06-09T22:39:25.445269Z","end":"2026-06-09T22:39:25.550339Z","steps":["trace[1939387633] 'process raft request' (duration: 46.112551ms)","trace[1939387633] 'compare' (duration: 58.688027ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:39:40.707366Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023e0.wal"} {"level":"info","ts":"2026-06-09T22:41:14.523676Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f3d52791b64f7591","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T22:41:14.530072Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-09T22:41:14.530167Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-09T22:43:25.047769Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8121} {"level":"info","ts":"2026-06-09T22:43:25.109132Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8121,"took":"59.558074ms","hash":4167885324,"current-db-size-bytes":58343424,"current-db-size":"58 MB","current-db-size-in-use-bytes":29753344,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-09T22:43:25.109245Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4167885324,"revision":8121,"compact-revision":6021} {"level":"warn","ts":"2026-06-09T22:44:07.245204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.014249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-09T22:44:07.245291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.678427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-09T22:44:07.245398Z","caller":"traceutil/trace.go:171","msg":"trace[529008519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11167; }","duration":"145.839912ms","start":"2026-06-09T22:44:07.099536Z","end":"2026-06-09T22:44:07.245376Z","steps":["trace[529008519] 'range keys from in-memory index tree' (duration: 145.469381ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:44:07.245321Z","caller":"traceutil/trace.go:171","msg":"trace[1069717820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11167; }","duration":"157.168423ms","start":"2026-06-09T22:44:07.088121Z","end":"2026-06-09T22:44:07.24529Z","steps":["trace[1069717820] 'range keys from in-memory index tree' (duration: 156.803014ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:44:25.731305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.592676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T22:44:25.731405Z","caller":"traceutil/trace.go:171","msg":"trace[210923117] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:11271; }","duration":"185.740282ms","start":"2026-06-09T22:44:25.545645Z","end":"2026-06-09T22:44:25.731385Z","steps":["trace[210923117] 'count revisions from in-memory index tree' (duration: 185.514184ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:48:25.055427Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10725} {"level":"info","ts":"2026-06-09T22:48:25.135373Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10725,"took":"77.62209ms","hash":3874120728,"current-db-size-bytes":58343424,"current-db-size":"58 MB","current-db-size-in-use-bytes":27852800,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-09T22:48:25.135433Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3874120728,"revision":10725,"compact-revision":8121} {"level":"info","ts":"2026-06-09T22:50:29.394004Z","caller":"traceutil/trace.go:171","msg":"trace[1469369259] linearizableReadLoop","detail":"{readStateIndex:14213; appliedIndex:14212; }","duration":"115.481124ms","start":"2026-06-09T22:50:29.278504Z","end":"2026-06-09T22:50:29.393985Z","steps":["trace[1469369259] 'read index received' (duration: 115.303099ms)","trace[1469369259] 'applied index is now lower than readState.Index' (duration: 177.215µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:50:29.394114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.592537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:50:29.39414Z","caller":"traceutil/trace.go:171","msg":"trace[751449278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13745; }","duration":"115.671709ms","start":"2026-06-09T22:50:29.278461Z","end":"2026-06-09T22:50:29.394132Z","steps":["trace[751449278] 'agreement among raft nodes before linearized reading' (duration: 115.609407ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:50:29.394097Z","caller":"traceutil/trace.go:171","msg":"trace[2054517413] transaction","detail":"{read_only:false; response_revision:13745; number_of_response:1; }","duration":"132.886701ms","start":"2026-06-09T22:50:29.261151Z","end":"2026-06-09T22:50:29.394038Z","steps":["trace[2054517413] 'process raft request' (duration: 132.715586ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:50:54.719478Z","caller":"traceutil/trace.go:171","msg":"trace[254223958] linearizableReadLoop","detail":"{readStateIndex:14482; appliedIndex:14481; }","duration":"116.439201ms","start":"2026-06-09T22:50:54.603006Z","end":"2026-06-09T22:50:54.719445Z","steps":["trace[254223958] 'read index received' (duration: 116.290507ms)","trace[254223958] 'applied index is now lower than readState.Index' (duration: 147.114µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:50:54.719762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.73354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-northd-fc978744-xmswr\" ","response":"range_response_count:1 size:7032"} {"level":"info","ts":"2026-06-09T22:50:54.719808Z","caller":"traceutil/trace.go:171","msg":"trace[513496349] range","detail":"{range_begin:/registry/pods/openstack/ovn-northd-fc978744-xmswr; range_end:; response_count:1; response_revision:14007; }","duration":"116.804182ms","start":"2026-06-09T22:50:54.60299Z","end":"2026-06-09T22:50:54.719795Z","steps":["trace[513496349] 'agreement among raft nodes before linearized reading' (duration: 116.573115ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:50:54.720084Z","caller":"traceutil/trace.go:171","msg":"trace[188972515] transaction","detail":"{read_only:false; response_revision:14007; number_of_response:1; }","duration":"220.271301ms","start":"2026-06-09T22:50:54.499214Z","end":"2026-06-09T22:50:54.719485Z","steps":["trace[188972515] 'process raft request' (duration: 220.035604ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:51:59.958078Z","caller":"traceutil/trace.go:171","msg":"trace[1642698578] transaction","detail":"{read_only:false; response_revision:14915; number_of_response:1; }","duration":"126.23276ms","start":"2026-06-09T22:51:59.831825Z","end":"2026-06-09T22:51:59.958058Z","steps":["trace[1642698578] 'process raft request' (duration: 126.104057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:52:17.994861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.015934ms","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-09T22:52:17.99497Z","caller":"traceutil/trace.go:171","msg":"trace[971920640] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15008; }","duration":"103.194969ms","start":"2026-06-09T22:52:17.89176Z","end":"2026-06-09T22:52:17.994955Z","steps":["trace[971920640] 'range keys from in-memory index tree' (duration: 102.847889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:52:24.633184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.265231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:52:24.633337Z","caller":"traceutil/trace.go:171","msg":"trace[1689765951] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15042; }","duration":"150.470336ms","start":"2026-06-09T22:52:24.482811Z","end":"2026-06-09T22:52:24.633281Z","steps":["trace[1689765951] 'range keys from in-memory index tree' (duration: 149.960313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:52:24.633493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.2491ms","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":"warn","ts":"2026-06-09T22:52:24.63356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.55748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-06-09T22:52:24.633585Z","caller":"traceutil/trace.go:171","msg":"trace[1452403511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15042; }","duration":"328.383884ms","start":"2026-06-09T22:52:24.30518Z","end":"2026-06-09T22:52:24.633564Z","steps":["trace[1452403511] 'range keys from in-memory index tree' (duration: 328.045035ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:52:24.633619Z","caller":"traceutil/trace.go:171","msg":"trace[1678443497] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15042; }","duration":"150.661592ms","start":"2026-06-09T22:52:24.482941Z","end":"2026-06-09T22:52:24.633602Z","steps":["trace[1678443497] 'range keys from in-memory index tree' (duration: 150.336683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:52:24.63364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:52:24.305163Z","time spent":"328.462827ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T22:52:24.633684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.039843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:52:24.633727Z","caller":"traceutil/trace.go:171","msg":"trace[254644413] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15042; }","duration":"150.081324ms","start":"2026-06-09T22:52:24.483629Z","end":"2026-06-09T22:52:24.63371Z","steps":["trace[254644413] 'range keys from in-memory index tree' (duration: 149.970591ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:52:24.633817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.520768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-09T22:52:24.633629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.961231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:52:24.63384Z","caller":"traceutil/trace.go:171","msg":"trace[1271246940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15042; }","duration":"328.5711ms","start":"2026-06-09T22:52:24.305263Z","end":"2026-06-09T22:52:24.633834Z","steps":["trace[1271246940] 'range keys from in-memory index tree' (duration: 328.444066ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:52:24.633856Z","caller":"traceutil/trace.go:171","msg":"trace[543404732] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15042; }","duration":"150.226079ms","start":"2026-06-09T22:52:24.483617Z","end":"2026-06-09T22:52:24.633844Z","steps":["trace[543404732] 'range keys from in-memory index tree' (duration: 149.91316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:52:24.63387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:52:24.305249Z","time spent":"328.614571ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-09T22:52:24.843582Z","caller":"traceutil/trace.go:171","msg":"trace[1529725665] linearizableReadLoop","detail":"{readStateIndex:15549; appliedIndex:15547; }","duration":"202.756457ms","start":"2026-06-09T22:52:24.640792Z","end":"2026-06-09T22:52:24.843549Z","steps":["trace[1529725665] 'read index received' (duration: 8.77735ms)","trace[1529725665] 'applied index is now lower than readState.Index' (duration: 193.977857ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:52:24.843885Z","caller":"traceutil/trace.go:171","msg":"trace[1327358346] transaction","detail":"{read_only:false; response_revision:15044; number_of_response:1; }","duration":"204.182987ms","start":"2026-06-09T22:52:24.639623Z","end":"2026-06-09T22:52:24.843806Z","steps":["trace[1327358346] 'process raft request' (duration: 176.017364ms)","trace[1327358346] 'compare' (duration: 27.782592ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:52:24.843986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.177048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-06-09T22:52:24.84405Z","caller":"traceutil/trace.go:171","msg":"trace[560359773] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15044; }","duration":"203.26083ms","start":"2026-06-09T22:52:24.640775Z","end":"2026-06-09T22:52:24.844036Z","steps":["trace[560359773] 'agreement among raft nodes before linearized reading' (duration: 203.039034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:52:24.844085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.954156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-06-09T22:52:24.84418Z","caller":"traceutil/trace.go:171","msg":"trace[1088377659] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15044; }","duration":"174.063709ms","start":"2026-06-09T22:52:24.670094Z","end":"2026-06-09T22:52:24.844157Z","steps":["trace[1088377659] 'agreement among raft nodes before linearized reading' (duration: 173.793931ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:53:25.060775Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12819} {"level":"info","ts":"2026-06-09T22:53:25.142438Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12819,"took":"78.963229ms","hash":113385442,"current-db-size-bytes":58343424,"current-db-size":"58 MB","current-db-size-in-use-bytes":31195136,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-09T22:53:25.142501Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":113385442,"revision":12819,"compact-revision":10725} {"level":"warn","ts":"2026-06-09T22:58:11.455254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.259144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:942212"} {"level":"info","ts":"2026-06-09T22:58:11.455328Z","caller":"traceutil/trace.go:171","msg":"trace[1078562713] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17647; }","duration":"157.395537ms","start":"2026-06-09T22:58:11.297919Z","end":"2026-06-09T22:58:11.455314Z","steps":["trace[1078562713] 'range keys from in-memory index tree' (duration: 156.44391ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:58:11.480583Z","caller":"traceutil/trace.go:171","msg":"trace[1838306695] transaction","detail":"{read_only:false; response_revision:17648; number_of_response:1; }","duration":"146.18578ms","start":"2026-06-09T22:58:11.334369Z","end":"2026-06-09T22:58:11.480555Z","steps":["trace[1838306695] 'process raft request' (duration: 146.034176ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:58:18.82043Z","caller":"traceutil/trace.go:171","msg":"trace[1753114694] transaction","detail":"{read_only:false; response_revision:17705; number_of_response:1; }","duration":"148.98286ms","start":"2026-06-09T22:58:18.671414Z","end":"2026-06-09T22:58:18.820396Z","steps":["trace[1753114694] 'process raft request' (duration: 133.556044ms)","trace[1753114694] 'compare' (duration: 15.154438ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:58:19.297164Z","caller":"traceutil/trace.go:171","msg":"trace[1072113353] transaction","detail":"{read_only:false; response_revision:17707; number_of_response:1; }","duration":"152.18014ms","start":"2026-06-09T22:58:19.144953Z","end":"2026-06-09T22:58:19.297134Z","steps":["trace[1072113353] 'process raft request' (duration: 151.959073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:58:19.773244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.272481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T22:58:19.773346Z","caller":"traceutil/trace.go:171","msg":"trace[896703564] linearizableReadLoop","detail":"{readStateIndex:18301; appliedIndex:18300; }","duration":"332.788314ms","start":"2026-06-09T22:58:19.440543Z","end":"2026-06-09T22:58:19.773332Z","steps":["trace[896703564] 'read index received' (duration: 116.211974ms)","trace[896703564] 'applied index is now lower than readState.Index' (duration: 216.57508ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:58:19.773432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.894678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T22:58:19.77346Z","caller":"traceutil/trace.go:171","msg":"trace[1135636647] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:17708; }","duration":"332.959689ms","start":"2026-06-09T22:58:19.440494Z","end":"2026-06-09T22:58:19.773453Z","steps":["trace[1135636647] 'agreement among raft nodes before linearized reading' (duration: 332.896298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:58:19.773486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:58:19.440472Z","time spent":"333.00753ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":30,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-06-09T22:58:19.773472Z","caller":"traceutil/trace.go:171","msg":"trace[1891908735] transaction","detail":"{read_only:false; response_revision:17708; number_of_response:1; }","duration":"524.098119ms","start":"2026-06-09T22:58:19.249311Z","end":"2026-06-09T22:58:19.773409Z","steps":["trace[1891908735] 'process raft request' (duration: 307.568721ms)","trace[1891908735] 'compare' (duration: 215.903861ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:58:19.773651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:58:19.249274Z","time spent":"524.297505ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:58:19.816541Z","caller":"traceutil/trace.go:171","msg":"trace[642145876] transaction","detail":"{read_only:false; response_revision:17709; number_of_response:1; }","duration":"304.151734ms","start":"2026-06-09T22:58:19.51237Z","end":"2026-06-09T22:58:19.816521Z","steps":["trace[642145876] 'process raft request' (duration: 304.049321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:58:19.816665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:58:19.512325Z","time spent":"304.273817ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:58:19.829992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.242889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:3226"} {"level":"info","ts":"2026-06-09T22:58:19.829989Z","caller":"traceutil/trace.go:171","msg":"trace[1306268577] transaction","detail":"{read_only:false; response_revision:17710; number_of_response:1; }","duration":"271.574904ms","start":"2026-06-09T22:58:19.558376Z","end":"2026-06-09T22:58:19.829951Z","steps":["trace[1306268577] 'process raft request' (duration: 271.309356ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:58:19.830069Z","caller":"traceutil/trace.go:171","msg":"trace[1268564348] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:17710; }","duration":"119.378013ms","start":"2026-06-09T22:58:19.710672Z","end":"2026-06-09T22:58:19.83005Z","steps":["trace[1268564348] 'agreement among raft nodes before linearized reading' (duration: 119.174067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:58:19.830062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.548694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T22:58:19.830194Z","caller":"traceutil/trace.go:171","msg":"trace[3305128] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:17710; }","duration":"110.747089ms","start":"2026-06-09T22:58:19.719417Z","end":"2026-06-09T22:58:19.830164Z","steps":["trace[3305128] 'agreement among raft nodes before linearized reading' (duration: 110.42953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:58:19.83026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.031057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-09T22:58:19.830303Z","caller":"traceutil/trace.go:171","msg":"trace[1723768646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17710; }","duration":"140.095659ms","start":"2026-06-09T22:58:19.690187Z","end":"2026-06-09T22:58:19.830282Z","steps":["trace[1723768646] 'agreement among raft nodes before linearized reading' (duration: 139.968656ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:58:19.830346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.729309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-6k9mf\" ","response":"range_response_count:1 size:9800"} {"level":"info","ts":"2026-06-09T22:58:19.830385Z","caller":"traceutil/trace.go:171","msg":"trace[834665564] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-6k9mf; range_end:; response_count:1; response_revision:17710; }","duration":"276.820922ms","start":"2026-06-09T22:58:19.553552Z","end":"2026-06-09T22:58:19.830373Z","steps":["trace[834665564] 'agreement among raft nodes before linearized reading' (duration: 276.647987ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:58:19.830105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.084366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T22:58:19.830483Z","caller":"traceutil/trace.go:171","msg":"trace[1927193988] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:17710; }","duration":"325.520948ms","start":"2026-06-09T22:58:19.50495Z","end":"2026-06-09T22:58:19.830471Z","steps":["trace[1927193988] 'agreement among raft nodes before linearized reading' (duration: 325.025765ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:58:19.830519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:58:19.504933Z","time spent":"325.57283ms","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true "} {"level":"info","ts":"2026-06-09T22:58:25.067159Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15418} {"level":"info","ts":"2026-06-09T22:58:25.162038Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15418,"took":"91.808474ms","hash":2152656652,"current-db-size-bytes":58343424,"current-db-size":"58 MB","current-db-size-in-use-bytes":32477184,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-09T22:58:25.162121Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2152656652,"revision":15418,"compact-revision":12819} {"level":"info","ts":"2026-06-09T23:01:29.910342Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f3d52791b64f7591","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T23:01:29.916273Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-09T23:01:29.916382Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-09T23:03:25.073828Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17754} {"level":"info","ts":"2026-06-09T23:03:25.17168Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17754,"took":"94.991672ms","hash":1258604577,"current-db-size-bytes":58343424,"current-db-size":"58 MB","current-db-size-in-use-bytes":34443264,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-09T23:03:25.171739Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1258604577,"revision":17754,"compact-revision":15418} {"level":"info","ts":"2026-06-09T23:03:53.725214Z","caller":"traceutil/trace.go:171","msg":"trace[1858943888] transaction","detail":"{read_only:false; response_revision:20296; number_of_response:1; }","duration":"208.567098ms","start":"2026-06-09T23:03:53.516622Z","end":"2026-06-09T23:03:53.725189Z","steps":["trace[1858943888] 'process raft request' (duration: 208.396443ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:03:53.910394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.219167ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-06-09T23:03:53.910538Z","caller":"traceutil/trace.go:171","msg":"trace[2038688983] linearizableReadLoop","detail":"{readStateIndex:20975; appliedIndex:20972; }","duration":"340.941113ms","start":"2026-06-09T23:03:53.569582Z","end":"2026-06-09T23:03:53.910524Z","steps":["trace[2038688983] 'read index received' (duration: 5.758208ms)","trace[2038688983] 'applied index is now lower than readState.Index' (duration: 335.181325ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:03:53.91083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.249772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-09T23:03:53.910892Z","caller":"traceutil/trace.go:171","msg":"trace[1278427204] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20296; }","duration":"341.359096ms","start":"2026-06-09T23:03:53.569523Z","end":"2026-06-09T23:03:53.910882Z","steps":["trace[1278427204] 'agreement among raft nodes before linearized reading' (duration: 341.212242ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:03:53.910938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:03:53.569491Z","time spent":"341.438898ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T23:03:53.910961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.162489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-09T23:03:53.911038Z","caller":"traceutil/trace.go:171","msg":"trace[333970990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20296; }","duration":"318.281612ms","start":"2026-06-09T23:03:53.592738Z","end":"2026-06-09T23:03:53.91102Z","steps":["trace[333970990] 'agreement among raft nodes before linearized reading' (duration: 318.066016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:03:53.911083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:03:53.59272Z","time spent":"318.354835ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T23:03:53.91116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.159016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-06-09T23:03:53.911263Z","caller":"traceutil/trace.go:171","msg":"trace[1553272526] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:20296; }","duration":"236.32372ms","start":"2026-06-09T23:03:53.674915Z","end":"2026-06-09T23:03:53.911239Z","steps":["trace[1553272526] 'agreement among raft nodes before linearized reading' (duration: 235.98372ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:03:54.052057Z","caller":"traceutil/trace.go:171","msg":"trace[563052508] transaction","detail":"{read_only:false; response_revision:20297; number_of_response:1; }","duration":"137.161694ms","start":"2026-06-09T23:03:53.91487Z","end":"2026-06-09T23:03:54.052031Z","steps":["trace[563052508] 'process raft request' (duration: 137.02681ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:03:54.069727Z","caller":"traceutil/trace.go:171","msg":"trace[2042594795] transaction","detail":"{read_only:false; response_revision:20298; number_of_response:1; }","duration":"153.425408ms","start":"2026-06-09T23:03:53.916251Z","end":"2026-06-09T23:03:54.069676Z","steps":["trace[2042594795] 'process raft request' (duration: 153.192101ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:03:54.217494Z","caller":"traceutil/trace.go:171","msg":"trace[760576999] transaction","detail":"{read_only:false; response_revision:20299; number_of_response:1; }","duration":"143.284143ms","start":"2026-06-09T23:03:54.074177Z","end":"2026-06-09T23:03:54.217461Z","steps":["trace[760576999] 'process raft request' (duration: 143.102497ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:03:54.588424Z","caller":"traceutil/trace.go:171","msg":"trace[575414320] transaction","detail":"{read_only:false; response_revision:20300; number_of_response:1; }","duration":"119.696393ms","start":"2026-06-09T23:03:54.468703Z","end":"2026-06-09T23:03:54.5884Z","steps":["trace[575414320] 'process raft request' (duration: 119.546309ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:03:54.906344Z","caller":"traceutil/trace.go:171","msg":"trace[812105615] transaction","detail":"{read_only:false; response_revision:20301; number_of_response:1; }","duration":"119.244071ms","start":"2026-06-09T23:03:54.787066Z","end":"2026-06-09T23:03:54.90631Z","steps":["trace[812105615] 'process raft request' (duration: 119.034605ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:07:40.032426Z","caller":"traceutil/trace.go:171","msg":"trace[31541821] transaction","detail":"{read_only:false; response_revision:22709; number_of_response:1; }","duration":"185.775556ms","start":"2026-06-09T23:07:39.846625Z","end":"2026-06-09T23:07:40.032401Z","steps":["trace[31541821] 'process raft request' (duration: 185.438696ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:08:25.080008Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20150} {"level":"info","ts":"2026-06-09T23:08:25.191768Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20150,"took":"108.705858ms","hash":2401292482,"current-db-size-bytes":58343424,"current-db-size":"58 MB","current-db-size-in-use-bytes":40939520,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-06-09T23:08:25.19185Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2401292482,"revision":20150,"compact-revision":17754} {"level":"info","ts":"2026-06-09T23:11:15.11457Z","caller":"traceutil/trace.go:171","msg":"trace[517466087] linearizableReadLoop","detail":"{readStateIndex:25690; appliedIndex:25689; }","duration":"235.715209ms","start":"2026-06-09T23:11:14.878829Z","end":"2026-06-09T23:11:15.114544Z","steps":["trace[517466087] 'read index received' (duration: 220.289271ms)","trace[517466087] 'applied index is now lower than readState.Index' (duration: 15.424658ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:15.114701Z","caller":"traceutil/trace.go:171","msg":"trace[2110451475] transaction","detail":"{read_only:false; response_revision:24883; number_of_response:1; }","duration":"319.233941ms","start":"2026-06-09T23:11:14.795453Z","end":"2026-06-09T23:11:15.114687Z","steps":["trace[2110451475] 'process raft request' (duration: 303.6516ms)","trace[2110451475] 'compare' (duration: 14.803301ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:15.114776Z","caller":"traceutil/trace.go:171","msg":"trace[1754183292] transaction","detail":"{read_only:false; response_revision:24884; number_of_response:1; }","duration":"161.079435ms","start":"2026-06-09T23:11:14.953669Z","end":"2026-06-09T23:11:15.114748Z","steps":["trace[1754183292] 'process raft request' (duration: 160.805348ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:15.115145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:14.795424Z","time spent":"319.304114ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T23:11:15.11517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.309814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T23:11:15.115224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.956956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-09T23:11:15.115287Z","caller":"traceutil/trace.go:171","msg":"trace[1030759726] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:24884; }","duration":"236.493429ms","start":"2026-06-09T23:11:14.878767Z","end":"2026-06-09T23:11:15.115261Z","steps":["trace[1030759726] 'agreement among raft nodes before linearized reading' (duration: 236.187211ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:15.115314Z","caller":"traceutil/trace.go:171","msg":"trace[2089360777] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24884; }","duration":"125.11099ms","start":"2026-06-09T23:11:14.990177Z","end":"2026-06-09T23:11:15.115288Z","steps":["trace[2089360777] 'agreement among raft nodes before linearized reading' (duration: 124.789762ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:15.348392Z","caller":"traceutil/trace.go:171","msg":"trace[2100432072] linearizableReadLoop","detail":"{readStateIndex:25693; appliedIndex:25692; }","duration":"181.55464ms","start":"2026-06-09T23:11:15.166815Z","end":"2026-06-09T23:11:15.348369Z","steps":["trace[2100432072] 'read index received' (duration: 108.228062ms)","trace[2100432072] 'applied index is now lower than readState.Index' (duration: 73.325198ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:15.348478Z","caller":"traceutil/trace.go:171","msg":"trace[574067793] transaction","detail":"{read_only:false; response_revision:24886; number_of_response:1; }","duration":"191.527771ms","start":"2026-06-09T23:11:15.156939Z","end":"2026-06-09T23:11:15.348467Z","steps":["trace[574067793] 'process raft request' (duration: 118.040619ms)","trace[574067793] 'compare' (duration: 73.01047ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:11:15.348761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.821898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-09T23:11:15.348828Z","caller":"traceutil/trace.go:171","msg":"trace[15495384] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24886; }","duration":"182.052634ms","start":"2026-06-09T23:11:15.166758Z","end":"2026-06-09T23:11:15.348811Z","steps":["trace[15495384] 'agreement among raft nodes before linearized reading' (duration: 181.717695ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:19.131074Z","caller":"traceutil/trace.go:171","msg":"trace[109294901] linearizableReadLoop","detail":"{readStateIndex:25718; appliedIndex:25717; }","duration":"201.710347ms","start":"2026-06-09T23:11:18.929326Z","end":"2026-06-09T23:11:19.131036Z","steps":["trace[109294901] 'read index received' (duration: 201.482111ms)","trace[109294901] 'applied index is now lower than readState.Index' (duration: 225.206µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:19.131102Z","caller":"traceutil/trace.go:171","msg":"trace[1623994079] transaction","detail":"{read_only:false; response_revision:24910; number_of_response:1; }","duration":"268.736463ms","start":"2026-06-09T23:11:18.862335Z","end":"2026-06-09T23:11:19.131071Z","steps":["trace[1623994079] 'process raft request' (duration: 268.486606ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:19.131369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.005304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:19.13146Z","caller":"traceutil/trace.go:171","msg":"trace[363930356] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:24910; }","duration":"202.171879ms","start":"2026-06-09T23:11:18.929267Z","end":"2026-06-09T23:11:19.131439Z","steps":["trace[363930356] 'agreement among raft nodes before linearized reading' (duration: 201.926702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:19.135002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.962405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:19.135054Z","caller":"traceutil/trace.go:171","msg":"trace[467142705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24913; }","duration":"108.075408ms","start":"2026-06-09T23:11:19.026967Z","end":"2026-06-09T23:11:19.135042Z","steps":["trace[467142705] 'agreement among raft nodes before linearized reading' (duration: 107.948815ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:19.402956Z","caller":"traceutil/trace.go:171","msg":"trace[1725615416] linearizableReadLoop","detail":"{readStateIndex:25722; appliedIndex:25721; }","duration":"196.098925ms","start":"2026-06-09T23:11:19.206835Z","end":"2026-06-09T23:11:19.402934Z","steps":["trace[1725615416] 'read index received' (duration: 120.313901ms)","trace[1725615416] 'applied index is now lower than readState.Index' (duration: 75.784024ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:19.403059Z","caller":"traceutil/trace.go:171","msg":"trace[714273634] transaction","detail":"{read_only:false; response_revision:24914; number_of_response:1; }","duration":"231.090562ms","start":"2026-06-09T23:11:19.171929Z","end":"2026-06-09T23:11:19.403019Z","steps":["trace[714273634] 'process raft request' (duration: 155.219576ms)","trace[714273634] 'compare' (duration: 75.680611ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:11:19.403279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.422993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T23:11:19.403988Z","caller":"traceutil/trace.go:171","msg":"trace[255731837] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:24914; }","duration":"197.157333ms","start":"2026-06-09T23:11:19.206791Z","end":"2026-06-09T23:11:19.403948Z","steps":["trace[255731837] 'agreement among raft nodes before linearized reading' (duration: 196.344891ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:19.619985Z","caller":"traceutil/trace.go:171","msg":"trace[1419427148] transaction","detail":"{read_only:false; response_revision:24915; number_of_response:1; }","duration":"255.986357ms","start":"2026-06-09T23:11:19.36395Z","end":"2026-06-09T23:11:19.619937Z","steps":["trace[1419427148] 'process raft request' (duration: 190.048341ms)","trace[1419427148] 'compare' (duration: 65.577357ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:19.620056Z","caller":"traceutil/trace.go:171","msg":"trace[169618504] transaction","detail":"{read_only:false; response_revision:24916; number_of_response:1; }","duration":"183.368719ms","start":"2026-06-09T23:11:19.436667Z","end":"2026-06-09T23:11:19.620036Z","steps":["trace[169618504] 'process raft request' (duration: 183.251636ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:19.62012Z","caller":"traceutil/trace.go:171","msg":"trace[1300955213] linearizableReadLoop","detail":"{readStateIndex:25723; appliedIndex:25722; }","duration":"216.410396ms","start":"2026-06-09T23:11:19.403666Z","end":"2026-06-09T23:11:19.620076Z","steps":["trace[1300955213] 'read index received' (duration: 150.681324ms)","trace[1300955213] 'applied index is now lower than readState.Index' (duration: 65.725282ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:11:19.620332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.485778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-09T23:11:19.620438Z","caller":"traceutil/trace.go:171","msg":"trace[1304988220] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24916; }","duration":"226.634992ms","start":"2026-06-09T23:11:19.393779Z","end":"2026-06-09T23:11:19.620414Z","steps":["trace[1304988220] 'agreement among raft nodes before linearized reading' (duration: 226.418226ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:19.8749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.711555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T23:11:19.875155Z","caller":"traceutil/trace.go:171","msg":"trace[243859093] transaction","detail":"{read_only:false; response_revision:24917; number_of_response:1; }","duration":"250.247762ms","start":"2026-06-09T23:11:19.624875Z","end":"2026-06-09T23:11:19.875122Z","steps":["trace[243859093] 'process raft request' (duration: 119.813217ms)","trace[243859093] 'compare' (duration: 129.450598ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:19.875183Z","caller":"traceutil/trace.go:171","msg":"trace[2080370259] linearizableReadLoop","detail":"{readStateIndex:25727; appliedIndex:25724; }","duration":"114.01291ms","start":"2026-06-09T23:11:19.761148Z","end":"2026-06-09T23:11:19.875161Z","steps":["trace[2080370259] 'read index received' (duration: 110.574356ms)","trace[2080370259] 'applied index is now lower than readState.Index' (duration: 3.437244ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:19.875329Z","caller":"traceutil/trace.go:171","msg":"trace[1506722271] transaction","detail":"{read_only:false; response_revision:24918; number_of_response:1; }","duration":"213.220419ms","start":"2026-06-09T23:11:19.662079Z","end":"2026-06-09T23:11:19.875299Z","steps":["trace[1506722271] 'process raft request' (duration: 212.931921ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:19.875346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.182874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:19.875407Z","caller":"traceutil/trace.go:171","msg":"trace[1635108041] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:24919; }","duration":"114.296437ms","start":"2026-06-09T23:11:19.761097Z","end":"2026-06-09T23:11:19.875393Z","steps":["trace[1635108041] 'agreement among raft nodes before linearized reading' (duration: 114.191154ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:19.875324Z","caller":"traceutil/trace.go:171","msg":"trace[1118499145] transaction","detail":"{read_only:false; response_revision:24919; number_of_response:1; }","duration":"213.134016ms","start":"2026-06-09T23:11:19.662156Z","end":"2026-06-09T23:11:19.87529Z","steps":["trace[1118499145] 'process raft request' (duration: 212.948772ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:20.177389Z","caller":"traceutil/trace.go:171","msg":"trace[497861322] transaction","detail":"{read_only:false; response_revision:24920; number_of_response:1; }","duration":"297.883012ms","start":"2026-06-09T23:11:19.879486Z","end":"2026-06-09T23:11:20.177369Z","steps":["trace[497861322] 'process raft request' (duration: 290.982866ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:20.187384Z","caller":"traceutil/trace.go:171","msg":"trace[1636495382] linearizableReadLoop","detail":"{readStateIndex:25729; appliedIndex:25727; }","duration":"157.680193ms","start":"2026-06-09T23:11:20.029678Z","end":"2026-06-09T23:11:20.187358Z","steps":["trace[1636495382] 'read index received' (duration: 140.801316ms)","trace[1636495382] 'applied index is now lower than readState.Index' (duration: 16.877687ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:20.18754Z","caller":"traceutil/trace.go:171","msg":"trace[308871179] transaction","detail":"{read_only:false; response_revision:24921; number_of_response:1; }","duration":"197.865413ms","start":"2026-06-09T23:11:19.989654Z","end":"2026-06-09T23:11:20.187519Z","steps":["trace[308871179] 'process raft request' (duration: 197.537444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:20.187827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.122405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:20.187916Z","caller":"traceutil/trace.go:171","msg":"trace[1714889026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24921; }","duration":"158.257999ms","start":"2026-06-09T23:11:20.029638Z","end":"2026-06-09T23:11:20.187896Z","steps":["trace[1714889026] 'agreement among raft nodes before linearized reading' (duration: 158.078584ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:20.522913Z","caller":"traceutil/trace.go:171","msg":"trace[1085203982] transaction","detail":"{read_only:false; response_revision:24923; number_of_response:1; }","duration":"163.649006ms","start":"2026-06-09T23:11:20.359232Z","end":"2026-06-09T23:11:20.522881Z","steps":["trace[1085203982] 'process raft request' (duration: 129.459249ms)","trace[1085203982] 'compare' (duration: 34.023082ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:20.741466Z","caller":"traceutil/trace.go:171","msg":"trace[1096225810] transaction","detail":"{read_only:false; response_revision:24925; number_of_response:1; }","duration":"184.074929ms","start":"2026-06-09T23:11:20.557367Z","end":"2026-06-09T23:11:20.741442Z","steps":["trace[1096225810] 'process raft request' (duration: 184.016178ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:20.741517Z","caller":"traceutil/trace.go:171","msg":"trace[1993916237] transaction","detail":"{read_only:false; response_revision:24924; number_of_response:1; }","duration":"214.536005ms","start":"2026-06-09T23:11:20.526945Z","end":"2026-06-09T23:11:20.741481Z","steps":["trace[1993916237] 'process raft request' (duration: 142.02849ms)","trace[1993916237] 'compare' (duration: 72.229058ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:20.741602Z","caller":"traceutil/trace.go:171","msg":"trace[922894445] linearizableReadLoop","detail":"{readStateIndex:25732; appliedIndex:25731; }","duration":"213.908528ms","start":"2026-06-09T23:11:20.527575Z","end":"2026-06-09T23:11:20.741483Z","steps":["trace[922894445] 'read index received' (duration: 141.411393ms)","trace[922894445] 'applied index is now lower than readState.Index' (duration: 72.492165ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:11:20.742114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.509674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-09T23:11:20.742262Z","caller":"traceutil/trace.go:171","msg":"trace[128057044] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24925; }","duration":"214.648358ms","start":"2026-06-09T23:11:20.527568Z","end":"2026-06-09T23:11:20.742216Z","steps":["trace[128057044] 'agreement among raft nodes before linearized reading' (duration: 214.052852ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:21.395295Z","caller":"traceutil/trace.go:171","msg":"trace[1996073535] transaction","detail":"{read_only:false; response_revision:24926; number_of_response:1; }","duration":"258.65827ms","start":"2026-06-09T23:11:21.136605Z","end":"2026-06-09T23:11:21.395263Z","steps":["trace[1996073535] 'process raft request' (duration: 258.455525ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:21.423807Z","caller":"traceutil/trace.go:171","msg":"trace[132094667] linearizableReadLoop","detail":"{readStateIndex:25736; appliedIndex:25734; }","duration":"215.675025ms","start":"2026-06-09T23:11:21.208121Z","end":"2026-06-09T23:11:21.423797Z","steps":["trace[132094667] 'read index received' (duration: 187.860781ms)","trace[132094667] 'applied index is now lower than readState.Index' (duration: 27.813584ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:21.423871Z","caller":"traceutil/trace.go:171","msg":"trace[1848739271] transaction","detail":"{read_only:false; response_revision:24928; number_of_response:1; }","duration":"282.630659ms","start":"2026-06-09T23:11:21.141233Z","end":"2026-06-09T23:11:21.423864Z","steps":["trace[1848739271] 'process raft request' (duration: 282.536827ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:21.423857Z","caller":"traceutil/trace.go:171","msg":"trace[1071851485] transaction","detail":"{read_only:false; response_revision:24927; number_of_response:1; }","duration":"282.687021ms","start":"2026-06-09T23:11:21.14115Z","end":"2026-06-09T23:11:21.423837Z","steps":["trace[1071851485] 'process raft request' (duration: 282.550328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:21.424044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.911261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:21.424121Z","caller":"traceutil/trace.go:171","msg":"trace[835332869] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:24928; }","duration":"216.025015ms","start":"2026-06-09T23:11:21.208076Z","end":"2026-06-09T23:11:21.424101Z","steps":["trace[835332869] 'agreement among raft nodes before linearized reading' (duration: 215.902651ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:21.573356Z","caller":"traceutil/trace.go:171","msg":"trace[1789127832] transaction","detail":"{read_only:false; response_revision:24929; number_of_response:1; }","duration":"144.149938ms","start":"2026-06-09T23:11:21.429188Z","end":"2026-06-09T23:11:21.573337Z","steps":["trace[1789127832] 'process raft request' (duration: 133.467478ms)","trace[1789127832] 'compare' (duration: 10.594377ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:11:21.884954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.720402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T23:11:21.885071Z","caller":"traceutil/trace.go:171","msg":"trace[1810453510] linearizableReadLoop","detail":"{readStateIndex:25738; appliedIndex:25737; }","duration":"254.548988ms","start":"2026-06-09T23:11:21.630507Z","end":"2026-06-09T23:11:21.885056Z","steps":["trace[1810453510] 'read index received' (duration: 109.585529ms)","trace[1810453510] 'applied index is now lower than readState.Index' (duration: 144.962199ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:21.885111Z","caller":"traceutil/trace.go:171","msg":"trace[1213221380] transaction","detail":"{read_only:false; response_revision:24930; number_of_response:1; }","duration":"258.876536ms","start":"2026-06-09T23:11:21.626204Z","end":"2026-06-09T23:11:21.88508Z","steps":["trace[1213221380] 'process raft request' (duration: 113.911047ms)","trace[1213221380] 'compare' (duration: 144.451005ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:11:21.885155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.650752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:21.885179Z","caller":"traceutil/trace.go:171","msg":"trace[2018693255] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24930; }","duration":"254.706333ms","start":"2026-06-09T23:11:21.630465Z","end":"2026-06-09T23:11:21.885171Z","steps":["trace[2018693255] 'agreement among raft nodes before linearized reading' (duration: 254.648822ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:22.121131Z","caller":"traceutil/trace.go:171","msg":"trace[370074892] transaction","detail":"{read_only:false; response_revision:24931; number_of_response:1; }","duration":"241.193397ms","start":"2026-06-09T23:11:21.879896Z","end":"2026-06-09T23:11:22.121089Z","steps":["trace[370074892] 'process raft request' (duration: 224.020412ms)","trace[370074892] 'compare' (duration: 16.820975ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:22.193571Z","caller":"traceutil/trace.go:171","msg":"trace[1912663566] linearizableReadLoop","detail":"{readStateIndex:25740; appliedIndex:25738; }","duration":"308.4615ms","start":"2026-06-09T23:11:21.885091Z","end":"2026-06-09T23:11:22.193553Z","steps":["trace[1912663566] 'read index received' (duration: 219.009266ms)","trace[1912663566] 'applied index is now lower than readState.Index' (duration: 89.451104ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:11:22.193715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.787861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T23:11:22.193714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.911224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-09T23:11:22.193753Z","caller":"traceutil/trace.go:171","msg":"trace[1180773061] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:24932; }","duration":"321.894294ms","start":"2026-06-09T23:11:21.871849Z","end":"2026-06-09T23:11:22.193744Z","steps":["trace[1180773061] 'agreement among raft nodes before linearized reading' (duration: 321.804952ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:22.193765Z","caller":"traceutil/trace.go:171","msg":"trace[1350760369] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24932; }","duration":"315.006436ms","start":"2026-06-09T23:11:21.878745Z","end":"2026-06-09T23:11:22.193752Z","steps":["trace[1350760369] 'agreement among raft nodes before linearized reading' (duration: 314.822771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:22.193792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:21.871833Z","time spent":"321.949195ms","remote":"127.0.0.1:53380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":30,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-06-09T23:11:22.193798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:21.878715Z","time spent":"315.074948ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-06-09T23:11:22.193835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.16727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:22.193932Z","caller":"traceutil/trace.go:171","msg":"trace[1420862352] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:24932; }","duration":"495.342724ms","start":"2026-06-09T23:11:21.698558Z","end":"2026-06-09T23:11:22.1939Z","steps":["trace[1420862352] 'agreement among raft nodes before linearized reading' (duration: 495.140199ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:22.194004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:21.69854Z","time spent":"495.446468ms","remote":"127.0.0.1:59804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true "} {"level":"warn","ts":"2026-06-09T23:11:22.194004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.364548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:22.194195Z","caller":"traceutil/trace.go:171","msg":"trace[339597895] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:24932; }","duration":"556.606814ms","start":"2026-06-09T23:11:21.637577Z","end":"2026-06-09T23:11:22.194183Z","steps":["trace[339597895] 'agreement among raft nodes before linearized reading' (duration: 556.284325ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:22.194346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:21.637552Z","time spent":"556.786729ms","remote":"127.0.0.1:59740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":30,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"info","ts":"2026-06-09T23:11:22.194676Z","caller":"traceutil/trace.go:171","msg":"trace[118688061] transaction","detail":"{read_only:false; response_revision:24932; number_of_response:1; }","duration":"313.944419ms","start":"2026-06-09T23:11:21.880696Z","end":"2026-06-09T23:11:22.19464Z","steps":["trace[118688061] 'process raft request' (duration: 312.736846ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:22.194795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:21.880673Z","time spent":"314.055902ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T23:11:22.195042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.316838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:22.195066Z","caller":"traceutil/trace.go:171","msg":"trace[1605693909] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24933; }","duration":"304.383039ms","start":"2026-06-09T23:11:21.890677Z","end":"2026-06-09T23:11:22.19506Z","steps":["trace[1605693909] 'agreement among raft nodes before linearized reading' (duration: 304.013479ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:22.195081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:21.89066Z","time spent":"304.417511ms","remote":"127.0.0.1:53492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-06-09T23:11:22.195614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.519039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:22.195669Z","caller":"traceutil/trace.go:171","msg":"trace[1563733675] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:24933; }","duration":"112.877499ms","start":"2026-06-09T23:11:22.082778Z","end":"2026-06-09T23:11:22.195656Z","steps":["trace[1563733675] 'agreement among raft nodes before linearized reading' (duration: 111.923844ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:22.196045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.862429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:22.196098Z","caller":"traceutil/trace.go:171","msg":"trace[185474472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24933; }","duration":"167.916501ms","start":"2026-06-09T23:11:22.028169Z","end":"2026-06-09T23:11:22.196085Z","steps":["trace[185474472] 'agreement among raft nodes before linearized reading' (duration: 166.751269ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:22.419132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.777938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T23:11:22.419274Z","caller":"traceutil/trace.go:171","msg":"trace[874982217] transaction","detail":"{read_only:false; response_revision:24934; number_of_response:1; }","duration":"221.193575ms","start":"2026-06-09T23:11:22.198064Z","end":"2026-06-09T23:11:22.419258Z","steps":["trace[874982217] 'process raft request' (duration: 81.228172ms)","trace[874982217] 'compare' (duration: 139.45932ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:22.419649Z","caller":"traceutil/trace.go:171","msg":"trace[1696836661] linearizableReadLoop","detail":"{readStateIndex:25743; appliedIndex:25741; }","duration":"219.666354ms","start":"2026-06-09T23:11:22.199921Z","end":"2026-06-09T23:11:22.419587Z","steps":["trace[1696836661] 'read index received' (duration: 79.405853ms)","trace[1696836661] 'applied index is now lower than readState.Index' (duration: 140.259161ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:22.419785Z","caller":"traceutil/trace.go:171","msg":"trace[1060568192] transaction","detail":"{read_only:false; response_revision:24935; number_of_response:1; }","duration":"220.26398ms","start":"2026-06-09T23:11:22.199494Z","end":"2026-06-09T23:11:22.419758Z","steps":["trace[1060568192] 'process raft request' (duration: 219.730935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:22.420312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.075655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:22.420674Z","caller":"traceutil/trace.go:171","msg":"trace[167745797] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24935; }","duration":"220.686532ms","start":"2026-06-09T23:11:22.19989Z","end":"2026-06-09T23:11:22.420577Z","steps":["trace[167745797] 'agreement among raft nodes before linearized reading' (duration: 219.941792ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:22.677144Z","caller":"traceutil/trace.go:171","msg":"trace[1576934525] transaction","detail":"{read_only:false; response_revision:24937; number_of_response:1; }","duration":"129.981423ms","start":"2026-06-09T23:11:22.547119Z","end":"2026-06-09T23:11:22.6771Z","steps":["trace[1576934525] 'process raft request' (duration: 129.643794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:32.970109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.106824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:9511"} {"level":"info","ts":"2026-06-09T23:11:32.970315Z","caller":"traceutil/trace.go:171","msg":"trace[1839383969] transaction","detail":"{read_only:false; response_revision:25053; number_of_response:1; }","duration":"289.231928ms","start":"2026-06-09T23:11:32.681071Z","end":"2026-06-09T23:11:32.970303Z","steps":["trace[1839383969] 'process raft request' (duration: 289.139355ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:32.970316Z","caller":"traceutil/trace.go:171","msg":"trace[1833990999] transaction","detail":"{read_only:false; number_of_response:1; response_revision:25052; }","duration":"293.876535ms","start":"2026-06-09T23:11:32.676415Z","end":"2026-06-09T23:11:32.970292Z","steps":["trace[1833990999] 'process raft request' (duration: 121.484113ms)","trace[1833990999] 'compare' (duration: 172.032422ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:32.970478Z","caller":"traceutil/trace.go:171","msg":"trace[369793012] linearizableReadLoop","detail":"{readStateIndex:25863; appliedIndex:25862; }","duration":"293.915775ms","start":"2026-06-09T23:11:32.676554Z","end":"2026-06-09T23:11:32.97047Z","steps":["trace[369793012] 'read index received' (duration: 121.271067ms)","trace[369793012] 'applied index is now lower than readState.Index' (duration: 172.643698ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:11:32.970709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.154052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-manila-server-0.18b78c87d47cc429\" ","response":"range_response_count:1 size:1010"} {"level":"warn","ts":"2026-06-09T23:11:32.970711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.233455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:32.970728Z","caller":"traceutil/trace.go:171","msg":"trace[1707786746] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-manila-server-0.18b78c87d47cc429; range_end:; response_count:1; response_revision:25053; }","duration":"294.214553ms","start":"2026-06-09T23:11:32.676505Z","end":"2026-06-09T23:11:32.97072Z","steps":["trace[1707786746] 'agreement among raft nodes before linearized reading' (duration: 294.0947ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:32.970749Z","caller":"traceutil/trace.go:171","msg":"trace[1873290526] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:25053; }","duration":"290.302157ms","start":"2026-06-09T23:11:32.680434Z","end":"2026-06-09T23:11:32.970736Z","steps":["trace[1873290526] 'agreement among raft nodes before linearized reading' (duration: 290.235545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:32.970915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.719261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-09T23:11:32.970931Z","caller":"traceutil/trace.go:171","msg":"trace[285289947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25053; }","duration":"175.767323ms","start":"2026-06-09T23:11:32.795157Z","end":"2026-06-09T23:11:32.970925Z","steps":["trace[285289947] 'agreement among raft nodes before linearized reading' (duration: 175.711491ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:32.970981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.777474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-09T23:11:32.971026Z","caller":"traceutil/trace.go:171","msg":"trace[464914739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25053; }","duration":"230.850896ms","start":"2026-06-09T23:11:32.740162Z","end":"2026-06-09T23:11:32.971013Z","steps":["trace[464914739] 'agreement among raft nodes before linearized reading' (duration: 230.732283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:32.971812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.621685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2224964"} {"level":"info","ts":"2026-06-09T23:11:32.97184Z","caller":"traceutil/trace.go:171","msg":"trace[1743898017] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:25053; }","duration":"173.649486ms","start":"2026-06-09T23:11:32.798184Z","end":"2026-06-09T23:11:32.971834Z","steps":["trace[1743898017] 'agreement among raft nodes before linearized reading' (duration: 172.821913ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:34.346783Z","caller":"traceutil/trace.go:171","msg":"trace[1061801764] transaction","detail":"{read_only:false; response_revision:25078; number_of_response:1; }","duration":"394.09992ms","start":"2026-06-09T23:11:33.952637Z","end":"2026-06-09T23:11:34.346737Z","steps":["trace[1061801764] 'process raft request' (duration: 297.310627ms)","trace[1061801764] 'compare' (duration: 96.667599ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:11:34.34693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:33.952611Z","time spent":"394.243114ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T23:11:34.68092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.902692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T23:11:34.681105Z","caller":"traceutil/trace.go:171","msg":"trace[1025675943] linearizableReadLoop","detail":"{readStateIndex:25892; appliedIndex:25890; }","duration":"691.762118ms","start":"2026-06-09T23:11:33.989331Z","end":"2026-06-09T23:11:34.681093Z","steps":["trace[1025675943] 'read index received' (duration: 260.648804ms)","trace[1025675943] 'applied index is now lower than readState.Index' (duration: 431.112404ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:34.681128Z","caller":"traceutil/trace.go:171","msg":"trace[838299494] transaction","detail":"{read_only:false; response_revision:25081; number_of_response:1; }","duration":"448.160366ms","start":"2026-06-09T23:11:34.232949Z","end":"2026-06-09T23:11:34.68111Z","steps":["trace[838299494] 'process raft request' (duration: 448.097605ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:34.68115Z","caller":"traceutil/trace.go:171","msg":"trace[32741412] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"445.219856ms","start":"2026-06-09T23:11:34.235926Z","end":"2026-06-09T23:11:34.681146Z","steps":["trace[32741412] 'process raft request' (duration: 445.141314ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:34.681224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:34.235897Z","time spent":"445.308779ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T23:11:34.681223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:34.232918Z","time spent":"448.260048ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T23:11:34.681315Z","caller":"traceutil/trace.go:171","msg":"trace[712176188] transaction","detail":"{read_only:false; response_revision:25080; number_of_response:1; }","duration":"501.136962ms","start":"2026-06-09T23:11:34.180167Z","end":"2026-06-09T23:11:34.681304Z","steps":["trace[712176188] 'process raft request' (duration: 500.848484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:34.68137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:34.180136Z","time spent":"501.207204ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T23:11:34.681419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.096716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c9814f3c-f85b-4ee6-aecf-9731a3f40938\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-09T23:11:34.681437Z","caller":"traceutil/trace.go:171","msg":"trace[251355664] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c9814f3c-f85b-4ee6-aecf-9731a3f40938; range_end:; response_count:1; response_revision:25082; }","duration":"692.143108ms","start":"2026-06-09T23:11:33.989287Z","end":"2026-06-09T23:11:34.681431Z","steps":["trace[251355664] 'agreement among raft nodes before linearized reading' (duration: 692.039175ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:34.68146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:33.98927Z","time spent":"692.185969ms","remote":"127.0.0.1:53408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c9814f3c-f85b-4ee6-aecf-9731a3f40938\" "} {"level":"info","ts":"2026-06-09T23:11:34.681478Z","caller":"traceutil/trace.go:171","msg":"trace[1882974904] transaction","detail":"{read_only:false; response_revision:25079; number_of_response:1; }","duration":"701.542153ms","start":"2026-06-09T23:11:33.979889Z","end":"2026-06-09T23:11:34.681431Z","steps":["trace[1882974904] 'process raft request' (duration: 468.091466ms)","trace[1882974904] 'compare' (duration: 232.691567ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:11:34.681721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:33.979866Z","time spent":"701.728238ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T23:11:34.710851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.638402ms","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:8"} {"level":"warn","ts":"2026-06-09T23:11:34.710964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.189431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:34.710976Z","caller":"traceutil/trace.go:171","msg":"trace[156367157] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25083; }","duration":"702.817746ms","start":"2026-06-09T23:11:34.008128Z","end":"2026-06-09T23:11:34.710945Z","steps":["trace[156367157] 'agreement among raft nodes before linearized reading' (duration: 702.57154ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:34.710995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.339961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-06-09T23:11:34.711036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.060923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-06-09T23:11:34.711071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:34.008115Z","time spent":"702.93574ms","remote":"127.0.0.1:35834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":32,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-06-09T23:11:34.711116Z","caller":"traceutil/trace.go:171","msg":"trace[855389892] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25083; }","duration":"203.140495ms","start":"2026-06-09T23:11:34.507953Z","end":"2026-06-09T23:11:34.711094Z","steps":["trace[855389892] 'agreement among raft nodes before linearized reading' (duration: 202.95272ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:34.711146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.541699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:34.711187Z","caller":"traceutil/trace.go:171","msg":"trace[712792764] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:25083; }","duration":"216.638042ms","start":"2026-06-09T23:11:34.494536Z","end":"2026-06-09T23:11:34.711174Z","steps":["trace[712792764] 'agreement among raft nodes before linearized reading' (duration: 216.573581ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:34.711229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.528601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-06-09T23:11:34.711248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.460883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:34.711273Z","caller":"traceutil/trace.go:171","msg":"trace[1943143466] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25083; }","duration":"236.606453ms","start":"2026-06-09T23:11:34.474653Z","end":"2026-06-09T23:11:34.71126Z","steps":["trace[1943143466] 'agreement among raft nodes before linearized reading' (duration: 236.435037ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:34.711056Z","caller":"traceutil/trace.go:171","msg":"trace[1470900215] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:25083; }","duration":"405.328945ms","start":"2026-06-09T23:11:34.305705Z","end":"2026-06-09T23:11:34.711033Z","steps":["trace[1470900215] 'agreement among raft nodes before linearized reading' (duration: 405.178271ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:34.711328Z","caller":"traceutil/trace.go:171","msg":"trace[468544696] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25083; }","duration":"162.573536ms","start":"2026-06-09T23:11:34.548734Z","end":"2026-06-09T23:11:34.711308Z","steps":["trace[468544696] 'agreement among raft nodes before linearized reading' (duration: 162.451143ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:34.711392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:34.305684Z","time spent":"405.682665ms","remote":"127.0.0.1:33860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true "} {"level":"warn","ts":"2026-06-09T23:11:34.711463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.380916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c9814f3c-f85b-4ee6-aecf-9731a3f40938\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-09T23:11:34.711515Z","caller":"traceutil/trace.go:171","msg":"trace[1952731354] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c9814f3c-f85b-4ee6-aecf-9731a3f40938; range_end:; response_count:1; response_revision:25083; }","duration":"678.469998ms","start":"2026-06-09T23:11:34.033029Z","end":"2026-06-09T23:11:34.711499Z","steps":["trace[1952731354] 'agreement among raft nodes before linearized reading' (duration: 678.313264ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:34.711559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:34.03301Z","time spent":"678.53621ms","remote":"127.0.0.1:53408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c9814f3c-f85b-4ee6-aecf-9731a3f40938\" "} {"level":"info","ts":"2026-06-09T23:11:34.711084Z","caller":"traceutil/trace.go:171","msg":"trace[720892592] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25083; }","duration":"227.461475ms","start":"2026-06-09T23:11:34.483605Z","end":"2026-06-09T23:11:34.711066Z","steps":["trace[720892592] 'agreement among raft nodes before linearized reading' (duration: 227.200058ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:34.912682Z","caller":"traceutil/trace.go:171","msg":"trace[2097938726] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"219.92762ms","start":"2026-06-09T23:11:34.692729Z","end":"2026-06-09T23:11:34.912657Z","steps":["trace[2097938726] 'process raft request' (duration: 219.754605ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:34.926747Z","caller":"traceutil/trace.go:171","msg":"trace[878775340] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"210.042743ms","start":"2026-06-09T23:11:34.716667Z","end":"2026-06-09T23:11:34.92671Z","steps":["trace[878775340] 'process raft request' (duration: 209.778786ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:34.927071Z","caller":"traceutil/trace.go:171","msg":"trace[1930182577] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"209.58732ms","start":"2026-06-09T23:11:34.717449Z","end":"2026-06-09T23:11:34.927036Z","steps":["trace[1930182577] 'process raft request' (duration: 209.204339ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:34.927194Z","caller":"traceutil/trace.go:171","msg":"trace[533812569] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"208.872161ms","start":"2026-06-09T23:11:34.718268Z","end":"2026-06-09T23:11:34.92714Z","steps":["trace[533812569] 'process raft request' (duration: 208.681126ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:35.239568Z","caller":"traceutil/trace.go:171","msg":"trace[188351886] linearizableReadLoop","detail":"{readStateIndex:25901; appliedIndex:25900; }","duration":"210.645888ms","start":"2026-06-09T23:11:35.028902Z","end":"2026-06-09T23:11:35.239548Z","steps":["trace[188351886] 'read index received' (duration: 210.480804ms)","trace[188351886] 'applied index is now lower than readState.Index' (duration: 164.484µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:35.239803Z","caller":"traceutil/trace.go:171","msg":"trace[1491804030] transaction","detail":"{read_only:false; response_revision:25088; number_of_response:1; }","duration":"229.036068ms","start":"2026-06-09T23:11:35.010756Z","end":"2026-06-09T23:11:35.239792Z","steps":["trace[1491804030] 'process raft request' (duration: 228.680468ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:35.239987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.08864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:35.24002Z","caller":"traceutil/trace.go:171","msg":"trace[1789872351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25088; }","duration":"211.165532ms","start":"2026-06-09T23:11:35.028838Z","end":"2026-06-09T23:11:35.240003Z","steps":["trace[1789872351] 'agreement among raft nodes before linearized reading' (duration: 211.110281ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:35.240217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.184796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-09T23:11:35.240288Z","caller":"traceutil/trace.go:171","msg":"trace[1715999982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25088; }","duration":"210.29157ms","start":"2026-06-09T23:11:35.029978Z","end":"2026-06-09T23:11:35.24027Z","steps":["trace[1715999982] 'agreement among raft nodes before linearized reading' (duration: 210.081804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:35.240457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.415032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-09T23:11:35.240514Z","caller":"traceutil/trace.go:171","msg":"trace[2093438934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25088; }","duration":"210.482205ms","start":"2026-06-09T23:11:35.030019Z","end":"2026-06-09T23:11:35.240501Z","steps":["trace[2093438934] 'agreement among raft nodes before linearized reading' (duration: 210.29151ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:35.395109Z","caller":"traceutil/trace.go:171","msg":"trace[687379116] transaction","detail":"{read_only:false; response_revision:25090; number_of_response:1; }","duration":"148.740021ms","start":"2026-06-09T23:11:35.246335Z","end":"2026-06-09T23:11:35.395075Z","steps":["trace[687379116] 'process raft request' (duration: 148.680749ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:35.395529Z","caller":"traceutil/trace.go:171","msg":"trace[1465488462] transaction","detail":"{read_only:false; response_revision:25089; number_of_response:1; }","duration":"150.073358ms","start":"2026-06-09T23:11:35.245441Z","end":"2026-06-09T23:11:35.395514Z","steps":["trace[1465488462] 'process raft request' (duration: 115.180981ms)","trace[1465488462] 'compare' (duration: 34.226359ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:35.575254Z","caller":"traceutil/trace.go:171","msg":"trace[1074131327] transaction","detail":"{read_only:false; response_revision:25091; number_of_response:1; }","duration":"124.469853ms","start":"2026-06-09T23:11:35.450757Z","end":"2026-06-09T23:11:35.575227Z","steps":["trace[1074131327] 'process raft request' (duration: 111.10265ms)","trace[1074131327] 'compare' (duration: 13.235799ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:35.576072Z","caller":"traceutil/trace.go:171","msg":"trace[2140200447] transaction","detail":"{read_only:false; response_revision:25093; number_of_response:1; }","duration":"103.428853ms","start":"2026-06-09T23:11:35.4726Z","end":"2026-06-09T23:11:35.576029Z","steps":["trace[2140200447] 'process raft request' (duration: 103.377402ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:35.576494Z","caller":"traceutil/trace.go:171","msg":"trace[952678518] transaction","detail":"{read_only:false; response_revision:25092; number_of_response:1; }","duration":"111.824ms","start":"2026-06-09T23:11:35.464658Z","end":"2026-06-09T23:11:35.576482Z","steps":["trace[952678518] 'process raft request' (duration: 110.969058ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:35.885525Z","caller":"traceutil/trace.go:171","msg":"trace[1279857333] linearizableReadLoop","detail":"{readStateIndex:25907; appliedIndex:25906; }","duration":"167.87518ms","start":"2026-06-09T23:11:35.717602Z","end":"2026-06-09T23:11:35.885477Z","steps":["trace[1279857333] 'read index received' (duration: 154.518098ms)","trace[1279857333] 'applied index is now lower than readState.Index' (duration: 13.355122ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:35.885555Z","caller":"traceutil/trace.go:171","msg":"trace[474341075] transaction","detail":"{read_only:false; response_revision:25094; number_of_response:1; }","duration":"234.123535ms","start":"2026-06-09T23:11:35.651393Z","end":"2026-06-09T23:11:35.885517Z","steps":["trace[474341075] 'process raft request' (duration: 220.789614ms)","trace[474341075] 'compare' (duration: 12.965381ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:11:35.885726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.104506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c9814f3c-f85b-4ee6-aecf-9731a3f40938\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-06-09T23:11:35.886029Z","caller":"traceutil/trace.go:171","msg":"trace[1088785699] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c9814f3c-f85b-4ee6-aecf-9731a3f40938; range_end:; response_count:1; response_revision:25094; }","duration":"168.417474ms","start":"2026-06-09T23:11:35.717587Z","end":"2026-06-09T23:11:35.886005Z","steps":["trace[1088785699] 'agreement among raft nodes before linearized reading' (duration: 168.008183ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:35.887188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.87596ms","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:6"} {"level":"info","ts":"2026-06-09T23:11:35.88726Z","caller":"traceutil/trace.go:171","msg":"trace[374295607] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:25095; }","duration":"122.965663ms","start":"2026-06-09T23:11:35.764276Z","end":"2026-06-09T23:11:35.887242Z","steps":["trace[374295607] 'agreement among raft nodes before linearized reading' (duration: 122.825439ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:35.887328Z","caller":"traceutil/trace.go:171","msg":"trace[83211616] transaction","detail":"{read_only:false; response_revision:25095; number_of_response:1; }","duration":"132.899871ms","start":"2026-06-09T23:11:35.754402Z","end":"2026-06-09T23:11:35.887302Z","steps":["trace[83211616] 'process raft request' (duration: 132.582253ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:37.053478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.307433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T23:11:37.053739Z","caller":"traceutil/trace.go:171","msg":"trace[241769969] linearizableReadLoop","detail":"{readStateIndex:25912; appliedIndex:25909; }","duration":"163.020388ms","start":"2026-06-09T23:11:36.890695Z","end":"2026-06-09T23:11:37.053716Z","steps":["trace[241769969] 'read index received' (duration: 51.571µs)","trace[241769969] 'applied index is now lower than readState.Index' (duration: 162.967847ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:37.053738Z","caller":"traceutil/trace.go:171","msg":"trace[1865533645] transaction","detail":"{read_only:false; response_revision:25097; number_of_response:1; }","duration":"365.522046ms","start":"2026-06-09T23:11:36.688134Z","end":"2026-06-09T23:11:37.053656Z","steps":["trace[1865533645] 'process raft request' (duration: 193.953116ms)","trace[1865533645] 'compare' (duration: 170.908142ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:37.053762Z","caller":"traceutil/trace.go:171","msg":"trace[1108208211] transaction","detail":"{read_only:false; response_revision:25098; number_of_response:1; }","duration":"365.422174ms","start":"2026-06-09T23:11:36.688312Z","end":"2026-06-09T23:11:37.053734Z","steps":["trace[1108208211] 'process raft request' (duration: 365.26631ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:37.053843Z","caller":"traceutil/trace.go:171","msg":"trace[642045830] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"365.414364ms","start":"2026-06-09T23:11:36.688407Z","end":"2026-06-09T23:11:37.053822Z","steps":["trace[642045830] 'process raft request' (duration: 365.26033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:37.053912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:36.688289Z","time spent":"365.553597ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T23:11:37.053948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:36.688372Z","time spent":"365.532297ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T23:11:37.053946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:36.688104Z","time spent":"365.731262ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T23:11:37.053949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.672659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:37.054025Z","caller":"traceutil/trace.go:171","msg":"trace[1190529204] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:25099; }","duration":"138.795482ms","start":"2026-06-09T23:11:36.915209Z","end":"2026-06-09T23:11:37.054004Z","steps":["trace[1190529204] 'agreement among raft nodes before linearized reading' (duration: 138.634917ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:37.054155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.379886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-09T23:11:37.054189Z","caller":"traceutil/trace.go:171","msg":"trace[846575603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25099; }","duration":"122.458209ms","start":"2026-06-09T23:11:36.931723Z","end":"2026-06-09T23:11:37.054181Z","steps":["trace[846575603] 'agreement among raft nodes before linearized reading' (duration: 122.335916ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:37.054182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.46807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c9814f3c-f85b-4ee6-aecf-9731a3f40938\" ","response":"range_response_count:1 size:3764"} {"level":"warn","ts":"2026-06-09T23:11:37.054183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.965026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-06-09T23:11:37.054214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.569482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-06-09T23:11:37.054263Z","caller":"traceutil/trace.go:171","msg":"trace[766279511] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c9814f3c-f85b-4ee6-aecf-9731a3f40938; range_end:; response_count:1; response_revision:25099; }","duration":"163.593763ms","start":"2026-06-09T23:11:36.890649Z","end":"2026-06-09T23:11:37.054243Z","steps":["trace[766279511] 'agreement among raft nodes before linearized reading' (duration: 163.423278ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:37.054273Z","caller":"traceutil/trace.go:171","msg":"trace[1889204910] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25099; }","duration":"122.648614ms","start":"2026-06-09T23:11:36.931607Z","end":"2026-06-09T23:11:37.054256Z","steps":["trace[1889204910] 'agreement among raft nodes before linearized reading' (duration: 122.49424ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:37.05427Z","caller":"traceutil/trace.go:171","msg":"trace[969782037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25099; }","duration":"122.349396ms","start":"2026-06-09T23:11:36.931898Z","end":"2026-06-09T23:11:37.054248Z","steps":["trace[969782037] 'agreement among raft nodes before linearized reading' (duration: 122.138571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:37.358585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.570798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T23:11:37.358762Z","caller":"traceutil/trace.go:171","msg":"trace[1720236348] linearizableReadLoop","detail":"{readStateIndex:25915; appliedIndex:25913; }","duration":"108.064809ms","start":"2026-06-09T23:11:37.250684Z","end":"2026-06-09T23:11:37.358749Z","steps":["trace[1720236348] 'read index received' (duration: 37.321µs)","trace[1720236348] 'applied index is now lower than readState.Index' (duration: 108.026678ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:11:37.358837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.149451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:37.358862Z","caller":"traceutil/trace.go:171","msg":"trace[1184455142] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25102; }","duration":"108.216723ms","start":"2026-06-09T23:11:37.250637Z","end":"2026-06-09T23:11:37.358853Z","steps":["trace[1184455142] 'agreement among raft nodes before linearized reading' (duration: 108.152091ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:37.359067Z","caller":"traceutil/trace.go:171","msg":"trace[1444211532] transaction","detail":"{read_only:false; response_revision:25101; number_of_response:1; }","duration":"299.667391ms","start":"2026-06-09T23:11:37.059362Z","end":"2026-06-09T23:11:37.35903Z","steps":["trace[1444211532] 'process raft request' (duration: 154.571849ms)","trace[1444211532] 'compare' (duration: 144.391873ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:37.359122Z","caller":"traceutil/trace.go:171","msg":"trace[2015396262] transaction","detail":"{read_only:false; response_revision:25102; number_of_response:1; }","duration":"298.602713ms","start":"2026-06-09T23:11:37.060458Z","end":"2026-06-09T23:11:37.35906Z","steps":["trace[2015396262] 'process raft request' (duration: 298.215133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:37.799201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.487447ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:37.799344Z","caller":"traceutil/trace.go:171","msg":"trace[1512786307] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25102; }","duration":"292.656202ms","start":"2026-06-09T23:11:37.506666Z","end":"2026-06-09T23:11:37.799322Z","steps":["trace[1512786307] 'range keys from in-memory index tree' (duration: 292.461227ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:37.79958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.021392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T23:11:37.799763Z","caller":"traceutil/trace.go:171","msg":"trace[1573508987] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"397.066921ms","start":"2026-06-09T23:11:37.402668Z","end":"2026-06-09T23:11:37.799735Z","steps":["trace[1573508987] 'process raft request' (duration: 224.798812ms)","trace[1573508987] 'compare' (duration: 171.767666ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:11:37.799842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:37.402647Z","time spent":"397.160134ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T23:11:38.098114Z","caller":"traceutil/trace.go:171","msg":"trace[1695281470] transaction","detail":"{read_only:false; response_revision:25104; number_of_response:1; }","duration":"694.998947ms","start":"2026-06-09T23:11:37.403074Z","end":"2026-06-09T23:11:38.098073Z","steps":["trace[1695281470] 'process raft request' (duration: 674.734637ms)","trace[1695281470] 'compare' (duration: 19.986061ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:38.098169Z","caller":"traceutil/trace.go:171","msg":"trace[1723673655] linearizableReadLoop","detail":"{readStateIndex:25917; appliedIndex:25915; }","duration":"645.614828ms","start":"2026-06-09T23:11:37.452532Z","end":"2026-06-09T23:11:38.098147Z","steps":["trace[1723673655] 'read index received' (duration: 174.949612ms)","trace[1723673655] 'applied index is now lower than readState.Index' (duration: 470.664366ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:38.098167Z","caller":"traceutil/trace.go:171","msg":"trace[522456180] transaction","detail":"{read_only:false; response_revision:25105; number_of_response:1; }","duration":"515.051779ms","start":"2026-06-09T23:11:37.583089Z","end":"2026-06-09T23:11:38.098141Z","steps":["trace[522456180] 'process raft request' (duration: 514.893734ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:38.098224Z","caller":"traceutil/trace.go:171","msg":"trace[448285889] transaction","detail":"{read_only:false; response_revision:25106; number_of_response:1; }","duration":"514.865494ms","start":"2026-06-09T23:11:37.583327Z","end":"2026-06-09T23:11:38.098193Z","steps":["trace[448285889] 'process raft request' (duration: 514.739251ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:38.098279Z","caller":"traceutil/trace.go:171","msg":"trace[1106676604] transaction","detail":"{read_only:false; response_revision:25107; number_of_response:1; }","duration":"514.096734ms","start":"2026-06-09T23:11:37.584171Z","end":"2026-06-09T23:11:38.098268Z","steps":["trace[1106676604] 'process raft request' (duration: 513.93794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:38.098315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:37.583059Z","time spent":"515.170482ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T23:11:38.098325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:37.40306Z","time spent":"695.14968ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T23:11:38.09835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:37.584144Z","time spent":"514.159525ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T23:11:38.098405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:37.583291Z","time spent":"515.010398ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T23:11:38.098456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.418631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:38.098529Z","caller":"traceutil/trace.go:171","msg":"trace[905857422] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25107; }","duration":"642.550614ms","start":"2026-06-09T23:11:37.455957Z","end":"2026-06-09T23:11:38.098508Z","steps":["trace[905857422] 'agreement among raft nodes before linearized reading' (duration: 642.431141ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:38.098546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.333242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-09T23:11:38.098587Z","caller":"traceutil/trace.go:171","msg":"trace[476777350] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25107; }","duration":"206.405904ms","start":"2026-06-09T23:11:37.892169Z","end":"2026-06-09T23:11:38.098575Z","steps":["trace[476777350] 'agreement among raft nodes before linearized reading' (duration: 206.286931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:38.098593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:37.455937Z","time spent":"642.639617ms","remote":"127.0.0.1:59840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-06-09T23:11:38.098779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.517154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:11:38.09881Z","caller":"traceutil/trace.go:171","msg":"trace[477950476] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:25107; }","duration":"628.550675ms","start":"2026-06-09T23:11:37.47025Z","end":"2026-06-09T23:11:38.0988Z","steps":["trace[477950476] 'agreement among raft nodes before linearized reading' (duration: 628.496354ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:38.098839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:37.470191Z","time spent":"628.640178ms","remote":"127.0.0.1:41652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true "} {"level":"warn","ts":"2026-06-09T23:11:38.09895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.420939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-06-09T23:11:38.099044Z","caller":"traceutil/trace.go:171","msg":"trace[831927452] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25107; }","duration":"646.591223ms","start":"2026-06-09T23:11:37.452433Z","end":"2026-06-09T23:11:38.099024Z","steps":["trace[831927452] 'agreement among raft nodes before linearized reading' (duration: 646.380247ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:38.099093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:37.452397Z","time spent":"646.684276ms","remote":"127.0.0.1:35654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3260,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-06-09T23:11:38.101797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.882378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-09T23:11:38.101886Z","caller":"traceutil/trace.go:171","msg":"trace[2079970104] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25107; }","duration":"379.028192ms","start":"2026-06-09T23:11:37.722838Z","end":"2026-06-09T23:11:38.101866Z","steps":["trace[2079970104] 'agreement among raft nodes before linearized reading' (duration: 375.922608ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:38.101926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:11:37.722815Z","time spent":"379.100683ms","remote":"127.0.0.1:53740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2699,"response size":33,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-06-09T23:11:38.426914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.645053ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-06-09T23:11:40.307234Z","caller":"traceutil/trace.go:171","msg":"trace[1660703479] transaction","detail":"{read_only:false; response_revision:25125; number_of_response:1; }","duration":"137.118736ms","start":"2026-06-09T23:11:40.170083Z","end":"2026-06-09T23:11:40.307202Z","steps":["trace[1660703479] 'process raft request' (duration: 136.930901ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:44.318128Z","caller":"traceutil/trace.go:171","msg":"trace[684330915] transaction","detail":"{read_only:false; response_revision:25166; number_of_response:1; }","duration":"201.46227ms","start":"2026-06-09T23:11:44.116646Z","end":"2026-06-09T23:11:44.318108Z","steps":["trace[684330915] 'process raft request' (duration: 201.322527ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:44.33204Z","caller":"traceutil/trace.go:171","msg":"trace[1721193470] linearizableReadLoop","detail":"{readStateIndex:25983; appliedIndex:25980; }","duration":"203.446864ms","start":"2026-06-09T23:11:44.12857Z","end":"2026-06-09T23:11:44.332017Z","steps":["trace[1721193470] 'read index received' (duration: 189.436625ms)","trace[1721193470] 'applied index is now lower than readState.Index' (duration: 14.009099ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:11:44.332124Z","caller":"traceutil/trace.go:171","msg":"trace[190693130] transaction","detail":"{read_only:false; response_revision:25167; number_of_response:1; }","duration":"212.644814ms","start":"2026-06-09T23:11:44.119421Z","end":"2026-06-09T23:11:44.332066Z","steps":["trace[190693130] 'process raft request' (duration: 212.376146ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:44.332243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.66003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-09T23:11:44.332286Z","caller":"traceutil/trace.go:171","msg":"trace[979749424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25168; }","duration":"203.747232ms","start":"2026-06-09T23:11:44.128528Z","end":"2026-06-09T23:11:44.332275Z","steps":["trace[979749424] 'agreement among raft nodes before linearized reading' (duration: 203.605238ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:44.332157Z","caller":"traceutil/trace.go:171","msg":"trace[563901123] transaction","detail":"{read_only:false; response_revision:25168; number_of_response:1; }","duration":"206.086535ms","start":"2026-06-09T23:11:44.126035Z","end":"2026-06-09T23:11:44.332122Z","steps":["trace[563901123] 'process raft request' (duration: 205.935841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:44.332418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.206183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10359"} {"level":"info","ts":"2026-06-09T23:11:44.332464Z","caller":"traceutil/trace.go:171","msg":"trace[737385792] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25168; }","duration":"149.315566ms","start":"2026-06-09T23:11:44.183137Z","end":"2026-06-09T23:11:44.332453Z","steps":["trace[737385792] 'agreement among raft nodes before linearized reading' (duration: 149.08984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:11:44.332712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.353927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-06-09T23:11:44.332806Z","caller":"traceutil/trace.go:171","msg":"trace[487083892] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25168; }","duration":"139.44747ms","start":"2026-06-09T23:11:44.193328Z","end":"2026-06-09T23:11:44.332776Z","steps":["trace[487083892] 'agreement among raft nodes before linearized reading' (duration: 139.222944ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:44.489213Z","caller":"traceutil/trace.go:171","msg":"trace[1904852372] transaction","detail":"{read_only:false; response_revision:25169; number_of_response:1; }","duration":"152.497723ms","start":"2026-06-09T23:11:44.33669Z","end":"2026-06-09T23:11:44.489188Z","steps":["trace[1904852372] 'process raft request' (duration: 152.317918ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:44.540224Z","caller":"traceutil/trace.go:171","msg":"trace[583353636] transaction","detail":"{read_only:false; response_revision:25170; number_of_response:1; }","duration":"202.96163ms","start":"2026-06-09T23:11:44.337221Z","end":"2026-06-09T23:11:44.540182Z","steps":["trace[583353636] 'process raft request' (duration: 202.636362ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:11:44.540484Z","caller":"traceutil/trace.go:171","msg":"trace[2056675732] transaction","detail":"{read_only:false; response_revision:25171; number_of_response:1; }","duration":"197.709028ms","start":"2026-06-09T23:11:44.342742Z","end":"2026-06-09T23:11:44.540451Z","steps":["trace[2056675732] 'process raft request' (duration: 197.351968ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:05.269904Z","caller":"traceutil/trace.go:171","msg":"trace[2026405663] transaction","detail":"{read_only:false; response_revision:25348; number_of_response:1; }","duration":"105.308194ms","start":"2026-06-09T23:12:05.164565Z","end":"2026-06-09T23:12:05.269873Z","steps":["trace[2026405663] 'process raft request' (duration: 105.14232ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:05.28557Z","caller":"traceutil/trace.go:171","msg":"trace[374661535] transaction","detail":"{read_only:false; response_revision:25350; number_of_response:1; }","duration":"113.60238ms","start":"2026-06-09T23:12:05.171939Z","end":"2026-06-09T23:12:05.285542Z","steps":["trace[374661535] 'process raft request' (duration: 113.474565ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:05.28556Z","caller":"traceutil/trace.go:171","msg":"trace[2132694886] transaction","detail":"{read_only:false; response_revision:25349; number_of_response:1; }","duration":"115.909972ms","start":"2026-06-09T23:12:05.169612Z","end":"2026-06-09T23:12:05.285522Z","steps":["trace[2132694886] 'process raft request' (duration: 115.651784ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:05.478264Z","caller":"traceutil/trace.go:171","msg":"trace[1745976542] linearizableReadLoop","detail":"{readStateIndex:26170; appliedIndex:26169; }","duration":"111.993775ms","start":"2026-06-09T23:12:05.366251Z","end":"2026-06-09T23:12:05.478245Z","steps":["trace[1745976542] 'read index received' (duration: 72.956707ms)","trace[1745976542] 'applied index is now lower than readState.Index' (duration: 39.036278ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:05.478768Z","caller":"traceutil/trace.go:171","msg":"trace[2119833618] transaction","detail":"{read_only:false; response_revision:25351; number_of_response:1; }","duration":"188.409927ms","start":"2026-06-09T23:12:05.290329Z","end":"2026-06-09T23:12:05.478739Z","steps":["trace[2119833618] 'process raft request' (duration: 148.914426ms)","trace[2119833618] 'compare' (duration: 38.899375ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:12:05.479129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.859759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:12:05.47916Z","caller":"traceutil/trace.go:171","msg":"trace[1709497835] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:25351; }","duration":"112.936291ms","start":"2026-06-09T23:12:05.366213Z","end":"2026-06-09T23:12:05.47915Z","steps":["trace[1709497835] 'agreement among raft nodes before linearized reading' (duration: 112.8717ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:05.741282Z","caller":"traceutil/trace.go:171","msg":"trace[332497385] transaction","detail":"{read_only:false; response_revision:25353; number_of_response:1; }","duration":"207.324019ms","start":"2026-06-09T23:12:05.533691Z","end":"2026-06-09T23:12:05.741015Z","steps":["trace[332497385] 'process raft request' (duration: 200.927096ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:05.954966Z","caller":"traceutil/trace.go:171","msg":"trace[1290092224] transaction","detail":"{read_only:false; response_revision:25354; number_of_response:1; }","duration":"394.030988ms","start":"2026-06-09T23:12:05.560908Z","end":"2026-06-09T23:12:05.954939Z","steps":["trace[1290092224] 'process raft request' (duration: 321.512864ms)","trace[1290092224] 'compare' (duration: 72.271827ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:12:05.955112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:12:05.560885Z","time spent":"394.155713ms","remote":"127.0.0.1:41750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7897,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T23:12:06.171257Z","caller":"traceutil/trace.go:171","msg":"trace[718954700] linearizableReadLoop","detail":"{readStateIndex:26175; appliedIndex:26174; }","duration":"186.386012ms","start":"2026-06-09T23:12:05.984851Z","end":"2026-06-09T23:12:06.171237Z","steps":["trace[718954700] 'read index received' (duration: 186.035202ms)","trace[718954700] 'applied index is now lower than readState.Index' (duration: 349.35µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:12:06.171416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.531316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T23:12:06.171455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.048533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-09T23:12:06.171414Z","caller":"traceutil/trace.go:171","msg":"trace[2075938601] transaction","detail":"{read_only:false; response_revision:25356; number_of_response:1; }","duration":"193.426262ms","start":"2026-06-09T23:12:05.977902Z","end":"2026-06-09T23:12:06.171328Z","steps":["trace[2075938601] 'process raft request' (duration: 193.035092ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:06.171502Z","caller":"traceutil/trace.go:171","msg":"trace[539554334] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25356; }","duration":"200.635908ms","start":"2026-06-09T23:12:05.970844Z","end":"2026-06-09T23:12:06.17148Z","steps":["trace[539554334] 'agreement among raft nodes before linearized reading' (duration: 200.466084ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:12:06.171506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.884961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18571"} {"level":"info","ts":"2026-06-09T23:12:06.171584Z","caller":"traceutil/trace.go:171","msg":"trace[2066402595] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:25356; }","duration":"208.985985ms","start":"2026-06-09T23:12:05.962581Z","end":"2026-06-09T23:12:06.171567Z","steps":["trace[2066402595] 'agreement among raft nodes before linearized reading' (duration: 208.760219ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:06.171495Z","caller":"traceutil/trace.go:171","msg":"trace[30060111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25356; }","duration":"179.148035ms","start":"2026-06-09T23:12:05.992335Z","end":"2026-06-09T23:12:06.171483Z","steps":["trace[30060111] 'agreement among raft nodes before linearized reading' (duration: 179.025542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:12:06.171583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.464761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T23:12:06.171653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.43898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:12:06.171857Z","caller":"traceutil/trace.go:171","msg":"trace[1903605737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25356; }","duration":"142.677447ms","start":"2026-06-09T23:12:06.029144Z","end":"2026-06-09T23:12:06.171822Z","steps":["trace[1903605737] 'agreement among raft nodes before linearized reading' (duration: 142.326518ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:06.171677Z","caller":"traceutil/trace.go:171","msg":"trace[700398915] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25356; }","duration":"104.675038ms","start":"2026-06-09T23:12:06.066972Z","end":"2026-06-09T23:12:06.171647Z","steps":["trace[700398915] 'agreement among raft nodes before linearized reading' (duration: 104.429091ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:06.548736Z","caller":"traceutil/trace.go:171","msg":"trace[22303814] transaction","detail":"{read_only:false; response_revision:25359; number_of_response:1; }","duration":"128.888333ms","start":"2026-06-09T23:12:06.419821Z","end":"2026-06-09T23:12:06.54871Z","steps":["trace[22303814] 'process raft request' (duration: 128.687137ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:06.568426Z","caller":"traceutil/trace.go:171","msg":"trace[357990666] transaction","detail":"{read_only:false; response_revision:25360; number_of_response:1; }","duration":"146.794858ms","start":"2026-06-09T23:12:06.421604Z","end":"2026-06-09T23:12:06.568399Z","steps":["trace[357990666] 'process raft request' (duration: 146.544071ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:06.750737Z","caller":"traceutil/trace.go:171","msg":"trace[1953850513] linearizableReadLoop","detail":"{readStateIndex:26180; appliedIndex:26179; }","duration":"106.004333ms","start":"2026-06-09T23:12:06.644708Z","end":"2026-06-09T23:12:06.750712Z","steps":["trace[1953850513] 'read index received' (duration: 21.493692ms)","trace[1953850513] 'applied index is now lower than readState.Index' (duration: 84.510021ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:06.75094Z","caller":"traceutil/trace.go:171","msg":"trace[1195827525] transaction","detail":"{read_only:false; response_revision:25361; number_of_response:1; }","duration":"146.504561ms","start":"2026-06-09T23:12:06.604342Z","end":"2026-06-09T23:12:06.750847Z","steps":["trace[1195827525] 'process raft request' (duration: 61.898007ms)","trace[1195827525] 'compare' (duration: 84.365957ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:12:06.751122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.383083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-06-09T23:12:06.751255Z","caller":"traceutil/trace.go:171","msg":"trace[1612050027] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25361; }","duration":"106.561517ms","start":"2026-06-09T23:12:06.644643Z","end":"2026-06-09T23:12:06.751204Z","steps":["trace[1612050027] 'agreement among raft nodes before linearized reading' (duration: 106.204768ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:07.513262Z","caller":"traceutil/trace.go:171","msg":"trace[624318012] transaction","detail":"{read_only:false; response_revision:25365; number_of_response:1; }","duration":"222.153261ms","start":"2026-06-09T23:12:07.291074Z","end":"2026-06-09T23:12:07.513227Z","steps":["trace[624318012] 'process raft request' (duration: 185.123068ms)","trace[624318012] 'compare' (duration: 36.631253ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:07.513314Z","caller":"traceutil/trace.go:171","msg":"trace[1637617365] linearizableReadLoop","detail":"{readStateIndex:26185; appliedIndex:26182; }","duration":"212.370586ms","start":"2026-06-09T23:12:07.300908Z","end":"2026-06-09T23:12:07.513279Z","steps":["trace[1637617365] 'read index received' (duration: 20.010622ms)","trace[1637617365] 'applied index is now lower than readState.Index' (duration: 192.358484ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:07.513887Z","caller":"traceutil/trace.go:171","msg":"trace[739569680] transaction","detail":"{read_only:false; response_revision:25366; number_of_response:1; }","duration":"220.780724ms","start":"2026-06-09T23:12:07.293091Z","end":"2026-06-09T23:12:07.513872Z","steps":["trace[739569680] 'process raft request' (duration: 220.024744ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:12:07.51419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.252849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:12:07.51431Z","caller":"traceutil/trace.go:171","msg":"trace[1008751118] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:25366; }","duration":"213.434845ms","start":"2026-06-09T23:12:07.300847Z","end":"2026-06-09T23:12:07.514282Z","steps":["trace[1008751118] 'agreement among raft nodes before linearized reading' (duration: 213.021624ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:07.729614Z","caller":"traceutil/trace.go:171","msg":"trace[144386745] transaction","detail":"{read_only:false; response_revision:25368; number_of_response:1; }","duration":"143.852839ms","start":"2026-06-09T23:12:07.585728Z","end":"2026-06-09T23:12:07.729581Z","steps":["trace[144386745] 'process raft request' (duration: 70.495961ms)","trace[144386745] 'compare' (duration: 73.099051ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:07.884393Z","caller":"traceutil/trace.go:171","msg":"trace[826847497] transaction","detail":"{read_only:false; response_revision:25369; number_of_response:1; }","duration":"134.257379ms","start":"2026-06-09T23:12:07.750107Z","end":"2026-06-09T23:12:07.884364Z","steps":["trace[826847497] 'process raft request' (duration: 113.960629ms)","trace[826847497] 'compare' (duration: 19.610362ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:08.940098Z","caller":"traceutil/trace.go:171","msg":"trace[786528478] transaction","detail":"{read_only:false; response_revision:25376; number_of_response:1; }","duration":"131.852034ms","start":"2026-06-09T23:12:08.808228Z","end":"2026-06-09T23:12:08.94008Z","steps":["trace[786528478] 'process raft request' (duration: 131.71456ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:09.078455Z","caller":"traceutil/trace.go:171","msg":"trace[382601539] transaction","detail":"{read_only:false; response_revision:25377; number_of_response:1; }","duration":"125.973283ms","start":"2026-06-09T23:12:08.952445Z","end":"2026-06-09T23:12:09.078418Z","steps":["trace[382601539] 'process raft request' (duration: 40.283381ms)","trace[382601539] 'compare' (duration: 85.306842ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:09.703662Z","caller":"traceutil/trace.go:171","msg":"trace[697613523] linearizableReadLoop","detail":"{readStateIndex:26200; appliedIndex:26199; }","duration":"118.741629ms","start":"2026-06-09T23:12:09.584874Z","end":"2026-06-09T23:12:09.703616Z","steps":["trace[697613523] 'read index received' (duration: 32.561473ms)","trace[697613523] 'applied index is now lower than readState.Index' (duration: 86.177746ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:09.703756Z","caller":"traceutil/trace.go:171","msg":"trace[2084269756] transaction","detail":"{read_only:false; response_revision:25380; number_of_response:1; }","duration":"183.612387ms","start":"2026-06-09T23:12:09.520112Z","end":"2026-06-09T23:12:09.703725Z","steps":["trace[2084269756] 'process raft request' (duration: 97.335858ms)","trace[2084269756] 'compare' (duration: 85.913749ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:12:09.704048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.136269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-09T23:12:09.704113Z","caller":"traceutil/trace.go:171","msg":"trace[89982886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25380; }","duration":"119.266493ms","start":"2026-06-09T23:12:09.58483Z","end":"2026-06-09T23:12:09.704096Z","steps":["trace[89982886] 'agreement among raft nodes before linearized reading' (duration: 118.942434ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:12:09.941858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.123183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T23:12:09.942178Z","caller":"traceutil/trace.go:171","msg":"trace[1817366936] transaction","detail":"{read_only:false; response_revision:25383; number_of_response:1; }","duration":"158.694322ms","start":"2026-06-09T23:12:09.783434Z","end":"2026-06-09T23:12:09.942128Z","steps":["trace[1817366936] 'process raft request' (duration: 158.570358ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:09.942425Z","caller":"traceutil/trace.go:171","msg":"trace[843322877] transaction","detail":"{read_only:false; response_revision:25382; number_of_response:1; }","duration":"178.165029ms","start":"2026-06-09T23:12:09.764242Z","end":"2026-06-09T23:12:09.942407Z","steps":["trace[843322877] 'process raft request' (duration: 73.378279ms)","trace[843322877] 'compare' (duration: 103.937347ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:10.497483Z","caller":"traceutil/trace.go:171","msg":"trace[1637815975] transaction","detail":"{read_only:false; response_revision:25385; number_of_response:1; }","duration":"126.201301ms","start":"2026-06-09T23:12:10.371262Z","end":"2026-06-09T23:12:10.497463Z","steps":["trace[1637815975] 'process raft request' (duration: 126.048787ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:10.644301Z","caller":"traceutil/trace.go:171","msg":"trace[178028632] transaction","detail":"{read_only:false; response_revision:25386; number_of_response:1; }","duration":"211.4217ms","start":"2026-06-09T23:12:10.432644Z","end":"2026-06-09T23:12:10.644066Z","steps":["trace[178028632] 'process raft request' (duration: 135.214054ms)","trace[178028632] 'compare' (duration: 75.941399ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:10.689106Z","caller":"traceutil/trace.go:171","msg":"trace[1564181177] transaction","detail":"{read_only:false; response_revision:25388; number_of_response:1; }","duration":"126.053696ms","start":"2026-06-09T23:12:10.563031Z","end":"2026-06-09T23:12:10.689085Z","steps":["trace[1564181177] 'process raft request' (duration: 126.011055ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:10.689156Z","caller":"traceutil/trace.go:171","msg":"trace[1148323628] transaction","detail":"{read_only:false; response_revision:25387; number_of_response:1; }","duration":"144.068184ms","start":"2026-06-09T23:12:10.545062Z","end":"2026-06-09T23:12:10.689131Z","steps":["trace[1148323628] 'process raft request' (duration: 143.857668ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:10.801113Z","caller":"traceutil/trace.go:171","msg":"trace[481451387] linearizableReadLoop","detail":"{readStateIndex:26209; appliedIndex:26206; }","duration":"192.878986ms","start":"2026-06-09T23:12:10.608215Z","end":"2026-06-09T23:12:10.801094Z","steps":["trace[481451387] 'read index received' (duration: 80.747477ms)","trace[481451387] 'applied index is now lower than readState.Index' (duration: 112.130269ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:10.801241Z","caller":"traceutil/trace.go:171","msg":"trace[79519897] transaction","detail":"{read_only:false; response_revision:25389; number_of_response:1; }","duration":"221.335858ms","start":"2026-06-09T23:12:10.579875Z","end":"2026-06-09T23:12:10.801211Z","steps":["trace[79519897] 'process raft request' (duration: 184.654764ms)","trace[79519897] 'compare' (duration: 36.464918ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:12:10.801273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.044401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T23:12:10.801303Z","caller":"traceutil/trace.go:171","msg":"trace[288337302] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:25389; }","duration":"193.126103ms","start":"2026-06-09T23:12:10.608166Z","end":"2026-06-09T23:12:10.801292Z","steps":["trace[288337302] 'agreement among raft nodes before linearized reading' (duration: 193.031531ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:11.445363Z","caller":"traceutil/trace.go:171","msg":"trace[983798214] transaction","detail":"{read_only:false; response_revision:25393; number_of_response:1; }","duration":"101.963813ms","start":"2026-06-09T23:12:11.343356Z","end":"2026-06-09T23:12:11.44532Z","steps":["trace[983798214] 'process raft request' (duration: 101.568713ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:22.930746Z","caller":"traceutil/trace.go:171","msg":"trace[472096074] transaction","detail":"{read_only:false; response_revision:25475; number_of_response:1; }","duration":"200.971616ms","start":"2026-06-09T23:12:22.729736Z","end":"2026-06-09T23:12:22.930708Z","steps":["trace[472096074] 'process raft request' (duration: 138.423811ms)","trace[472096074] 'compare' (duration: 62.298218ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:23.165768Z","caller":"traceutil/trace.go:171","msg":"trace[1057525350] linearizableReadLoop","detail":"{readStateIndex:26300; appliedIndex:26299; }","duration":"137.314072ms","start":"2026-06-09T23:12:23.028413Z","end":"2026-06-09T23:12:23.165727Z","steps":["trace[1057525350] 'read index received' (duration: 98.014577ms)","trace[1057525350] 'applied index is now lower than readState.Index' (duration: 39.297925ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:23.165824Z","caller":"traceutil/trace.go:171","msg":"trace[502344953] transaction","detail":"{read_only:false; response_revision:25478; number_of_response:1; }","duration":"153.18156ms","start":"2026-06-09T23:12:23.012597Z","end":"2026-06-09T23:12:23.165779Z","steps":["trace[502344953] 'process raft request' (duration: 113.811133ms)","trace[502344953] 'compare' (duration: 38.404271ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:12:23.165976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.543298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:12:23.16607Z","caller":"traceutil/trace.go:171","msg":"trace[810558693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25478; }","duration":"137.666831ms","start":"2026-06-09T23:12:23.028379Z","end":"2026-06-09T23:12:23.166045Z","steps":["trace[810558693] 'agreement among raft nodes before linearized reading' (duration: 137.496737ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:27.903471Z","caller":"traceutil/trace.go:171","msg":"trace[118971354] transaction","detail":"{read_only:false; response_revision:25510; number_of_response:1; }","duration":"126.047966ms","start":"2026-06-09T23:12:27.777397Z","end":"2026-06-09T23:12:27.903445Z","steps":["trace[118971354] 'process raft request' (duration: 125.619875ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:33.396978Z","caller":"traceutil/trace.go:171","msg":"trace[1261316277] linearizableReadLoop","detail":"{readStateIndex:26381; appliedIndex:26380; }","duration":"204.443975ms","start":"2026-06-09T23:12:33.192498Z","end":"2026-06-09T23:12:33.396942Z","steps":["trace[1261316277] 'read index received' (duration: 204.194748ms)","trace[1261316277] 'applied index is now lower than readState.Index' (duration: 247.617µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:33.397098Z","caller":"traceutil/trace.go:171","msg":"trace[2077151455] transaction","detail":"{read_only:false; response_revision:25557; number_of_response:1; }","duration":"257.163241ms","start":"2026-06-09T23:12:33.139892Z","end":"2026-06-09T23:12:33.397055Z","steps":["trace[2077151455] 'process raft request' (duration: 256.79401ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:12:33.397198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.657591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:12:33.397251Z","caller":"traceutil/trace.go:171","msg":"trace[1162609389] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:25557; }","duration":"204.780855ms","start":"2026-06-09T23:12:33.192455Z","end":"2026-06-09T23:12:33.397236Z","steps":["trace[1162609389] 'agreement among raft nodes before linearized reading' (duration: 204.652751ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:33.4583Z","caller":"traceutil/trace.go:171","msg":"trace[209623711] transaction","detail":"{read_only:false; response_revision:25558; number_of_response:1; }","duration":"249.28338ms","start":"2026-06-09T23:12:33.20897Z","end":"2026-06-09T23:12:33.458253Z","steps":["trace[209623711] 'process raft request' (duration: 236.837379ms)","trace[209623711] 'compare' (duration: 12.06087ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:33.549376Z","caller":"traceutil/trace.go:171","msg":"trace[1406446074] linearizableReadLoop","detail":"{readStateIndex:26383; appliedIndex:26381; }","duration":"152.268125ms","start":"2026-06-09T23:12:33.397069Z","end":"2026-06-09T23:12:33.549337Z","steps":["trace[1406446074] 'read index received' (duration: 48.711014ms)","trace[1406446074] 'applied index is now lower than readState.Index' (duration: 103.554711ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:12:33.549585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.30547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-09T23:12:33.549646Z","caller":"traceutil/trace.go:171","msg":"trace[1736765243] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25558; }","duration":"338.398923ms","start":"2026-06-09T23:12:33.211233Z","end":"2026-06-09T23:12:33.549632Z","steps":["trace[1736765243] 'agreement among raft nodes before linearized reading' (duration: 338.231138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:12:33.549675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:12:33.211226Z","time spent":"338.441354ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-09T23:12:33.549876Z","caller":"traceutil/trace.go:171","msg":"trace[455045764] transaction","detail":"{read_only:false; response_revision:25559; number_of_response:1; }","duration":"145.466762ms","start":"2026-06-09T23:12:33.4044Z","end":"2026-06-09T23:12:33.549866Z","steps":["trace[455045764] 'process raft request' (duration: 144.731862ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:33.549972Z","caller":"traceutil/trace.go:171","msg":"trace[1196387001] transaction","detail":"{read_only:false; response_revision:25560; number_of_response:1; }","duration":"145.142153ms","start":"2026-06-09T23:12:33.404824Z","end":"2026-06-09T23:12:33.549966Z","steps":["trace[1196387001] 'process raft request' (duration: 144.796414ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:12:33.549879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.444938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:208"} {"level":"info","ts":"2026-06-09T23:12:33.550136Z","caller":"traceutil/trace.go:171","msg":"trace[410469276] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:25560; }","duration":"147.768027ms","start":"2026-06-09T23:12:33.402349Z","end":"2026-06-09T23:12:33.550117Z","steps":["trace[410469276] 'agreement among raft nodes before linearized reading' (duration: 147.351296ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:33.684546Z","caller":"traceutil/trace.go:171","msg":"trace[1923530906] transaction","detail":"{read_only:false; response_revision:25561; number_of_response:1; }","duration":"130.17367ms","start":"2026-06-09T23:12:33.554334Z","end":"2026-06-09T23:12:33.684508Z","steps":["trace[1923530906] 'process raft request' (duration: 129.578293ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:33.968282Z","caller":"traceutil/trace.go:171","msg":"trace[142131077] transaction","detail":"{read_only:false; response_revision:25562; number_of_response:1; }","duration":"408.117518ms","start":"2026-06-09T23:12:33.560126Z","end":"2026-06-09T23:12:33.968244Z","steps":["trace[142131077] 'process raft request' (duration: 357.681076ms)","trace[142131077] 'compare' (duration: 50.208626ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:33.968311Z","caller":"traceutil/trace.go:171","msg":"trace[1587570298] transaction","detail":"{read_only:false; response_revision:25563; number_of_response:1; }","duration":"396.830139ms","start":"2026-06-09T23:12:33.571453Z","end":"2026-06-09T23:12:33.968283Z","steps":["trace[1587570298] 'process raft request' (duration: 396.722546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:12:33.968488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:12:33.571437Z","time spent":"396.963544ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T23:12:33.96849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:12:33.560104Z","time spent":"408.291733ms","remote":"127.0.0.1:53700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2789,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T23:12:33.968714Z","caller":"traceutil/trace.go:171","msg":"trace[2083600076] transaction","detail":"{read_only:false; response_revision:25564; number_of_response:1; }","duration":"225.747556ms","start":"2026-06-09T23:12:33.742913Z","end":"2026-06-09T23:12:33.968661Z","steps":["trace[2083600076] 'process raft request' (duration: 225.324554ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:34.173828Z","caller":"traceutil/trace.go:171","msg":"trace[1048401904] linearizableReadLoop","detail":"{readStateIndex:26391; appliedIndex:26390; }","duration":"197.633264ms","start":"2026-06-09T23:12:33.976169Z","end":"2026-06-09T23:12:34.173802Z","steps":["trace[1048401904] 'read index received' (duration: 197.414547ms)","trace[1048401904] 'applied index is now lower than readState.Index' (duration: 217.647µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:12:34.17404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.325371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:12:34.174074Z","caller":"traceutil/trace.go:171","msg":"trace[507546405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25566; }","duration":"138.437465ms","start":"2026-06-09T23:12:34.035626Z","end":"2026-06-09T23:12:34.174064Z","steps":["trace[507546405] 'agreement among raft nodes before linearized reading' (duration: 138.343602ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:12:34.174069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.89166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:208"} {"level":"info","ts":"2026-06-09T23:12:34.174118Z","caller":"traceutil/trace.go:171","msg":"trace[1213530324] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:25566; }","duration":"197.988964ms","start":"2026-06-09T23:12:33.976116Z","end":"2026-06-09T23:12:34.174105Z","steps":["trace[1213530324] 'agreement among raft nodes before linearized reading' (duration: 197.84145ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:34.174581Z","caller":"traceutil/trace.go:171","msg":"trace[1915357030] transaction","detail":"{read_only:false; response_revision:25566; number_of_response:1; }","duration":"200.147254ms","start":"2026-06-09T23:12:33.973753Z","end":"2026-06-09T23:12:34.1739Z","steps":["trace[1915357030] 'process raft request' (duration: 199.899107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:12:34.450608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.994494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T23:12:34.450709Z","caller":"traceutil/trace.go:171","msg":"trace[1126469723] transaction","detail":"{read_only:false; response_revision:25567; number_of_response:1; }","duration":"271.887938ms","start":"2026-06-09T23:12:34.178803Z","end":"2026-06-09T23:12:34.450691Z","steps":["trace[1126469723] 'process raft request' (duration: 129.740679ms)","trace[1126469723] 'compare' (duration: 141.724816ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:34.469451Z","caller":"traceutil/trace.go:171","msg":"trace[1224115374] linearizableReadLoop","detail":"{readStateIndex:26393; appliedIndex:26391; }","duration":"279.232084ms","start":"2026-06-09T23:12:34.190198Z","end":"2026-06-09T23:12:34.46943Z","steps":["trace[1224115374] 'read index received' (duration: 118.359357ms)","trace[1224115374] 'applied index is now lower than readState.Index' (duration: 160.871967ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:34.469544Z","caller":"traceutil/trace.go:171","msg":"trace[910091190] transaction","detail":"{read_only:false; response_revision:25568; number_of_response:1; }","duration":"286.983612ms","start":"2026-06-09T23:12:34.182537Z","end":"2026-06-09T23:12:34.469521Z","steps":["trace[910091190] 'process raft request' (duration: 286.767326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:12:34.469632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.418469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-06-09T23:12:34.469658Z","caller":"traceutil/trace.go:171","msg":"trace[42984037] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25568; }","duration":"279.485661ms","start":"2026-06-09T23:12:34.190163Z","end":"2026-06-09T23:12:34.469649Z","steps":["trace[42984037] 'agreement among raft nodes before linearized reading' (duration: 279.371668ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:12:34.469666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.162602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/ports/\" range_end:\"/registry/openstack.k-orc.cloud/ports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:12:34.469734Z","caller":"traceutil/trace.go:171","msg":"trace[1243216091] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/ports/; range_end:/registry/openstack.k-orc.cloud/ports0; response_count:0; response_revision:25568; }","duration":"273.285427ms","start":"2026-06-09T23:12:34.196429Z","end":"2026-06-09T23:12:34.469714Z","steps":["trace[1243216091] 'agreement among raft nodes before linearized reading' (duration: 273.164383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:12:34.469885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.116053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:12:34.469914Z","caller":"traceutil/trace.go:171","msg":"trace[1820027660] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:25568; }","duration":"122.190555ms","start":"2026-06-09T23:12:34.347714Z","end":"2026-06-09T23:12:34.469904Z","steps":["trace[1820027660] 'agreement among raft nodes before linearized reading' (duration: 122.137433ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:12:34.786274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.811707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T23:12:34.786375Z","caller":"traceutil/trace.go:171","msg":"trace[1927956284] linearizableReadLoop","detail":"{readStateIndex:26394; appliedIndex:26393; }","duration":"289.651638ms","start":"2026-06-09T23:12:34.496708Z","end":"2026-06-09T23:12:34.78636Z","steps":["trace[1927956284] 'read index received' (duration: 118.673636ms)","trace[1927956284] 'applied index is now lower than readState.Index' (duration: 170.976892ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:34.786438Z","caller":"traceutil/trace.go:171","msg":"trace[806117503] transaction","detail":"{read_only:false; response_revision:25569; number_of_response:1; }","duration":"311.925616ms","start":"2026-06-09T23:12:34.474501Z","end":"2026-06-09T23:12:34.786427Z","steps":["trace[806117503] 'process raft request' (duration: 140.879522ms)","trace[806117503] 'compare' (duration: 170.710084ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:12:34.786656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:12:34.473992Z","time spent":"312.627565ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T23:12:34.786635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.920046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-06-09T23:12:34.786734Z","caller":"traceutil/trace.go:171","msg":"trace[1399739518] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25569; }","duration":"290.08779ms","start":"2026-06-09T23:12:34.496621Z","end":"2026-06-09T23:12:34.786709Z","steps":["trace[1399739518] 'agreement among raft nodes before linearized reading' (duration: 289.825912ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:12:34.883279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.633528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-09T23:12:34.883399Z","caller":"traceutil/trace.go:171","msg":"trace[612822232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25570; }","duration":"178.799102ms","start":"2026-06-09T23:12:34.704584Z","end":"2026-06-09T23:12:34.883383Z","steps":["trace[612822232] 'agreement among raft nodes before linearized reading' (duration: 178.563546ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:34.883451Z","caller":"traceutil/trace.go:171","msg":"trace[1194602547] transaction","detail":"{read_only:false; response_revision:25570; number_of_response:1; }","duration":"128.74591ms","start":"2026-06-09T23:12:34.754678Z","end":"2026-06-09T23:12:34.883424Z","steps":["trace[1194602547] 'process raft request' (duration: 128.307898ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:12:35.214418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.83593ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T23:12:35.214734Z","caller":"traceutil/trace.go:171","msg":"trace[553326109] transaction","detail":"{read_only:false; response_revision:25571; number_of_response:1; }","duration":"326.944039ms","start":"2026-06-09T23:12:34.887764Z","end":"2026-06-09T23:12:35.214708Z","steps":["trace[553326109] 'process raft request' (duration: 118.718848ms)","trace[553326109] 'compare' (duration: 207.324106ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:12:35.214878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T23:12:34.887736Z","time spent":"327.101113ms","remote":"127.0.0.1:53482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T23:12:35.263587Z","caller":"traceutil/trace.go:171","msg":"trace[1187563859] transaction","detail":"{read_only:false; response_revision:25572; number_of_response:1; }","duration":"285.779309ms","start":"2026-06-09T23:12:34.977784Z","end":"2026-06-09T23:12:35.263563Z","steps":["trace[1187563859] 'process raft request' (duration: 285.570813ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:35.263891Z","caller":"traceutil/trace.go:171","msg":"trace[98291060] linearizableReadLoop","detail":"{readStateIndex:26399; appliedIndex:26395; }","duration":"259.90607ms","start":"2026-06-09T23:12:35.003958Z","end":"2026-06-09T23:12:35.263864Z","steps":["trace[98291060] 'read index received' (duration: 2.592584ms)","trace[98291060] 'applied index is now lower than readState.Index' (duration: 257.312566ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:35.263945Z","caller":"traceutil/trace.go:171","msg":"trace[1374975213] transaction","detail":"{read_only:false; response_revision:25573; number_of_response:1; }","duration":"275.988753ms","start":"2026-06-09T23:12:34.987849Z","end":"2026-06-09T23:12:35.263837Z","steps":["trace[1374975213] 'process raft request' (duration: 275.642574ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:35.264093Z","caller":"traceutil/trace.go:171","msg":"trace[1908287147] transaction","detail":"{read_only:false; response_revision:25574; number_of_response:1; }","duration":"275.170399ms","start":"2026-06-09T23:12:34.988774Z","end":"2026-06-09T23:12:35.263944Z","steps":["trace[1908287147] 'process raft request' (duration: 274.952233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:12:35.264241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.259909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-09T23:12:35.264368Z","caller":"traceutil/trace.go:171","msg":"trace[10677225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25574; }","duration":"260.394854ms","start":"2026-06-09T23:12:35.003923Z","end":"2026-06-09T23:12:35.264318Z","steps":["trace[10677225] 'agreement among raft nodes before linearized reading' (duration: 260.037974ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:35.458434Z","caller":"traceutil/trace.go:171","msg":"trace[444425419] transaction","detail":"{read_only:false; response_revision:25576; number_of_response:1; }","duration":"162.234975ms","start":"2026-06-09T23:12:35.296175Z","end":"2026-06-09T23:12:35.45841Z","steps":["trace[444425419] 'process raft request' (duration: 88.067344ms)","trace[444425419] 'compare' (duration: 73.707958ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:12:35.92091Z","caller":"traceutil/trace.go:171","msg":"trace[911969499] transaction","detail":"{read_only:false; response_revision:25578; number_of_response:1; }","duration":"149.425263ms","start":"2026-06-09T23:12:35.771459Z","end":"2026-06-09T23:12:35.920884Z","steps":["trace[911969499] 'process raft request' (duration: 149.252318ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:55.544148Z","caller":"traceutil/trace.go:171","msg":"trace[1310484939] transaction","detail":"{read_only:false; response_revision:25859; number_of_response:1; }","duration":"212.40627ms","start":"2026-06-09T23:12:55.331691Z","end":"2026-06-09T23:12:55.544097Z","steps":["trace[1310484939] 'process raft request' (duration: 212.123933ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:55.544185Z","caller":"traceutil/trace.go:171","msg":"trace[2100697524] transaction","detail":"{read_only:false; response_revision:25860; number_of_response:1; }","duration":"207.121301ms","start":"2026-06-09T23:12:55.337033Z","end":"2026-06-09T23:12:55.544154Z","steps":["trace[2100697524] 'process raft request' (duration: 207.041739ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:55.588969Z","caller":"traceutil/trace.go:171","msg":"trace[814830602] transaction","detail":"{read_only:false; response_revision:25861; number_of_response:1; }","duration":"219.431647ms","start":"2026-06-09T23:12:55.369519Z","end":"2026-06-09T23:12:55.58895Z","steps":["trace[814830602] 'process raft request' (duration: 219.144099ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:12:55.588948Z","caller":"traceutil/trace.go:171","msg":"trace[1932218251] linearizableReadLoop","detail":"{readStateIndex:26692; appliedIndex:26689; }","duration":"212.140502ms","start":"2026-06-09T23:12:55.376775Z","end":"2026-06-09T23:12:55.588915Z","steps":["trace[1932218251] 'read index received' (duration: 167.02606ms)","trace[1932218251] 'applied index is now lower than readState.Index' (duration: 45.111152ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T23:12:55.589136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.348609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-09T23:12:55.589356Z","caller":"traceutil/trace.go:171","msg":"trace[122001902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25861; }","duration":"212.605025ms","start":"2026-06-09T23:12:55.376737Z","end":"2026-06-09T23:12:55.589342Z","steps":["trace[122001902] 'agreement among raft nodes before linearized reading' (duration: 212.258955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T23:12:55.59788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.991533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T23:12:55.597918Z","caller":"traceutil/trace.go:171","msg":"trace[1683467529] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25862; }","duration":"192.067136ms","start":"2026-06-09T23:12:55.40584Z","end":"2026-06-09T23:12:55.597907Z","steps":["trace[1683467529] 'agreement among raft nodes before linearized reading' (duration: 191.895661ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:13:09.613001Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068d1.wal"} {"level":"info","ts":"2026-06-09T23:13:25.089612Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23123} {"level":"info","ts":"2026-06-09T23:13:25.224449Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23123,"took":"129.45073ms","hash":1997516547,"current-db-size-bytes":58343424,"current-db-size":"58 MB","current-db-size-in-use-bytes":36061184,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-06-09T23:13:25.224528Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1997516547,"revision":23123,"compact-revision":20150} {"level":"info","ts":"2026-06-09T23:15:44.831771Z","caller":"traceutil/trace.go:171","msg":"trace[1838705722] transaction","detail":"{read_only:false; response_revision:27511; number_of_response:1; }","duration":"140.083346ms","start":"2026-06-09T23:15:44.691653Z","end":"2026-06-09T23:15:44.831736Z","steps":["trace[1838705722] 'process raft request' (duration: 98.608147ms)","trace[1838705722] 'compare' (duration: 41.188301ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T23:15:44.831958Z","caller":"traceutil/trace.go:171","msg":"trace[2057465736] transaction","detail":"{read_only:false; response_revision:27512; number_of_response:1; }","duration":"140.219809ms","start":"2026-06-09T23:15:44.691698Z","end":"2026-06-09T23:15:44.831918Z","steps":["trace[2057465736] 'process raft request' (duration: 139.89741ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T23:18:25.097133Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26113} {"level":"info","ts":"2026-06-09T23:18:25.244342Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26113,"took":"143.49379ms","hash":567534204,"current-db-size-bytes":58343424,"current-db-size":"58 MB","current-db-size-in-use-bytes":34578432,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-09T23:18:25.24456Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":567534204,"revision":26113,"compact-revision":23123} {"level":"info","ts":"2026-06-09T23:19:02.121167Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f3d52791b64f7591","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T23:19:02.127025Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-09T23:19:02.127185Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-09T23:23:25.103688Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28811} {"level":"info","ts":"2026-06-09T23:23:25.243744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28811,"took":"136.727536ms","hash":943663,"current-db-size-bytes":58343424,"current-db-size":"58 MB","current-db-size-in-use-bytes":31576064,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-09T23:23:25.243835Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":943663,"revision":28811,"compact-revision":26113} {"level":"info","ts":"2026-06-09T23:28:25.111087Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31033} {"level":"info","ts":"2026-06-09T23:28:25.247996Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31033,"took":"122.364742ms","hash":760617308,"current-db-size-bytes":58343424,"current-db-size":"58 MB","current-db-size-in-use-bytes":31154176,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-09T23:28:25.248078Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":760617308,"revision":31033,"compact-revision":28811} {"level":"info","ts":"2026-06-09T23:33:25.117289Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33302} {"level":"info","ts":"2026-06-09T23:33:25.267496Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33302,"took":"145.507689ms","hash":3732539132,"current-db-size-bytes":59387904,"current-db-size":"59 MB","current-db-size-in-use-bytes":32133120,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-09T23:33:25.267556Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3732539132,"revision":33302,"compact-revision":31033} {"level":"info","ts":"2026-06-09T23:38:25.123817Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35594} {"level":"info","ts":"2026-06-09T23:38:25.268231Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35594,"took":"139.53759ms","hash":2356677539,"current-db-size-bytes":60493824,"current-db-size":"60 MB","current-db-size-in-use-bytes":31907840,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-09T23:38:25.268363Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2356677539,"revision":35594,"compact-revision":33302} {"level":"info","ts":"2026-06-09T23:40:27.67326Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f3d52791b64f7591","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T23:40:27.677794Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-09T23:40:27.677882Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-09T23:43:25.129972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37820} {"level":"info","ts":"2026-06-09T23:43:25.246441Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37820,"took":"112.135986ms","hash":3398286160,"current-db-size-bytes":60493824,"current-db-size":"60 MB","current-db-size-in-use-bytes":31535104,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-09T23:43:25.246536Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3398286160,"revision":37820,"compact-revision":35594}