{"level":"warn","ts":"2026-05-18T17:46:29.1782Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-05-18T17:46:29.178314Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.156:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.156:2380","--initial-cluster=instance=https://199.19.213.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.156:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]}
{"level":"warn","ts":"2026-05-18T17:46:29.178396Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-05-18T17:46:29.178409Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.156:2380"]}
{"level":"info","ts":"2026-05-18T17:46:29.178431Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-05-18T17:46:29.179282Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.156:2379"]}
{"level":"info","ts":"2026-05-18T17:46:29.179367Z","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.19.213.156:2380"],"listen-peer-urls":["https://199.19.213.156:2380"],"advertise-client-urls":["https://199.19.213.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.156:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"}
{"level":"info","ts":"2026-05-18T17:46:29.183684Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.539637ms"}
{"level":"info","ts":"2026-05-18T17:46:29.19071Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"163ef968c92247ab","cluster-id":"59d11c119c278888"}
{"level":"info","ts":"2026-05-18T17:46:29.191016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab switched to configuration voters=()"}
{"level":"info","ts":"2026-05-18T17:46:29.191042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became follower at term 0"}
{"level":"info","ts":"2026-05-18T17:46:29.191062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 163ef968c92247ab [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-05-18T17:46:29.191076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became follower at term 1"}
{"level":"info","ts":"2026-05-18T17:46:29.191126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab switched to configuration voters=(1602992745836857259)"}
{"level":"warn","ts":"2026-05-18T17:46:29.200633Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-05-18T17:46:29.20207Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-05-18T17:46:29.203823Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"}
{"level":"info","ts":"2026-05-18T17:46:29.206605Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"163ef968c92247ab","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-05-18T17:46:29.206844Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"163ef968c92247ab","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-05-18T17:46:29.20699Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
{"level":"info","ts":"2026-05-18T17:46:29.207126Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
{"level":"info","ts":"2026-05-18T17:46:29.207153Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
{"level":"info","ts":"2026-05-18T17:46:29.208877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab switched to configuration voters=(1602992745836857259)"}
{"level":"info","ts":"2026-05-18T17:46:29.209621Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"59d11c119c278888","local-member-id":"163ef968c92247ab","added-peer-id":"163ef968c92247ab","added-peer-peer-urls":["https://199.19.213.156:2380"]}
{"level":"info","ts":"2026-05-18T17:46:29.211817Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-05-18T17:46:29.212108Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"163ef968c92247ab","initial-advertise-peer-urls":["https://199.19.213.156:2380"],"listen-peer-urls":["https://199.19.213.156:2380"],"advertise-client-urls":["https://199.19.213.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-05-18T17:46:29.212152Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-05-18T17:46:29.212326Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.156:2380"}
{"level":"info","ts":"2026-05-18T17:46:29.212354Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.156:2380"}
{"level":"info","ts":"2026-05-18T17:46:29.492045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab is starting a new election at term 1"}
{"level":"info","ts":"2026-05-18T17:46:29.492119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became pre-candidate at term 1"}
{"level":"info","ts":"2026-05-18T17:46:29.492156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab received MsgPreVoteResp from 163ef968c92247ab at term 1"}
{"level":"info","ts":"2026-05-18T17:46:29.492176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became candidate at term 2"}
{"level":"info","ts":"2026-05-18T17:46:29.492208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab received MsgVoteResp from 163ef968c92247ab at term 2"}
{"level":"info","ts":"2026-05-18T17:46:29.492225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became leader at term 2"}
{"level":"info","ts":"2026-05-18T17:46:29.492244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 163ef968c92247ab elected leader 163ef968c92247ab at term 2"}
{"level":"info","ts":"2026-05-18T17:46:29.494315Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"163ef968c92247ab","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.156:2379]}","request-path":"/0/members/163ef968c92247ab/attributes","cluster-id":"59d11c119c278888","publish-timeout":"7s"}
{"level":"info","ts":"2026-05-18T17:46:29.494358Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-05-18T17:46:29.494412Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-18T17:46:29.494409Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-05-18T17:46:29.494772Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-05-18T17:46:29.494856Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-05-18T17:46:29.496173Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"59d11c119c278888","local-member-id":"163ef968c92247ab","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-18T17:46:29.496308Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-18T17:46:29.496356Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-05-18T17:46:29.496867Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-05-18T17:46:29.497189Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.156:2379"}
{"level":"info","ts":"2026-05-18T17:47:04.382515Z","caller":"traceutil/trace.go:171","msg":"trace[105147181] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"161.54334ms","start":"2026-05-18T17:47:04.220938Z","end":"2026-05-18T17:47:04.382481Z","steps":["trace[105147181] 'process raft request'  (duration: 160.913248ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T17:47:04.554181Z","caller":"traceutil/trace.go:171","msg":"trace[896863532] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"154.943568ms","start":"2026-05-18T17:47:04.399216Z","end":"2026-05-18T17:47:04.55416Z","steps":["trace[896863532] 'process raft request'  (duration: 154.769714ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T17:47:04.580956Z","caller":"traceutil/trace.go:171","msg":"trace[503491600] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"133.073434ms","start":"2026-05-18T17:47:04.447857Z","end":"2026-05-18T17:47:04.58093Z","steps":["trace[503491600] 'process raft request'  (duration: 132.938292ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T17:47:05.038045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.582593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T17:47:05.038139Z","caller":"traceutil/trace.go:171","msg":"trace[1891655379] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"321.744016ms","start":"2026-05-18T17:47:04.71638Z","end":"2026-05-18T17:47:05.038124Z","steps":["trace[1891655379] 'range keys from in-memory index tree'  (duration: 321.493882ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T17:47:05.038121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.272992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"warn","ts":"2026-05-18T17:47:05.038179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T17:47:04.716359Z","time spent":"321.810559ms","remote":"127.0.0.1:40366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-05-18T17:47:05.038206Z","caller":"traceutil/trace.go:171","msg":"trace[1492157088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"147.399855ms","start":"2026-05-18T17:47:04.890779Z","end":"2026-05-18T17:47:05.038179Z","steps":["trace[1492157088] 'range keys from in-memory index tree'  (duration: 147.096889ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T17:48:32.364333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.643117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"warn","ts":"2026-05-18T17:48:32.364386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.892721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-05-18T17:48:32.364454Z","caller":"traceutil/trace.go:171","msg":"trace[1176079850] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1835; }","duration":"122.82271ms","start":"2026-05-18T17:48:32.241604Z","end":"2026-05-18T17:48:32.364427Z","steps":["trace[1176079850] 'range keys from in-memory index tree'  (duration: 122.35862ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T17:48:32.364452Z","caller":"traceutil/trace.go:171","msg":"trace[319899670] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1835; }","duration":"197.006354ms","start":"2026-05-18T17:48:32.167429Z","end":"2026-05-18T17:48:32.364435Z","steps":["trace[319899670] 'count revisions from in-memory index tree'  (duration: 196.80406ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T17:48:32.364444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.866506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"}
{"level":"info","ts":"2026-05-18T17:48:32.364525Z","caller":"traceutil/trace.go:171","msg":"trace[1584078847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1835; }","duration":"289.001689ms","start":"2026-05-18T17:48:32.075503Z","end":"2026-05-18T17:48:32.364505Z","steps":["trace[1584078847] 'range keys from in-memory index tree'  (duration: 288.666092ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T17:48:32.364673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.165941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-05-18T17:48:32.364777Z","caller":"traceutil/trace.go:171","msg":"trace[1435944022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1835; }","duration":"138.337144ms","start":"2026-05-18T17:48:32.226415Z","end":"2026-05-18T17:48:32.364752Z","steps":["trace[1435944022] 'range keys from in-memory index tree'  (duration: 137.944246ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T17:48:47.091203Z","caller":"traceutil/trace.go:171","msg":"trace[1739998848] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"131.033486ms","start":"2026-05-18T17:48:46.960148Z","end":"2026-05-18T17:48:47.091181Z","steps":["trace[1739998848] 'process raft request'  (duration: 130.905664ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T17:49:20.797287Z","caller":"traceutil/trace.go:171","msg":"trace[895192290] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"125.085615ms","start":"2026-05-18T17:49:20.67217Z","end":"2026-05-18T17:49:20.797255Z","steps":["trace[895192290] 'process raft request'  (duration: 100.127169ms)","trace[895192290] 'compare'  (duration: 24.64582ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T17:49:20.797547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.552418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"}
{"level":"info","ts":"2026-05-18T17:49:20.797612Z","caller":"traceutil/trace.go:171","msg":"trace[1916550802] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2150; }","duration":"101.685811ms","start":"2026-05-18T17:49:20.695908Z","end":"2026-05-18T17:49:20.797594Z","steps":["trace[1916550802] 'agreement among raft nodes before linearized reading'  (duration: 101.466657ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T17:49:20.797737Z","caller":"traceutil/trace.go:171","msg":"trace[246069198] linearizableReadLoop","detail":"{readStateIndex:2227; appliedIndex:2226; }","duration":"101.226732ms","start":"2026-05-18T17:49:20.695988Z","end":"2026-05-18T17:49:20.797215Z","steps":["trace[246069198] 'read index received'  (duration: 76.285757ms)","trace[246069198] 'applied index is now lower than readState.Index'  (duration: 24.939375ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T17:49:26.999718Z","caller":"traceutil/trace.go:171","msg":"trace[14820030] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"127.709275ms","start":"2026-05-18T17:49:26.871978Z","end":"2026-05-18T17:49:26.999688Z","steps":["trace[14820030] 'process raft request'  (duration: 127.526071ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T17:49:27.110044Z","caller":"traceutil/trace.go:171","msg":"trace[1343211023] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"124.025957ms","start":"2026-05-18T17:49:26.985979Z","end":"2026-05-18T17:49:27.110005Z","steps":["trace[1343211023] 'process raft request'  (duration: 123.949105ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T17:49:27.110093Z","caller":"traceutil/trace.go:171","msg":"trace[1320285238] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"237.745926ms","start":"2026-05-18T17:49:26.872323Z","end":"2026-05-18T17:49:27.110069Z","steps":["trace[1320285238] 'process raft request'  (duration: 226.330259ms)","trace[1320285238] 'compare'  (duration: 11.095131ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T17:49:27.113978Z","caller":"traceutil/trace.go:171","msg":"trace[1249763964] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"102.483376ms","start":"2026-05-18T17:49:27.011471Z","end":"2026-05-18T17:49:27.113954Z","steps":["trace[1249763964] 'process raft request'  (duration: 102.15175ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T17:50:11.466015Z","caller":"traceutil/trace.go:171","msg":"trace[971900593] transaction","detail":"{read_only:false; response_revision:2597; number_of_response:1; }","duration":"166.542682ms","start":"2026-05-18T17:50:11.299436Z","end":"2026-05-18T17:50:11.465979Z","steps":["trace[971900593] 'process raft request'  (duration: 166.049123ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T17:50:44.683517Z","caller":"traceutil/trace.go:171","msg":"trace[1844126465] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"118.674813ms","start":"2026-05-18T17:50:44.56482Z","end":"2026-05-18T17:50:44.683495Z","steps":["trace[1844126465] 'process raft request'  (duration: 118.551171ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T17:54:36.853912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.070947ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089338919 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/cilium.io/ciliumidentities/58298\" mod_revision:0 > success:<request_put:<key:\"/registry/cilium.io/ciliumidentities/58298\" value_size:1763 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-05-18T17:54:36.854031Z","caller":"traceutil/trace.go:171","msg":"trace[1515470025] linearizableReadLoop","detail":"{readStateIndex:5146; appliedIndex:5145; }","duration":"181.233522ms","start":"2026-05-18T17:54:36.67278Z","end":"2026-05-18T17:54:36.854014Z","steps":["trace[1515470025] 'read index received'  (duration: 77.498454ms)","trace[1515470025] 'applied index is now lower than readState.Index'  (duration: 103.733778ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T17:54:36.854124Z","caller":"traceutil/trace.go:171","msg":"trace[1262538393] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"244.70081ms","start":"2026-05-18T17:54:36.609406Z","end":"2026-05-18T17:54:36.854107Z","steps":["trace[1262538393] 'process raft request'  (duration: 140.87357ms)","trace[1262538393] 'compare'  (duration: 102.930963ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T17:54:36.854279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.275754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-05-18T17:54:36.854335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.820284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wb6mf\" ","response":"range_response_count:1 size:6451"}
{"level":"info","ts":"2026-05-18T17:54:36.854374Z","caller":"traceutil/trace.go:171","msg":"trace[1720027312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4960; }","duration":"135.414157ms","start":"2026-05-18T17:54:36.71894Z","end":"2026-05-18T17:54:36.854354Z","steps":["trace[1720027312] 'agreement among raft nodes before linearized reading'  (duration: 135.200383ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T17:54:36.854402Z","caller":"traceutil/trace.go:171","msg":"trace[201680810] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wb6mf; range_end:; response_count:1; response_revision:4960; }","duration":"100.939546ms","start":"2026-05-18T17:54:36.753445Z","end":"2026-05-18T17:54:36.854384Z","steps":["trace[201680810] 'agreement among raft nodes before linearized reading'  (duration: 100.701752ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T17:54:36.854387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.965511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c41e09d2-e29e-4a3b-b9cc-0a60643f5927\" ","response":"range_response_count:1 size:3721"}
{"level":"warn","ts":"2026-05-18T17:54:36.854426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.619009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-05-18T17:54:36.854511Z","caller":"traceutil/trace.go:171","msg":"trace[1968782208] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c41e09d2-e29e-4a3b-b9cc-0a60643f5927; range_end:; response_count:1; response_revision:4960; }","duration":"106.125334ms","start":"2026-05-18T17:54:36.748343Z","end":"2026-05-18T17:54:36.854469Z","steps":["trace[1968782208] 'agreement among raft nodes before linearized reading'  (duration: 105.815228ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T17:54:36.854525Z","caller":"traceutil/trace.go:171","msg":"trace[327068442] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4960; }","duration":"181.777952ms","start":"2026-05-18T17:54:36.672727Z","end":"2026-05-18T17:54:36.854505Z","steps":["trace[327068442] 'agreement among raft nodes before linearized reading'  (duration: 181.447976ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T17:54:54.351275Z","caller":"traceutil/trace.go:171","msg":"trace[573942254] transaction","detail":"{read_only:false; response_revision:5333; number_of_response:1; }","duration":"154.596419ms","start":"2026-05-18T17:54:54.196645Z","end":"2026-05-18T17:54:54.351241Z","steps":["trace[573942254] 'process raft request'  (duration: 64.823954ms)","trace[573942254] 'compare'  (duration: 89.366297ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T17:54:54.351333Z","caller":"traceutil/trace.go:171","msg":"trace[541077861] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"150.789607ms","start":"2026-05-18T17:54:54.200514Z","end":"2026-05-18T17:54:54.351304Z","steps":["trace[541077861] 'process raft request'  (duration: 150.486672ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T17:54:54.690285Z","caller":"traceutil/trace.go:171","msg":"trace[999747373] linearizableReadLoop","detail":"{readStateIndex:5533; appliedIndex:5532; }","duration":"142.232766ms","start":"2026-05-18T17:54:54.548027Z","end":"2026-05-18T17:54:54.69026Z","steps":["trace[999747373] 'read index received'  (duration: 142.121184ms)","trace[999747373] 'applied index is now lower than readState.Index'  (duration: 110.552µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T17:54:54.69033Z","caller":"traceutil/trace.go:171","msg":"trace[1848846643] transaction","detail":"{read_only:false; response_revision:5336; number_of_response:1; }","duration":"214.090062ms","start":"2026-05-18T17:54:54.476204Z","end":"2026-05-18T17:54:54.690294Z","steps":["trace[1848846643] 'process raft request'  (duration: 213.884119ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T17:54:54.690518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.4869ms","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-05-18T17:54:54.690566Z","caller":"traceutil/trace.go:171","msg":"trace[1471747277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5336; }","duration":"142.610682ms","start":"2026-05-18T17:54:54.547943Z","end":"2026-05-18T17:54:54.690554Z","steps":["trace[1471747277] 'agreement among raft nodes before linearized reading'  (duration: 142.442789ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T17:54:54.827586Z","caller":"traceutil/trace.go:171","msg":"trace[993670223] linearizableReadLoop","detail":"{readStateIndex:5535; appliedIndex:5534; }","duration":"108.405877ms","start":"2026-05-18T17:54:54.719154Z","end":"2026-05-18T17:54:54.82756Z","steps":["trace[993670223] 'read index received'  (duration: 6.408731ms)","trace[993670223] 'applied index is now lower than readState.Index'  (duration: 101.995826ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T17:54:54.827657Z","caller":"traceutil/trace.go:171","msg":"trace[584185822] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"133.137254ms","start":"2026-05-18T17:54:54.694473Z","end":"2026-05-18T17:54:54.82761Z","steps":["trace[584185822] 'process raft request'  (duration: 73.801394ms)","trace[584185822] 'compare'  (duration: 59.175477ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T17:54:54.82776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.59178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T17:54:54.82782Z","caller":"traceutil/trace.go:171","msg":"trace[893813401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5338; }","duration":"108.696653ms","start":"2026-05-18T17:54:54.71911Z","end":"2026-05-18T17:54:54.827807Z","steps":["trace[893813401] 'agreement among raft nodes before linearized reading'  (duration: 108.55883ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T17:56:29.853464Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3047}
{"level":"info","ts":"2026-05-18T17:56:29.933596Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3047,"took":"78.616824ms","hash":2371699138,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":32280576,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-05-18T17:56:29.933674Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2371699138,"revision":3047,"compact-revision":-1}
{"level":"info","ts":"2026-05-18T18:01:20.008779Z","caller":"traceutil/trace.go:171","msg":"trace[1361127365] transaction","detail":"{read_only:false; response_revision:8269; number_of_response:1; }","duration":"218.460004ms","start":"2026-05-18T18:01:19.790294Z","end":"2026-05-18T18:01:20.008754Z","steps":["trace[1361127365] 'process raft request'  (duration: 218.303252ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:01:20.175414Z","caller":"traceutil/trace.go:171","msg":"trace[507688079] transaction","detail":"{read_only:false; response_revision:8271; number_of_response:1; }","duration":"199.749601ms","start":"2026-05-18T18:01:19.975648Z","end":"2026-05-18T18:01:20.175398Z","steps":["trace[507688079] 'process raft request'  (duration: 199.423205ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:01:20.175406Z","caller":"traceutil/trace.go:171","msg":"trace[689982617] transaction","detail":"{read_only:false; response_revision:8270; number_of_response:1; }","duration":"200.019895ms","start":"2026-05-18T18:01:19.975362Z","end":"2026-05-18T18:01:20.175382Z","steps":["trace[689982617] 'process raft request'  (duration: 157.86091ms)","trace[689982617] 'compare'  (duration: 41.618805ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:01:29.859737Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6202}
{"level":"info","ts":"2026-05-18T18:01:29.953323Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6202,"took":"91.738162ms","hash":3663428949,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":22581248,"current-db-size-in-use":"23 MB"}
{"level":"info","ts":"2026-05-18T18:01:29.953393Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3663428949,"revision":6202,"compact-revision":3047}
{"level":"info","ts":"2026-05-18T18:01:32.523157Z","caller":"traceutil/trace.go:171","msg":"trace[72883467] linearizableReadLoop","detail":"{readStateIndex:8629; appliedIndex:8628; }","duration":"298.683619ms","start":"2026-05-18T18:01:32.224436Z","end":"2026-05-18T18:01:32.523119Z","steps":["trace[72883467] 'read index received'  (duration: 22.676128ms)","trace[72883467] 'applied index is now lower than readState.Index'  (duration: 276.006231ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:01:32.523205Z","caller":"traceutil/trace.go:171","msg":"trace[1729879889] transaction","detail":"{read_only:false; response_revision:8335; number_of_response:1; }","duration":"305.65931ms","start":"2026-05-18T18:01:32.217508Z","end":"2026-05-18T18:01:32.523167Z","steps":["trace[1729879889] 'process raft request'  (duration: 305.381744ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:01:32.523406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.949224ms","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-05-18T18:01:32.523464Z","caller":"traceutil/trace.go:171","msg":"trace[2071901945] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8335; }","duration":"299.040345ms","start":"2026-05-18T18:01:32.224405Z","end":"2026-05-18T18:01:32.523445Z","steps":["trace[2071901945] 'agreement among raft nodes before linearized reading'  (duration: 298.840561ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:01:32.523613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:01:32.217475Z","time spent":"305.839233ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:8324 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"warn","ts":"2026-05-18T18:01:32.94956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.880496ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089347567 username:\"kube-apiserver-etcd-client\" auth_revision:1 > lease_grant:<ttl:15-second id:47ab9e3c324a13ee>","response":"size:39"}
{"level":"info","ts":"2026-05-18T18:02:00.851694Z","caller":"traceutil/trace.go:171","msg":"trace[1676178480] linearizableReadLoop","detail":"{readStateIndex:8843; appliedIndex:8842; }","duration":"185.988431ms","start":"2026-05-18T18:02:00.665682Z","end":"2026-05-18T18:02:00.85167Z","steps":["trace[1676178480] 'read index received'  (duration: 185.873289ms)","trace[1676178480] 'applied index is now lower than readState.Index'  (duration: 113.832µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:02:00.851799Z","caller":"traceutil/trace.go:171","msg":"trace[475727353] transaction","detail":"{read_only:false; response_revision:8540; number_of_response:1; }","duration":"239.812496ms","start":"2026-05-18T18:02:00.611952Z","end":"2026-05-18T18:02:00.851765Z","steps":["trace[475727353] 'process raft request'  (duration: 239.556522ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:02:00.851912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.209415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-05-18T18:02:00.851953Z","caller":"traceutil/trace.go:171","msg":"trace[1619084791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8540; }","duration":"186.301007ms","start":"2026-05-18T18:02:00.665641Z","end":"2026-05-18T18:02:00.851942Z","steps":["trace[1619084791] 'agreement among raft nodes before linearized reading'  (duration: 186.128774ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:02:13.470003Z","caller":"traceutil/trace.go:171","msg":"trace[1091713237] transaction","detail":"{read_only:false; response_revision:8652; number_of_response:1; }","duration":"262.74368ms","start":"2026-05-18T18:02:13.20722Z","end":"2026-05-18T18:02:13.469963Z","steps":["trace[1091713237] 'process raft request'  (duration: 187.509389ms)","trace[1091713237] 'compare'  (duration: 74.777001ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:02:13.512171Z","caller":"traceutil/trace.go:171","msg":"trace[675920729] transaction","detail":"{read_only:false; response_revision:8653; number_of_response:1; }","duration":"257.895198ms","start":"2026-05-18T18:02:13.254251Z","end":"2026-05-18T18:02:13.512146Z","steps":["trace[675920729] 'process raft request'  (duration: 257.352948ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:02:14.173587Z","caller":"traceutil/trace.go:171","msg":"trace[1316678751] transaction","detail":"{read_only:false; response_revision:8661; number_of_response:1; }","duration":"105.186876ms","start":"2026-05-18T18:02:14.068375Z","end":"2026-05-18T18:02:14.173562Z","steps":["trace[1316678751] 'process raft request'  (duration: 105.024923ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:03:03.368425Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024ee.wal"}
{"level":"info","ts":"2026-05-18T18:03:17.480921Z","caller":"traceutil/trace.go:171","msg":"trace[179888251] transaction","detail":"{read_only:false; response_revision:9326; number_of_response:1; }","duration":"100.323734ms","start":"2026-05-18T18:03:17.380561Z","end":"2026-05-18T18:03:17.480885Z","steps":["trace[179888251] 'process raft request'  (duration: 67.700198ms)","trace[179888251] 'compare'  (duration: 32.448703ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:04:06.217259Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"163ef968c92247ab","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-05-18T18:04:06.222332Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-05-18T18:04:06.222418Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"info","ts":"2026-05-18T18:06:29.866835Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8322}
{"level":"info","ts":"2026-05-18T18:06:29.9483Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8322,"took":"79.341619ms","hash":643053027,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":25460736,"current-db-size-in-use":"26 MB"}
{"level":"info","ts":"2026-05-18T18:06:29.948385Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":643053027,"revision":8322,"compact-revision":6202}
{"level":"info","ts":"2026-05-18T18:07:32.920994Z","caller":"traceutil/trace.go:171","msg":"trace[1009719320] linearizableReadLoop","detail":"{readStateIndex:11730; appliedIndex:11729; }","duration":"103.933732ms","start":"2026-05-18T18:07:32.817041Z","end":"2026-05-18T18:07:32.920974Z","steps":["trace[1009719320] 'read index received'  (duration: 33.811108ms)","trace[1009719320] 'applied index is now lower than readState.Index'  (duration: 70.121974ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:07:32.921172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.129335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.156\" ","response":"range_response_count:1 size:135"}
{"level":"info","ts":"2026-05-18T18:07:32.921158Z","caller":"traceutil/trace.go:171","msg":"trace[953779978] transaction","detail":"{read_only:false; response_revision:11344; number_of_response:1; }","duration":"132.769877ms","start":"2026-05-18T18:07:32.788348Z","end":"2026-05-18T18:07:32.921118Z","steps":["trace[953779978] 'process raft request'  (duration: 62.48206ms)","trace[953779978] 'compare'  (duration: 69.95542ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:07:32.921394Z","caller":"traceutil/trace.go:171","msg":"trace[919314894] range","detail":"{range_begin:/registry/masterleases/199.19.213.156; range_end:; response_count:1; response_revision:11344; }","duration":"104.391201ms","start":"2026-05-18T18:07:32.816995Z","end":"2026-05-18T18:07:32.921386Z","steps":["trace[919314894] 'agreement among raft nodes before linearized reading'  (duration: 104.061865ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:07:33.136446Z","caller":"traceutil/trace.go:171","msg":"trace[1794577981] transaction","detail":"{read_only:false; response_revision:11346; number_of_response:1; }","duration":"180.421826ms","start":"2026-05-18T18:07:32.95599Z","end":"2026-05-18T18:07:33.136412Z","steps":["trace[1794577981] 'process raft request'  (duration: 131.987661ms)","trace[1794577981] 'compare'  (duration: 48.041268ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:07:33.136511Z","caller":"traceutil/trace.go:171","msg":"trace[163765320] transaction","detail":"{read_only:false; response_revision:11347; number_of_response:1; }","duration":"179.746102ms","start":"2026-05-18T18:07:32.956723Z","end":"2026-05-18T18:07:33.136469Z","steps":["trace[163765320] 'process raft request'  (duration: 179.61018ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:07:33.136474Z","caller":"traceutil/trace.go:171","msg":"trace[1681328346] linearizableReadLoop","detail":"{readStateIndex:11734; appliedIndex:11732; }","duration":"121.885201ms","start":"2026-05-18T18:07:33.014569Z","end":"2026-05-18T18:07:33.136454Z","steps":["trace[1681328346] 'read index received'  (duration: 73.347134ms)","trace[1681328346] 'applied index is now lower than readState.Index'  (duration: 48.536637ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:07:33.136631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.039724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T18:07:33.136683Z","caller":"traceutil/trace.go:171","msg":"trace[103142972] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:11347; }","duration":"122.139206ms","start":"2026-05-18T18:07:33.014531Z","end":"2026-05-18T18:07:33.13667Z","steps":["trace[103142972] 'agreement among raft nodes before linearized reading'  (duration: 121.982843ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:10:11.525288Z","caller":"traceutil/trace.go:171","msg":"trace[444857320] linearizableReadLoop","detail":"{readStateIndex:12819; appliedIndex:12818; }","duration":"134.385887ms","start":"2026-05-18T18:10:11.390875Z","end":"2026-05-18T18:10:11.525261Z","steps":["trace[444857320] 'read index received'  (duration: 133.905748ms)","trace[444857320] 'applied index is now lower than readState.Index'  (duration: 479.079µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:10:11.525367Z","caller":"traceutil/trace.go:171","msg":"trace[109430928] transaction","detail":"{read_only:false; response_revision:12399; number_of_response:1; }","duration":"230.41852ms","start":"2026-05-18T18:10:11.2949Z","end":"2026-05-18T18:10:11.525319Z","steps":["trace[109430928] 'process raft request'  (duration: 229.964011ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:10:11.525442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.536929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-05-18T18:10:11.52548Z","caller":"traceutil/trace.go:171","msg":"trace[1268722954] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:12399; }","duration":"134.63011ms","start":"2026-05-18T18:10:11.390838Z","end":"2026-05-18T18:10:11.525469Z","steps":["trace[1268722954] 'agreement among raft nodes before linearized reading'  (duration: 134.529009ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:11:29.873975Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10810}
{"level":"info","ts":"2026-05-18T18:11:29.948341Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10810,"took":"71.989908ms","hash":56837098,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":27795456,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-05-18T18:11:29.948471Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":56837098,"revision":10810,"compact-revision":8322}
{"level":"info","ts":"2026-05-18T18:13:22.916931Z","caller":"traceutil/trace.go:171","msg":"trace[1841438831] transaction","detail":"{read_only:false; response_revision:13745; number_of_response:1; }","duration":"116.838033ms","start":"2026-05-18T18:13:22.80006Z","end":"2026-05-18T18:13:22.916898Z","steps":["trace[1841438831] 'process raft request'  (duration: 77.274826ms)","trace[1841438831] 'compare'  (duration: 39.24063ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:13:33.635633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.621254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-05-18T18:13:33.63572Z","caller":"traceutil/trace.go:171","msg":"trace[829214736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13798; }","duration":"103.748986ms","start":"2026-05-18T18:13:33.531947Z","end":"2026-05-18T18:13:33.635696Z","steps":["trace[829214736] 'range keys from in-memory index tree'  (duration: 103.464541ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:13:33.777998Z","caller":"traceutil/trace.go:171","msg":"trace[1190780470] transaction","detail":"{read_only:false; response_revision:13800; number_of_response:1; }","duration":"105.146732ms","start":"2026-05-18T18:13:33.672835Z","end":"2026-05-18T18:13:33.777982Z","steps":["trace[1190780470] 'process raft request'  (duration: 105.055571ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:42.132292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.644448ms","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-05-18T18:13:42.132403Z","caller":"traceutil/trace.go:171","msg":"trace[2038822934] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:13841; }","duration":"284.77915ms","start":"2026-05-18T18:13:41.847589Z","end":"2026-05-18T18:13:42.132368Z","steps":["trace[2038822934] 'range keys from in-memory index tree'  (duration: 284.439623ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:42.132407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.101665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19085"}
{"level":"info","ts":"2026-05-18T18:13:42.132526Z","caller":"traceutil/trace.go:171","msg":"trace[845207171] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:13841; }","duration":"102.281109ms","start":"2026-05-18T18:13:42.030216Z","end":"2026-05-18T18:13:42.132498Z","steps":["trace[845207171] 'range keys from in-memory index tree'  (duration: 101.787939ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:13:42.326736Z","caller":"traceutil/trace.go:171","msg":"trace[329573494] transaction","detail":"{read_only:false; response_revision:13842; number_of_response:1; }","duration":"190.476812ms","start":"2026-05-18T18:13:42.136235Z","end":"2026-05-18T18:13:42.326712Z","steps":["trace[329573494] 'process raft request'  (duration: 190.344879ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:13:42.326747Z","caller":"traceutil/trace.go:171","msg":"trace[167381355] linearizableReadLoop","detail":"{readStateIndex:14314; appliedIndex:14314; }","duration":"188.238719ms","start":"2026-05-18T18:13:42.138472Z","end":"2026-05-18T18:13:42.32671Z","steps":["trace[167381355] 'read index received'  (duration: 188.222408ms)","trace[167381355] 'applied index is now lower than readState.Index'  (duration: 13.681µs)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:13:42.327074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.523245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19085"}
{"level":"info","ts":"2026-05-18T18:13:42.327172Z","caller":"traceutil/trace.go:171","msg":"trace[1556405722] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:13842; }","duration":"188.699128ms","start":"2026-05-18T18:13:42.138441Z","end":"2026-05-18T18:13:42.32714Z","steps":["trace[1556405722] 'agreement among raft nodes before linearized reading'  (duration: 188.364911ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:13:42.458919Z","caller":"traceutil/trace.go:171","msg":"trace[1547731877] transaction","detail":"{read_only:false; response_revision:13843; number_of_response:1; }","duration":"148.734184ms","start":"2026-05-18T18:13:42.310136Z","end":"2026-05-18T18:13:42.458871Z","steps":["trace[1547731877] 'process raft request'  (duration: 148.429259ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:13:50.733909Z","caller":"traceutil/trace.go:171","msg":"trace[1775186085] transaction","detail":"{read_only:false; response_revision:13899; number_of_response:1; }","duration":"251.790126ms","start":"2026-05-18T18:13:50.482089Z","end":"2026-05-18T18:13:50.733879Z","steps":["trace[1775186085] 'process raft request'  (duration: 251.599003ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:50.911353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.674757ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089363106 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:13874 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-05-18T18:13:50.911512Z","caller":"traceutil/trace.go:171","msg":"trace[449126399] linearizableReadLoop","detail":"{readStateIndex:14376; appliedIndex:14375; }","duration":"300.321892ms","start":"2026-05-18T18:13:50.61117Z","end":"2026-05-18T18:13:50.911492Z","steps":["trace[449126399] 'read index received'  (duration: 122.822564ms)","trace[449126399] 'applied index is now lower than readState.Index'  (duration: 177.497798ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:13:50.911543Z","caller":"traceutil/trace.go:171","msg":"trace[2057586536] transaction","detail":"{read_only:false; response_revision:13900; number_of_response:1; }","duration":"424.721878ms","start":"2026-05-18T18:13:50.486786Z","end":"2026-05-18T18:13:50.911508Z","steps":["trace[2057586536] 'process raft request'  (duration: 322.512591ms)","trace[2057586536] 'compare'  (duration: 101.290139ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:13:50.911705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.541117ms","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-05-18T18:13:50.911746Z","caller":"traceutil/trace.go:171","msg":"trace[1928310438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13900; }","duration":"300.619257ms","start":"2026-05-18T18:13:50.611118Z","end":"2026-05-18T18:13:50.911737Z","steps":["trace[1928310438] 'agreement among raft nodes before linearized reading'  (duration: 300.460304ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:50.911717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:13:50.486758Z","time spent":"424.853681ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:13874 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-05-18T18:13:50.911782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:13:50.611096Z","time spent":"300.677959ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-05-18T18:13:50.930991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.770018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-05-18T18:13:50.931065Z","caller":"traceutil/trace.go:171","msg":"trace[1351473897] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13901; }","duration":"312.86265ms","start":"2026-05-18T18:13:50.618185Z","end":"2026-05-18T18:13:50.931048Z","steps":["trace[1351473897] 'agreement among raft nodes before linearized reading'  (duration: 312.623105ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:50.931051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.413617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-05-18T18:13:50.931106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:13:50.618175Z","time spent":"312.922151ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-05-18T18:13:50.931133Z","caller":"traceutil/trace.go:171","msg":"trace[1983076950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13901; }","duration":"211.56379ms","start":"2026-05-18T18:13:50.719546Z","end":"2026-05-18T18:13:50.93111Z","steps":["trace[1983076950] 'agreement among raft nodes before linearized reading'  (duration: 211.404897ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:50.931105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.45313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-jnzcc\" ","response":"range_response_count:1 size:11628"}
{"level":"info","ts":"2026-05-18T18:13:50.931205Z","caller":"traceutil/trace.go:171","msg":"trace[1283816033] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-jnzcc; range_end:; response_count:1; response_revision:13901; }","duration":"112.613913ms","start":"2026-05-18T18:13:50.818572Z","end":"2026-05-18T18:13:50.931186Z","steps":["trace[1283816033] 'agreement among raft nodes before linearized reading'  (duration: 112.270327ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:50.931239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.693298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wb6mf\" ","response":"range_response_count:1 size:6451"}
{"level":"info","ts":"2026-05-18T18:13:50.931328Z","caller":"traceutil/trace.go:171","msg":"trace[138716951] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wb6mf; range_end:; response_count:1; response_revision:13901; }","duration":"276.84206ms","start":"2026-05-18T18:13:50.654464Z","end":"2026-05-18T18:13:50.931306Z","steps":["trace[138716951] 'agreement among raft nodes before linearized reading'  (duration: 276.602826ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:50.931489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.783294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-05-18T18:13:50.931582Z","caller":"traceutil/trace.go:171","msg":"trace[1235791236] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13901; }","duration":"140.938297ms","start":"2026-05-18T18:13:50.790625Z","end":"2026-05-18T18:13:50.931563Z","steps":["trace[1235791236] 'agreement among raft nodes before linearized reading'  (duration: 140.678832ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:52.114582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.707817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-05-18T18:13:52.114669Z","caller":"traceutil/trace.go:171","msg":"trace[925624997] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:13909; }","duration":"127.8402ms","start":"2026-05-18T18:13:51.986805Z","end":"2026-05-18T18:13:52.114645Z","steps":["trace[925624997] 'count revisions from in-memory index tree'  (duration: 127.638946ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:13:52.840582Z","caller":"traceutil/trace.go:171","msg":"trace[988422401] transaction","detail":"{read_only:false; response_revision:13910; number_of_response:1; }","duration":"101.234999ms","start":"2026-05-18T18:13:52.739317Z","end":"2026-05-18T18:13:52.840552Z","steps":["trace[988422401] 'process raft request'  (duration: 100.988044ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:53.279313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.854091ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089363133 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/masterleases/199.19.213.156\" mod_revision:13848 > success:<request_put:<key:\"/registry/masterleases/199.19.213.156\" value_size:68 lease:5164395379089363131 >> failure:<request_range:<key:\"/registry/masterleases/199.19.213.156\" > >>","response":"size:16"}
{"level":"info","ts":"2026-05-18T18:13:53.279529Z","caller":"traceutil/trace.go:171","msg":"trace[559859684] linearizableReadLoop","detail":"{readStateIndex:14390; appliedIndex:14387; }","duration":"333.202173ms","start":"2026-05-18T18:13:52.94631Z","end":"2026-05-18T18:13:53.279512Z","steps":["trace[559859684] 'read index received'  (duration: 13.144348ms)","trace[559859684] 'applied index is now lower than readState.Index'  (duration: 320.057025ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:13:53.279522Z","caller":"traceutil/trace.go:171","msg":"trace[2043080987] transaction","detail":"{read_only:false; response_revision:13911; number_of_response:1; }","duration":"376.818865ms","start":"2026-05-18T18:13:52.902668Z","end":"2026-05-18T18:13:53.279487Z","steps":["trace[2043080987] 'process raft request'  (duration: 56.693519ms)","trace[2043080987] 'compare'  (duration: 319.690708ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:13:53.279629Z","caller":"traceutil/trace.go:171","msg":"trace[1536546234] transaction","detail":"{read_only:false; response_revision:13912; number_of_response:1; }","duration":"362.514765ms","start":"2026-05-18T18:13:52.917104Z","end":"2026-05-18T18:13:53.279619Z","steps":["trace[1536546234] 'process raft request'  (duration: 362.320111ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:53.279724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:13:52.902649Z","time spent":"376.947467ms","remote":"127.0.0.1:40398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/masterleases/199.19.213.156\" mod_revision:13848 > success:<request_put:<key:\"/registry/masterleases/199.19.213.156\" value_size:68 lease:5164395379089363131 >> failure:<request_range:<key:\"/registry/masterleases/199.19.213.156\" > >"}
{"level":"warn","ts":"2026-05-18T18:13:53.279754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:13:52.917087Z","time spent":"362.574576ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:13900 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-05-18T18:13:53.279824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.479558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"warn","ts":"2026-05-18T18:13:53.279875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.618064ms","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-05-18T18:13:53.279825Z","caller":"traceutil/trace.go:171","msg":"trace[255291478] transaction","detail":"{read_only:false; response_revision:13913; number_of_response:1; }","duration":"344.093508ms","start":"2026-05-18T18:13:52.935708Z","end":"2026-05-18T18:13:53.279802Z","steps":["trace[255291478] 'process raft request'  (duration: 343.768372ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:13:53.279924Z","caller":"traceutil/trace.go:171","msg":"trace[1020591533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13913; }","duration":"330.688875ms","start":"2026-05-18T18:13:52.94922Z","end":"2026-05-18T18:13:53.279909Z","steps":["trace[1020591533] 'agreement among raft nodes before linearized reading'  (duration: 330.536372ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:13:53.279927Z","caller":"traceutil/trace.go:171","msg":"trace[361396857] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:13913; }","duration":"333.613261ms","start":"2026-05-18T18:13:52.946287Z","end":"2026-05-18T18:13:53.2799Z","steps":["trace[361396857] 'agreement among raft nodes before linearized reading'  (duration: 333.404517ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:53.279932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.958117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"warn","ts":"2026-05-18T18:13:53.279954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.830008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"warn","ts":"2026-05-18T18:13:53.279974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:13:52.949205Z","time spent":"330.760807ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-05-18T18:13:53.27999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:13:52.946277Z","time spent":"333.695582ms","remote":"127.0.0.1:45484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "}
{"level":"info","ts":"2026-05-18T18:13:53.280017Z","caller":"traceutil/trace.go:171","msg":"trace[1382668938] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13913; }","duration":"156.9432ms","start":"2026-05-18T18:13:53.123056Z","end":"2026-05-18T18:13:53.279999Z","steps":["trace[1382668938] 'agreement among raft nodes before linearized reading'  (duration: 156.801707ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:53.279933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.294674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wb6mf\" ","response":"range_response_count:1 size:6451"}
{"level":"warn","ts":"2026-05-18T18:13:53.279982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:13:52.935688Z","time spent":"344.2424ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:13901 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-05-18T18:13:53.28006Z","caller":"traceutil/trace.go:171","msg":"trace[1251461087] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wb6mf; range_end:; response_count:1; response_revision:13913; }","duration":"272.449257ms","start":"2026-05-18T18:13:53.007597Z","end":"2026-05-18T18:13:53.280046Z","steps":["trace[1251461087] 'agreement among raft nodes before linearized reading'  (duration: 272.169601ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:53.279944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.062997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-05-18T18:13:53.280061Z","caller":"traceutil/trace.go:171","msg":"trace[1533739807] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13913; }","duration":"281.08171ms","start":"2026-05-18T18:13:52.998912Z","end":"2026-05-18T18:13:53.279993Z","steps":["trace[1533739807] 'agreement among raft nodes before linearized reading'  (duration: 280.887236ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:13:53.280117Z","caller":"traceutil/trace.go:171","msg":"trace[1491618013] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13913; }","duration":"282.264252ms","start":"2026-05-18T18:13:52.997837Z","end":"2026-05-18T18:13:53.280101Z","steps":["trace[1491618013] 'agreement among raft nodes before linearized reading'  (duration: 281.977026ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:53.877887Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5164395379089363144,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-05-18T18:13:54.378863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5164395379089363144,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-05-18T18:13:54.45921Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.081805328s","expected-duration":"1s"}
{"level":"info","ts":"2026-05-18T18:13:54.45975Z","caller":"traceutil/trace.go:171","msg":"trace[1573123984] transaction","detail":"{read_only:false; response_revision:13915; number_of_response:1; }","duration":"1.17478113s","start":"2026-05-18T18:13:53.284937Z","end":"2026-05-18T18:13:54.459718Z","steps":["trace[1573123984] 'process raft request'  (duration: 1.174583946s)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:54.459898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:13:53.284914Z","time spent":"1.174905953s","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:13902 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-05-18T18:13:54.460142Z","caller":"traceutil/trace.go:171","msg":"trace[736261479] transaction","detail":"{read_only:false; response_revision:13916; number_of_response:1; }","duration":"1.174934624s","start":"2026-05-18T18:13:53.285176Z","end":"2026-05-18T18:13:54.460111Z","steps":["trace[736261479] 'process raft request'  (duration: 1.174498535s)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:13:54.460246Z","caller":"traceutil/trace.go:171","msg":"trace[1907816903] transaction","detail":"{read_only:false; response_revision:13917; number_of_response:1; }","duration":"1.174830152s","start":"2026-05-18T18:13:53.285354Z","end":"2026-05-18T18:13:54.460184Z","steps":["trace[1907816903] 'process raft request'  (duration: 1.174673629s)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:54.460511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:13:53.285341Z","time spent":"1.175070737s","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:13904 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-05-18T18:13:54.460498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:13:53.285165Z","time spent":"1.175169359s","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:13903 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-05-18T18:13:54.537015Z","caller":"traceutil/trace.go:171","msg":"trace[364629076] linearizableReadLoop","detail":"{readStateIndex:14395; appliedIndex:14391; }","duration":"1.159481523s","start":"2026-05-18T18:13:53.377515Z","end":"2026-05-18T18:13:54.536997Z","steps":["trace[364629076] 'read index received'  (duration: 1.081980501s)","trace[364629076] 'applied index is now lower than readState.Index'  (duration: 77.500301ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:13:54.537035Z","caller":"traceutil/trace.go:171","msg":"trace[1031987842] transaction","detail":"{read_only:false; response_revision:13918; number_of_response:1; }","duration":"1.002249798s","start":"2026-05-18T18:13:53.534763Z","end":"2026-05-18T18:13:54.537013Z","steps":["trace[1031987842] 'process raft request'  (duration: 992.969873ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:54.537187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:13:53.534736Z","time spent":"1.00238109s","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:13908 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:459 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"warn","ts":"2026-05-18T18:13:54.537277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252069468s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"}
{"level":"info","ts":"2026-05-18T18:13:54.537342Z","caller":"traceutil/trace.go:171","msg":"trace[887652601] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:13918; }","duration":"1.25217056s","start":"2026-05-18T18:13:53.285156Z","end":"2026-05-18T18:13:54.537327Z","steps":["trace[887652601] 'agreement among raft nodes before linearized reading'  (duration: 1.251940505s)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:54.53736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.370922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"warn","ts":"2026-05-18T18:13:54.537374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:13:53.285147Z","time spent":"1.25222045s","remote":"127.0.0.1:40656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "}
{"level":"info","ts":"2026-05-18T18:13:54.537386Z","caller":"traceutil/trace.go:171","msg":"trace[2016330497] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:13918; }","duration":"921.432884ms","start":"2026-05-18T18:13:53.615944Z","end":"2026-05-18T18:13:54.537377Z","steps":["trace[2016330497] 'agreement among raft nodes before linearized reading'  (duration: 921.374512ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:54.537407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:13:53.615903Z","time spent":"921.497625ms","remote":"127.0.0.1:45510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "}
{"level":"warn","ts":"2026-05-18T18:13:54.537392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.258914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-05-18T18:13:54.5373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045831189s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-05-18T18:13:54.537461Z","caller":"traceutil/trace.go:171","msg":"trace[1402962320] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:13918; }","duration":"262.372207ms","start":"2026-05-18T18:13:54.275066Z","end":"2026-05-18T18:13:54.537438Z","steps":["trace[1402962320] 'agreement among raft nodes before linearized reading'  (duration: 262.266325ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:13:54.537516Z","caller":"traceutil/trace.go:171","msg":"trace[1062093371] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:13918; }","duration":"1.046084884s","start":"2026-05-18T18:13:53.491405Z","end":"2026-05-18T18:13:54.53749Z","steps":["trace[1062093371] 'agreement among raft nodes before linearized reading'  (duration: 1.045762108s)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:54.537535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.200219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"warn","ts":"2026-05-18T18:13:54.537609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:13:53.491386Z","time spent":"1.046194885s","remote":"127.0.0.1:40708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":103,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "}
{"level":"info","ts":"2026-05-18T18:13:54.537677Z","caller":"traceutil/trace.go:171","msg":"trace[900421814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13918; }","duration":"598.360211ms","start":"2026-05-18T18:13:53.939268Z","end":"2026-05-18T18:13:54.537628Z","steps":["trace[900421814] 'agreement among raft nodes before linearized reading'  (duration: 597.993464ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:13:54.537756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:13:53.939246Z","time spent":"598.496124ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-05-18T18:14:15.601669Z","caller":"traceutil/trace.go:171","msg":"trace[86520083] transaction","detail":"{read_only:false; response_revision:14221; number_of_response:1; }","duration":"253.256989ms","start":"2026-05-18T18:14:15.348392Z","end":"2026-05-18T18:14:15.601649Z","steps":["trace[86520083] 'process raft request'  (duration: 253.068316ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:14:15.675329Z","caller":"traceutil/trace.go:171","msg":"trace[59663171] linearizableReadLoop","detail":"{readStateIndex:14711; appliedIndex:14709; }","duration":"209.629061ms","start":"2026-05-18T18:14:15.46567Z","end":"2026-05-18T18:14:15.675299Z","steps":["trace[59663171] 'read index received'  (duration: 135.828903ms)","trace[59663171] 'applied index is now lower than readState.Index'  (duration: 73.798898ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:14:15.675394Z","caller":"traceutil/trace.go:171","msg":"trace[220515724] transaction","detail":"{read_only:false; response_revision:14222; number_of_response:1; }","duration":"323.755538ms","start":"2026-05-18T18:14:15.351594Z","end":"2026-05-18T18:14:15.67535Z","steps":["trace[220515724] 'process raft request'  (duration: 323.518524ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:14:15.675629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.33226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wb6mf\" ","response":"range_response_count:1 size:6451"}
{"level":"info","ts":"2026-05-18T18:14:15.675674Z","caller":"traceutil/trace.go:171","msg":"trace[584729889] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wb6mf; range_end:; response_count:1; response_revision:14222; }","duration":"123.424452ms","start":"2026-05-18T18:14:15.552239Z","end":"2026-05-18T18:14:15.675663Z","steps":["trace[584729889] 'agreement among raft nodes before linearized reading'  (duration: 123.262179ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:14:15.675643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.949196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"warn","ts":"2026-05-18T18:14:15.675681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:14:15.351568Z","time spent":"323.909131ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:14149 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"info","ts":"2026-05-18T18:14:15.675731Z","caller":"traceutil/trace.go:171","msg":"trace[480619209] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14222; }","duration":"210.087959ms","start":"2026-05-18T18:14:15.465624Z","end":"2026-05-18T18:14:15.675711Z","steps":["trace[480619209] 'agreement among raft nodes before linearized reading'  (duration: 209.830785ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:15:36.048814Z","caller":"traceutil/trace.go:171","msg":"trace[60571073] linearizableReadLoop","detail":"{readStateIndex:15507; appliedIndex:15506; }","duration":"120.839757ms","start":"2026-05-18T18:15:35.927944Z","end":"2026-05-18T18:15:36.048783Z","steps":["trace[60571073] 'read index received'  (duration: 120.565182ms)","trace[60571073] 'applied index is now lower than readState.Index'  (duration: 273.365µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:15:36.048865Z","caller":"traceutil/trace.go:171","msg":"trace[1768371679] transaction","detail":"{read_only:false; response_revision:14998; number_of_response:1; }","duration":"159.825652ms","start":"2026-05-18T18:15:35.88899Z","end":"2026-05-18T18:15:36.048815Z","steps":["trace[1768371679] 'process raft request'  (duration: 159.616358ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:15:36.049046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.088391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-8969q\" ","response":"range_response_count:1 size:9712"}
{"level":"info","ts":"2026-05-18T18:15:36.049093Z","caller":"traceutil/trace.go:171","msg":"trace[1641011781] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-8969q; range_end:; response_count:1; response_revision:14998; }","duration":"121.193423ms","start":"2026-05-18T18:15:35.927886Z","end":"2026-05-18T18:15:36.04908Z","steps":["trace[1641011781] 'agreement among raft nodes before linearized reading'  (duration: 121.01555ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:15:36.049645Z","caller":"traceutil/trace.go:171","msg":"trace[1506705191] transaction","detail":"{read_only:false; response_revision:14999; number_of_response:1; }","duration":"107.183583ms","start":"2026-05-18T18:15:35.942428Z","end":"2026-05-18T18:15:36.049611Z","steps":["trace[1506705191] 'process raft request'  (duration: 106.977499ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:15:36.309997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.736416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-05-18T18:15:36.310069Z","caller":"traceutil/trace.go:171","msg":"trace[1820874273] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14999; }","duration":"212.886749ms","start":"2026-05-18T18:15:36.097166Z","end":"2026-05-18T18:15:36.310053Z","steps":["trace[1820874273] 'range keys from in-memory index tree'  (duration: 212.602724ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:15:36.310811Z","caller":"traceutil/trace.go:171","msg":"trace[2040867199] transaction","detail":"{read_only:false; response_revision:15000; number_of_response:1; }","duration":"113.958334ms","start":"2026-05-18T18:15:36.196805Z","end":"2026-05-18T18:15:36.310763Z","steps":["trace[2040867199] 'process raft request'  (duration: 100.259559ms)","trace[2040867199] 'compare'  (duration: 12.638156ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:16:29.880703Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12987}
{"level":"info","ts":"2026-05-18T18:16:29.959422Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12987,"took":"75.285774ms","hash":1043320852,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":30629888,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-05-18T18:16:29.959519Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1043320852,"revision":12987,"compact-revision":10810}
{"level":"info","ts":"2026-05-18T18:18:35.792565Z","caller":"traceutil/trace.go:171","msg":"trace[1887731426] transaction","detail":"{read_only:false; response_revision:16455; number_of_response:1; }","duration":"152.752814ms","start":"2026-05-18T18:18:35.639773Z","end":"2026-05-18T18:18:35.792525Z","steps":["trace[1887731426] 'process raft request'  (duration: 152.299805ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:21:17.409666Z","caller":"traceutil/trace.go:171","msg":"trace[290634191] transaction","detail":"{read_only:false; response_revision:17551; number_of_response:1; }","duration":"128.911864ms","start":"2026-05-18T18:21:17.280722Z","end":"2026-05-18T18:21:17.409634Z","steps":["trace[290634191] 'process raft request'  (duration: 128.6683ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:21:17.417039Z","caller":"traceutil/trace.go:171","msg":"trace[636911172] transaction","detail":"{read_only:false; response_revision:17552; number_of_response:1; }","duration":"105.764551ms","start":"2026-05-18T18:21:17.311245Z","end":"2026-05-18T18:21:17.41701Z","steps":["trace[636911172] 'process raft request'  (duration: 105.625388ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:21:24.40856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.13954ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089376814 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:17574 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T18:21:24.408697Z","caller":"traceutil/trace.go:171","msg":"trace[1963696972] linearizableReadLoop","detail":"{readStateIndex:18182; appliedIndex:18181; }","duration":"202.514353ms","start":"2026-05-18T18:21:24.206163Z","end":"2026-05-18T18:21:24.408677Z","steps":["trace[1963696972] 'read index received'  (duration: 19.19896ms)","trace[1963696972] 'applied index is now lower than readState.Index'  (duration: 183.313823ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:21:24.408769Z","caller":"traceutil/trace.go:171","msg":"trace[1783333381] transaction","detail":"{read_only:false; response_revision:17587; number_of_response:1; }","duration":"347.410987ms","start":"2026-05-18T18:21:24.061324Z","end":"2026-05-18T18:21:24.408735Z","steps":["trace[1783333381] 'process raft request'  (duration: 163.970131ms)","trace[1783333381] 'compare'  (duration: 182.946157ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:21:24.408887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.725626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-25tgh\" ","response":"range_response_count:1 size:20778"}
{"level":"info","ts":"2026-05-18T18:21:24.40893Z","caller":"traceutil/trace.go:171","msg":"trace[23760903] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-25tgh; range_end:; response_count:1; response_revision:17587; }","duration":"202.798168ms","start":"2026-05-18T18:21:24.206119Z","end":"2026-05-18T18:21:24.408917Z","steps":["trace[23760903] 'agreement among raft nodes before linearized reading'  (duration: 202.612405ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:21:24.409179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:21:24.061292Z","time spent":"347.55529ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:17574 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:458 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"info","ts":"2026-05-18T18:21:24.62757Z","caller":"traceutil/trace.go:171","msg":"trace[1452258249] transaction","detail":"{read_only:false; response_revision:17588; number_of_response:1; }","duration":"203.460951ms","start":"2026-05-18T18:21:24.424078Z","end":"2026-05-18T18:21:24.627539Z","steps":["trace[1452258249] 'process raft request'  (duration: 203.274587ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:21:24.653551Z","caller":"traceutil/trace.go:171","msg":"trace[682896873] transaction","detail":"{read_only:false; response_revision:17589; number_of_response:1; }","duration":"122.301021ms","start":"2026-05-18T18:21:24.531177Z","end":"2026-05-18T18:21:24.653478Z","steps":["trace[682896873] 'process raft request'  (duration: 122.088287ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:21:29.88816Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15342}
{"level":"info","ts":"2026-05-18T18:21:29.983683Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15342,"took":"93.43061ms","hash":2849864012,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":32190464,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-05-18T18:21:29.98376Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2849864012,"revision":15342,"compact-revision":12987}
{"level":"info","ts":"2026-05-18T18:25:00.002772Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"163ef968c92247ab","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-05-18T18:25:00.007879Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002}
{"level":"info","ts":"2026-05-18T18:25:00.007963Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}
{"level":"info","ts":"2026-05-18T18:26:29.894538Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17689}
{"level":"info","ts":"2026-05-18T18:26:30.01327Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17689,"took":"114.847067ms","hash":695184701,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":33681408,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-05-18T18:26:30.013354Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":695184701,"revision":17689,"compact-revision":15342}
{"level":"warn","ts":"2026-05-18T18:27:09.846054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.310977ms","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-05-18T18:27:09.846143Z","caller":"traceutil/trace.go:171","msg":"trace[815819664] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20265; }","duration":"114.46437ms","start":"2026-05-18T18:27:09.731658Z","end":"2026-05-18T18:27:09.846123Z","steps":["trace[815819664] 'range keys from in-memory index tree'  (duration: 114.111534ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:27:09.989315Z","caller":"traceutil/trace.go:171","msg":"trace[2117865615] transaction","detail":"{read_only:false; response_revision:20266; number_of_response:1; }","duration":"139.121896ms","start":"2026-05-18T18:27:09.850169Z","end":"2026-05-18T18:27:09.989291Z","steps":["trace[2117865615] 'process raft request'  (duration: 138.972783ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:27:10.266096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.029888ms","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-05-18T18:27:10.266228Z","caller":"traceutil/trace.go:171","msg":"trace[1579272277] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:20266; }","duration":"100.18326ms","start":"2026-05-18T18:27:10.166026Z","end":"2026-05-18T18:27:10.26621Z","steps":["trace[1579272277] 'range keys from in-memory index tree'  (duration: 99.998767ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:27:10.287224Z","caller":"traceutil/trace.go:171","msg":"trace[936146610] linearizableReadLoop","detail":"{readStateIndex:20952; appliedIndex:20950; }","duration":"307.022733ms","start":"2026-05-18T18:27:09.980183Z","end":"2026-05-18T18:27:10.287206Z","steps":["trace[936146610] 'read index received'  (duration: 8.946049ms)","trace[936146610] 'applied index is now lower than readState.Index'  (duration: 298.075674ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:27:10.287354Z","caller":"traceutil/trace.go:171","msg":"trace[1145223445] transaction","detail":"{read_only:false; response_revision:20267; number_of_response:1; }","duration":"318.325636ms","start":"2026-05-18T18:27:09.968986Z","end":"2026-05-18T18:27:10.287312Z","steps":["trace[1145223445] 'process raft request'  (duration: 318.049381ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:27:10.287421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.232137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"}
{"level":"warn","ts":"2026-05-18T18:27:10.287621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:27:09.968963Z","time spent":"318.52604ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:20258 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"info","ts":"2026-05-18T18:27:10.287652Z","caller":"traceutil/trace.go:171","msg":"trace[441285707] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20267; }","duration":"307.448691ms","start":"2026-05-18T18:27:09.98015Z","end":"2026-05-18T18:27:10.287598Z","steps":["trace[441285707] 'agreement among raft nodes before linearized reading'  (duration: 307.159285ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:27:10.287744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:27:09.980138Z","time spent":"307.577485ms","remote":"127.0.0.1:40566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12232,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "}
{"level":"warn","ts":"2026-05-18T18:27:10.287912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.262573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"}
{"level":"info","ts":"2026-05-18T18:27:10.288108Z","caller":"traceutil/trace.go:171","msg":"trace[986185648] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:20267; }","duration":"100.548347ms","start":"2026-05-18T18:27:10.187527Z","end":"2026-05-18T18:27:10.288076Z","steps":["trace[986185648] 'agreement among raft nodes before linearized reading'  (duration: 99.916155ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:27:10.544415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.37779ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089384394 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/cert-manager/cert-manager-controller\" mod_revision:20195 > success:<request_put:<key:\"/registry/leases/cert-manager/cert-manager-controller\" value_size:453 >> failure:<request_range:<key:\"/registry/leases/cert-manager/cert-manager-controller\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T18:27:10.544569Z","caller":"traceutil/trace.go:171","msg":"trace[777395126] linearizableReadLoop","detail":"{readStateIndex:20953; appliedIndex:20952; }","duration":"251.599108ms","start":"2026-05-18T18:27:10.292946Z","end":"2026-05-18T18:27:10.544545Z","steps":["trace[777395126] 'read index received'  (duration: 126.918123ms)","trace[777395126] 'applied index is now lower than readState.Index'  (duration: 124.679245ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:27:10.544661Z","caller":"traceutil/trace.go:171","msg":"trace[1764675660] transaction","detail":"{read_only:false; response_revision:20268; number_of_response:1; }","duration":"251.979456ms","start":"2026-05-18T18:27:10.292633Z","end":"2026-05-18T18:27:10.544613Z","steps":["trace[1764675660] 'process raft request'  (duration: 127.26035ms)","trace[1764675660] 'compare'  (duration: 124.158045ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:27:10.544776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.837532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18451"}
{"level":"info","ts":"2026-05-18T18:27:10.544823Z","caller":"traceutil/trace.go:171","msg":"trace[1705342268] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20268; }","duration":"251.927634ms","start":"2026-05-18T18:27:10.29288Z","end":"2026-05-18T18:27:10.544808Z","steps":["trace[1705342268] 'agreement among raft nodes before linearized reading'  (duration: 251.726301ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:27:10.695886Z","caller":"traceutil/trace.go:171","msg":"trace[1126994691] transaction","detail":"{read_only:false; response_revision:20269; number_of_response:1; }","duration":"129.404754ms","start":"2026-05-18T18:27:10.566451Z","end":"2026-05-18T18:27:10.695856Z","steps":["trace[1126994691] 'process raft request'  (duration: 110.898907ms)","trace[1126994691] 'compare'  (duration: 18.378175ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:27:10.824473Z","caller":"traceutil/trace.go:171","msg":"trace[42615854] transaction","detail":"{read_only:false; response_revision:20270; number_of_response:1; }","duration":"257.76803ms","start":"2026-05-18T18:27:10.566678Z","end":"2026-05-18T18:27:10.824446Z","steps":["trace[42615854] 'process raft request'  (duration: 257.093337ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:27:10.824859Z","caller":"traceutil/trace.go:171","msg":"trace[1494806578] linearizableReadLoop","detail":"{readStateIndex:20955; appliedIndex:20953; }","duration":"215.77304ms","start":"2026-05-18T18:27:10.609061Z","end":"2026-05-18T18:27:10.824835Z","steps":["trace[1494806578] 'read index received'  (duration: 68.324267ms)","trace[1494806578] 'applied index is now lower than readState.Index'  (duration: 147.447283ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:27:10.8249Z","caller":"traceutil/trace.go:171","msg":"trace[1511172102] transaction","detail":"{read_only:false; response_revision:20272; number_of_response:1; }","duration":"156.940823ms","start":"2026-05-18T18:27:10.667932Z","end":"2026-05-18T18:27:10.824873Z","steps":["trace[1511172102] 'process raft request'  (duration: 156.733499ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:27:10.824849Z","caller":"traceutil/trace.go:171","msg":"trace[2133039015] transaction","detail":"{read_only:false; response_revision:20271; number_of_response:1; }","duration":"157.663857ms","start":"2026-05-18T18:27:10.667142Z","end":"2026-05-18T18:27:10.824806Z","steps":["trace[2133039015] 'process raft request'  (duration: 157.256369ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:27:10.825093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.729478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-05-18T18:27:10.825121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.100055ms","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-05-18T18:27:10.825144Z","caller":"traceutil/trace.go:171","msg":"trace[436562728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20272; }","duration":"105.842421ms","start":"2026-05-18T18:27:10.719288Z","end":"2026-05-18T18:27:10.825131Z","steps":["trace[436562728] 'agreement among raft nodes before linearized reading'  (duration: 105.724118ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:27:10.825165Z","caller":"traceutil/trace.go:171","msg":"trace[363326582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20272; }","duration":"166.228317ms","start":"2026-05-18T18:27:10.658924Z","end":"2026-05-18T18:27:10.825152Z","steps":["trace[363326582] 'agreement among raft nodes before linearized reading'  (duration: 166.064584ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:27:10.825187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.119156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:27:10.825219Z","caller":"traceutil/trace.go:171","msg":"trace[1883706514] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20272; }","duration":"216.198337ms","start":"2026-05-18T18:27:10.609013Z","end":"2026-05-18T18:27:10.825211Z","steps":["trace[1883706514] 'agreement among raft nodes before linearized reading'  (duration: 216.118756ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:27:10.825228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.807986ms","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-05-18T18:27:10.825294Z","caller":"traceutil/trace.go:171","msg":"trace[634599675] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20272; }","duration":"160.888587ms","start":"2026-05-18T18:27:10.664375Z","end":"2026-05-18T18:27:10.825264Z","steps":["trace[634599675] 'agreement among raft nodes before linearized reading'  (duration: 160.706364ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:27:11.100947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.659333ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089384405 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:20261 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T18:27:11.101252Z","caller":"traceutil/trace.go:171","msg":"trace[641806463] linearizableReadLoop","detail":"{readStateIndex:20959; appliedIndex:20957; }","duration":"270.199974ms","start":"2026-05-18T18:27:10.831034Z","end":"2026-05-18T18:27:11.101234Z","steps":["trace[641806463] 'read index received'  (duration: 143.128243ms)","trace[641806463] 'applied index is now lower than readState.Index'  (duration: 127.070471ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:27:11.101357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.317186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:27:11.101584Z","caller":"traceutil/trace.go:171","msg":"trace[2092826965] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20274; }","duration":"270.576421ms","start":"2026-05-18T18:27:10.830995Z","end":"2026-05-18T18:27:11.101571Z","steps":["trace[2092826965] 'agreement among raft nodes before linearized reading'  (duration: 270.302006ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:27:11.101337Z","caller":"traceutil/trace.go:171","msg":"trace[1090683453] transaction","detail":"{read_only:false; response_revision:20274; number_of_response:1; }","duration":"271.62579ms","start":"2026-05-18T18:27:10.829679Z","end":"2026-05-18T18:27:11.101304Z","steps":["trace[1090683453] 'process raft request'  (duration: 271.372696ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:27:11.101401Z","caller":"traceutil/trace.go:171","msg":"trace[52290598] transaction","detail":"{read_only:false; response_revision:20273; number_of_response:1; }","duration":"271.874645ms","start":"2026-05-18T18:27:10.82946Z","end":"2026-05-18T18:27:11.101335Z","steps":["trace[52290598] 'process raft request'  (duration: 144.689402ms)","trace[52290598] 'compare'  (duration: 126.551451ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:31:02.907658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.023651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-05-18T18:31:02.907627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.947777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:31:02.90771Z","caller":"traceutil/trace.go:171","msg":"trace[1586182029] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:22690; }","duration":"194.110333ms","start":"2026-05-18T18:31:02.713586Z","end":"2026-05-18T18:31:02.907697Z","steps":["trace[1586182029] 'count revisions from in-memory index tree'  (duration: 193.94944ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:31:02.907722Z","caller":"traceutil/trace.go:171","msg":"trace[914903498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22690; }","duration":"187.09406ms","start":"2026-05-18T18:31:02.720613Z","end":"2026-05-18T18:31:02.907707Z","steps":["trace[914903498] 'range keys from in-memory index tree'  (duration: 186.851566ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:31:02.907797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.223359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager\" ","response":"range_response_count:1 size:5023"}
{"level":"info","ts":"2026-05-18T18:31:02.907882Z","caller":"traceutil/trace.go:171","msg":"trace[1539110078] range","detail":"{range_begin:/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager; range_end:; response_count:1; response_revision:22690; }","duration":"139.349792ms","start":"2026-05-18T18:31:02.768513Z","end":"2026-05-18T18:31:02.907862Z","steps":["trace[1539110078] 'range keys from in-memory index tree'  (duration: 139.037656ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:31:29.899638Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20060}
{"level":"info","ts":"2026-05-18T18:31:30.003916Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20060,"took":"100.429823ms","hash":1125256347,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":40931328,"current-db-size-in-use":"41 MB"}
{"level":"info","ts":"2026-05-18T18:31:30.003963Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1125256347,"revision":20060,"compact-revision":17689}
{"level":"info","ts":"2026-05-18T18:33:21.082904Z","caller":"traceutil/trace.go:171","msg":"trace[1574852478] transaction","detail":"{read_only:false; response_revision:24123; number_of_response:1; }","duration":"106.440306ms","start":"2026-05-18T18:33:20.976436Z","end":"2026-05-18T18:33:21.082876Z","steps":["trace[1574852478] 'process raft request'  (duration: 106.289973ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:34:28.014512Z","caller":"traceutil/trace.go:171","msg":"trace[324852944] transaction","detail":"{read_only:false; response_revision:24770; number_of_response:1; }","duration":"131.851096ms","start":"2026-05-18T18:34:27.882633Z","end":"2026-05-18T18:34:28.014484Z","steps":["trace[324852944] 'process raft request'  (duration: 131.681761ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:34:28.320457Z","caller":"traceutil/trace.go:171","msg":"trace[445008777] linearizableReadLoop","detail":"{readStateIndex:25581; appliedIndex:25580; }","duration":"138.973669ms","start":"2026-05-18T18:34:28.181463Z","end":"2026-05-18T18:34:28.320437Z","steps":["trace[445008777] 'read index received'  (duration: 138.873968ms)","trace[445008777] 'applied index is now lower than readState.Index'  (duration: 98.961µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:34:28.320799Z","caller":"traceutil/trace.go:171","msg":"trace[1681843976] transaction","detail":"{read_only:false; response_revision:24772; number_of_response:1; }","duration":"142.823791ms","start":"2026-05-18T18:34:28.177909Z","end":"2026-05-18T18:34:28.320733Z","steps":["trace[1681843976] 'process raft request'  (duration: 142.392773ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:34:28.321478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.660012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"}
{"level":"info","ts":"2026-05-18T18:34:28.32151Z","caller":"traceutil/trace.go:171","msg":"trace[1799000522] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24772; }","duration":"140.09096ms","start":"2026-05-18T18:34:28.181408Z","end":"2026-05-18T18:34:28.321499Z","steps":["trace[1799000522] 'agreement among raft nodes before linearized reading'  (duration: 139.631742ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:34:28.607705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.850202ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089396280 > lease_revoke:<id:47ab9e3c324ad125>","response":"size:28"}
{"level":"info","ts":"2026-05-18T18:34:28.608298Z","caller":"traceutil/trace.go:171","msg":"trace[796927699] transaction","detail":"{read_only:false; response_revision:24773; number_of_response:1; }","duration":"282.035674ms","start":"2026-05-18T18:34:28.326232Z","end":"2026-05-18T18:34:28.608267Z","steps":["trace[796927699] 'process raft request'  (duration: 281.637386ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:34:28.608303Z","caller":"traceutil/trace.go:171","msg":"trace[1320330895] linearizableReadLoop","detail":"{readStateIndex:25582; appliedIndex:25581; }","duration":"287.759122ms","start":"2026-05-18T18:34:28.320512Z","end":"2026-05-18T18:34:28.608272Z","steps":["trace[1320330895] 'read index received'  (duration: 144.228997ms)","trace[1320330895] 'applied index is now lower than readState.Index'  (duration: 143.515884ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:34:28.608547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.451714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"}
{"level":"info","ts":"2026-05-18T18:34:28.608582Z","caller":"traceutil/trace.go:171","msg":"trace[500338154] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24773; }","duration":"341.532116ms","start":"2026-05-18T18:34:28.267039Z","end":"2026-05-18T18:34:28.608571Z","steps":["trace[500338154] 'agreement among raft nodes before linearized reading'  (duration: 341.355643ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:34:28.608609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:34:28.26702Z","time spent":"341.581057ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-05-18T18:34:28.657001Z","caller":"traceutil/trace.go:171","msg":"trace[1213497195] transaction","detail":"{read_only:false; response_revision:24774; number_of_response:1; }","duration":"184.573538ms","start":"2026-05-18T18:34:28.472399Z","end":"2026-05-18T18:34:28.656973Z","steps":["trace[1213497195] 'process raft request'  (duration: 184.278683ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:34:28.657655Z","caller":"traceutil/trace.go:171","msg":"trace[1134139440] transaction","detail":"{read_only:false; response_revision:24775; number_of_response:1; }","duration":"185.098158ms","start":"2026-05-18T18:34:28.472514Z","end":"2026-05-18T18:34:28.657613Z","steps":["trace[1134139440] 'process raft request'  (duration: 184.383745ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:34:28.657954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.656358ms","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":"info","ts":"2026-05-18T18:34:28.658166Z","caller":"traceutil/trace.go:171","msg":"trace[1581738735] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24776; }","duration":"108.043355ms","start":"2026-05-18T18:34:28.550009Z","end":"2026-05-18T18:34:28.658052Z","steps":["trace[1581738735] 'agreement among raft nodes before linearized reading'  (duration: 107.564296ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:34:28.658199Z","caller":"traceutil/trace.go:171","msg":"trace[897897028] transaction","detail":"{read_only:false; response_revision:24776; number_of_response:1; }","duration":"182.967757ms","start":"2026-05-18T18:34:28.474768Z","end":"2026-05-18T18:34:28.657736Z","steps":["trace[897897028] 'process raft request'  (duration: 182.633101ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:34:28.658576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.411859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:34:28.658697Z","caller":"traceutil/trace.go:171","msg":"trace[730689765] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:24776; }","duration":"223.589343ms","start":"2026-05-18T18:34:28.435091Z","end":"2026-05-18T18:34:28.65868Z","steps":["trace[730689765] 'agreement among raft nodes before linearized reading'  (duration: 223.358248ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:34:28.796759Z","caller":"traceutil/trace.go:171","msg":"trace[27438001] transaction","detail":"{read_only:false; response_revision:24777; number_of_response:1; }","duration":"184.12317ms","start":"2026-05-18T18:34:28.612621Z","end":"2026-05-18T18:34:28.796744Z","steps":["trace[27438001] 'process raft request'  (duration: 161.594976ms)","trace[27438001] 'compare'  (duration: 22.29201ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:34:47.575885Z","caller":"traceutil/trace.go:171","msg":"trace[1307258181] transaction","detail":"{read_only:false; response_revision:24970; number_of_response:1; }","duration":"172.759665ms","start":"2026-05-18T18:34:47.40309Z","end":"2026-05-18T18:34:47.57585Z","steps":["trace[1307258181] 'process raft request'  (duration: 172.515971ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:34:47.620607Z","caller":"traceutil/trace.go:171","msg":"trace[979466204] linearizableReadLoop","detail":"{readStateIndex:25784; appliedIndex:25782; }","duration":"168.896273ms","start":"2026-05-18T18:34:47.451673Z","end":"2026-05-18T18:34:47.62057Z","steps":["trace[979466204] 'read index received'  (duration: 123.981627ms)","trace[979466204] 'applied index is now lower than readState.Index'  (duration: 44.913246ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:34:47.620737Z","caller":"traceutil/trace.go:171","msg":"trace[1680609786] transaction","detail":"{read_only:false; response_revision:24971; number_of_response:1; }","duration":"181.100572ms","start":"2026-05-18T18:34:47.43957Z","end":"2026-05-18T18:34:47.620671Z","steps":["trace[1680609786] 'process raft request'  (duration: 180.774976ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:34:47.620887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.194688ms","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-05-18T18:34:47.62092Z","caller":"traceutil/trace.go:171","msg":"trace[1971893351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24971; }","duration":"169.29642ms","start":"2026-05-18T18:34:47.451614Z","end":"2026-05-18T18:34:47.62091Z","steps":["trace[1971893351] 'agreement among raft nodes before linearized reading'  (duration: 169.119977ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:34:47.843153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.063179ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089396800 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:24902 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T18:34:47.843306Z","caller":"traceutil/trace.go:171","msg":"trace[1534882736] transaction","detail":"{read_only:false; response_revision:24972; number_of_response:1; }","duration":"215.882408ms","start":"2026-05-18T18:34:47.627395Z","end":"2026-05-18T18:34:47.843277Z","steps":["trace[1534882736] 'process raft request'  (duration: 92.571145ms)","trace[1534882736] 'compare'  (duration: 122.906816ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:34:47.858877Z","caller":"traceutil/trace.go:171","msg":"trace[1114499079] linearizableReadLoop","detail":"{readStateIndex:25786; appliedIndex:25784; }","duration":"211.820281ms","start":"2026-05-18T18:34:47.647036Z","end":"2026-05-18T18:34:47.858856Z","steps":["trace[1114499079] 'read index received'  (duration: 73.030496ms)","trace[1114499079] 'applied index is now lower than readState.Index'  (duration: 138.788665ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:34:47.858991Z","caller":"traceutil/trace.go:171","msg":"trace[2046903558] transaction","detail":"{read_only:false; response_revision:24973; number_of_response:1; }","duration":"227.246912ms","start":"2026-05-18T18:34:47.631719Z","end":"2026-05-18T18:34:47.858966Z","steps":["trace[2046903558] 'process raft request'  (duration: 227.005818ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:34:47.859031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.984124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:34:47.859058Z","caller":"traceutil/trace.go:171","msg":"trace[348196535] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:24973; }","duration":"212.066946ms","start":"2026-05-18T18:34:47.646983Z","end":"2026-05-18T18:34:47.85905Z","steps":["trace[348196535] 'agreement among raft nodes before linearized reading'  (duration: 211.989974ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:34:47.859258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.475657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:34:47.859339Z","caller":"traceutil/trace.go:171","msg":"trace[145551278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24973; }","duration":"140.640291ms","start":"2026-05-18T18:34:47.718678Z","end":"2026-05-18T18:34:47.859319Z","steps":["trace[145551278] 'agreement among raft nodes before linearized reading'  (duration: 140.490697ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:34:47.859379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.948683ms","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-05-18T18:34:47.859512Z","caller":"traceutil/trace.go:171","msg":"trace[1121229700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24973; }","duration":"187.119726ms","start":"2026-05-18T18:34:47.672337Z","end":"2026-05-18T18:34:47.859457Z","steps":["trace[1121229700] 'agreement among raft nodes before linearized reading'  (duration: 186.754179ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:34:47.859265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.166767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:34:47.859649Z","caller":"traceutil/trace.go:171","msg":"trace[1445787019] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:24973; }","duration":"135.599006ms","start":"2026-05-18T18:34:47.72402Z","end":"2026-05-18T18:34:47.859619Z","steps":["trace[1445787019] 'agreement among raft nodes before linearized reading'  (duration: 135.086346ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:35:03.334521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.175425ms","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-05-18T18:35:03.334643Z","caller":"traceutil/trace.go:171","msg":"trace[1395153241] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25132; }","duration":"121.373558ms","start":"2026-05-18T18:35:03.213243Z","end":"2026-05-18T18:35:03.334617Z","steps":["trace[1395153241] 'range keys from in-memory index tree'  (duration: 120.895459ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:35:03.46673Z","caller":"traceutil/trace.go:171","msg":"trace[304793730] linearizableReadLoop","detail":"{readStateIndex:25950; appliedIndex:25949; }","duration":"112.274526ms","start":"2026-05-18T18:35:03.354412Z","end":"2026-05-18T18:35:03.466687Z","steps":["trace[304793730] 'read index received'  (duration: 112.024691ms)","trace[304793730] 'applied index is now lower than readState.Index'  (duration: 248.035µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:35:03.466841Z","caller":"traceutil/trace.go:171","msg":"trace[260688451] transaction","detail":"{read_only:false; response_revision:25133; number_of_response:1; }","duration":"127.079594ms","start":"2026-05-18T18:35:03.339716Z","end":"2026-05-18T18:35:03.466795Z","steps":["trace[260688451] 'process raft request'  (duration: 126.729268ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:35:03.466936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.47811ms","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-05-18T18:35:03.466988Z","caller":"traceutil/trace.go:171","msg":"trace[919274669] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:25133; }","duration":"112.612652ms","start":"2026-05-18T18:35:03.354358Z","end":"2026-05-18T18:35:03.46697Z","steps":["trace[919274669] 'agreement among raft nodes before linearized reading'  (duration: 112.48774ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:35:35.116844Z","caller":"traceutil/trace.go:171","msg":"trace[738043364] transaction","detail":"{read_only:false; response_revision:25392; number_of_response:1; }","duration":"213.660676ms","start":"2026-05-18T18:35:34.903145Z","end":"2026-05-18T18:35:35.116805Z","steps":["trace[738043364] 'process raft request'  (duration: 213.436862ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:35:35.300549Z","caller":"traceutil/trace.go:171","msg":"trace[80244899] linearizableReadLoop","detail":"{readStateIndex:26218; appliedIndex:26216; }","duration":"183.720392ms","start":"2026-05-18T18:35:35.1168Z","end":"2026-05-18T18:35:35.300521Z","steps":["trace[80244899] 'read index received'  (duration: 115.437655ms)","trace[80244899] 'applied index is now lower than readState.Index'  (duration: 68.281707ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:35:35.30067Z","caller":"traceutil/trace.go:171","msg":"trace[952540793] transaction","detail":"{read_only:false; response_revision:25393; number_of_response:1; }","duration":"396.340179ms","start":"2026-05-18T18:35:34.904314Z","end":"2026-05-18T18:35:35.300655Z","steps":["trace[952540793] 'process raft request'  (duration: 327.95939ms)","trace[952540793] 'compare'  (duration: 68.044913ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:35:35.30078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:35:34.90427Z","time spent":"396.426271ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:25378 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-05-18T18:35:35.300827Z","caller":"traceutil/trace.go:171","msg":"trace[19099488] transaction","detail":"{read_only:false; response_revision:25394; number_of_response:1; }","duration":"389.977639ms","start":"2026-05-18T18:35:34.910814Z","end":"2026-05-18T18:35:35.300791Z","steps":["trace[19099488] 'process raft request'  (duration: 389.632783ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:35:35.301009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:35:34.910788Z","time spent":"390.112462ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:25379 > success:<request_put:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" value_size:442 >> failure:<request_range:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" > >"}
{"level":"warn","ts":"2026-05-18T18:35:35.30104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.212221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"}
{"level":"info","ts":"2026-05-18T18:35:35.301131Z","caller":"traceutil/trace.go:171","msg":"trace[1930359404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25394; }","duration":"184.369664ms","start":"2026-05-18T18:35:35.116738Z","end":"2026-05-18T18:35:35.301108Z","steps":["trace[1930359404] 'agreement among raft nodes before linearized reading'  (duration: 184.042148ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:35:35.314391Z","caller":"traceutil/trace.go:171","msg":"trace[961459077] transaction","detail":"{read_only:false; response_revision:25395; number_of_response:1; }","duration":"119.769878ms","start":"2026-05-18T18:35:35.194605Z","end":"2026-05-18T18:35:35.314375Z","steps":["trace[961459077] 'process raft request'  (duration: 119.571573ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:35:35.314545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.878738ms","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-05-18T18:35:35.314666Z","caller":"traceutil/trace.go:171","msg":"trace[8159307] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:25395; }","duration":"113.080032ms","start":"2026-05-18T18:35:35.201558Z","end":"2026-05-18T18:35:35.314638Z","steps":["trace[8159307] 'agreement among raft nodes before linearized reading'  (duration: 112.844327ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:35:36.706868Z","caller":"traceutil/trace.go:171","msg":"trace[382904473] transaction","detail":"{read_only:false; response_revision:25405; number_of_response:1; }","duration":"114.047799ms","start":"2026-05-18T18:35:36.592802Z","end":"2026-05-18T18:35:36.70685Z","steps":["trace[382904473] 'process raft request'  (duration: 113.556939ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:35:40.294421Z","caller":"traceutil/trace.go:171","msg":"trace[1225579790] transaction","detail":"{read_only:false; response_revision:25430; number_of_response:1; }","duration":"117.947823ms","start":"2026-05-18T18:35:40.176444Z","end":"2026-05-18T18:35:40.294392Z","steps":["trace[1225579790] 'process raft request'  (duration: 117.77941ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:35:40.447724Z","caller":"traceutil/trace.go:171","msg":"trace[1547385701] transaction","detail":"{read_only:false; response_revision:25431; number_of_response:1; }","duration":"135.748809ms","start":"2026-05-18T18:35:40.311942Z","end":"2026-05-18T18:35:40.447691Z","steps":["trace[1547385701] 'process raft request'  (duration: 115.877034ms)","trace[1547385701] 'compare'  (duration: 19.696552ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:35:40.722482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.661857ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089397857 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:25419 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T18:35:40.72286Z","caller":"traceutil/trace.go:171","msg":"trace[1795960482] transaction","detail":"{read_only:false; response_revision:25432; number_of_response:1; }","duration":"408.645871ms","start":"2026-05-18T18:35:40.314159Z","end":"2026-05-18T18:35:40.722805Z","steps":["trace[1795960482] 'process raft request'  (duration: 290.584406ms)","trace[1795960482] 'compare'  (duration: 117.573036ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:35:40.722917Z","caller":"traceutil/trace.go:171","msg":"trace[1122347055] transaction","detail":"{read_only:false; response_revision:25433; number_of_response:1; }","duration":"401.432064ms","start":"2026-05-18T18:35:40.32144Z","end":"2026-05-18T18:35:40.722872Z","steps":["trace[1122347055] 'process raft request'  (duration: 401.131328ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:35:40.723007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:35:40.314144Z","time spent":"408.782903ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:25419 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-05-18T18:35:40.723063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:35:40.321412Z","time spent":"401.569777ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi\" mod_revision:25395 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi\" value_size:518 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-control-plane-system/kubeadm-control-plane-manager-leader-election-capi\" > >"}
{"level":"warn","ts":"2026-05-18T18:35:41.019496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.651755ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089397862 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" mod_revision:25420 > success:<request_put:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" value_size:507 >> failure:<request_range:<key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T18:35:41.019882Z","caller":"traceutil/trace.go:171","msg":"trace[1567854413] transaction","detail":"{read_only:false; response_revision:25434; number_of_response:1; }","duration":"288.353183ms","start":"2026-05-18T18:35:40.731484Z","end":"2026-05-18T18:35:41.019837Z","steps":["trace[1567854413] 'process raft request'  (duration: 142.24814ms)","trace[1567854413] 'compare'  (duration: 145.498212ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:35:42.070144Z","caller":"traceutil/trace.go:171","msg":"trace[965157790] transaction","detail":"{read_only:false; response_revision:25445; number_of_response:1; }","duration":"127.211467ms","start":"2026-05-18T18:35:41.942904Z","end":"2026-05-18T18:35:42.070115Z","steps":["trace[965157790] 'process raft request'  (duration: 127.048364ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:35:42.540511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.063476ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089397894 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:25429 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T18:35:42.541025Z","caller":"traceutil/trace.go:171","msg":"trace[479384497] linearizableReadLoop","detail":"{readStateIndex:26271; appliedIndex:26270; }","duration":"242.085482ms","start":"2026-05-18T18:35:42.298907Z","end":"2026-05-18T18:35:42.540992Z","steps":["trace[479384497] 'read index received'  (duration: 55.411µs)","trace[479384497] 'applied index is now lower than readState.Index'  (duration: 242.027351ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:35:42.541154Z","caller":"traceutil/trace.go:171","msg":"trace[1756140945] transaction","detail":"{read_only:false; response_revision:25446; number_of_response:1; }","duration":"416.048569ms","start":"2026-05-18T18:35:42.125085Z","end":"2026-05-18T18:35:42.541133Z","steps":["trace[1756140945] 'process raft request'  (duration: 117.188488ms)","trace[1756140945] 'compare'  (duration: 297.815902ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:35:42.541246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:35:42.125058Z","time spent":"416.140411ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:25429 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-05-18T18:35:42.541407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.481079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-05-18T18:35:42.541497Z","caller":"traceutil/trace.go:171","msg":"trace[1159220661] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25446; }","duration":"242.618562ms","start":"2026-05-18T18:35:42.298855Z","end":"2026-05-18T18:35:42.541474Z","steps":["trace[1159220661] 'agreement among raft nodes before linearized reading'  (duration: 242.383387ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:35:58.474177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.810818ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089398522 > lease_revoke:<id:47ab9e3c324ad891>","response":"size:28"}
{"level":"info","ts":"2026-05-18T18:35:58.780773Z","caller":"traceutil/trace.go:171","msg":"trace[95233444] transaction","detail":"{read_only:false; response_revision:25705; number_of_response:1; }","duration":"148.865616ms","start":"2026-05-18T18:35:58.631892Z","end":"2026-05-18T18:35:58.780758Z","steps":["trace[95233444] 'process raft request'  (duration: 148.609061ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:35:58.780694Z","caller":"traceutil/trace.go:171","msg":"trace[930432158] linearizableReadLoop","detail":"{readStateIndex:26535; appliedIndex:26534; }","duration":"139.412667ms","start":"2026-05-18T18:35:58.641259Z","end":"2026-05-18T18:35:58.780672Z","steps":["trace[930432158] 'read index received'  (duration: 139.178683ms)","trace[930432158] 'applied index is now lower than readState.Index'  (duration: 233.124µs)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:35:58.781124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.851986ms","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-05-18T18:35:58.781222Z","caller":"traceutil/trace.go:171","msg":"trace[254137468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25705; }","duration":"140.021039ms","start":"2026-05-18T18:35:58.641181Z","end":"2026-05-18T18:35:58.781202Z","steps":["trace[254137468] 'agreement among raft nodes before linearized reading'  (duration: 139.728143ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:35:58.781322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.120311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"}
{"level":"info","ts":"2026-05-18T18:35:58.781639Z","caller":"traceutil/trace.go:171","msg":"trace[2052056317] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25705; }","duration":"114.201492ms","start":"2026-05-18T18:35:58.667148Z","end":"2026-05-18T18:35:58.781349Z","steps":["trace[2052056317] 'agreement among raft nodes before linearized reading'  (duration: 114.063539ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:36:29.904807Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23008}
{"level":"info","ts":"2026-05-18T18:36:30.033428Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23008,"took":"125.499585ms","hash":736711462,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":35348480,"current-db-size-in-use":"35 MB"}
{"level":"info","ts":"2026-05-18T18:36:30.033522Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":736711462,"revision":23008,"compact-revision":20060}
{"level":"warn","ts":"2026-05-18T18:36:38.34812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.177567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:36:38.348243Z","caller":"traceutil/trace.go:171","msg":"trace[1622918134] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26024; }","duration":"127.349961ms","start":"2026-05-18T18:36:38.220873Z","end":"2026-05-18T18:36:38.348223Z","steps":["trace[1622918134] 'range keys from in-memory index tree'  (duration: 126.980584ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:36:38.348422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.694669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"}
{"level":"info","ts":"2026-05-18T18:36:38.348656Z","caller":"traceutil/trace.go:171","msg":"trace[1674833871] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26024; }","duration":"127.102046ms","start":"2026-05-18T18:36:38.22152Z","end":"2026-05-18T18:36:38.348622Z","steps":["trace[1674833871] 'range keys from in-memory index tree'  (duration: 126.175988ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:36:38.517535Z","caller":"traceutil/trace.go:171","msg":"trace[966152865] linearizableReadLoop","detail":"{readStateIndex:26864; appliedIndex:26863; }","duration":"159.766861ms","start":"2026-05-18T18:36:38.357735Z","end":"2026-05-18T18:36:38.517502Z","steps":["trace[966152865] 'read index received'  (duration: 159.363864ms)","trace[966152865] 'applied index is now lower than readState.Index'  (duration: 402.357µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:36:38.517698Z","caller":"traceutil/trace.go:171","msg":"trace[2008434196] transaction","detail":"{read_only:false; response_revision:26025; number_of_response:1; }","duration":"191.904336ms","start":"2026-05-18T18:36:38.325741Z","end":"2026-05-18T18:36:38.517646Z","steps":["trace[2008434196] 'process raft request'  (duration: 191.479468ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:36:38.517785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.023555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"}
{"level":"info","ts":"2026-05-18T18:36:38.517823Z","caller":"traceutil/trace.go:171","msg":"trace[331530040] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:26025; }","duration":"160.091857ms","start":"2026-05-18T18:36:38.357717Z","end":"2026-05-18T18:36:38.517808Z","steps":["trace[331530040] 'agreement among raft nodes before linearized reading'  (duration: 159.912573ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:36:38.908771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.433189ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089399572 > lease_revoke:<id:47ab9e3c324add80>","response":"size:28"}
{"level":"info","ts":"2026-05-18T18:36:38.908927Z","caller":"traceutil/trace.go:171","msg":"trace[228153964] linearizableReadLoop","detail":"{readStateIndex:26865; appliedIndex:26864; }","duration":"387.722867ms","start":"2026-05-18T18:36:38.521179Z","end":"2026-05-18T18:36:38.908902Z","steps":["trace[228153964] 'read index received'  (duration: 67.067574ms)","trace[228153964] 'applied index is now lower than readState.Index'  (duration: 320.653603ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:36:38.909128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.926881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-9ccd4dbe-277f-4ba1-ad0a-743700d6287c\" ","response":"range_response_count:1 size:1322"}
{"level":"info","ts":"2026-05-18T18:36:38.9092Z","caller":"traceutil/trace.go:171","msg":"trace[1869353685] range","detail":"{range_begin:/registry/persistentvolumes/pvc-9ccd4dbe-277f-4ba1-ad0a-743700d6287c; range_end:; response_count:1; response_revision:26025; }","duration":"388.062043ms","start":"2026-05-18T18:36:38.52112Z","end":"2026-05-18T18:36:38.909182Z","steps":["trace[1869353685] 'agreement among raft nodes before linearized reading'  (duration: 387.86226ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:36:38.909229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:36:38.5211Z","time spent":"388.123734ms","remote":"127.0.0.1:40520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1344,"request content":"key:\"/registry/persistentvolumes/pvc-9ccd4dbe-277f-4ba1-ad0a-743700d6287c\" "}
{"level":"warn","ts":"2026-05-18T18:36:38.909128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.316638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:36:38.909359Z","caller":"traceutil/trace.go:171","msg":"trace[665410551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26025; }","duration":"189.627463ms","start":"2026-05-18T18:36:38.719724Z","end":"2026-05-18T18:36:38.909352Z","steps":["trace[665410551] 'agreement among raft nodes before linearized reading'  (duration: 189.319748ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:37:10.009919Z","caller":"traceutil/trace.go:171","msg":"trace[280401933] transaction","detail":"{read_only:false; response_revision:26256; number_of_response:1; }","duration":"130.360867ms","start":"2026-05-18T18:37:09.879518Z","end":"2026-05-18T18:37:10.009878Z","steps":["trace[280401933] 'process raft request'  (duration: 130.079132ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:37:10.282156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.666089ms","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-05-18T18:37:10.282375Z","caller":"traceutil/trace.go:171","msg":"trace[570897012] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:26256; }","duration":"116.904114ms","start":"2026-05-18T18:37:10.165447Z","end":"2026-05-18T18:37:10.282351Z","steps":["trace[570897012] 'range keys from in-memory index tree'  (duration: 116.645209ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:37:10.28272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.727628ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089400354 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:26243 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T18:37:10.282889Z","caller":"traceutil/trace.go:171","msg":"trace[2124610964] linearizableReadLoop","detail":"{readStateIndex:27103; appliedIndex:27101; }","duration":"342.059666ms","start":"2026-05-18T18:37:09.9408Z","end":"2026-05-18T18:37:10.28286Z","steps":["trace[2124610964] 'read index received'  (duration: 68.816888ms)","trace[2124610964] 'applied index is now lower than readState.Index'  (duration: 273.240498ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:37:10.283018Z","caller":"traceutil/trace.go:171","msg":"trace[2095854908] transaction","detail":"{read_only:false; response_revision:26257; number_of_response:1; }","duration":"403.304761ms","start":"2026-05-18T18:37:09.879693Z","end":"2026-05-18T18:37:10.282998Z","steps":["trace[2095854908] 'process raft request'  (duration: 259.129714ms)","trace[2095854908] 'compare'  (duration: 143.310031ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:37:10.283169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:37:09.879658Z","time spent":"403.409822ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:26243 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-05-18T18:37:10.283191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.349001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:37:10.283295Z","caller":"traceutil/trace.go:171","msg":"trace[2028005096] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:26257; }","duration":"342.527425ms","start":"2026-05-18T18:37:09.940745Z","end":"2026-05-18T18:37:10.283272Z","steps":["trace[2028005096] 'agreement among raft nodes before linearized reading'  (duration: 342.329301ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:37:10.283352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:37:09.940728Z","time spent":"342.613287ms","remote":"127.0.0.1:44362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "}
{"level":"warn","ts":"2026-05-18T18:37:10.292146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.511125ms","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-05-18T18:37:10.29221Z","caller":"traceutil/trace.go:171","msg":"trace[1256001720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26258; }","duration":"194.592017ms","start":"2026-05-18T18:37:10.097588Z","end":"2026-05-18T18:37:10.29218Z","steps":["trace[1256001720] 'agreement among raft nodes before linearized reading'  (duration: 194.452975ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:37:10.747117Z","caller":"traceutil/trace.go:171","msg":"trace[1922441368] transaction","detail":"{read_only:false; response_revision:26260; number_of_response:1; }","duration":"101.36249ms","start":"2026-05-18T18:37:10.645691Z","end":"2026-05-18T18:37:10.747054Z","steps":["trace[1922441368] 'process raft request'  (duration: 100.945582ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:37:41.956153Z","caller":"traceutil/trace.go:171","msg":"trace[1360861724] transaction","detail":"{read_only:false; response_revision:26492; number_of_response:1; }","duration":"199.642723ms","start":"2026-05-18T18:37:41.756473Z","end":"2026-05-18T18:37:41.956116Z","steps":["trace[1360861724] 'process raft request'  (duration: 117.029766ms)","trace[1360861724] 'compare'  (duration: 82.127028ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:37:41.958283Z","caller":"traceutil/trace.go:171","msg":"trace[877759153] linearizableReadLoop","detail":"{readStateIndex:27346; appliedIndex:27344; }","duration":"182.749893ms","start":"2026-05-18T18:37:41.775506Z","end":"2026-05-18T18:37:41.958256Z","steps":["trace[877759153] 'read index received'  (duration: 98.015727ms)","trace[877759153] 'applied index is now lower than readState.Index'  (duration: 84.732996ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:37:41.958409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.886027ms","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-05-18T18:37:41.958372Z","caller":"traceutil/trace.go:171","msg":"trace[666819374] transaction","detail":"{read_only:false; response_revision:26493; number_of_response:1; }","duration":"201.774953ms","start":"2026-05-18T18:37:41.756554Z","end":"2026-05-18T18:37:41.958329Z","steps":["trace[666819374] 'process raft request'  (duration: 201.600999ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:37:41.958453Z","caller":"traceutil/trace.go:171","msg":"trace[1353392424] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:26493; }","duration":"182.990929ms","start":"2026-05-18T18:37:41.775448Z","end":"2026-05-18T18:37:41.958439Z","steps":["trace[1353392424] 'agreement among raft nodes before linearized reading'  (duration: 182.903437ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:13.250125Z","caller":"traceutil/trace.go:171","msg":"trace[370628440] linearizableReadLoop","detail":"{readStateIndex:27581; appliedIndex:27580; }","duration":"119.060894ms","start":"2026-05-18T18:38:13.131037Z","end":"2026-05-18T18:38:13.250098Z","steps":["trace[370628440] 'read index received'  (duration: 118.926271ms)","trace[370628440] 'applied index is now lower than readState.Index'  (duration: 133.103µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:38:13.250159Z","caller":"traceutil/trace.go:171","msg":"trace[803888643] transaction","detail":"{read_only:false; response_revision:26721; number_of_response:1; }","duration":"266.724656ms","start":"2026-05-18T18:38:12.983402Z","end":"2026-05-18T18:38:13.250127Z","steps":["trace[803888643] 'process raft request'  (duration: 266.527313ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:13.250496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.424401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-05-18T18:38:13.250868Z","caller":"traceutil/trace.go:171","msg":"trace[588955621] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:26721; }","duration":"119.875799ms","start":"2026-05-18T18:38:13.130956Z","end":"2026-05-18T18:38:13.250832Z","steps":["trace[588955621] 'agreement among raft nodes before linearized reading'  (duration: 119.233607ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:13.462821Z","caller":"traceutil/trace.go:171","msg":"trace[1794622360] transaction","detail":"{read_only:false; response_revision:26722; number_of_response:1; }","duration":"115.719111ms","start":"2026-05-18T18:38:13.347075Z","end":"2026-05-18T18:38:13.462795Z","steps":["trace[1794622360] 'process raft request'  (duration: 115.534688ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:13.653422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.122618ms","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-05-18T18:38:13.653594Z","caller":"traceutil/trace.go:171","msg":"trace[1515277951] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26723; }","duration":"116.317042ms","start":"2026-05-18T18:38:13.537249Z","end":"2026-05-18T18:38:13.653566Z","steps":["trace[1515277951] 'range keys from in-memory index tree'  (duration: 115.980976ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:13.653754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.836417ms","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-05-18T18:38:13.653918Z","caller":"traceutil/trace.go:171","msg":"trace[1886476670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26723; }","duration":"122.085672ms","start":"2026-05-18T18:38:13.531794Z","end":"2026-05-18T18:38:13.653879Z","steps":["trace[1886476670] 'range keys from in-memory index tree'  (duration: 121.48386ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:13.811622Z","caller":"traceutil/trace.go:171","msg":"trace[376347907] transaction","detail":"{read_only:false; response_revision:26725; number_of_response:1; }","duration":"152.945982ms","start":"2026-05-18T18:38:13.658621Z","end":"2026-05-18T18:38:13.811567Z","steps":["trace[376347907] 'process raft request'  (duration: 141.349013ms)","trace[376347907] 'compare'  (duration: 11.430925ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:38:14.004821Z","caller":"traceutil/trace.go:171","msg":"trace[1679591880] linearizableReadLoop","detail":"{readStateIndex:27586; appliedIndex:27584; }","duration":"285.649343ms","start":"2026-05-18T18:38:13.719148Z","end":"2026-05-18T18:38:14.004797Z","steps":["trace[1679591880] 'read index received'  (duration: 80.841354ms)","trace[1679591880] 'applied index is now lower than readState.Index'  (duration: 204.807049ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:38:14.004882Z","caller":"traceutil/trace.go:171","msg":"trace[58130948] transaction","detail":"{read_only:false; response_revision:26726; number_of_response:1; }","duration":"345.404689ms","start":"2026-05-18T18:38:13.659445Z","end":"2026-05-18T18:38:14.00485Z","steps":["trace[58130948] 'process raft request'  (duration: 345.185045ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:14.005039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:38:13.659404Z","time spent":"345.543791ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:26711 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-05-18T18:38:14.005022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.845496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:38:14.005122Z","caller":"traceutil/trace.go:171","msg":"trace[1706646036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26726; }","duration":"286.005299ms","start":"2026-05-18T18:38:13.719091Z","end":"2026-05-18T18:38:14.005097Z","steps":["trace[1706646036] 'agreement among raft nodes before linearized reading'  (duration: 285.789875ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:14.150469Z","caller":"traceutil/trace.go:171","msg":"trace[2098763502] transaction","detail":"{read_only:false; response_revision:26727; number_of_response:1; }","duration":"293.274377ms","start":"2026-05-18T18:38:13.857142Z","end":"2026-05-18T18:38:14.150416Z","steps":["trace[2098763502] 'process raft request'  (duration: 268.815026ms)","trace[2098763502] 'compare'  (duration: 24.195626ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:38:14.150555Z","caller":"traceutil/trace.go:171","msg":"trace[1655799844] linearizableReadLoop","detail":"{readStateIndex:27587; appliedIndex:27586; }","duration":"145.605944ms","start":"2026-05-18T18:38:14.004883Z","end":"2026-05-18T18:38:14.150489Z","steps":["trace[1655799844] 'read index received'  (duration: 121.077732ms)","trace[1655799844] 'applied index is now lower than readState.Index'  (duration: 24.520872ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:38:14.15105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.395929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"}
{"level":"info","ts":"2026-05-18T18:38:14.151139Z","caller":"traceutil/trace.go:171","msg":"trace[1985134394] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26729; }","duration":"318.531222ms","start":"2026-05-18T18:38:13.832586Z","end":"2026-05-18T18:38:14.151117Z","steps":["trace[1985134394] 'agreement among raft nodes before linearized reading'  (duration: 318.200396ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:14.151191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:38:13.832568Z","time spent":"318.614203ms","remote":"127.0.0.1:40612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5029,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "}
{"level":"warn","ts":"2026-05-18T18:38:14.151015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.409289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-05-18T18:38:14.151219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.139165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"}
{"level":"warn","ts":"2026-05-18T18:38:14.151224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.764627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"}
{"level":"warn","ts":"2026-05-18T18:38:14.151312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.967749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"warn","ts":"2026-05-18T18:38:14.151311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.94145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:38:14.151335Z","caller":"traceutil/trace.go:171","msg":"trace[2622479] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26729; }","duration":"319.028701ms","start":"2026-05-18T18:38:13.8323Z","end":"2026-05-18T18:38:14.151329Z","steps":["trace[2622479] 'agreement among raft nodes before linearized reading'  (duration: 318.99684ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:14.151359Z","caller":"traceutil/trace.go:171","msg":"trace[691298181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26729; }","duration":"145.015122ms","start":"2026-05-18T18:38:14.006332Z","end":"2026-05-18T18:38:14.151347Z","steps":["trace[691298181] 'agreement among raft nodes before linearized reading'  (duration: 144.947251ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:14.151367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:38:13.83227Z","time spent":"319.091081ms","remote":"127.0.0.1:40612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "}
{"level":"info","ts":"2026-05-18T18:38:14.151372Z","caller":"traceutil/trace.go:171","msg":"trace[92626938] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:26729; }","duration":"240.981301ms","start":"2026-05-18T18:38:13.910367Z","end":"2026-05-18T18:38:14.151349Z","steps":["trace[92626938] 'agreement among raft nodes before linearized reading'  (duration: 240.669545ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:14.151258Z","caller":"traceutil/trace.go:171","msg":"trace[1886314774] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:26729; }","duration":"292.211656ms","start":"2026-05-18T18:38:13.859031Z","end":"2026-05-18T18:38:14.151243Z","steps":["trace[1886314774] 'agreement among raft nodes before linearized reading'  (duration: 292.033623ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:14.151252Z","caller":"traceutil/trace.go:171","msg":"trace[1564778518] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26729; }","duration":"318.669344ms","start":"2026-05-18T18:38:13.832561Z","end":"2026-05-18T18:38:14.15123Z","steps":["trace[1564778518] 'agreement among raft nodes before linearized reading'  (duration: 318.397829ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:14.151419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.448419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:38:14.151605Z","caller":"traceutil/trace.go:171","msg":"trace[1951824145] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26729; }","duration":"319.695735ms","start":"2026-05-18T18:38:13.831881Z","end":"2026-05-18T18:38:14.151577Z","steps":["trace[1951824145] 'agreement among raft nodes before linearized reading'  (duration: 319.354578ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:14.151706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:38:13.832555Z","time spent":"318.965699ms","remote":"127.0.0.1:40612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "}
{"level":"warn","ts":"2026-05-18T18:38:14.151733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:38:13.831862Z","time spent":"319.827597ms","remote":"127.0.0.1:40612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "}
{"level":"info","ts":"2026-05-18T18:38:21.500405Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006bfa.wal"}
{"level":"warn","ts":"2026-05-18T18:38:32.933342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.330253ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089402391 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:26849 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:459 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T18:38:32.93348Z","caller":"traceutil/trace.go:171","msg":"trace[2027976434] linearizableReadLoop","detail":"{readStateIndex:27728; appliedIndex:27727; }","duration":"283.837958ms","start":"2026-05-18T18:38:32.649622Z","end":"2026-05-18T18:38:32.93346Z","steps":["trace[2027976434] 'read index received'  (duration: 142.343412ms)","trace[2027976434] 'applied index is now lower than readState.Index'  (duration: 141.493406ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:38:32.933647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.046143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:38:32.9336Z","caller":"traceutil/trace.go:171","msg":"trace[3643949] transaction","detail":"{read_only:false; response_revision:26864; number_of_response:1; }","duration":"361.473922ms","start":"2026-05-18T18:38:32.572079Z","end":"2026-05-18T18:38:32.933553Z","steps":["trace[3643949] 'process raft request'  (duration: 219.804623ms)","trace[3643949] 'compare'  (duration: 140.970896ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:38:32.933682Z","caller":"traceutil/trace.go:171","msg":"trace[946909964] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26864; }","duration":"284.137744ms","start":"2026-05-18T18:38:32.649533Z","end":"2026-05-18T18:38:32.93367Z","steps":["trace[946909964] 'agreement among raft nodes before linearized reading'  (duration: 284.037462ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:32.933812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.861445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"warn","ts":"2026-05-18T18:38:32.933824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:38:32.572052Z","time spent":"361.647125ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" mod_revision:26849 > success:<request_put:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" value_size:459 >> failure:<request_range:<key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" > >"}
{"level":"info","ts":"2026-05-18T18:38:32.933874Z","caller":"traceutil/trace.go:171","msg":"trace[455341803] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:26864; }","duration":"105.957907ms","start":"2026-05-18T18:38:32.827901Z","end":"2026-05-18T18:38:32.933859Z","steps":["trace[455341803] 'agreement among raft nodes before linearized reading'  (duration: 105.835494ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:32.933914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.315938ms","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-05-18T18:38:32.93381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.889659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:38:32.93397Z","caller":"traceutil/trace.go:171","msg":"trace[440912621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26864; }","duration":"249.40157ms","start":"2026-05-18T18:38:32.684556Z","end":"2026-05-18T18:38:32.933957Z","steps":["trace[440912621] 'agreement among raft nodes before linearized reading'  (duration: 249.186986ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:32.933988Z","caller":"traceutil/trace.go:171","msg":"trace[1787155644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26864; }","duration":"215.159675ms","start":"2026-05-18T18:38:32.718818Z","end":"2026-05-18T18:38:32.933978Z","steps":["trace[1787155644] 'agreement among raft nodes before linearized reading'  (duration: 214.92052ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:32.934012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.383533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"}
{"level":"info","ts":"2026-05-18T18:38:32.934101Z","caller":"traceutil/trace.go:171","msg":"trace[427561828] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:26864; }","duration":"262.539627ms","start":"2026-05-18T18:38:32.671526Z","end":"2026-05-18T18:38:32.934065Z","steps":["trace[427561828] 'agreement among raft nodes before linearized reading'  (duration: 262.20179ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:32.934169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.338712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"}
{"level":"info","ts":"2026-05-18T18:38:32.934855Z","caller":"traceutil/trace.go:171","msg":"trace[2014819134] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26864; }","duration":"213.104116ms","start":"2026-05-18T18:38:32.721699Z","end":"2026-05-18T18:38:32.934803Z","steps":["trace[2014819134] 'agreement among raft nodes before linearized reading'  (duration: 212.052516ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:33.070997Z","caller":"traceutil/trace.go:171","msg":"trace[486411420] linearizableReadLoop","detail":"{readStateIndex:27729; appliedIndex:27728; }","duration":"135.976071ms","start":"2026-05-18T18:38:32.934986Z","end":"2026-05-18T18:38:33.070962Z","steps":["trace[486411420] 'read index received'  (duration: 130.714102ms)","trace[486411420] 'applied index is now lower than readState.Index'  (duration: 5.259939ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:38:33.071179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.168726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:38:33.071214Z","caller":"traceutil/trace.go:171","msg":"trace[1661130791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26864; }","duration":"136.270037ms","start":"2026-05-18T18:38:32.934934Z","end":"2026-05-18T18:38:33.071204Z","steps":["trace[1661130791] 'agreement among raft nodes before linearized reading'  (duration: 136.173525ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:33.072655Z","caller":"traceutil/trace.go:171","msg":"trace[1392112185] transaction","detail":"{read_only:false; response_revision:26865; number_of_response:1; }","duration":"133.676229ms","start":"2026-05-18T18:38:32.938956Z","end":"2026-05-18T18:38:33.072632Z","steps":["trace[1392112185] 'process raft request'  (duration: 133.494736ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:33.072931Z","caller":"traceutil/trace.go:171","msg":"trace[1252993114] transaction","detail":"{read_only:false; response_revision:26866; number_of_response:1; }","duration":"133.802482ms","start":"2026-05-18T18:38:32.939092Z","end":"2026-05-18T18:38:33.072895Z","steps":["trace[1252993114] 'process raft request'  (duration: 133.498336ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:51.212157Z","caller":"traceutil/trace.go:171","msg":"trace[1844607006] transaction","detail":"{read_only:false; response_revision:26994; number_of_response:1; }","duration":"215.466911ms","start":"2026-05-18T18:38:50.996657Z","end":"2026-05-18T18:38:51.212123Z","steps":["trace[1844607006] 'process raft request'  (duration: 215.280477ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:51.220818Z","caller":"traceutil/trace.go:171","msg":"trace[237029405] transaction","detail":"{read_only:false; response_revision:26995; number_of_response:1; }","duration":"200.707093ms","start":"2026-05-18T18:38:51.020078Z","end":"2026-05-18T18:38:51.220785Z","steps":["trace[237029405] 'process raft request'  (duration: 200.382507ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:51.524721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.409264ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089402848 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:26983 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T18:38:51.524906Z","caller":"traceutil/trace.go:171","msg":"trace[774457172] linearizableReadLoop","detail":"{readStateIndex:27865; appliedIndex:27864; }","duration":"215.660404ms","start":"2026-05-18T18:38:51.309221Z","end":"2026-05-18T18:38:51.524882Z","steps":["trace[774457172] 'read index received'  (duration: 72.959305ms)","trace[774457172] 'applied index is now lower than readState.Index'  (duration: 142.699309ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:38:51.525004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.785086ms","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-05-18T18:38:51.525039Z","caller":"traceutil/trace.go:171","msg":"trace[173596971] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:26997; }","duration":"215.862088ms","start":"2026-05-18T18:38:51.309166Z","end":"2026-05-18T18:38:51.525028Z","steps":["trace[173596971] 'agreement among raft nodes before linearized reading'  (duration: 215.782446ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:51.525039Z","caller":"traceutil/trace.go:171","msg":"trace[1823650960] transaction","detail":"{read_only:false; response_revision:26997; number_of_response:1; }","duration":"297.798673ms","start":"2026-05-18T18:38:51.227189Z","end":"2026-05-18T18:38:51.524987Z","steps":["trace[1823650960] 'process raft request'  (duration: 154.957301ms)","trace[1823650960] 'compare'  (duration: 142.169589ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:38:51.593813Z","caller":"traceutil/trace.go:171","msg":"trace[1792511987] transaction","detail":"{read_only:false; response_revision:26998; number_of_response:1; }","duration":"194.171999ms","start":"2026-05-18T18:38:51.399609Z","end":"2026-05-18T18:38:51.593781Z","steps":["trace[1792511987] 'process raft request'  (duration: 193.872953ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:51.808644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.391839ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089402852 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/capi-system/controller-leader-election-capi\" mod_revision:26985 > success:<request_put:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" value_size:453 >> failure:<request_range:<key:\"/registry/leases/capi-system/controller-leader-election-capi\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T18:38:51.808802Z","caller":"traceutil/trace.go:171","msg":"trace[1519815009] transaction","detail":"{read_only:false; response_revision:26999; number_of_response:1; }","duration":"190.626422ms","start":"2026-05-18T18:38:51.618153Z","end":"2026-05-18T18:38:51.808779Z","steps":["trace[1519815009] 'process raft request'  (duration: 26.965069ms)","trace[1519815009] 'compare'  (duration: 163.229046ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:38:52.069797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.640973ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089402853 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:26986 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T18:38:52.070368Z","caller":"traceutil/trace.go:171","msg":"trace[1401782941] transaction","detail":"{read_only:false; response_revision:27000; number_of_response:1; }","duration":"441.885706ms","start":"2026-05-18T18:38:51.62845Z","end":"2026-05-18T18:38:52.070336Z","steps":["trace[1401782941] 'process raft request'  (duration: 286.59054ms)","trace[1401782941] 'compare'  (duration: 154.500261ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:38:52.070506Z","caller":"traceutil/trace.go:171","msg":"trace[1377696957] transaction","detail":"{read_only:false; response_revision:27001; number_of_response:1; }","duration":"272.464884ms","start":"2026-05-18T18:38:51.798022Z","end":"2026-05-18T18:38:52.070487Z","steps":["trace[1377696957] 'process raft request'  (duration: 271.909494ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:52.070513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:38:51.628432Z","time spent":"442.002058ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:26986 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"info","ts":"2026-05-18T18:38:52.211272Z","caller":"traceutil/trace.go:171","msg":"trace[1291973218] transaction","detail":"{read_only:false; response_revision:27002; number_of_response:1; }","duration":"136.559293ms","start":"2026-05-18T18:38:52.074671Z","end":"2026-05-18T18:38:52.21123Z","steps":["trace[1291973218] 'process raft request'  (duration: 133.593278ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:52.614605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.989521ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089402861 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:26990 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T18:38:52.614714Z","caller":"traceutil/trace.go:171","msg":"trace[1575057352] transaction","detail":"{read_only:false; response_revision:27004; number_of_response:1; }","duration":"331.163411ms","start":"2026-05-18T18:38:52.283531Z","end":"2026-05-18T18:38:52.614694Z","steps":["trace[1575057352] 'process raft request'  (duration: 201.990597ms)","trace[1575057352] 'compare'  (duration: 128.525421ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:38:52.614781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:38:52.283498Z","time spent":"331.256333ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:26990 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"warn","ts":"2026-05-18T18:38:52.862539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.540627ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089402862 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:26991 > success:<request_put:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" value_size:442 >> failure:<request_range:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T18:38:52.862759Z","caller":"traceutil/trace.go:171","msg":"trace[1363849826] transaction","detail":"{read_only:false; response_revision:27005; number_of_response:1; }","duration":"578.868418ms","start":"2026-05-18T18:38:52.283852Z","end":"2026-05-18T18:38:52.86272Z","steps":["trace[1363849826] 'process raft request'  (duration: 454.005785ms)","trace[1363849826] 'compare'  (duration: 124.056958ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:38:52.86291Z","caller":"traceutil/trace.go:171","msg":"trace[1103658093] transaction","detail":"{read_only:false; response_revision:27007; number_of_response:1; }","duration":"103.46089ms","start":"2026-05-18T18:38:52.759427Z","end":"2026-05-18T18:38:52.862888Z","steps":["trace[1103658093] 'process raft request'  (duration: 103.400199ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:52.862939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:38:52.283845Z","time spent":"579.00105ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" mod_revision:26991 > success:<request_put:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" value_size:442 >> failure:<request_range:<key:\"/registry/leases/orc-system/f35396c5.k-orc.cloud\" > >"}
{"level":"info","ts":"2026-05-18T18:38:52.863055Z","caller":"traceutil/trace.go:171","msg":"trace[1831300441] transaction","detail":"{read_only:false; response_revision:27006; number_of_response:1; }","duration":"578.055063ms","start":"2026-05-18T18:38:52.284991Z","end":"2026-05-18T18:38:52.863046Z","steps":["trace[1831300441] 'process raft request'  (duration: 577.684496ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:52.863079Z","caller":"traceutil/trace.go:171","msg":"trace[74546024] linearizableReadLoop","detail":"{readStateIndex:27874; appliedIndex:27872; }","duration":"302.323847ms","start":"2026-05-18T18:38:52.560746Z","end":"2026-05-18T18:38:52.863069Z","steps":["trace[74546024] 'read index received'  (duration: 177.049577ms)","trace[74546024] 'applied index is now lower than readState.Index'  (duration: 125.26835ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:38:52.863128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:38:52.284973Z","time spent":"578.110893ms","remote":"127.0.0.1:40640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:26992 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:465 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-05-18T18:38:52.863203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.478791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:38:52.863235Z","caller":"traceutil/trace.go:171","msg":"trace[751750602] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27007; }","duration":"302.562842ms","start":"2026-05-18T18:38:52.560663Z","end":"2026-05-18T18:38:52.863226Z","steps":["trace[751750602] 'agreement among raft nodes before linearized reading'  (duration: 302.503871ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:52.863264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:38:52.560632Z","time spent":"302.627263ms","remote":"127.0.0.1:40612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "}
{"level":"warn","ts":"2026-05-18T18:38:52.863294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.921429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:38:52.863333Z","caller":"traceutil/trace.go:171","msg":"trace[213370932] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27007; }","duration":"302.007901ms","start":"2026-05-18T18:38:52.561314Z","end":"2026-05-18T18:38:52.863322Z","steps":["trace[213370932] 'agreement among raft nodes before linearized reading'  (duration: 301.94301ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:52.86337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:38:52.561286Z","time spent":"302.077732ms","remote":"127.0.0.1:40612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "}
{"level":"warn","ts":"2026-05-18T18:38:52.8635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.222436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:38:52.86353Z","caller":"traceutil/trace.go:171","msg":"trace[1819631370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27007; }","duration":"145.252967ms","start":"2026-05-18T18:38:52.718269Z","end":"2026-05-18T18:38:52.863522Z","steps":["trace[1819631370] 'agreement among raft nodes before linearized reading'  (duration: 145.174235ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:52.863678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.710091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:38:52.863704Z","caller":"traceutil/trace.go:171","msg":"trace[262708542] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27007; }","duration":"297.753082ms","start":"2026-05-18T18:38:52.565943Z","end":"2026-05-18T18:38:52.863696Z","steps":["trace[262708542] 'agreement among raft nodes before linearized reading'  (duration: 297.710391ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:52.86372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.418744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:38:52.863757Z","caller":"traceutil/trace.go:171","msg":"trace[697677345] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27007; }","duration":"298.475985ms","start":"2026-05-18T18:38:52.565272Z","end":"2026-05-18T18:38:52.863748Z","steps":["trace[697677345] 'agreement among raft nodes before linearized reading'  (duration: 298.423754ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:52.863813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.150421ms","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":"info","ts":"2026-05-18T18:38:52.863845Z","caller":"traceutil/trace.go:171","msg":"trace[631121744] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:27007; }","duration":"202.217251ms","start":"2026-05-18T18:38:52.66162Z","end":"2026-05-18T18:38:52.863837Z","steps":["trace[631121744] 'agreement among raft nodes before linearized reading'  (duration: 202.15703ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:52.863818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.341812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:38:52.863896Z","caller":"traceutil/trace.go:171","msg":"trace[233179179] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27007; }","duration":"299.440723ms","start":"2026-05-18T18:38:52.564435Z","end":"2026-05-18T18:38:52.863875Z","steps":["trace[233179179] 'agreement among raft nodes before linearized reading'  (duration: 299.319241ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:52.863922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.511935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:38:52.863953Z","caller":"traceutil/trace.go:171","msg":"trace[1396033320] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27007; }","duration":"299.560695ms","start":"2026-05-18T18:38:52.564383Z","end":"2026-05-18T18:38:52.863944Z","steps":["trace[1396033320] 'agreement among raft nodes before linearized reading'  (duration: 299.512414ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:52.864082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.744188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-05-18T18:38:52.86411Z","caller":"traceutil/trace.go:171","msg":"trace[784773723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27007; }","duration":"291.788838ms","start":"2026-05-18T18:38:52.572315Z","end":"2026-05-18T18:38:52.864103Z","steps":["trace[784773723] 'agreement among raft nodes before linearized reading'  (duration: 291.666596ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:52.864251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.751299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"}
{"level":"info","ts":"2026-05-18T18:38:52.864291Z","caller":"traceutil/trace.go:171","msg":"trace[566218195] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27007; }","duration":"299.79128ms","start":"2026-05-18T18:38:52.56449Z","end":"2026-05-18T18:38:52.864281Z","steps":["trace[566218195] 'agreement among raft nodes before linearized reading'  (duration: 299.624457ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:52.864295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.236001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"}
{"level":"info","ts":"2026-05-18T18:38:52.864331Z","caller":"traceutil/trace.go:171","msg":"trace[489707597] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27007; }","duration":"298.284921ms","start":"2026-05-18T18:38:52.566036Z","end":"2026-05-18T18:38:52.864321Z","steps":["trace[489707597] 'agreement among raft nodes before linearized reading'  (duration: 298.116378ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:52.864385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.663529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:38:52.864421Z","caller":"traceutil/trace.go:171","msg":"trace[1436746372] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27007; }","duration":"297.71506ms","start":"2026-05-18T18:38:52.566695Z","end":"2026-05-18T18:38:52.86441Z","steps":["trace[1436746372] 'agreement among raft nodes before linearized reading'  (duration: 297.666569ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:52.864434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.874374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:38:52.864459Z","caller":"traceutil/trace.go:171","msg":"trace[1896303624] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27007; }","duration":"297.914335ms","start":"2026-05-18T18:38:52.566537Z","end":"2026-05-18T18:38:52.864451Z","steps":["trace[1896303624] 'agreement among raft nodes before linearized reading'  (duration: 297.872784ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:53.02207Z","caller":"traceutil/trace.go:171","msg":"trace[1245052283] transaction","detail":"{read_only:false; response_revision:27008; number_of_response:1; }","duration":"139.512899ms","start":"2026-05-18T18:38:52.882533Z","end":"2026-05-18T18:38:53.022046Z","steps":["trace[1245052283] 'process raft request'  (duration: 139.124602ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:53.28288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.879773ms","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-05-18T18:38:53.282955Z","caller":"traceutil/trace.go:171","msg":"trace[556610303] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:27009; }","duration":"126.015624ms","start":"2026-05-18T18:38:53.156924Z","end":"2026-05-18T18:38:53.28294Z","steps":["trace[556610303] 'count revisions from in-memory index tree'  (duration: 125.79732ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:53.34696Z","caller":"traceutil/trace.go:171","msg":"trace[1506228006] transaction","detail":"{read_only:false; response_revision:27010; number_of_response:1; }","duration":"129.424698ms","start":"2026-05-18T18:38:53.217509Z","end":"2026-05-18T18:38:53.346934Z","steps":["trace[1506228006] 'process raft request'  (duration: 129.008331ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:53.47679Z","caller":"traceutil/trace.go:171","msg":"trace[1200370115] transaction","detail":"{read_only:false; response_revision:27011; number_of_response:1; }","duration":"118.160027ms","start":"2026-05-18T18:38:53.358605Z","end":"2026-05-18T18:38:53.476765Z","steps":["trace[1200370115] 'process raft request'  (duration: 118.023214ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:54.213191Z","caller":"traceutil/trace.go:171","msg":"trace[319997990] transaction","detail":"{read_only:false; response_revision:27016; number_of_response:1; }","duration":"125.816151ms","start":"2026-05-18T18:38:54.087353Z","end":"2026-05-18T18:38:54.213169Z","steps":["trace[319997990] 'process raft request'  (duration: 116.205311ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:56.374308Z","caller":"traceutil/trace.go:171","msg":"trace[1055392057] transaction","detail":"{read_only:false; response_revision:27029; number_of_response:1; }","duration":"155.324847ms","start":"2026-05-18T18:38:56.218953Z","end":"2026-05-18T18:38:56.374278Z","steps":["trace[1055392057] 'process raft request'  (duration: 100.988513ms)","trace[1055392057] 'compare'  (duration: 54.015538ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:38:56.374374Z","caller":"traceutil/trace.go:171","msg":"trace[198259469] linearizableReadLoop","detail":"{readStateIndex:27898; appliedIndex:27897; }","duration":"147.987279ms","start":"2026-05-18T18:38:56.226375Z","end":"2026-05-18T18:38:56.374363Z","steps":["trace[198259469] 'read index received'  (duration: 93.540292ms)","trace[198259469] 'applied index is now lower than readState.Index'  (duration: 54.445717ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:38:56.374313Z","caller":"traceutil/trace.go:171","msg":"trace[365144264] transaction","detail":"{read_only:false; response_revision:27030; number_of_response:1; }","duration":"147.321986ms","start":"2026-05-18T18:38:56.226966Z","end":"2026-05-18T18:38:56.374288Z","steps":["trace[365144264] 'process raft request'  (duration: 147.221915ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:56.374596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.196383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"info","ts":"2026-05-18T18:38:56.374672Z","caller":"traceutil/trace.go:171","msg":"trace[1438135683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27030; }","duration":"148.335865ms","start":"2026-05-18T18:38:56.226318Z","end":"2026-05-18T18:38:56.374654Z","steps":["trace[1438135683] 'agreement among raft nodes before linearized reading'  (duration: 148.136201ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:56.374862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.50757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"}
{"level":"info","ts":"2026-05-18T18:38:56.374902Z","caller":"traceutil/trace.go:171","msg":"trace[243124160] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:27030; }","duration":"129.549521ms","start":"2026-05-18T18:38:56.245342Z","end":"2026-05-18T18:38:56.374891Z","steps":["trace[243124160] 'agreement among raft nodes before linearized reading'  (duration: 129.449369ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:58.541153Z","caller":"traceutil/trace.go:171","msg":"trace[1589125320] linearizableReadLoop","detail":"{readStateIndex:27915; appliedIndex:27914; }","duration":"111.42605ms","start":"2026-05-18T18:38:58.429702Z","end":"2026-05-18T18:38:58.541128Z","steps":["trace[1589125320] 'read index received'  (duration: 111.380909ms)","trace[1589125320] 'applied index is now lower than readState.Index'  (duration: 44.001µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:38:58.5413Z","caller":"traceutil/trace.go:171","msg":"trace[459885079] transaction","detail":"{read_only:false; response_revision:27045; number_of_response:1; }","duration":"158.555687ms","start":"2026-05-18T18:38:58.382673Z","end":"2026-05-18T18:38:58.541229Z","steps":["trace[459885079] 'process raft request'  (duration: 158.221501ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:58.541369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.645714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-05-18T18:38:58.541418Z","caller":"traceutil/trace.go:171","msg":"trace[1786989953] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:27045; }","duration":"111.759736ms","start":"2026-05-18T18:38:58.429647Z","end":"2026-05-18T18:38:58.541407Z","steps":["trace[1786989953] 'agreement among raft nodes before linearized reading'  (duration: 111.639094ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:38:58.643521Z","caller":"traceutil/trace.go:171","msg":"trace[1146664561] linearizableReadLoop","detail":"{readStateIndex:27916; appliedIndex:27915; }","duration":"102.242737ms","start":"2026-05-18T18:38:58.541254Z","end":"2026-05-18T18:38:58.643497Z","steps":["trace[1146664561] 'read index received'  (duration: 72.383044ms)","trace[1146664561] 'applied index is now lower than readState.Index'  (duration: 29.857853ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:38:58.643879Z","caller":"traceutil/trace.go:171","msg":"trace[575102010] transaction","detail":"{read_only:false; response_revision:27046; number_of_response:1; }","duration":"168.418643ms","start":"2026-05-18T18:38:58.475437Z","end":"2026-05-18T18:38:58.643855Z","steps":["trace[575102010] 'process raft request'  (duration: 138.263045ms)","trace[575102010] 'compare'  (duration: 29.673379ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:38:58.644032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.230199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"}
{"level":"info","ts":"2026-05-18T18:38:58.644104Z","caller":"traceutil/trace.go:171","msg":"trace[1190695774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27046; }","duration":"187.3392ms","start":"2026-05-18T18:38:58.456748Z","end":"2026-05-18T18:38:58.644087Z","steps":["trace[1190695774] 'agreement among raft nodes before linearized reading'  (duration: 187.100406ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:38:58.644159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.739436ms","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-05-18T18:38:58.644253Z","caller":"traceutil/trace.go:171","msg":"trace[720126285] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27047; }","duration":"188.886889ms","start":"2026-05-18T18:38:58.455347Z","end":"2026-05-18T18:38:58.644234Z","steps":["trace[720126285] 'agreement among raft nodes before linearized reading'  (duration: 188.575753ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:39:32.072564Z","caller":"traceutil/trace.go:171","msg":"trace[256965733] transaction","detail":"{read_only:false; response_revision:27308; number_of_response:1; }","duration":"119.871339ms","start":"2026-05-18T18:39:31.95267Z","end":"2026-05-18T18:39:32.072541Z","steps":["trace[256965733] 'process raft request'  (duration: 119.731506ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:39:33.15945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.010117ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089403860 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/masterleases/199.19.213.156\" mod_revision:27227 > success:<request_put:<key:\"/registry/masterleases/199.19.213.156\" value_size:68 lease:5164395379089403856 >> failure:<request_range:<key:\"/registry/masterleases/199.19.213.156\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T18:39:33.159585Z","caller":"traceutil/trace.go:171","msg":"trace[68615192] linearizableReadLoop","detail":"{readStateIndex:28193; appliedIndex:28191; }","duration":"134.758639ms","start":"2026-05-18T18:39:33.024816Z","end":"2026-05-18T18:39:33.159575Z","steps":["trace[68615192] 'read index received'  (duration: 131.05115ms)","trace[68615192] 'applied index is now lower than readState.Index'  (duration: 3.706949ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:39:33.159627Z","caller":"traceutil/trace.go:171","msg":"trace[1475726093] transaction","detail":"{read_only:false; response_revision:27316; number_of_response:1; }","duration":"151.844201ms","start":"2026-05-18T18:39:33.007777Z","end":"2026-05-18T18:39:33.159622Z","steps":["trace[1475726093] 'process raft request'  (duration: 151.748689ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:39:33.159709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.891362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:39:33.15973Z","caller":"traceutil/trace.go:171","msg":"trace[585322407] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:27316; }","duration":"134.945333ms","start":"2026-05-18T18:39:33.024777Z","end":"2026-05-18T18:39:33.159723Z","steps":["trace[585322407] 'agreement among raft nodes before linearized reading'  (duration: 134.907622ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:39:33.159772Z","caller":"traceutil/trace.go:171","msg":"trace[447479134] transaction","detail":"{read_only:false; response_revision:27315; number_of_response:1; }","duration":"174.856175ms","start":"2026-05-18T18:39:32.984899Z","end":"2026-05-18T18:39:33.159755Z","steps":["trace[447479134] 'process raft request'  (duration: 15.484771ms)","trace[447479134] 'compare'  (duration: 158.64891ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:39:33.185567Z","caller":"traceutil/trace.go:171","msg":"trace[81171577] transaction","detail":"{read_only:false; response_revision:27317; number_of_response:1; }","duration":"153.497433ms","start":"2026-05-18T18:39:33.03205Z","end":"2026-05-18T18:39:33.185548Z","steps":["trace[81171577] 'process raft request'  (duration: 153.400811ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:39:33.528156Z","caller":"traceutil/trace.go:171","msg":"trace[472112720] linearizableReadLoop","detail":"{readStateIndex:28195; appliedIndex:28194; }","duration":"342.462273ms","start":"2026-05-18T18:39:33.185667Z","end":"2026-05-18T18:39:33.52813Z","steps":["trace[472112720] 'read index received'  (duration: 342.370091ms)","trace[472112720] 'applied index is now lower than readState.Index'  (duration: 91.132µs)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:39:33.528332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.226209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:1 size:5007"}
{"level":"info","ts":"2026-05-18T18:39:33.528403Z","caller":"traceutil/trace.go:171","msg":"trace[1772494200] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:1; response_revision:27318; }","duration":"351.327011ms","start":"2026-05-18T18:39:33.177062Z","end":"2026-05-18T18:39:33.528389Z","steps":["trace[1772494200] 'agreement among raft nodes before linearized reading'  (duration: 351.163458ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:39:33.528439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:39:33.177054Z","time spent":"351.376271ms","remote":"127.0.0.1:40612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5029,"request content":"key:\"/registry/jobs/openstack/manila-db-sync\" "}
{"level":"info","ts":"2026-05-18T18:39:33.528352Z","caller":"traceutil/trace.go:171","msg":"trace[1346866487] transaction","detail":"{read_only:false; response_revision:27318; number_of_response:1; }","duration":"361.321649ms","start":"2026-05-18T18:39:33.166906Z","end":"2026-05-18T18:39:33.528228Z","steps":["trace[1346866487] 'process raft request'  (duration: 361.029462ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:39:33.528503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.147894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"}
{"level":"info","ts":"2026-05-18T18:39:33.528551Z","caller":"traceutil/trace.go:171","msg":"trace[215812072] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:27318; }","duration":"363.239915ms","start":"2026-05-18T18:39:33.165297Z","end":"2026-05-18T18:39:33.528537Z","steps":["trace[215812072] 'agreement among raft nodes before linearized reading'  (duration: 362.97706ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:39:33.528604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:39:33.165284Z","time spent":"363.298896ms","remote":"127.0.0.1:40542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "}
{"level":"warn","ts":"2026-05-18T18:39:33.528703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:39:33.166891Z","time spent":"361.628345ms","remote":"127.0.0.1:40612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4989,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/jobs/openstack/manila-db-sync\" mod_revision:25765 > success:<request_put:<key:\"/registry/jobs/openstack/manila-db-sync\" value_size:4942 >> failure:<request_range:<key:\"/registry/jobs/openstack/manila-db-sync\" > >"}
{"level":"warn","ts":"2026-05-18T18:39:33.59456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.240454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-05-18T18:39:33.594582Z","caller":"traceutil/trace.go:171","msg":"trace[922934873] transaction","detail":"{read_only:false; response_revision:27319; number_of_response:1; }","duration":"103.885448ms","start":"2026-05-18T18:39:33.490665Z","end":"2026-05-18T18:39:33.594551Z","steps":["trace[922934873] 'process raft request'  (duration: 88.477968ms)","trace[922934873] 'compare'  (duration: 15.059473ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:39:33.594645Z","caller":"traceutil/trace.go:171","msg":"trace[654792797] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:27319; }","duration":"398.377967ms","start":"2026-05-18T18:39:33.196249Z","end":"2026-05-18T18:39:33.594627Z","steps":["trace[654792797] 'agreement among raft nodes before linearized reading'  (duration: 398.219154ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:39:33.594604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.944347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"warn","ts":"2026-05-18T18:39:33.594686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:39:33.196233Z","time spent":"398.441618ms","remote":"127.0.0.1:40832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "}
{"level":"info","ts":"2026-05-18T18:39:33.594741Z","caller":"traceutil/trace.go:171","msg":"trace[2022177689] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:27319; }","duration":"313.161312ms","start":"2026-05-18T18:39:33.281551Z","end":"2026-05-18T18:39:33.594712Z","steps":["trace[2022177689] 'agreement among raft nodes before linearized reading'  (duration: 312.894447ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:39:33.594802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.767459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"}
{"level":"warn","ts":"2026-05-18T18:39:33.594813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:39:33.28153Z","time spent":"313.268873ms","remote":"127.0.0.1:34132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":30,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "}
{"level":"info","ts":"2026-05-18T18:39:33.594839Z","caller":"traceutil/trace.go:171","msg":"trace[1593423014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27319; }","duration":"342.83572ms","start":"2026-05-18T18:39:33.251989Z","end":"2026-05-18T18:39:33.594824Z","steps":["trace[1593423014] 'agreement among raft nodes before linearized reading'  (duration: 342.719027ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:39:33.594876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:39:33.251944Z","time spent":"342.924091ms","remote":"127.0.0.1:40640","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-05-18T18:39:33.720908Z","caller":"traceutil/trace.go:171","msg":"trace[1766416585] transaction","detail":"{read_only:false; response_revision:27320; number_of_response:1; }","duration":"121.58568ms","start":"2026-05-18T18:39:33.599245Z","end":"2026-05-18T18:39:33.720831Z","steps":["trace[1766416585] 'process raft request'  (duration: 115.148619ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:39:33.973803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.477501ms","expected-duration":"100ms","prefix":"","request":"header:<ID:5164395379089403875 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:27305 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:18"}
{"level":"info","ts":"2026-05-18T18:39:33.973941Z","caller":"traceutil/trace.go:171","msg":"trace[1887653747] linearizableReadLoop","detail":"{readStateIndex:28198; appliedIndex:28197; }","duration":"237.835632ms","start":"2026-05-18T18:39:33.736081Z","end":"2026-05-18T18:39:33.973916Z","steps":["trace[1887653747] 'read index received'  (duration: 126.101596ms)","trace[1887653747] 'applied index is now lower than readState.Index'  (duration: 111.732356ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:39:33.973989Z","caller":"traceutil/trace.go:171","msg":"trace[1207474388] transaction","detail":"{read_only:false; response_revision:27321; number_of_response:1; }","duration":"249.40092ms","start":"2026-05-18T18:39:33.724557Z","end":"2026-05-18T18:39:33.973958Z","steps":["trace[1207474388] 'process raft request'  (duration: 137.634884ms)","trace[1207474388] 'compare'  (duration: 110.645134ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:39:33.974183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.110387ms","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-05-18T18:39:33.974227Z","caller":"traceutil/trace.go:171","msg":"trace[162416410] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27321; }","duration":"238.173858ms","start":"2026-05-18T18:39:33.736041Z","end":"2026-05-18T18:39:33.974215Z","steps":["trace[162416410] 'agreement among raft nodes before linearized reading'  (duration: 237.977465ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:39:34.056304Z","caller":"traceutil/trace.go:171","msg":"trace[733560853] transaction","detail":"{read_only:false; response_revision:27322; number_of_response:1; }","duration":"196.812439ms","start":"2026-05-18T18:39:33.859461Z","end":"2026-05-18T18:39:34.056273Z","steps":["trace[733560853] 'process raft request'  (duration: 196.562594ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:39:34.203179Z","caller":"traceutil/trace.go:171","msg":"trace[1447186617] transaction","detail":"{read_only:false; response_revision:27324; number_of_response:1; }","duration":"118.762087ms","start":"2026-05-18T18:39:34.084384Z","end":"2026-05-18T18:39:34.203146Z","steps":["trace[1447186617] 'process raft request'  (duration: 118.605824ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:39:34.203524Z","caller":"traceutil/trace.go:171","msg":"trace[426804771] transaction","detail":"{read_only:false; response_revision:27323; number_of_response:1; }","duration":"215.719045ms","start":"2026-05-18T18:39:33.987762Z","end":"2026-05-18T18:39:34.203481Z","steps":["trace[426804771] 'process raft request'  (duration: 118.792968ms)","trace[426804771] 'compare'  (duration: 96.024459ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:39:34.203559Z","caller":"traceutil/trace.go:171","msg":"trace[1924778222] linearizableReadLoop","detail":"{readStateIndex:28200; appliedIndex:28198; }","duration":"186.019706ms","start":"2026-05-18T18:39:34.017501Z","end":"2026-05-18T18:39:34.203521Z","steps":["trace[1924778222] 'read index received'  (duration: 38.574307ms)","trace[1924778222] 'applied index is now lower than readState.Index'  (duration: 147.442849ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:39:34.203761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.24092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-05-18T18:39:34.203882Z","caller":"traceutil/trace.go:171","msg":"trace[794058654] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:27324; }","duration":"186.422133ms","start":"2026-05-18T18:39:34.017435Z","end":"2026-05-18T18:39:34.203857Z","steps":["trace[794058654] 'agreement among raft nodes before linearized reading'  (duration: 186.23833ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:39:40.397958Z","caller":"traceutil/trace.go:171","msg":"trace[130292725] transaction","detail":"{read_only:false; response_revision:27366; number_of_response:1; }","duration":"310.523711ms","start":"2026-05-18T18:39:40.087404Z","end":"2026-05-18T18:39:40.397928Z","steps":["trace[130292725] 'process raft request'  (duration: 255.80019ms)","trace[130292725] 'compare'  (duration: 54.584969ms)"],"step_count":2}
{"level":"warn","ts":"2026-05-18T18:39:40.39815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-18T18:39:40.087362Z","time spent":"310.713535ms","remote":"127.0.0.1:40552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18493,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/minions/instance\" mod_revision:26535 > success:<request_put:<key:\"/registry/minions/instance\" value_size:18457 >> failure:<request_range:<key:\"/registry/minions/instance\" > >"}
{"level":"info","ts":"2026-05-18T18:39:40.473762Z","caller":"traceutil/trace.go:171","msg":"trace[384120939] transaction","detail":"{read_only:false; response_revision:27367; number_of_response:1; }","duration":"251.713443ms","start":"2026-05-18T18:39:40.221973Z","end":"2026-05-18T18:39:40.473686Z","steps":["trace[384120939] 'process raft request'  (duration: 251.438198ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:39:40.474061Z","caller":"traceutil/trace.go:171","msg":"trace[746384863] linearizableReadLoop","detail":"{readStateIndex:28245; appliedIndex:28243; }","duration":"247.973313ms","start":"2026-05-18T18:39:40.226053Z","end":"2026-05-18T18:39:40.474027Z","steps":["trace[746384863] 'read index received'  (duration: 117.379822ms)","trace[746384863] 'applied index is now lower than readState.Index'  (duration: 130.590001ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:39:40.474331Z","caller":"traceutil/trace.go:171","msg":"trace[816413468] transaction","detail":"{read_only:false; response_revision:27368; number_of_response:1; }","duration":"164.876928ms","start":"2026-05-18T18:39:40.309429Z","end":"2026-05-18T18:39:40.474306Z","steps":["trace[816413468] 'process raft request'  (duration: 164.176065ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:39:40.474372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.293099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"}
{"level":"info","ts":"2026-05-18T18:39:40.474615Z","caller":"traceutil/trace.go:171","msg":"trace[1145503661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27368; }","duration":"248.576764ms","start":"2026-05-18T18:39:40.226018Z","end":"2026-05-18T18:39:40.474595Z","steps":["trace[1145503661] 'agreement among raft nodes before linearized reading'  (duration: 248.171436ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:39:41.348728Z","caller":"traceutil/trace.go:171","msg":"trace[761074867] transaction","detail":"{read_only:false; response_revision:27374; number_of_response:1; }","duration":"163.610743ms","start":"2026-05-18T18:39:41.185043Z","end":"2026-05-18T18:39:41.348653Z","steps":["trace[761074867] 'process raft request'  (duration: 163.335098ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:39:41.348834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.880532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-05-18T18:39:41.348893Z","caller":"traceutil/trace.go:171","msg":"trace[1758115676] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27374; }","duration":"142.993705ms","start":"2026-05-18T18:39:41.205884Z","end":"2026-05-18T18:39:41.348878Z","steps":["trace[1758115676] 'agreement among raft nodes before linearized reading'  (duration: 142.840452ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:39:41.348876Z","caller":"traceutil/trace.go:171","msg":"trace[1919527689] linearizableReadLoop","detail":"{readStateIndex:28252; appliedIndex:28251; }","duration":"142.676289ms","start":"2026-05-18T18:39:41.205927Z","end":"2026-05-18T18:39:41.348603Z","steps":["trace[1919527689] 'read index received'  (duration: 142.470675ms)","trace[1919527689] 'applied index is now lower than readState.Index'  (duration: 204.094µs)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:39:41.393641Z","caller":"traceutil/trace.go:171","msg":"trace[2013255265] transaction","detail":"{read_only:false; response_revision:27375; number_of_response:1; }","duration":"185.148259ms","start":"2026-05-18T18:39:41.208429Z","end":"2026-05-18T18:39:41.393577Z","steps":["trace[2013255265] 'process raft request'  (duration: 184.840773ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:39:41.393835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.429167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418774"}
{"level":"info","ts":"2026-05-18T18:39:41.393961Z","caller":"traceutil/trace.go:171","msg":"trace[1246969413] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:27375; }","duration":"113.60905ms","start":"2026-05-18T18:39:41.280323Z","end":"2026-05-18T18:39:41.393932Z","steps":["trace[1246969413] 'agreement among raft nodes before linearized reading'  (duration: 113.157621ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T18:39:41.556971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.860353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"}
{"level":"info","ts":"2026-05-18T18:39:41.557031Z","caller":"traceutil/trace.go:171","msg":"trace[682033517] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:27375; }","duration":"133.966335ms","start":"2026-05-18T18:39:41.423051Z","end":"2026-05-18T18:39:41.557017Z","steps":["trace[682033517] 'range keys from in-memory index tree'  (duration: 133.718611ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:39:41.557436Z","caller":"traceutil/trace.go:171","msg":"trace[1031510314] transaction","detail":"{read_only:false; response_revision:27376; number_of_response:1; }","duration":"106.855174ms","start":"2026-05-18T18:39:41.450549Z","end":"2026-05-18T18:39:41.557404Z","steps":["trace[1031510314] 'process raft request'  (duration: 44.349865ms)","trace[1031510314] 'compare'  (duration: 62.273245ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:39:41.783958Z","caller":"traceutil/trace.go:171","msg":"trace[180604540] transaction","detail":"{read_only:false; response_revision:27378; number_of_response:1; }","duration":"132.969306ms","start":"2026-05-18T18:39:41.650964Z","end":"2026-05-18T18:39:41.783933Z","steps":["trace[180604540] 'process raft request'  (duration: 89.656789ms)","trace[180604540] 'compare'  (duration: 42.878938ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:39:42.020664Z","caller":"traceutil/trace.go:171","msg":"trace[202562416] transaction","detail":"{read_only:false; response_revision:27379; number_of_response:1; }","duration":"207.5157ms","start":"2026-05-18T18:39:41.813115Z","end":"2026-05-18T18:39:42.02063Z","steps":["trace[202562416] 'process raft request'  (duration: 206.814418ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T18:39:42.60681Z","caller":"traceutil/trace.go:171","msg":"trace[418550800] transaction","detail":"{read_only:false; response_revision:27384; number_of_response:1; }","duration":"100.929952ms","start":"2026-05-18T18:39:42.505842Z","end":"2026-05-18T18:39:42.606772Z","steps":["trace[418550800] 'process raft request'  (duration: 44.527589ms)","trace[418550800] 'compare'  (duration: 56.164368ms)"],"step_count":2}
{"level":"info","ts":"2026-05-18T18:41:29.911132Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25964}
{"level":"info","ts":"2026-05-18T18:41:30.065748Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25964,"took":"150.043264ms","hash":4127816167,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":31723520,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-05-18T18:41:30.065843Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4127816167,"revision":25964,"compact-revision":23008}
{"level":"info","ts":"2026-05-18T18:42:35.687263Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"163ef968c92247ab","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-05-18T18:42:35.69695Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003}
{"level":"info","ts":"2026-05-18T18:42:35.697032Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003}
{"level":"info","ts":"2026-05-18T18:46:29.917474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28388}
{"level":"info","ts":"2026-05-18T18:46:30.035397Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28388,"took":"114.420425ms","hash":3869929839,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":35147776,"current-db-size-in-use":"35 MB"}
{"level":"info","ts":"2026-05-18T18:46:30.035477Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3869929839,"revision":28388,"compact-revision":25964}
{"level":"info","ts":"2026-05-18T18:51:29.923725Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30895}
{"level":"info","ts":"2026-05-18T18:51:30.060837Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30895,"took":"133.039826ms","hash":1414138497,"current-db-size-bytes":58998784,"current-db-size":"59 MB","current-db-size-in-use-bytes":30289920,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-05-18T18:51:30.06099Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1414138497,"revision":30895,"compact-revision":28388}
{"level":"info","ts":"2026-05-18T18:56:29.937834Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33158}
{"level":"info","ts":"2026-05-18T18:56:30.176477Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33158,"took":"232.215664ms","hash":1319427959,"current-db-size-bytes":60039168,"current-db-size":"60 MB","current-db-size-in-use-bytes":30392320,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-05-18T18:56:30.176578Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1319427959,"revision":33158,"compact-revision":30895}
{"level":"info","ts":"2026-05-18T19:01:29.944591Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35457}
{"level":"info","ts":"2026-05-18T19:01:30.093383Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35457,"took":"143.934225ms","hash":1357507427,"current-db-size-bytes":60039168,"current-db-size":"60 MB","current-db-size-in-use-bytes":31772672,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-05-18T19:01:30.093459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1357507427,"revision":35457,"compact-revision":33158}
{"level":"info","ts":"2026-05-18T19:03:46.953012Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"163ef968c92247ab","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-05-18T19:03:46.958234Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004}
{"level":"info","ts":"2026-05-18T19:03:46.958346Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}
{"level":"warn","ts":"2026-05-18T19:05:00.180682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.028092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/orc-system\" ","response":"range_response_count:1 size:502"}
{"level":"info","ts":"2026-05-18T19:05:00.180783Z","caller":"traceutil/trace.go:171","msg":"trace[905273322] range","detail":"{range_begin:/registry/namespaces/orc-system; range_end:; response_count:1; response_revision:39347; }","duration":"114.174806ms","start":"2026-05-18T19:05:00.066587Z","end":"2026-05-18T19:05:00.180761Z","steps":["trace[905273322] 'range keys from in-memory index tree'  (duration: 113.874499ms)"],"step_count":1}
{"level":"warn","ts":"2026-05-18T19:05:00.180923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.414002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/openstack\" ","response":"range_response_count:1 size:369"}
{"level":"info","ts":"2026-05-18T19:05:00.181028Z","caller":"traceutil/trace.go:171","msg":"trace[1053547299] range","detail":"{range_begin:/registry/namespaces/openstack; range_end:; response_count:1; response_revision:39347; }","duration":"113.558085ms","start":"2026-05-18T19:05:00.067445Z","end":"2026-05-18T19:05:00.181003Z","steps":["trace[1053547299] 'range keys from in-memory index tree'  (duration: 113.190328ms)"],"step_count":1}
{"level":"info","ts":"2026-05-18T19:06:29.951204Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37753}
{"level":"info","ts":"2026-05-18T19:06:30.071163Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37753,"took":"116.955959ms","hash":2238580623,"current-db-size-bytes":60882944,"current-db-size":"61 MB","current-db-size-in-use-bytes":32141312,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-05-18T19:06:30.071221Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2238580623,"revision":37753,"compact-revision":35457}