{"level":"warn","ts":"2026-10-15T19:00:55.936129Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T19:00:55.936251Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.138:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.138:2380","--initial-cluster=instance=https://162.253.55.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.138:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-15T19:00:55.936375Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-15T19:00:55.936392Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.138:2380"]} {"level":"info","ts":"2026-10-15T19:00:55.936422Z","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-10-15T19:00:55.937609Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.138:2379"]} {"level":"info","ts":"2026-10-15T19:00:55.937773Z","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://162.253.55.138:2380"],"listen-peer-urls":["https://162.253.55.138:2380"],"advertise-client-urls":["https://162.253.55.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.138:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-15T19:00:55.942949Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.77652ms"} {"level":"info","ts":"2026-10-15T19:00:55.954125Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ee8e5a779fc25cea","cluster-id":"8f8e3b77da0cbcef"} {"level":"info","ts":"2026-10-15T19:00:55.954225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea switched to configuration voters=()"} {"level":"info","ts":"2026-10-15T19:00:55.954257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became follower at term 0"} {"level":"info","ts":"2026-10-15T19:00:55.954277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ee8e5a779fc25cea [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-15T19:00:55.9543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became follower at term 1"} {"level":"info","ts":"2026-10-15T19:00:55.954358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea switched to configuration voters=(17189776297547685098)"} {"level":"warn","ts":"2026-10-15T19:00:55.968188Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-15T19:00:55.969994Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-15T19:00:55.971629Z","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-10-15T19:00:55.975606Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ee8e5a779fc25cea","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-15T19:00:55.981119Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ee8e5a779fc25cea","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-15T19:00:55.981312Z","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-10-15T19:00:55.981378Z","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-10-15T19:00:55.981397Z","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-10-15T19:00:55.981868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea switched to configuration voters=(17189776297547685098)"} {"level":"info","ts":"2026-10-15T19:00:55.982Z","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-10-15T19:00:55.982476Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.138:2380"} {"level":"info","ts":"2026-10-15T19:00:55.982555Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.138:2380"} {"level":"info","ts":"2026-10-15T19:00:55.982503Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8f8e3b77da0cbcef","local-member-id":"ee8e5a779fc25cea","added-peer-id":"ee8e5a779fc25cea","added-peer-peer-urls":["https://162.253.55.138:2380"]} {"level":"info","ts":"2026-10-15T19:00:55.982933Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ee8e5a779fc25cea","initial-advertise-peer-urls":["https://162.253.55.138:2380"],"listen-peer-urls":["https://162.253.55.138:2380"],"advertise-client-urls":["https://162.253.55.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-15T19:00:55.983013Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-15T19:00:56.954792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea is starting a new election at term 1"} {"level":"info","ts":"2026-10-15T19:00:56.954852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became pre-candidate at term 1"} {"level":"info","ts":"2026-10-15T19:00:56.954895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea received MsgPreVoteResp from ee8e5a779fc25cea at term 1"} {"level":"info","ts":"2026-10-15T19:00:56.954914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became candidate at term 2"} {"level":"info","ts":"2026-10-15T19:00:56.954925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea received MsgVoteResp from ee8e5a779fc25cea at term 2"} {"level":"info","ts":"2026-10-15T19:00:56.95494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ee8e5a779fc25cea became leader at term 2"} {"level":"info","ts":"2026-10-15T19:00:56.954958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ee8e5a779fc25cea elected leader ee8e5a779fc25cea at term 2"} {"level":"info","ts":"2026-10-15T19:00:56.956405Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T19:00:56.957639Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T19:00:56.957635Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ee8e5a779fc25cea","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.138:2379]}","request-path":"/0/members/ee8e5a779fc25cea/attributes","cluster-id":"8f8e3b77da0cbcef","publish-timeout":"7s"} {"level":"info","ts":"2026-10-15T19:00:56.95775Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-15T19:00:56.958003Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-15T19:00:56.958027Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-15T19:00:56.958962Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8f8e3b77da0cbcef","local-member-id":"ee8e5a779fc25cea","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T19:00:56.959126Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T19:00:56.959181Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-15T19:00:56.960691Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.138:2379"} {"level":"info","ts":"2026-10-15T19:00:56.9614Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-15T19:01:00.242125Z","caller":"traceutil/trace.go:171","msg":"trace[55886367] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"110.160686ms","start":"2026-10-15T19:01:00.131938Z","end":"2026-10-15T19:01:00.242099Z","steps":["trace[55886367] 'process raft request' (duration: 53.643015ms)","trace[55886367] 'compare' (duration: 56.382487ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:01:00.661661Z","caller":"traceutil/trace.go:171","msg":"trace[1517358005] transaction","detail":"{read_only:false; response_revision:126; number_of_response:1; }","duration":"134.10184ms","start":"2026-10-15T19:01:00.527524Z","end":"2026-10-15T19:01:00.661626Z","steps":["trace[1517358005] 'process raft request' (duration: 118.13325ms)","trace[1517358005] 'compare' (duration: 15.854037ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:01:08.845801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.87636ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T19:01:08.846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.025736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-wrpgt\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-10-15T19:01:08.846038Z","caller":"traceutil/trace.go:171","msg":"trace[1858583104] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:352; }","duration":"198.126285ms","start":"2026-10-15T19:01:08.647895Z","end":"2026-10-15T19:01:08.846021Z","steps":["trace[1858583104] 'range keys from in-memory index tree' (duration: 197.864809ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:08.846087Z","caller":"traceutil/trace.go:171","msg":"trace[1988577879] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-wrpgt; range_end:; response_count:1; response_revision:352; }","duration":"177.159889ms","start":"2026-10-15T19:01:08.668908Z","end":"2026-10-15T19:01:08.846068Z","steps":["trace[1988577879] 'range keys from in-memory index tree' (duration: 176.895293ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:19.267908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.760088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:01:19.268011Z","caller":"traceutil/trace.go:171","msg":"trace[265457911] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"162.328375ms","start":"2026-10-15T19:01:19.105667Z","end":"2026-10-15T19:01:19.267995Z","steps":["trace[265457911] 'process raft request' (duration: 19.919982ms)","trace[265457911] 'compare' (duration: 141.652766ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:01:43.385658Z","caller":"traceutil/trace.go:171","msg":"trace[657855513] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"139.494121ms","start":"2026-10-15T19:01:43.24614Z","end":"2026-10-15T19:01:43.385634Z","steps":["trace[657855513] 'read index received' (duration: 139.336617ms)","trace[657855513] 'applied index is now lower than readState.Index' (duration: 153.254µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:01:43.385752Z","caller":"traceutil/trace.go:171","msg":"trace[22753487] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"226.812412ms","start":"2026-10-15T19:01:43.158871Z","end":"2026-10-15T19:01:43.385684Z","steps":["trace[22753487] 'process raft request' (duration: 226.573287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:43.385888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.729566ms","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-10-15T19:01:43.385932Z","caller":"traceutil/trace.go:171","msg":"trace[741545964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:504; }","duration":"139.824439ms","start":"2026-10-15T19:01:43.246097Z","end":"2026-10-15T19:01:43.385921Z","steps":["trace[741545964] 'agreement among raft nodes before linearized reading' (duration: 139.680385ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:43.466749Z","caller":"traceutil/trace.go:171","msg":"trace[108476786] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"200.512358ms","start":"2026-10-15T19:01:43.266175Z","end":"2026-10-15T19:01:43.466687Z","steps":["trace[108476786] 'process raft request' (duration: 200.118829ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:43.466768Z","caller":"traceutil/trace.go:171","msg":"trace[460660596] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"195.649914ms","start":"2026-10-15T19:01:43.271086Z","end":"2026-10-15T19:01:43.466736Z","steps":["trace[460660596] 'process raft request' (duration: 195.359917ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:43.466836Z","caller":"traceutil/trace.go:171","msg":"trace[1848035180] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"220.551616ms","start":"2026-10-15T19:01:43.246274Z","end":"2026-10-15T19:01:43.466826Z","steps":["trace[1848035180] 'process raft request' (duration: 219.786378ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:43.466754Z","caller":"traceutil/trace.go:171","msg":"trace[302646173] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"217.170537ms","start":"2026-10-15T19:01:43.249552Z","end":"2026-10-15T19:01:43.466723Z","steps":["trace[302646173] 'process raft request' (duration: 216.650184ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:43.466959Z","caller":"traceutil/trace.go:171","msg":"trace[378084731] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"200.678521ms","start":"2026-10-15T19:01:43.266269Z","end":"2026-10-15T19:01:43.466947Z","steps":["trace[378084731] 'process raft request' (duration: 200.072867ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:43.466988Z","caller":"traceutil/trace.go:171","msg":"trace[15054409] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"195.777137ms","start":"2026-10-15T19:01:43.271202Z","end":"2026-10-15T19:01:43.466979Z","steps":["trace[15054409] 'process raft request' (duration: 195.290105ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:43.467002Z","caller":"traceutil/trace.go:171","msg":"trace[44191400] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"198.596423ms","start":"2026-10-15T19:01:43.268395Z","end":"2026-10-15T19:01:43.466992Z","steps":["trace[44191400] 'process raft request' (duration: 198.007569ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:43.466982Z","caller":"traceutil/trace.go:171","msg":"trace[856276715] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"215.489147ms","start":"2026-10-15T19:01:43.251434Z","end":"2026-10-15T19:01:43.466924Z","steps":["trace[856276715] 'process raft request' (duration: 214.813951ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:43.467043Z","caller":"traceutil/trace.go:171","msg":"trace[888031473] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"131.383421ms","start":"2026-10-15T19:01:43.33565Z","end":"2026-10-15T19:01:43.467033Z","steps":["trace[888031473] 'process raft request' (duration: 131.038683ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:43.467103Z","caller":"traceutil/trace.go:171","msg":"trace[848289138] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"164.536816ms","start":"2026-10-15T19:01:43.302435Z","end":"2026-10-15T19:01:43.466972Z","steps":["trace[848289138] 'process raft request' (duration: 164.190278ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:43.467503Z","caller":"traceutil/trace.go:171","msg":"trace[83091311] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"174.098779ms","start":"2026-10-15T19:01:43.293379Z","end":"2026-10-15T19:01:43.467477Z","steps":["trace[83091311] 'process raft request' (duration: 173.189358ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:50.211043Z","caller":"traceutil/trace.go:171","msg":"trace[1510018615] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"443.434035ms","start":"2026-10-15T19:01:49.767585Z","end":"2026-10-15T19:01:50.211019Z","steps":["trace[1510018615] 'read index received' (duration: 443.353853ms)","trace[1510018615] 'applied index is now lower than readState.Index' (duration: 79.152µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:01:50.211168Z","caller":"traceutil/trace.go:171","msg":"trace[1145389382] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"454.793511ms","start":"2026-10-15T19:01:49.756338Z","end":"2026-10-15T19:01:50.211132Z","steps":["trace[1145389382] 'process raft request' (duration: 454.521514ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:50.211249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.639401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-khnw4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-15T19:01:50.211291Z","caller":"traceutil/trace.go:171","msg":"trace[455753816] range","detail":"{range_begin:/registry/pods/kube-system/cilium-khnw4; range_end:; response_count:1; response_revision:612; }","duration":"443.701552ms","start":"2026-10-15T19:01:49.767579Z","end":"2026-10-15T19:01:50.211281Z","steps":["trace[455753816] 'agreement among raft nodes before linearized reading' (duration: 443.540758ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:50.211324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:49.767522Z","time spent":"443.794204ms","remote":"127.0.0.1:49000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-khnw4\" "} {"level":"warn","ts":"2026-10-15T19:01:50.211377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:49.756312Z","time spent":"454.946665ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:01:50.469469Z","caller":"traceutil/trace.go:171","msg":"trace[1926378897] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"652.519523ms","start":"2026-10-15T19:01:49.816916Z","end":"2026-10-15T19:01:50.469435Z","steps":["trace[1926378897] 'process raft request' (duration: 651.940709ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:50.470526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:49.816887Z","time spent":"653.133557ms","remote":"127.0.0.1:48918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:01:51.061368Z","caller":"traceutil/trace.go:171","msg":"trace[478438550] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"686.449786ms","start":"2026-10-15T19:01:50.374871Z","end":"2026-10-15T19:01:51.061321Z","steps":["trace[478438550] 'read index received' (duration: 93.70093ms)","trace[478438550] 'applied index is now lower than readState.Index' (duration: 592.747776ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:01:51.061434Z","caller":"traceutil/trace.go:171","msg":"trace[84551543] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"832.990661ms","start":"2026-10-15T19:01:50.228405Z","end":"2026-10-15T19:01:51.061396Z","steps":["trace[84551543] 'process raft request' (duration: 832.715304ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:51.061535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.65088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:01:51.061605Z","caller":"traceutil/trace.go:171","msg":"trace[11516561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"686.774193ms","start":"2026-10-15T19:01:50.374813Z","end":"2026-10-15T19:01:51.061587Z","steps":["trace[11516561] 'agreement among raft nodes before linearized reading' (duration: 686.62121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:51.061644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:50.374791Z","time spent":"686.845655ms","remote":"127.0.0.1:48802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T19:01:51.0618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:50.228371Z","time spent":"833.134984ms","remote":"127.0.0.1:49000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:01:51.084219Z","caller":"traceutil/trace.go:171","msg":"trace[1577022290] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"530.851169ms","start":"2026-10-15T19:01:50.553348Z","end":"2026-10-15T19:01:51.0842Z","steps":["trace[1577022290] 'process raft request' (duration: 530.718836ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:51.084381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:50.553311Z","time spent":"530.974742ms","remote":"127.0.0.1:49296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-15T19:01:51.084428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.121958ms","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-10-15T19:01:51.08448Z","caller":"traceutil/trace.go:171","msg":"trace[1026569041] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"360.20217ms","start":"2026-10-15T19:01:50.724263Z","end":"2026-10-15T19:01:51.084465Z","steps":["trace[1026569041] 'agreement among raft nodes before linearized reading' (duration: 360.037786ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:51.084518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:50.724243Z","time spent":"360.268481ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-15T19:01:51.597419Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6695341096322078517,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-15T19:01:51.720639Z","caller":"traceutil/trace.go:171","msg":"trace[1219316436] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"623.767141ms","start":"2026-10-15T19:01:51.09684Z","end":"2026-10-15T19:01:51.720607Z","steps":["trace[1219316436] 'read index received' (duration: 584.38624ms)","trace[1219316436] 'applied index is now lower than readState.Index' (duration: 39.379301ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:01:51.720722Z","caller":"traceutil/trace.go:171","msg":"trace[2071682394] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"631.044391ms","start":"2026-10-15T19:01:51.089615Z","end":"2026-10-15T19:01:51.72066Z","steps":["trace[2071682394] 'process raft request' (duration: 591.597319ms)","trace[2071682394] 'compare' (duration: 39.180876ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:01:51.720891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.058026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T19:01:51.720887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:51.089589Z","time spent":"631.205024ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:01:51.720952Z","caller":"traceutil/trace.go:171","msg":"trace[1427771541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"345.165678ms","start":"2026-10-15T19:01:51.375762Z","end":"2026-10-15T19:01:51.720927Z","steps":["trace[1427771541] 'agreement among raft nodes before linearized reading' (duration: 345.058696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:51.720994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:51.37574Z","time spent":"345.24317ms","remote":"127.0.0.1:48802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T19:01:51.72112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.653991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T19:01:51.721226Z","caller":"traceutil/trace.go:171","msg":"trace[1244492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"162.797065ms","start":"2026-10-15T19:01:51.558403Z","end":"2026-10-15T19:01:51.7212Z","steps":["trace[1244492] 'agreement among raft nodes before linearized reading' (duration: 162.488358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:51.72129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.086234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-15T19:01:51.721136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.271213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:01:51.721358Z","caller":"traceutil/trace.go:171","msg":"trace[1617976927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"211.201456ms","start":"2026-10-15T19:01:51.510143Z","end":"2026-10-15T19:01:51.721344Z","steps":["trace[1617976927] 'agreement among raft nodes before linearized reading' (duration: 211.055753ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:01:51.721456Z","caller":"traceutil/trace.go:171","msg":"trace[591989054] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:616; }","duration":"624.59704ms","start":"2026-10-15T19:01:51.096801Z","end":"2026-10-15T19:01:51.721398Z","steps":["trace[591989054] 'agreement among raft nodes before linearized reading' (duration: 623.960506ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:01:51.72157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:01:51.096784Z","time spent":"624.725643ms","remote":"127.0.0.1:49050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-10-15T19:01:51.722465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.056067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T19:01:51.722529Z","caller":"traceutil/trace.go:171","msg":"trace[354314615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"295.159829ms","start":"2026-10-15T19:01:51.427352Z","end":"2026-10-15T19:01:51.722512Z","steps":["trace[354314615] 'agreement among raft nodes before linearized reading' (duration: 293.91606ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:02:06.65703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.810218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T19:02:06.657117Z","caller":"traceutil/trace.go:171","msg":"trace[1338863399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:774; }","duration":"785.9348ms","start":"2026-10-15T19:02:05.871162Z","end":"2026-10-15T19:02:06.657097Z","steps":["trace[1338863399] 'range keys from in-memory index tree' (duration: 785.619153ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:02:06.657154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:02:05.871149Z","time spent":"785.995732ms","remote":"127.0.0.1:49070","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":"warn","ts":"2026-10-15T19:02:06.657193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.369708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-10-15T19:02:06.657187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.563068ms","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-10-15T19:02:06.657262Z","caller":"traceutil/trace.go:171","msg":"trace[2046260324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:774; }","duration":"788.47161ms","start":"2026-10-15T19:02:05.868773Z","end":"2026-10-15T19:02:06.657245Z","steps":["trace[2046260324] 'range keys from in-memory index tree' (duration: 788.224935ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:02:06.657327Z","caller":"traceutil/trace.go:171","msg":"trace[337579614] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:774; }","duration":"855.746923ms","start":"2026-10-15T19:02:05.80154Z","end":"2026-10-15T19:02:06.657287Z","steps":["trace[337579614] 'range keys from in-memory index tree' (duration: 855.333113ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:02:06.657349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:02:05.868763Z","time spent":"788.576922ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-15T19:02:06.65739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:02:05.80152Z","time spent":"855.855445ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-15T19:02:06.657496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.914444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-15T19:02:06.657732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.331967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-q8xpx\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-10-15T19:02:06.657883Z","caller":"traceutil/trace.go:171","msg":"trace[561418951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:774; }","duration":"787.830955ms","start":"2026-10-15T19:02:05.870029Z","end":"2026-10-15T19:02:06.65786Z","steps":["trace[561418951] 'range keys from in-memory index tree' (duration: 786.75057ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:02:06.657916Z","caller":"traceutil/trace.go:171","msg":"trace[672964644] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-q8xpx; range_end:; response_count:1; response_revision:774; }","duration":"788.565922ms","start":"2026-10-15T19:02:05.869334Z","end":"2026-10-15T19:02:06.6579Z","steps":["trace[672964644] 'range keys from in-memory index tree' (duration: 787.430735ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:02:06.657946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:02:05.870006Z","time spent":"787.922187ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T19:02:06.65796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:02:05.869326Z","time spent":"788.624523ms","remote":"127.0.0.1:49000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-q8xpx\" "} {"level":"info","ts":"2026-10-15T19:02:59.389054Z","caller":"traceutil/trace.go:171","msg":"trace[1568651716] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"173.489676ms","start":"2026-10-15T19:02:59.21552Z","end":"2026-10-15T19:02:59.389009Z","steps":["trace[1568651716] 'process raft request' (duration: 137.790111ms)","trace[1568651716] 'compare' (duration: 35.369877ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:03:25.174697Z","caller":"traceutil/trace.go:171","msg":"trace[70015333] linearizableReadLoop","detail":"{readStateIndex:1913; appliedIndex:1912; }","duration":"111.537297ms","start":"2026-10-15T19:03:25.063133Z","end":"2026-10-15T19:03:25.17467Z","steps":["trace[70015333] 'read index received' (duration: 111.291531ms)","trace[70015333] 'applied index is now lower than readState.Index' (duration: 244.516µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:03:25.174821Z","caller":"traceutil/trace.go:171","msg":"trace[508959801] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"165.005287ms","start":"2026-10-15T19:03:25.009785Z","end":"2026-10-15T19:03:25.174791Z","steps":["trace[508959801] 'process raft request' (duration: 164.71578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:03:25.175021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.873795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-49cf9108-e479-4407-a6ee-99fbda289e6f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-10-15T19:03:25.175104Z","caller":"traceutil/trace.go:171","msg":"trace[2002905567] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-49cf9108-e479-4407-a6ee-99fbda289e6f; range_end:; response_count:1; response_revision:1839; }","duration":"112.019069ms","start":"2026-10-15T19:03:25.063065Z","end":"2026-10-15T19:03:25.175085Z","steps":["trace[2002905567] 'agreement among raft nodes before linearized reading' (duration: 111.779963ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:03:25.403912Z","caller":"traceutil/trace.go:171","msg":"trace[1447951105] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"127.05195ms","start":"2026-10-15T19:03:25.276833Z","end":"2026-10-15T19:03:25.403885Z","steps":["trace[1447951105] 'process raft request' (duration: 105.429914ms)","trace[1447951105] 'compare' (duration: 21.420041ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:03:25.635906Z","caller":"traceutil/trace.go:171","msg":"trace[902194869] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"226.74075ms","start":"2026-10-15T19:03:25.40914Z","end":"2026-10-15T19:03:25.635881Z","steps":["trace[902194869] 'process raft request' (duration: 226.549745ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:03:25.636831Z","caller":"traceutil/trace.go:171","msg":"trace[1002606582] linearizableReadLoop","detail":"{readStateIndex:1920; appliedIndex:1918; }","duration":"104.953043ms","start":"2026-10-15T19:03:25.53185Z","end":"2026-10-15T19:03:25.636803Z","steps":["trace[1002606582] 'read index received' (duration: 103.925759ms)","trace[1002606582] 'applied index is now lower than readState.Index' (duration: 1.026394ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:03:25.637039Z","caller":"traceutil/trace.go:171","msg":"trace[1989704299] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"142.212954ms","start":"2026-10-15T19:03:25.494803Z","end":"2026-10-15T19:03:25.637016Z","steps":["trace[1989704299] 'process raft request' (duration: 141.858206ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:03:25.637489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.625919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-15T19:03:25.637559Z","caller":"traceutil/trace.go:171","msg":"trace[788809906] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1846; }","duration":"105.745252ms","start":"2026-10-15T19:03:25.531797Z","end":"2026-10-15T19:03:25.637542Z","steps":["trace[788809906] 'agreement among raft nodes before linearized reading' (duration: 105.109347ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:03:40.40295Z","caller":"traceutil/trace.go:171","msg":"trace[467171880] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"215.849936ms","start":"2026-10-15T19:03:40.18707Z","end":"2026-10-15T19:03:40.40292Z","steps":["trace[467171880] 'process raft request' (duration: 215.356314ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:10.079667Z","caller":"traceutil/trace.go:171","msg":"trace[759817431] linearizableReadLoop","detail":"{readStateIndex:2227; appliedIndex:2226; }","duration":"160.656203ms","start":"2026-10-15T19:04:09.918965Z","end":"2026-10-15T19:04:10.079621Z","steps":["trace[759817431] 'read index received' (duration: 122.673904ms)","trace[759817431] 'applied index is now lower than readState.Index' (duration: 37.980309ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:04:10.07991Z","caller":"traceutil/trace.go:171","msg":"trace[1503847251] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"170.296048ms","start":"2026-10-15T19:04:09.909581Z","end":"2026-10-15T19:04:10.079877Z","steps":["trace[1503847251] 'process raft request' (duration: 132.041422ms)","trace[1503847251] 'compare' (duration: 37.774825ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:04:10.080027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.027902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-15T19:04:10.080099Z","caller":"traceutil/trace.go:171","msg":"trace[895177026] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2143; }","duration":"161.169415ms","start":"2026-10-15T19:04:09.918911Z","end":"2026-10-15T19:04:10.08008Z","steps":["trace[895177026] 'agreement among raft nodes before linearized reading' (duration: 160.95259ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:10.080135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.697918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-15T19:04:10.080206Z","caller":"traceutil/trace.go:171","msg":"trace[1965002590] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2143; }","duration":"132.828771ms","start":"2026-10-15T19:04:09.947356Z","end":"2026-10-15T19:04:10.080184Z","steps":["trace[1965002590] 'agreement among raft nodes before linearized reading' (duration: 132.536405ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:10.21544Z","caller":"traceutil/trace.go:171","msg":"trace[236282258] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"130.929567ms","start":"2026-10-15T19:04:10.084488Z","end":"2026-10-15T19:04:10.215418Z","steps":["trace[236282258] 'process raft request' (duration: 120.861961ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:10.544024Z","caller":"traceutil/trace.go:171","msg":"trace[1049519567] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"107.776784ms","start":"2026-10-15T19:04:10.436222Z","end":"2026-10-15T19:04:10.543999Z","steps":["trace[1049519567] 'process raft request' (duration: 107.61172ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:16.26276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.616972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:04:16.262943Z","caller":"traceutil/trace.go:171","msg":"trace[1089177771] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2260; }","duration":"156.214499ms","start":"2026-10-15T19:04:16.106703Z","end":"2026-10-15T19:04:16.262918Z","steps":["trace[1089177771] 'read index received' (duration: 120.842µs)","trace[1089177771] 'applied index is now lower than readState.Index' (duration: 156.091647ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:04:16.263087Z","caller":"traceutil/trace.go:171","msg":"trace[640532422] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"338.653082ms","start":"2026-10-15T19:04:15.924372Z","end":"2026-10-15T19:04:16.263025Z","steps":["trace[640532422] 'process raft request' (duration: 180.59501ms)","trace[640532422] 'compare' (duration: 157.312875ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:04:16.263229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.485946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-10-15T19:04:16.263328Z","caller":"traceutil/trace.go:171","msg":"trace[1333764383] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2175; }","duration":"156.66329ms","start":"2026-10-15T19:04:16.106638Z","end":"2026-10-15T19:04:16.263302Z","steps":["trace[1333764383] 'agreement among raft nodes before linearized reading' (duration: 156.375613ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:16.263367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:04:15.924349Z","time spent":"338.860007ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:04:16.263536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.592059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-10-15T19:04:16.263912Z","caller":"traceutil/trace.go:171","msg":"trace[1402595659] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2175; }","duration":"140.146722ms","start":"2026-10-15T19:04:16.123744Z","end":"2026-10-15T19:04:16.263891Z","steps":["trace[1402595659] 'agreement among raft nodes before linearized reading' (duration: 139.319943ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:16.586683Z","caller":"traceutil/trace.go:171","msg":"trace[1367208874] linearizableReadLoop","detail":"{readStateIndex:2263; appliedIndex:2261; }","duration":"210.600743ms","start":"2026-10-15T19:04:16.376056Z","end":"2026-10-15T19:04:16.586656Z","steps":["trace[1367208874] 'read index received' (duration: 150.762321ms)","trace[1367208874] 'applied index is now lower than readState.Index' (duration: 59.836821ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:04:16.586872Z","caller":"traceutil/trace.go:171","msg":"trace[1544966846] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"318.08619ms","start":"2026-10-15T19:04:16.26877Z","end":"2026-10-15T19:04:16.586856Z","steps":["trace[1544966846] 'process raft request' (duration: 317.822064ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:04:16.586871Z","caller":"traceutil/trace.go:171","msg":"trace[963405991] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"318.085951ms","start":"2026-10-15T19:04:16.268751Z","end":"2026-10-15T19:04:16.586837Z","steps":["trace[963405991] 'process raft request' (duration: 258.053034ms)","trace[963405991] 'compare' (duration: 59.246808ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:04:16.58703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:04:16.268751Z","time spent":"318.149862ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:04:16.587047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:04:16.268704Z","time spent":"318.238324ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:04:16.587048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.976772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:04:16.587134Z","caller":"traceutil/trace.go:171","msg":"trace[298173050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2177; }","duration":"211.117075ms","start":"2026-10-15T19:04:16.375997Z","end":"2026-10-15T19:04:16.587114Z","steps":["trace[298173050] 'agreement among raft nodes before linearized reading' (duration: 210.90643ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:04:16.589409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.713187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:04:16.589541Z","caller":"traceutil/trace.go:171","msg":"trace[1283940132] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:2178; }","duration":"187.907211ms","start":"2026-10-15T19:04:16.401613Z","end":"2026-10-15T19:04:16.58952Z","steps":["trace[1283940132] 'agreement among raft nodes before linearized reading' (duration: 187.734477ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:05:05.050906Z","caller":"traceutil/trace.go:171","msg":"trace[1897824990] linearizableReadLoop","detail":"{readStateIndex:2712; appliedIndex:2711; }","duration":"216.833646ms","start":"2026-10-15T19:05:04.834042Z","end":"2026-10-15T19:05:05.050876Z","steps":["trace[1897824990] 'read index received' (duration: 216.629061ms)","trace[1897824990] 'applied index is now lower than readState.Index' (duration: 203.245µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:05:05.050984Z","caller":"traceutil/trace.go:171","msg":"trace[1343172416] transaction","detail":"{read_only:false; response_revision:2610; number_of_response:1; }","duration":"264.647843ms","start":"2026-10-15T19:05:04.78629Z","end":"2026-10-15T19:05:05.050938Z","steps":["trace[1343172416] 'process raft request' (duration: 264.363257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:05.051213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.150093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-15T19:05:05.051257Z","caller":"traceutil/trace.go:171","msg":"trace[810207626] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2610; }","duration":"217.260525ms","start":"2026-10-15T19:05:04.833985Z","end":"2026-10-15T19:05:05.051246Z","steps":["trace[810207626] 'agreement among raft nodes before linearized reading' (duration: 217.058841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:05.051265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.338151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-10-15T19:05:05.051324Z","caller":"traceutil/trace.go:171","msg":"trace[6559758] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2610; }","duration":"144.455845ms","start":"2026-10-15T19:05:04.906854Z","end":"2026-10-15T19:05:05.05131Z","steps":["trace[6559758] 'agreement among raft nodes before linearized reading' (duration: 144.299131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:05.051312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.069901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-10-15T19:05:05.051436Z","caller":"traceutil/trace.go:171","msg":"trace[1924009015] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2610; }","duration":"208.242695ms","start":"2026-10-15T19:05:04.843169Z","end":"2026-10-15T19:05:05.051412Z","steps":["trace[1924009015] 'agreement among raft nodes before linearized reading' (duration: 207.913598ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:38.522801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.184215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:05:38.522911Z","caller":"traceutil/trace.go:171","msg":"trace[1977815278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2811; }","duration":"146.316488ms","start":"2026-10-15T19:05:38.376569Z","end":"2026-10-15T19:05:38.522886Z","steps":["trace[1977815278] 'range keys from in-memory index tree' (duration: 146.127114ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:38.522801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.905296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-15T19:05:38.523006Z","caller":"traceutil/trace.go:171","msg":"trace[1963034665] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2811; }","duration":"172.193683ms","start":"2026-10-15T19:05:38.350784Z","end":"2026-10-15T19:05:38.522978Z","steps":["trace[1963034665] 'count revisions from in-memory index tree' (duration: 171.765982ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:39.112132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.477315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:05:39.112239Z","caller":"traceutil/trace.go:171","msg":"trace[336798916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2812; }","duration":"233.638498ms","start":"2026-10-15T19:05:38.878565Z","end":"2026-10-15T19:05:39.112203Z","steps":["trace[336798916] 'range keys from in-memory index tree' (duration: 233.255589ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:05:39.112258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.259106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-10-15T19:05:39.112353Z","caller":"traceutil/trace.go:171","msg":"trace[838123245] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2812; }","duration":"205.4343ms","start":"2026-10-15T19:05:38.906894Z","end":"2026-10-15T19:05:39.112328Z","steps":["trace[838123245] 'range keys from in-memory index tree' (duration: 205.029701ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:05:44.450441Z","caller":"traceutil/trace.go:171","msg":"trace[1535885157] transaction","detail":"{read_only:false; response_revision:2846; number_of_response:1; }","duration":"158.155116ms","start":"2026-10-15T19:05:44.292251Z","end":"2026-10-15T19:05:44.450406Z","steps":["trace[1535885157] 'process raft request' (duration: 157.886139ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:05:44.775238Z","caller":"traceutil/trace.go:171","msg":"trace[1885954709] transaction","detail":"{read_only:false; response_revision:2848; number_of_response:1; }","duration":"150.996228ms","start":"2026-10-15T19:05:44.624199Z","end":"2026-10-15T19:05:44.775195Z","steps":["trace[1885954709] 'process raft request' (duration: 118.14845ms)","trace[1885954709] 'compare' (duration: 32.670944ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:05:44.775396Z","caller":"traceutil/trace.go:171","msg":"trace[263705445] transaction","detail":"{read_only:false; response_revision:2849; number_of_response:1; }","duration":"132.021185ms","start":"2026-10-15T19:05:44.643354Z","end":"2026-10-15T19:05:44.775376Z","steps":["trace[263705445] 'process raft request' (duration: 131.7974ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:18.779458Z","caller":"traceutil/trace.go:171","msg":"trace[104793977] transaction","detail":"{read_only:false; response_revision:3804; number_of_response:1; }","duration":"111.947673ms","start":"2026-10-15T19:08:18.667479Z","end":"2026-10-15T19:08:18.779426Z","steps":["trace[104793977] 'process raft request' (duration: 104.729616ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:18.77973Z","caller":"traceutil/trace.go:171","msg":"trace[1605638466] transaction","detail":"{read_only:false; response_revision:3805; number_of_response:1; }","duration":"111.730458ms","start":"2026-10-15T19:08:18.66798Z","end":"2026-10-15T19:08:18.77971Z","steps":["trace[1605638466] 'process raft request' (duration: 111.37499ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:18.779995Z","caller":"traceutil/trace.go:171","msg":"trace[524401205] linearizableReadLoop","detail":"{readStateIndex:3955; appliedIndex:3952; }","duration":"109.444135ms","start":"2026-10-15T19:08:18.670531Z","end":"2026-10-15T19:08:18.779975Z","steps":["trace[524401205] 'read index received' (duration: 101.759417ms)","trace[524401205] 'applied index is now lower than readState.Index' (duration: 7.683728ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:08:18.7801Z","caller":"traceutil/trace.go:171","msg":"trace[1959851665] transaction","detail":"{read_only:false; response_revision:3807; number_of_response:1; }","duration":"109.242661ms","start":"2026-10-15T19:08:18.670843Z","end":"2026-10-15T19:08:18.780086Z","steps":["trace[1959851665] 'process raft request' (duration: 109.049546ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:18.780122Z","caller":"traceutil/trace.go:171","msg":"trace[348067014] transaction","detail":"{read_only:false; response_revision:3806; number_of_response:1; }","duration":"110.918649ms","start":"2026-10-15T19:08:18.669185Z","end":"2026-10-15T19:08:18.780104Z","steps":["trace[348067014] 'process raft request' (duration: 110.432368ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:08:18.780416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.863935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/secretgen-controller-cluster-role-binding\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:08:18.780493Z","caller":"traceutil/trace.go:171","msg":"trace[515835966] range","detail":"{range_begin:/registry/clusterrolebindings/secretgen-controller-cluster-role-binding; range_end:; response_count:0; response_revision:3807; }","duration":"110.06033ms","start":"2026-10-15T19:08:18.670415Z","end":"2026-10-15T19:08:18.780476Z","steps":["trace[515835966] 'agreement among raft nodes before linearized reading' (duration: 109.65626ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:53.241557Z","caller":"traceutil/trace.go:171","msg":"trace[74967222] transaction","detail":"{read_only:false; response_revision:4036; number_of_response:1; }","duration":"152.788899ms","start":"2026-10-15T19:08:53.088722Z","end":"2026-10-15T19:08:53.241511Z","steps":["trace[74967222] 'process raft request' (duration: 152.610235ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:08:53.298491Z","caller":"traceutil/trace.go:171","msg":"trace[1695403182] transaction","detail":"{read_only:false; response_revision:4037; number_of_response:1; }","duration":"209.062403ms","start":"2026-10-15T19:08:53.0894Z","end":"2026-10-15T19:08:53.298462Z","steps":["trace[1695403182] 'process raft request' (duration: 197.392262ms)","trace[1695403182] 'compare' (duration: 11.511027ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:08:53.298675Z","caller":"traceutil/trace.go:171","msg":"trace[1316644160] linearizableReadLoop","detail":"{readStateIndex:4193; appliedIndex:4191; }","duration":"193.706107ms","start":"2026-10-15T19:08:53.10493Z","end":"2026-10-15T19:08:53.298636Z","steps":["trace[1316644160] 'read index received' (duration: 136.489881ms)","trace[1316644160] 'applied index is now lower than readState.Index' (duration: 57.214356ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:08:53.298741Z","caller":"traceutil/trace.go:171","msg":"trace[357080986] transaction","detail":"{read_only:false; response_revision:4038; number_of_response:1; }","duration":"105.961925ms","start":"2026-10-15T19:08:53.192769Z","end":"2026-10-15T19:08:53.29873Z","steps":["trace[357080986] 'process raft request' (duration: 105.644998ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:08:53.298917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.963373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T19:08:53.298959Z","caller":"traceutil/trace.go:171","msg":"trace[11594699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4038; }","duration":"194.062945ms","start":"2026-10-15T19:08:53.104887Z","end":"2026-10-15T19:08:53.29895Z","steps":["trace[11594699] 'agreement among raft nodes before linearized reading' (duration: 193.905811ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:08:53.299208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.145417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T19:08:53.299273Z","caller":"traceutil/trace.go:171","msg":"trace[804134980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4038; }","duration":"194.26052ms","start":"2026-10-15T19:08:53.104996Z","end":"2026-10-15T19:08:53.299256Z","steps":["trace[804134980] 'agreement among raft nodes before linearized reading' (duration: 194.034235ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:17.776357Z","caller":"traceutil/trace.go:171","msg":"trace[1936911480] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"105.407235ms","start":"2026-10-15T19:10:17.67092Z","end":"2026-10-15T19:10:17.776328Z","steps":["trace[1936911480] 'process raft request' (duration: 79.667861ms)","trace[1936911480] 'compare' (duration: 25.55453ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:10:17.932944Z","caller":"traceutil/trace.go:171","msg":"trace[648820788] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"152.625076ms","start":"2026-10-15T19:10:17.780292Z","end":"2026-10-15T19:10:17.932918Z","steps":["trace[648820788] 'process raft request' (duration: 107.050163ms)","trace[648820788] 'compare' (duration: 45.326618ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:10:17.933058Z","caller":"traceutil/trace.go:171","msg":"trace[1291050205] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"124.292932ms","start":"2026-10-15T19:10:17.808731Z","end":"2026-10-15T19:10:17.933023Z","steps":["trace[1291050205] 'process raft request' (duration: 124.22714ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:17.933202Z","caller":"traceutil/trace.go:171","msg":"trace[197157125] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"152.205977ms","start":"2026-10-15T19:10:17.780988Z","end":"2026-10-15T19:10:17.933194Z","steps":["trace[197157125] 'process raft request' (duration: 151.92254ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:17.933206Z","caller":"traceutil/trace.go:171","msg":"trace[316409732] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"152.37897ms","start":"2026-10-15T19:10:17.780801Z","end":"2026-10-15T19:10:17.93318Z","steps":["trace[316409732] 'process raft request' (duration: 152.041023ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:21.727859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.124761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:10:21.727979Z","caller":"traceutil/trace.go:171","msg":"trace[395264858] linearizableReadLoop","detail":"{readStateIndex:5414; appliedIndex:5413; }","duration":"352.928854ms","start":"2026-10-15T19:10:21.375032Z","end":"2026-10-15T19:10:21.727961Z","steps":["trace[395264858] 'read index received' (duration: 98.788572ms)","trace[395264858] 'applied index is now lower than readState.Index' (duration: 254.136412ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:10:21.728104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.073677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:10:21.728075Z","caller":"traceutil/trace.go:171","msg":"trace[1803940723] transaction","detail":"{read_only:false; response_revision:5211; number_of_response:1; }","duration":"402.600302ms","start":"2026-10-15T19:10:21.325439Z","end":"2026-10-15T19:10:21.72804Z","steps":["trace[1803940723] 'process raft request' (duration: 148.229285ms)","trace[1803940723] 'compare' (duration: 253.301712ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:10:21.728131Z","caller":"traceutil/trace.go:171","msg":"trace[108860603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5211; }","duration":"353.142759ms","start":"2026-10-15T19:10:21.374979Z","end":"2026-10-15T19:10:21.728122Z","steps":["trace[108860603] 'agreement among raft nodes before linearized reading' (duration: 353.054817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:21.728162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:10:21.374959Z","time spent":"353.195221ms","remote":"127.0.0.1:48802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T19:10:21.728232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:10:21.325424Z","time spent":"402.742425ms","remote":"127.0.0.1:48926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1956,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-15T19:10:21.745184Z","caller":"traceutil/trace.go:171","msg":"trace[1052611094] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"326.117055ms","start":"2026-10-15T19:10:21.419043Z","end":"2026-10-15T19:10:21.74516Z","steps":["trace[1052611094] 'process raft request' (duration: 325.766237ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:21.745322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:10:21.419014Z","time spent":"326.235008ms","remote":"127.0.0.1:51784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":414675,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:10:21.774866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.750009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2c5ea283-189e-4059-90dc-c2e0e65b2e80\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-15T19:10:21.77495Z","caller":"traceutil/trace.go:171","msg":"trace[416712087] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2c5ea283-189e-4059-90dc-c2e0e65b2e80; range_end:; response_count:1; response_revision:5214; }","duration":"355.871822ms","start":"2026-10-15T19:10:21.419057Z","end":"2026-10-15T19:10:21.774929Z","steps":["trace[416712087] 'agreement among raft nodes before linearized reading' (duration: 355.649857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:21.774971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.985205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-r5rjd-tls\" ","response":"range_response_count:1 size:1967"} {"level":"warn","ts":"2026-10-15T19:10:21.775011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:10:21.419045Z","time spent":"355.956994ms","remote":"127.0.0.1:49000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3625,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2c5ea283-189e-4059-90dc-c2e0e65b2e80\" "} {"level":"warn","ts":"2026-10-15T19:10:21.775047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.375443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2c5ea283-189e-4059-90dc-c2e0e65b2e80\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-15T19:10:21.775054Z","caller":"traceutil/trace.go:171","msg":"trace[81355861] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-r5rjd-tls; range_end:; response_count:1; response_revision:5214; }","duration":"279.132559ms","start":"2026-10-15T19:10:21.495901Z","end":"2026-10-15T19:10:21.775034Z","steps":["trace[81355861] 'agreement among raft nodes before linearized reading' (duration: 278.797331ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:21.775091Z","caller":"traceutil/trace.go:171","msg":"trace[1019394092] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2c5ea283-189e-4059-90dc-c2e0e65b2e80; range_end:; response_count:1; response_revision:5214; }","duration":"375.466785ms","start":"2026-10-15T19:10:21.399611Z","end":"2026-10-15T19:10:21.775077Z","steps":["trace[1019394092] 'agreement among raft nodes before linearized reading' (duration: 375.356082ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:21.775121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:10:21.399587Z","time spent":"375.526545ms","remote":"127.0.0.1:49000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3625,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2c5ea283-189e-4059-90dc-c2e0e65b2e80\" "} {"level":"info","ts":"2026-10-15T19:10:36.022538Z","caller":"traceutil/trace.go:171","msg":"trace[773742868] transaction","detail":"{read_only:false; response_revision:5482; number_of_response:1; }","duration":"122.575522ms","start":"2026-10-15T19:10:35.899866Z","end":"2026-10-15T19:10:36.022441Z","steps":["trace[773742868] 'process raft request' (duration: 122.209964ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:36.150867Z","caller":"traceutil/trace.go:171","msg":"trace[1050290629] linearizableReadLoop","detail":"{readStateIndex:5695; appliedIndex:5693; }","duration":"134.330793ms","start":"2026-10-15T19:10:36.016509Z","end":"2026-10-15T19:10:36.150839Z","steps":["trace[1050290629] 'read index received' (duration: 5.535678ms)","trace[1050290629] 'applied index is now lower than readState.Index' (duration: 128.793925ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:10:36.15089Z","caller":"traceutil/trace.go:171","msg":"trace[914015805] transaction","detail":"{read_only:false; response_revision:5483; number_of_response:1; }","duration":"134.477347ms","start":"2026-10-15T19:10:36.016381Z","end":"2026-10-15T19:10:36.150858Z","steps":["trace[914015805] 'process raft request' (duration: 107.980515ms)","trace[914015805] 'compare' (duration: 26.343638ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:10:36.151153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.65189ms","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-10-15T19:10:36.151196Z","caller":"traceutil/trace.go:171","msg":"trace[312581853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5483; }","duration":"121.758522ms","start":"2026-10-15T19:10:36.029425Z","end":"2026-10-15T19:10:36.151184Z","steps":["trace[312581853] 'agreement among raft nodes before linearized reading' (duration: 121.563498ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:36.151463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.926437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-15T19:10:36.151586Z","caller":"traceutil/trace.go:171","msg":"trace[1855871536] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5483; }","duration":"135.081761ms","start":"2026-10-15T19:10:36.016483Z","end":"2026-10-15T19:10:36.151565Z","steps":["trace[1855871536] 'agreement among raft nodes before linearized reading' (duration: 134.505338ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:44.369799Z","caller":"traceutil/trace.go:171","msg":"trace[161228022] linearizableReadLoop","detail":"{readStateIndex:5835; appliedIndex:5834; }","duration":"172.564766ms","start":"2026-10-15T19:10:44.197207Z","end":"2026-10-15T19:10:44.369772Z","steps":["trace[161228022] 'read index received' (duration: 172.429373ms)","trace[161228022] 'applied index is now lower than readState.Index' (duration: 133.873µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:10:44.369917Z","caller":"traceutil/trace.go:171","msg":"trace[770340292] transaction","detail":"{read_only:false; response_revision:5621; number_of_response:1; }","duration":"191.946834ms","start":"2026-10-15T19:10:44.177928Z","end":"2026-10-15T19:10:44.369875Z","steps":["trace[770340292] 'process raft request' (duration: 191.665508ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:44.370176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.937625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-15T19:10:44.370268Z","caller":"traceutil/trace.go:171","msg":"trace[2098987287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5621; }","duration":"173.075698ms","start":"2026-10-15T19:10:44.197168Z","end":"2026-10-15T19:10:44.370244Z","steps":["trace[2098987287] 'agreement among raft nodes before linearized reading' (duration: 172.787611ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:44.506603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.696902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:10:44.506897Z","caller":"traceutil/trace.go:171","msg":"trace[1691648083] transaction","detail":"{read_only:false; response_revision:5623; number_of_response:1; }","duration":"131.973649ms","start":"2026-10-15T19:10:44.374902Z","end":"2026-10-15T19:10:44.506876Z","steps":["trace[1691648083] 'process raft request' (duration: 131.907467ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:44.507177Z","caller":"traceutil/trace.go:171","msg":"trace[1619547034] transaction","detail":"{read_only:false; response_revision:5622; number_of_response:1; }","duration":"154.176832ms","start":"2026-10-15T19:10:44.352986Z","end":"2026-10-15T19:10:44.507163Z","steps":["trace[1619547034] 'process raft request' (duration: 50.61284ms)","trace[1619547034] 'compare' (duration: 102.460787ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:10:44.507325Z","caller":"traceutil/trace.go:171","msg":"trace[382519170] linearizableReadLoop","detail":"{readStateIndex:5836; appliedIndex:5835; }","duration":"137.257611ms","start":"2026-10-15T19:10:44.370057Z","end":"2026-10-15T19:10:44.507315Z","steps":["trace[382519170] 'read index received' (duration: 33.530465ms)","trace[382519170] 'applied index is now lower than readState.Index' (duration: 103.725896ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:10:44.507482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.968977ms","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-10-15T19:10:44.507541Z","caller":"traceutil/trace.go:171","msg":"trace[884032215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5623; }","duration":"305.049208ms","start":"2026-10-15T19:10:44.202455Z","end":"2026-10-15T19:10:44.507504Z","steps":["trace[884032215] 'agreement among raft nodes before linearized reading' (duration: 304.896725ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:44.507571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:10:44.202433Z","time spent":"305.12747ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-15T19:10:44.50775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.64183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-76fvn\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-10-15T19:10:44.507784Z","caller":"traceutil/trace.go:171","msg":"trace[348437776] range","detail":"{range_begin:/registry/pods/openstack/keepalived-76fvn; range_end:; response_count:1; response_revision:5623; }","duration":"153.700932ms","start":"2026-10-15T19:10:44.354075Z","end":"2026-10-15T19:10:44.507776Z","steps":["trace[348437776] 'agreement among raft nodes before linearized reading' (duration: 153.61419ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:44.507947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.817741ms","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-10-15T19:10:44.508037Z","caller":"traceutil/trace.go:171","msg":"trace[216244588] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5623; }","duration":"294.936964ms","start":"2026-10-15T19:10:44.213079Z","end":"2026-10-15T19:10:44.508016Z","steps":["trace[216244588] 'agreement among raft nodes before linearized reading' (duration: 294.685608ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:10:44.508202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.121482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-15T19:10:44.508288Z","caller":"traceutil/trace.go:171","msg":"trace[1068923429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5623; }","duration":"296.238834ms","start":"2026-10-15T19:10:44.212029Z","end":"2026-10-15T19:10:44.508268Z","steps":["trace[1068923429] 'agreement among raft nodes before linearized reading' (duration: 295.813624ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:10:56.991889Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2914} {"level":"info","ts":"2026-10-15T19:10:57.06973Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2914,"took":"76.215361ms","hash":1002199888,"current-db-size-bytes":56762368,"current-db-size":"57 MB","current-db-size-in-use-bytes":32129024,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T19:10:57.069801Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1002199888,"revision":2914,"compact-revision":-1} {"level":"info","ts":"2026-10-15T19:15:57.000047Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5814} {"level":"info","ts":"2026-10-15T19:15:57.116602Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5814,"took":"113.549649ms","hash":1602034925,"current-db-size-bytes":56762368,"current-db-size":"57 MB","current-db-size-in-use-bytes":26574848,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-15T19:15:57.1167Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1602034925,"revision":5814,"compact-revision":2914} {"level":"info","ts":"2026-10-15T19:16:47.288081Z","caller":"traceutil/trace.go:171","msg":"trace[193357780] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"188.173762ms","start":"2026-10-15T19:16:47.09988Z","end":"2026-10-15T19:16:47.288054Z","steps":["trace[193357780] 'process raft request' (duration: 188.001808ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:16:47.288866Z","caller":"traceutil/trace.go:171","msg":"trace[685537087] linearizableReadLoop","detail":"{readStateIndex:8770; appliedIndex:8770; }","duration":"188.581791ms","start":"2026-10-15T19:16:47.100251Z","end":"2026-10-15T19:16:47.288833Z","steps":["trace[685537087] 'read index received' (duration: 188.568541ms)","trace[685537087] 'applied index is now lower than readState.Index' (duration: 10.48µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:16:47.289092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.819757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-10-15T19:16:47.289173Z","caller":"traceutil/trace.go:171","msg":"trace[1047671614] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8466; }","duration":"188.94237ms","start":"2026-10-15T19:16:47.100208Z","end":"2026-10-15T19:16:47.289151Z","steps":["trace[1047671614] 'agreement among raft nodes before linearized reading' (duration: 188.696434ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:16:47.517457Z","caller":"traceutil/trace.go:171","msg":"trace[1407336350] linearizableReadLoop","detail":"{readStateIndex:8771; appliedIndex:8770; }","duration":"228.463424ms","start":"2026-10-15T19:16:47.288948Z","end":"2026-10-15T19:16:47.517411Z","steps":["trace[1407336350] 'read index received' (duration: 204.148302ms)","trace[1407336350] 'applied index is now lower than readState.Index' (duration: 24.313112ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:16:47.517746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.437787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-15T19:16:47.517862Z","caller":"traceutil/trace.go:171","msg":"trace[844100324] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8466; }","duration":"408.57945ms","start":"2026-10-15T19:16:47.109235Z","end":"2026-10-15T19:16:47.517815Z","steps":["trace[844100324] 'agreement among raft nodes before linearized reading' (duration: 408.275713ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:16:47.517906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:16:47.10922Z","time spent":"408.675762ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-15T19:16:47.518104Z","caller":"traceutil/trace.go:171","msg":"trace[1974174643] transaction","detail":"{read_only:false; response_revision:8467; number_of_response:1; }","duration":"124.004878ms","start":"2026-10-15T19:16:47.39406Z","end":"2026-10-15T19:16:47.518065Z","steps":["trace[1974174643] 'process raft request' (duration: 123.751872ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:16:59.098352Z","caller":"traceutil/trace.go:171","msg":"trace[2006767636] linearizableReadLoop","detail":"{readStateIndex:8836; appliedIndex:8835; }","duration":"159.106919ms","start":"2026-10-15T19:16:58.939221Z","end":"2026-10-15T19:16:59.098328Z","steps":["trace[2006767636] 'read index received' (duration: 158.878574ms)","trace[2006767636] 'applied index is now lower than readState.Index' (duration: 227.355µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:16:59.0986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.354776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-76fvn\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-10-15T19:16:59.098665Z","caller":"traceutil/trace.go:171","msg":"trace[61233643] range","detail":"{range_begin:/registry/pods/openstack/keepalived-76fvn; range_end:; response_count:1; response_revision:8528; }","duration":"159.453117ms","start":"2026-10-15T19:16:58.939178Z","end":"2026-10-15T19:16:59.098631Z","steps":["trace[61233643] 'agreement among raft nodes before linearized reading' (duration: 159.273093ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:16:59.098952Z","caller":"traceutil/trace.go:171","msg":"trace[773881446] transaction","detail":"{read_only:false; response_revision:8528; number_of_response:1; }","duration":"161.71661ms","start":"2026-10-15T19:16:58.937195Z","end":"2026-10-15T19:16:59.098912Z","steps":["trace[773881446] 'process raft request' (duration: 160.963612ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:16:59.823197Z","caller":"traceutil/trace.go:171","msg":"trace[1495732564] transaction","detail":"{read_only:false; response_revision:8531; number_of_response:1; }","duration":"113.547966ms","start":"2026-10-15T19:16:59.709621Z","end":"2026-10-15T19:16:59.823169Z","steps":["trace[1495732564] 'process raft request' (duration: 113.372792ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:17:00.145359Z","caller":"traceutil/trace.go:171","msg":"trace[2029654062] transaction","detail":"{read_only:false; response_revision:8532; number_of_response:1; }","duration":"112.100833ms","start":"2026-10-15T19:17:00.033223Z","end":"2026-10-15T19:17:00.145324Z","steps":["trace[2029654062] 'process raft request' (duration: 111.851567ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:17:29.536036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.819236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-15T19:17:29.5361Z","caller":"traceutil/trace.go:171","msg":"trace[634738467] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:8744; }","duration":"421.932359ms","start":"2026-10-15T19:17:29.114153Z","end":"2026-10-15T19:17:29.536086Z","steps":["trace[634738467] 'range keys from in-memory index tree' (duration: 421.665682ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:17:29.536122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:17:29.114128Z","time spent":"421.988639ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":581,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-10-15T19:17:29.536385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.886526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-76fvn\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-10-15T19:17:29.536406Z","caller":"traceutil/trace.go:171","msg":"trace[1107051081] range","detail":"{range_begin:/registry/pods/openstack/keepalived-76fvn; range_end:; response_count:1; response_revision:8744; }","duration":"248.954498ms","start":"2026-10-15T19:17:29.287446Z","end":"2026-10-15T19:17:29.536401Z","steps":["trace[1107051081] 'range keys from in-memory index tree' (duration: 248.740833ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:17:29.536533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.547755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:17:29.536554Z","caller":"traceutil/trace.go:171","msg":"trace[2086978024] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:8744; }","duration":"231.597766ms","start":"2026-10-15T19:17:29.30495Z","end":"2026-10-15T19:17:29.536548Z","steps":["trace[2086978024] 'count revisions from in-memory index tree' (duration: 231.462904ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:17:29.536759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.439604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:17:29.536777Z","caller":"traceutil/trace.go:171","msg":"trace[49869402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8744; }","duration":"158.458564ms","start":"2026-10-15T19:17:29.378315Z","end":"2026-10-15T19:17:29.536774Z","steps":["trace[49869402] 'range keys from in-memory index tree' (duration: 158.369132ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:17:43.151745Z","caller":"traceutil/trace.go:171","msg":"trace[304529649] linearizableReadLoop","detail":"{readStateIndex:9178; appliedIndex:9177; }","duration":"134.07941ms","start":"2026-10-15T19:17:43.017642Z","end":"2026-10-15T19:17:43.151722Z","steps":["trace[304529649] 'read index received' (duration: 133.921587ms)","trace[304529649] 'applied index is now lower than readState.Index' (duration: 156.963µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:17:43.152079Z","caller":"traceutil/trace.go:171","msg":"trace[641388574] transaction","detail":"{read_only:false; response_revision:8857; number_of_response:1; }","duration":"145.7308ms","start":"2026-10-15T19:17:43.006334Z","end":"2026-10-15T19:17:43.152065Z","steps":["trace[641388574] 'process raft request' (duration: 145.236219ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:17:43.152628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.955021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-10-15T19:17:43.152704Z","caller":"traceutil/trace.go:171","msg":"trace[827305345] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:8857; }","duration":"135.074543ms","start":"2026-10-15T19:17:43.017609Z","end":"2026-10-15T19:17:43.152684Z","steps":["trace[827305345] 'agreement among raft nodes before linearized reading' (duration: 134.825128ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:17:43.15266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.447651ms","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-10-15T19:17:43.152827Z","caller":"traceutil/trace.go:171","msg":"trace[1546977457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8857; }","duration":"125.634245ms","start":"2026-10-15T19:17:43.027179Z","end":"2026-10-15T19:17:43.152813Z","steps":["trace[1546977457] 'agreement among raft nodes before linearized reading' (duration: 125.40608ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:17:43.301484Z","caller":"traceutil/trace.go:171","msg":"trace[1607267249] transaction","detail":"{read_only:false; response_revision:8858; number_of_response:1; }","duration":"144.478671ms","start":"2026-10-15T19:17:43.156968Z","end":"2026-10-15T19:17:43.301447Z","steps":["trace[1607267249] 'process raft request' (duration: 127.808705ms)","trace[1607267249] 'compare' (duration: 16.317138ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:17:43.441314Z","caller":"traceutil/trace.go:171","msg":"trace[1282818662] linearizableReadLoop","detail":"{readStateIndex:9181; appliedIndex:9178; }","duration":"246.492321ms","start":"2026-10-15T19:17:43.194793Z","end":"2026-10-15T19:17:43.441285Z","steps":["trace[1282818662] 'read index received' (duration: 89.977411ms)","trace[1282818662] 'applied index is now lower than readState.Index' (duration: 156.51377ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:17:43.44133Z","caller":"traceutil/trace.go:171","msg":"trace[1867569189] transaction","detail":"{read_only:false; response_revision:8859; number_of_response:1; }","duration":"283.892806ms","start":"2026-10-15T19:17:43.157397Z","end":"2026-10-15T19:17:43.441289Z","steps":["trace[1867569189] 'process raft request' (duration: 262.499942ms)","trace[1867569189] 'compare' (duration: 21.031246ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:17:43.441489Z","caller":"traceutil/trace.go:171","msg":"trace[2108311529] transaction","detail":"{read_only:false; response_revision:8860; number_of_response:1; }","duration":"283.955997ms","start":"2026-10-15T19:17:43.157489Z","end":"2026-10-15T19:17:43.441445Z","steps":["trace[2108311529] 'process raft request' (duration: 283.66856ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:17:43.441768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.951552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-10-15T19:17:43.442192Z","caller":"traceutil/trace.go:171","msg":"trace[23107966] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:8860; }","duration":"247.388402ms","start":"2026-10-15T19:17:43.194785Z","end":"2026-10-15T19:17:43.442173Z","steps":["trace[23107966] 'agreement among raft nodes before linearized reading' (duration: 246.702716ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:17:43.668998Z","caller":"traceutil/trace.go:171","msg":"trace[730979275] transaction","detail":"{read_only:false; response_revision:8861; number_of_response:1; }","duration":"177.69255ms","start":"2026-10-15T19:17:43.491276Z","end":"2026-10-15T19:17:43.668968Z","steps":["trace[730979275] 'process raft request' (duration: 177.218819ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:17:43.684806Z","caller":"traceutil/trace.go:171","msg":"trace[941428192] linearizableReadLoop","detail":"{readStateIndex:9183; appliedIndex:9181; }","duration":"154.306729ms","start":"2026-10-15T19:17:43.530477Z","end":"2026-10-15T19:17:43.684784Z","steps":["trace[941428192] 'read index received' (duration: 138.018112ms)","trace[941428192] 'applied index is now lower than readState.Index' (duration: 16.287687ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:17:43.68487Z","caller":"traceutil/trace.go:171","msg":"trace[2050426177] transaction","detail":"{read_only:false; response_revision:8862; number_of_response:1; }","duration":"155.100787ms","start":"2026-10-15T19:17:43.52971Z","end":"2026-10-15T19:17:43.684811Z","steps":["trace[2050426177] 'process raft request' (duration: 154.884752ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:17:43.684922Z","caller":"traceutil/trace.go:171","msg":"trace[1386569239] transaction","detail":"{read_only:false; response_revision:8863; number_of_response:1; }","duration":"131.01097ms","start":"2026-10-15T19:17:43.5539Z","end":"2026-10-15T19:17:43.684911Z","steps":["trace[1386569239] 'process raft request' (duration: 130.823065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:17:43.685178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.674877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-2ghpc\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-10-15T19:17:43.685284Z","caller":"traceutil/trace.go:171","msg":"trace[691877069] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-2ghpc; range_end:; response_count:1; response_revision:8863; }","duration":"154.854172ms","start":"2026-10-15T19:17:43.530401Z","end":"2026-10-15T19:17:43.685255Z","steps":["trace[691877069] 'agreement among raft nodes before linearized reading' (duration: 154.538504ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:17:58.38573Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002465.wal"} {"level":"info","ts":"2026-10-15T19:19:03.970443Z","caller":"traceutil/trace.go:171","msg":"trace[1906494685] transaction","detail":"{read_only:false; response_revision:9631; number_of_response:1; }","duration":"217.139168ms","start":"2026-10-15T19:19:03.753242Z","end":"2026-10-15T19:19:03.970381Z","steps":["trace[1906494685] 'process raft request' (duration: 122.408126ms)","trace[1906494685] 'compare' (duration: 94.508957ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:19:04.229094Z","caller":"traceutil/trace.go:171","msg":"trace[1624515455] transaction","detail":"{read_only:false; response_revision:9632; number_of_response:1; }","duration":"245.483157ms","start":"2026-10-15T19:19:03.98357Z","end":"2026-10-15T19:19:04.229053Z","steps":["trace[1624515455] 'process raft request' (duration: 155.28295ms)","trace[1624515455] 'compare' (duration: 89.771497ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:19:04.229109Z","caller":"traceutil/trace.go:171","msg":"trace[1065474803] transaction","detail":"{read_only:false; response_revision:9633; number_of_response:1; }","duration":"245.390874ms","start":"2026-10-15T19:19:03.983691Z","end":"2026-10-15T19:19:04.229082Z","steps":["trace[1065474803] 'process raft request' (duration: 245.230211ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:19:04.229363Z","caller":"traceutil/trace.go:171","msg":"trace[304022733] transaction","detail":"{read_only:false; response_revision:9634; number_of_response:1; }","duration":"179.903681ms","start":"2026-10-15T19:19:04.049441Z","end":"2026-10-15T19:19:04.229345Z","steps":["trace[304022733] 'process raft request' (duration: 179.589814ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:19:08.252448Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ee8e5a779fc25cea","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T19:19:08.259032Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-15T19:19:08.259377Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-15T19:20:57.008524Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8002} {"level":"info","ts":"2026-10-15T19:20:57.082006Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8002,"took":"70.661292ms","hash":1152985453,"current-db-size-bytes":56762368,"current-db-size":"57 MB","current-db-size-in-use-bytes":28655616,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-15T19:20:57.08208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1152985453,"revision":8002,"compact-revision":5814} {"level":"info","ts":"2026-10-15T19:22:47.436465Z","caller":"traceutil/trace.go:171","msg":"trace[1130542070] transaction","detail":"{read_only:false; response_revision:11474; number_of_response:1; }","duration":"106.791012ms","start":"2026-10-15T19:22:47.329629Z","end":"2026-10-15T19:22:47.43642Z","steps":["trace[1130542070] 'process raft request' (duration: 106.547046ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:25:57.017169Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10444} {"level":"info","ts":"2026-10-15T19:25:57.107647Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10444,"took":"87.687895ms","hash":3001044840,"current-db-size-bytes":56762368,"current-db-size":"57 MB","current-db-size-in-use-bytes":27426816,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-15T19:25:57.107723Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3001044840,"revision":10444,"compact-revision":8002} {"level":"warn","ts":"2026-10-15T19:29:16.074075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.253213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:29:16.07434Z","caller":"traceutil/trace.go:171","msg":"trace[246353941] linearizableReadLoop","detail":"{readStateIndex:14576; appliedIndex:14574; }","duration":"148.598819ms","start":"2026-10-15T19:29:15.925698Z","end":"2026-10-15T19:29:16.074297Z","steps":["trace[246353941] 'read index received' (duration: 37.009819ms)","trace[246353941] 'applied index is now lower than readState.Index' (duration: 111.58777ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:29:16.074454Z","caller":"traceutil/trace.go:171","msg":"trace[973771793] transaction","detail":"{read_only:false; response_revision:14087; number_of_response:1; }","duration":"150.272199ms","start":"2026-10-15T19:29:15.924168Z","end":"2026-10-15T19:29:16.07444Z","steps":["trace[973771793] 'process raft request' (duration: 150.047873ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:29:16.074456Z","caller":"traceutil/trace.go:171","msg":"trace[786650301] transaction","detail":"{read_only:false; response_revision:14086; number_of_response:1; }","duration":"150.592046ms","start":"2026-10-15T19:29:15.923818Z","end":"2026-10-15T19:29:16.07441Z","steps":["trace[786650301] 'process raft request' (duration: 38.878252ms)","trace[786650301] 'compare' (duration: 111.004057ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:29:16.074579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.883307ms","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-10-15T19:29:16.075059Z","caller":"traceutil/trace.go:171","msg":"trace[1407672595] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14087; }","duration":"149.352817ms","start":"2026-10-15T19:29:15.925651Z","end":"2026-10-15T19:29:16.075004Z","steps":["trace[1407672595] 'agreement among raft nodes before linearized reading' (duration: 148.836565ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:29:23.539576Z","caller":"traceutil/trace.go:171","msg":"trace[1112312046] linearizableReadLoop","detail":"{readStateIndex:14617; appliedIndex:14616; }","duration":"224.348539ms","start":"2026-10-15T19:29:23.315203Z","end":"2026-10-15T19:29:23.539551Z","steps":["trace[1112312046] 'read index received' (duration: 224.267607ms)","trace[1112312046] 'applied index is now lower than readState.Index' (duration: 79.962µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:29:23.539849Z","caller":"traceutil/trace.go:171","msg":"trace[579222459] transaction","detail":"{read_only:false; response_revision:14126; number_of_response:1; }","duration":"283.316557ms","start":"2026-10-15T19:29:23.256521Z","end":"2026-10-15T19:29:23.539837Z","steps":["trace[579222459] 'process raft request' (duration: 282.872817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:23.540072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.85112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:29:23.540105Z","caller":"traceutil/trace.go:171","msg":"trace[297538890] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:14126; }","duration":"224.951102ms","start":"2026-10-15T19:29:23.315144Z","end":"2026-10-15T19:29:23.540095Z","steps":["trace[297538890] 'agreement among raft nodes before linearized reading' (duration: 224.86636ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:23.833267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.473965ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-15T19:29:23.833447Z","caller":"traceutil/trace.go:171","msg":"trace[1813143635] linearizableReadLoop","detail":"{readStateIndex:14619; appliedIndex:14617; }","duration":"293.79007ms","start":"2026-10-15T19:29:23.53964Z","end":"2026-10-15T19:29:23.83343Z","steps":["trace[1813143635] 'read index received' (duration: 101.998997ms)","trace[1813143635] 'applied index is now lower than readState.Index' (duration: 191.790273ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:29:23.833524Z","caller":"traceutil/trace.go:171","msg":"trace[442076131] transaction","detail":"{read_only:false; response_revision:14127; number_of_response:1; }","duration":"471.707081ms","start":"2026-10-15T19:29:23.361768Z","end":"2026-10-15T19:29:23.833475Z","steps":["trace[442076131] 'process raft request' (duration: 279.931989ms)","trace[442076131] 'compare' (duration: 191.338582ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:29:23.833656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:29:23.36174Z","time spent":"471.856424ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:29:23.833711Z","caller":"traceutil/trace.go:171","msg":"trace[150128344] transaction","detail":"{read_only:false; response_revision:14128; number_of_response:1; }","duration":"441.688924ms","start":"2026-10-15T19:29:23.391987Z","end":"2026-10-15T19:29:23.833676Z","steps":["trace[150128344] 'process raft request' (duration: 441.387267ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:23.833796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.816947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-15T19:29:23.833885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.403166ms","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-10-15T19:29:23.833925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:29:23.39196Z","time spent":"441.829347ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:29:23.833947Z","caller":"traceutil/trace.go:171","msg":"trace[1035232576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14128; }","duration":"428.516778ms","start":"2026-10-15T19:29:23.405414Z","end":"2026-10-15T19:29:23.833931Z","steps":["trace[1035232576] 'agreement among raft nodes before linearized reading' (duration: 428.248681ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:29:23.833935Z","caller":"traceutil/trace.go:171","msg":"trace[20905067] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:14128; }","duration":"330.029412ms","start":"2026-10-15T19:29:23.503881Z","end":"2026-10-15T19:29:23.83391Z","steps":["trace[20905067] 'agreement among raft nodes before linearized reading' (duration: 329.754446ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:29:23.833992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:29:23.40539Z","time spent":"428.5943ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-15T19:29:23.834015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:29:23.50386Z","time spent":"330.138584ms","remote":"127.0.0.1:54118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-10-15T19:29:23.834221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.801234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-15T19:29:23.834292Z","caller":"traceutil/trace.go:171","msg":"trace[1334190728] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14128; }","duration":"182.910907ms","start":"2026-10-15T19:29:23.651364Z","end":"2026-10-15T19:29:23.834275Z","steps":["trace[1334190728] 'agreement among raft nodes before linearized reading' (duration: 182.687231ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:30:32.252012Z","caller":"traceutil/trace.go:171","msg":"trace[2031133422] transaction","detail":"{read_only:false; response_revision:15034; number_of_response:1; }","duration":"118.275206ms","start":"2026-10-15T19:30:32.133714Z","end":"2026-10-15T19:30:32.25199Z","steps":["trace[2031133422] 'process raft request' (duration: 118.118143ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:30:32.254615Z","caller":"traceutil/trace.go:171","msg":"trace[1022369585] transaction","detail":"{read_only:false; response_revision:15035; number_of_response:1; }","duration":"120.13994ms","start":"2026-10-15T19:30:32.13443Z","end":"2026-10-15T19:30:32.25457Z","steps":["trace[1022369585] 'process raft request' (duration: 119.997117ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:30:57.025003Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12719} {"level":"info","ts":"2026-10-15T19:30:57.113568Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12719,"took":"85.453853ms","hash":2009345879,"current-db-size-bytes":56762368,"current-db-size":"57 MB","current-db-size-in-use-bytes":30126080,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-15T19:30:57.113642Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2009345879,"revision":12719,"compact-revision":10444} {"level":"info","ts":"2026-10-15T19:31:06.064556Z","caller":"traceutil/trace.go:171","msg":"trace[284247057] linearizableReadLoop","detail":"{readStateIndex:15739; appliedIndex:15738; }","duration":"112.232643ms","start":"2026-10-15T19:31:05.952296Z","end":"2026-10-15T19:31:06.064529Z","steps":["trace[284247057] 'read index received' (duration: 111.941756ms)","trace[284247057] 'applied index is now lower than readState.Index' (duration: 289.487µs)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:31:06.065432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.123895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:76 size:737328"} {"level":"info","ts":"2026-10-15T19:31:06.065482Z","caller":"traceutil/trace.go:171","msg":"trace[1481951760] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:76; response_revision:15212; }","duration":"113.249908ms","start":"2026-10-15T19:31:05.952219Z","end":"2026-10-15T19:31:06.065469Z","steps":["trace[1481951760] 'agreement among raft nodes before linearized reading' (duration: 112.427578ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:31:06.066013Z","caller":"traceutil/trace.go:171","msg":"trace[806765541] transaction","detail":"{read_only:false; response_revision:15212; number_of_response:1; }","duration":"114.815655ms","start":"2026-10-15T19:31:05.951186Z","end":"2026-10-15T19:31:06.066002Z","steps":["trace[806765541] 'process raft request' (duration: 113.111965ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:31:06.602043Z","caller":"traceutil/trace.go:171","msg":"trace[1695282942] transaction","detail":"{read_only:false; response_revision:15213; number_of_response:1; }","duration":"223.884985ms","start":"2026-10-15T19:31:06.378135Z","end":"2026-10-15T19:31:06.60202Z","steps":["trace[1695282942] 'process raft request' (duration: 223.726381ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:31:06.775372Z","caller":"traceutil/trace.go:171","msg":"trace[205167259] linearizableReadLoop","detail":"{readStateIndex:15741; appliedIndex:15739; }","duration":"378.832525ms","start":"2026-10-15T19:31:06.396512Z","end":"2026-10-15T19:31:06.775344Z","steps":["trace[205167259] 'read index received' (duration: 205.366168ms)","trace[205167259] 'applied index is now lower than readState.Index' (duration: 173.465027ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:31:06.775494Z","caller":"traceutil/trace.go:171","msg":"trace[1461221993] transaction","detail":"{read_only:false; response_revision:15214; number_of_response:1; }","duration":"392.733272ms","start":"2026-10-15T19:31:06.382718Z","end":"2026-10-15T19:31:06.775451Z","steps":["trace[1461221993] 'process raft request' (duration: 388.534193ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:06.775705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.791146ms","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-10-15T19:31:06.775704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:31:06.3827Z","time spent":"392.893176ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:31:06.775734Z","caller":"traceutil/trace.go:171","msg":"trace[1193431538] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15214; }","duration":"359.817807ms","start":"2026-10-15T19:31:06.415908Z","end":"2026-10-15T19:31:06.775726Z","steps":["trace[1193431538] 'agreement among raft nodes before linearized reading' (duration: 359.677923ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:06.775763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:31:06.41586Z","time spent":"359.896618ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-15T19:31:06.775553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.008279ms","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:5"} {"level":"info","ts":"2026-10-15T19:31:06.775907Z","caller":"traceutil/trace.go:171","msg":"trace[1933325295] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:15214; }","duration":"379.410718ms","start":"2026-10-15T19:31:06.396481Z","end":"2026-10-15T19:31:06.775891Z","steps":["trace[1933325295] 'agreement among raft nodes before linearized reading' (duration: 379.002308ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:06.775952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:31:06.396469Z","time spent":"379.47066ms","remote":"127.0.0.1:46338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-10-15T19:31:06.775999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.487516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-15T19:31:06.776028Z","caller":"traceutil/trace.go:171","msg":"trace[959165886] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:15214; }","duration":"108.552248ms","start":"2026-10-15T19:31:06.667463Z","end":"2026-10-15T19:31:06.776016Z","steps":["trace[959165886] 'agreement among raft nodes before linearized reading' (duration: 108.509426ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:06.776195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.668436ms","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-10-15T19:31:06.776218Z","caller":"traceutil/trace.go:171","msg":"trace[1894527019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:15214; }","duration":"312.714327ms","start":"2026-10-15T19:31:06.463497Z","end":"2026-10-15T19:31:06.776211Z","steps":["trace[1894527019] 'agreement among raft nodes before linearized reading' (duration: 312.641415ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:06.776235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:31:06.463485Z","time spent":"312.745278ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-15T19:31:06.776493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.257834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-10-15T19:31:06.776519Z","caller":"traceutil/trace.go:171","msg":"trace[249201881] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15214; }","duration":"331.307235ms","start":"2026-10-15T19:31:06.445204Z","end":"2026-10-15T19:31:06.776511Z","steps":["trace[249201881] 'agreement among raft nodes before linearized reading' (duration: 331.228423ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:06.776536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:31:06.445192Z","time spent":"331.339486ms","remote":"127.0.0.1:48988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-10-15T19:31:06.776596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.421757ms","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-10-15T19:31:06.776709Z","caller":"traceutil/trace.go:171","msg":"trace[1584261356] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15214; }","duration":"345.572081ms","start":"2026-10-15T19:31:06.431112Z","end":"2026-10-15T19:31:06.776684Z","steps":["trace[1584261356] 'agreement among raft nodes before linearized reading' (duration: 345.388417ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:31:06.776755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:31:06.431101Z","time spent":"345.644483ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-15T19:35:57.033145Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15167} {"level":"info","ts":"2026-10-15T19:35:57.145558Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15167,"took":"100.334212ms","hash":2119416641,"current-db-size-bytes":56762368,"current-db-size":"57 MB","current-db-size-in-use-bytes":32088064,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T19:35:57.145639Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2119416641,"revision":15167,"compact-revision":12719} {"level":"info","ts":"2026-10-15T19:36:48.154354Z","caller":"traceutil/trace.go:171","msg":"trace[1782804768] transaction","detail":"{read_only:false; response_revision:17764; number_of_response:1; }","duration":"654.523332ms","start":"2026-10-15T19:36:47.499792Z","end":"2026-10-15T19:36:48.154315Z","steps":["trace[1782804768] 'process raft request' (duration: 654.316727ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:36:48.154506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:36:47.499765Z","time spent":"654.660125ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:36:48.193379Z","caller":"traceutil/trace.go:171","msg":"trace[129956907] transaction","detail":"{read_only:false; response_revision:17765; number_of_response:1; }","duration":"113.498268ms","start":"2026-10-15T19:36:48.079848Z","end":"2026-10-15T19:36:48.193346Z","steps":["trace[129956907] 'process raft request' (duration: 113.286853ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:39:58.174397Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ee8e5a779fc25cea","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T19:39:58.183331Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-15T19:39:58.183428Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-15T19:40:57.040483Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17388} {"level":"info","ts":"2026-10-15T19:40:57.142749Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17388,"took":"98.91724ms","hash":1701329159,"current-db-size-bytes":56762368,"current-db-size":"57 MB","current-db-size-in-use-bytes":34545664,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-15T19:40:57.142825Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1701329159,"revision":17388,"compact-revision":15167} {"level":"info","ts":"2026-10-15T19:41:57.54738Z","caller":"traceutil/trace.go:171","msg":"trace[340304694] transaction","detail":"{read_only:false; response_revision:20251; number_of_response:1; }","duration":"118.238779ms","start":"2026-10-15T19:41:57.429099Z","end":"2026-10-15T19:41:57.547338Z","steps":["trace[340304694] 'process raft request' (duration: 117.964992ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:42:13.717117Z","caller":"traceutil/trace.go:171","msg":"trace[1254575985] linearizableReadLoop","detail":"{readStateIndex:21027; appliedIndex:21026; }","duration":"103.166897ms","start":"2026-10-15T19:42:13.613923Z","end":"2026-10-15T19:42:13.71709Z","steps":["trace[1254575985] 'read index received' (duration: 102.970023ms)","trace[1254575985] 'applied index is now lower than readState.Index' (duration: 195.544µs)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:42:13.7172Z","caller":"traceutil/trace.go:171","msg":"trace[1934095272] transaction","detail":"{read_only:false; response_revision:20334; number_of_response:1; }","duration":"104.13584ms","start":"2026-10-15T19:42:13.613004Z","end":"2026-10-15T19:42:13.71714Z","steps":["trace[1934095272] 'process raft request' (duration: 103.879245ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:42:13.720417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.472935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:42:13.720488Z","caller":"traceutil/trace.go:171","msg":"trace[1744378806] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:20334; }","duration":"106.69354ms","start":"2026-10-15T19:42:13.613775Z","end":"2026-10-15T19:42:13.720468Z","steps":["trace[1744378806] 'agreement among raft nodes before linearized reading' (duration: 103.461285ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:42:13.720535Z","caller":"traceutil/trace.go:171","msg":"trace[532675687] transaction","detail":"{read_only:false; response_revision:20335; number_of_response:1; }","duration":"105.249806ms","start":"2026-10-15T19:42:13.615259Z","end":"2026-10-15T19:42:13.720509Z","steps":["trace[532675687] 'process raft request' (duration: 104.13223ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:42:19.935117Z","caller":"traceutil/trace.go:171","msg":"trace[279798049] transaction","detail":"{read_only:false; response_revision:20365; number_of_response:1; }","duration":"197.121252ms","start":"2026-10-15T19:42:19.737969Z","end":"2026-10-15T19:42:19.93509Z","steps":["trace[279798049] 'process raft request' (duration: 196.947558ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:42:20.267515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.004317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:42:20.267668Z","caller":"traceutil/trace.go:171","msg":"trace[334089995] linearizableReadLoop","detail":"{readStateIndex:21061; appliedIndex:21058; }","duration":"385.549611ms","start":"2026-10-15T19:42:19.882098Z","end":"2026-10-15T19:42:20.267648Z","steps":["trace[334089995] 'read index received' (duration: 52.807168ms)","trace[334089995] 'applied index is now lower than readState.Index' (duration: 332.741053ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:42:20.267919Z","caller":"traceutil/trace.go:171","msg":"trace[1413370824] transaction","detail":"{read_only:false; response_revision:20367; number_of_response:1; }","duration":"527.999823ms","start":"2026-10-15T19:42:19.739887Z","end":"2026-10-15T19:42:20.267887Z","steps":["trace[1413370824] 'process raft request' (duration: 527.709586ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:42:20.267965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.817587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:42:20.268084Z","caller":"traceutil/trace.go:171","msg":"trace[831305648] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:20367; }","duration":"386.003482ms","start":"2026-10-15T19:42:19.882046Z","end":"2026-10-15T19:42:20.26805Z","steps":["trace[831305648] 'agreement among raft nodes before linearized reading' (duration: 385.757626ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:42:20.268096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:42:19.739873Z","time spent":"528.121855ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:42:20.268093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.088532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-15T19:42:20.268168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:42:19.882024Z","time spent":"386.126845ms","remote":"127.0.0.1:46276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"info","ts":"2026-10-15T19:42:20.268202Z","caller":"traceutil/trace.go:171","msg":"trace[1250511822] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:20367; }","duration":"151.265316ms","start":"2026-10-15T19:42:20.116911Z","end":"2026-10-15T19:42:20.268177Z","steps":["trace[1250511822] 'agreement among raft nodes before linearized reading' (duration: 151.051091ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:42:20.268643Z","caller":"traceutil/trace.go:171","msg":"trace[811799570] transaction","detail":"{read_only:false; response_revision:20366; number_of_response:1; }","duration":"528.530975ms","start":"2026-10-15T19:42:19.739352Z","end":"2026-10-15T19:42:20.267883Z","steps":["trace[811799570] 'process raft request' (duration: 353.076447ms)","trace[811799570] 'compare' (duration: 174.798133ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:42:20.268991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:42:19.739326Z","time spent":"529.465387ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:42:20.546111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.851891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:42:20.546197Z","caller":"traceutil/trace.go:171","msg":"trace[1661903286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20367; }","duration":"171.030045ms","start":"2026-10-15T19:42:20.375149Z","end":"2026-10-15T19:42:20.546179Z","steps":["trace[1661903286] 'range keys from in-memory index tree' (duration: 170.667677ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:42:20.546486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.450085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-10-15T19:42:20.546628Z","caller":"traceutil/trace.go:171","msg":"trace[835890948] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20367; }","duration":"128.64587ms","start":"2026-10-15T19:42:20.417923Z","end":"2026-10-15T19:42:20.546569Z","steps":["trace[835890948] 'range keys from in-memory index tree' (duration: 128.149628ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:42:20.546791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.179595ms","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-10-15T19:42:20.546923Z","caller":"traceutil/trace.go:171","msg":"trace[813712246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20367; }","duration":"133.517793ms","start":"2026-10-15T19:42:20.41335Z","end":"2026-10-15T19:42:20.546868Z","steps":["trace[813712246] 'range keys from in-memory index tree' (duration: 132.857968ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:45:57.047644Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19908} {"level":"info","ts":"2026-10-15T19:45:57.185655Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19908,"took":"132.650314ms","hash":3360816698,"current-db-size-bytes":56762368,"current-db-size":"57 MB","current-db-size-in-use-bytes":32604160,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T19:45:57.185738Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3360816698,"revision":19908,"compact-revision":17388} {"level":"info","ts":"2026-10-15T19:46:07.321952Z","caller":"traceutil/trace.go:171","msg":"trace[370425303] transaction","detail":"{read_only:false; response_revision:22771; number_of_response:1; }","duration":"136.447002ms","start":"2026-10-15T19:46:07.185464Z","end":"2026-10-15T19:46:07.321911Z","steps":["trace[370425303] 'process raft request' (duration: 122.944748ms)","trace[370425303] 'compare' (duration: 13.33659ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:46:07.719168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.483366ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-10-15T19:46:07.719334Z","caller":"traceutil/trace.go:171","msg":"trace[1586712666] linearizableReadLoop","detail":"{readStateIndex:23541; appliedIndex:23540; }","duration":"342.834058ms","start":"2026-10-15T19:46:07.376471Z","end":"2026-10-15T19:46:07.719305Z","steps":["trace[1586712666] 'read index received' (duration: 53.732µs)","trace[1586712666] 'applied index is now lower than readState.Index' (duration: 342.778196ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:46:07.719719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.670066ms","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-10-15T19:46:07.719693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.197946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:46:07.719768Z","caller":"traceutil/trace.go:171","msg":"trace[344731044] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:22771; }","duration":"278.775719ms","start":"2026-10-15T19:46:07.440978Z","end":"2026-10-15T19:46:07.719754Z","steps":["trace[344731044] 'agreement among raft nodes before linearized reading' (duration: 278.633345ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:46:07.719797Z","caller":"traceutil/trace.go:171","msg":"trace[1719399023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22771; }","duration":"343.36221ms","start":"2026-10-15T19:46:07.376407Z","end":"2026-10-15T19:46:07.719769Z","steps":["trace[1719399023] 'agreement among raft nodes before linearized reading' (duration: 343.169675ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:46:07.719841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:46:07.376384Z","time spent":"343.449442ms","remote":"127.0.0.1:48802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T19:46:07.719866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.547801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-15T19:46:07.719948Z","caller":"traceutil/trace.go:171","msg":"trace[366740655] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:22771; }","duration":"154.639764ms","start":"2026-10-15T19:46:07.565284Z","end":"2026-10-15T19:46:07.719923Z","steps":["trace[366740655] 'agreement among raft nodes before linearized reading' (duration: 154.48512ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:47:45.910351Z","caller":"traceutil/trace.go:171","msg":"trace[1810206012] transaction","detail":"{read_only:false; response_revision:23787; number_of_response:1; }","duration":"155.005293ms","start":"2026-10-15T19:47:45.755305Z","end":"2026-10-15T19:47:45.91031Z","steps":["trace[1810206012] 'process raft request' (duration: 124.602684ms)","trace[1810206012] 'compare' (duration: 29.867016ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:49:38.903687Z","caller":"traceutil/trace.go:171","msg":"trace[514660931] transaction","detail":"{read_only:false; response_revision:24821; number_of_response:1; }","duration":"173.417251ms","start":"2026-10-15T19:49:38.730247Z","end":"2026-10-15T19:49:38.903664Z","steps":["trace[514660931] 'process raft request' (duration: 172.897069ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:49:39.122731Z","caller":"traceutil/trace.go:171","msg":"trace[128984868] linearizableReadLoop","detail":"{readStateIndex:25642; appliedIndex:25641; }","duration":"175.262435ms","start":"2026-10-15T19:49:38.947435Z","end":"2026-10-15T19:49:39.122697Z","steps":["trace[128984868] 'read index received' (duration: 152.772201ms)","trace[128984868] 'applied index is now lower than readState.Index' (duration: 22.488634ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:49:39.122951Z","caller":"traceutil/trace.go:171","msg":"trace[198032154] transaction","detail":"{read_only:false; response_revision:24822; number_of_response:1; }","duration":"214.276285ms","start":"2026-10-15T19:49:38.908625Z","end":"2026-10-15T19:49:39.122902Z","steps":["trace[198032154] 'process raft request' (duration: 191.662798ms)","trace[198032154] 'compare' (duration: 22.250458ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:49:39.123404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.543991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:49:39.12372Z","caller":"traceutil/trace.go:171","msg":"trace[884673591] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:24822; }","duration":"176.016292ms","start":"2026-10-15T19:49:38.947426Z","end":"2026-10-15T19:49:39.123443Z","steps":["trace[884673591] 'agreement among raft nodes before linearized reading' (duration: 175.47636ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:39.694701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.29789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-15T19:49:39.694917Z","caller":"traceutil/trace.go:171","msg":"trace[120691702] transaction","detail":"{read_only:false; response_revision:24823; number_of_response:1; }","duration":"571.650504ms","start":"2026-10-15T19:49:39.123235Z","end":"2026-10-15T19:49:39.694886Z","steps":["trace[120691702] 'process raft request' (duration: 159.071188ms)","trace[120691702] 'compare' (duration: 412.113276ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:49:39.695047Z","caller":"traceutil/trace.go:171","msg":"trace[1711539102] linearizableReadLoop","detail":"{readStateIndex:25647; appliedIndex:25642; }","duration":"317.358327ms","start":"2026-10-15T19:49:39.377671Z","end":"2026-10-15T19:49:39.695029Z","steps":["trace[1711539102] 'read index received' (duration: 51.92915ms)","trace[1711539102] 'applied index is now lower than readState.Index' (duration: 265.428127ms)"],"step_count":2} {"level":"warn","ts":"2026-10-15T19:49:39.695091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.123222Z","time spent":"571.793927ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:49:39.69522Z","caller":"traceutil/trace.go:171","msg":"trace[132716295] transaction","detail":"{read_only:false; response_revision:24824; number_of_response:1; }","duration":"567.896627ms","start":"2026-10-15T19:49:39.127312Z","end":"2026-10-15T19:49:39.695208Z","steps":["trace[132716295] 'process raft request' (duration: 567.517158ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:39.695288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.127287Z","time spent":"567.966718ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:49:39.695345Z","caller":"traceutil/trace.go:171","msg":"trace[1360639065] transaction","detail":"{read_only:false; response_revision:24826; number_of_response:1; }","duration":"567.727312ms","start":"2026-10-15T19:49:39.127573Z","end":"2026-10-15T19:49:39.6953Z","steps":["trace[1360639065] 'process raft request' (duration: 567.391354ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:39.695475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.725496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:49:39.695434Z","caller":"traceutil/trace.go:171","msg":"trace[1963353637] transaction","detail":"{read_only:false; response_revision:24825; number_of_response:1; }","duration":"567.868206ms","start":"2026-10-15T19:49:39.127537Z","end":"2026-10-15T19:49:39.695405Z","steps":["trace[1963353637] 'process raft request' (duration: 567.367074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:39.695505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.127563Z","time spent":"567.865666ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:49:39.695534Z","caller":"traceutil/trace.go:171","msg":"trace[1069382488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24827; }","duration":"317.90167ms","start":"2026-10-15T19:49:39.377617Z","end":"2026-10-15T19:49:39.695519Z","steps":["trace[1069382488] 'agreement among raft nodes before linearized reading' (duration: 317.728596ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:49:39.695465Z","caller":"traceutil/trace.go:171","msg":"trace[1306538905] transaction","detail":"{read_only:false; response_revision:24827; number_of_response:1; }","duration":"567.009635ms","start":"2026-10-15T19:49:39.128388Z","end":"2026-10-15T19:49:39.695398Z","steps":["trace[1306538905] 'process raft request' (duration: 566.606096ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:39.695788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.128367Z","time spent":"567.334373ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:49:39.695815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.377593Z","time spent":"318.209647ms","remote":"127.0.0.1:48802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-15T19:49:39.696311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.127529Z","time spent":"568.631274ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:49:39.713045Z","caller":"traceutil/trace.go:171","msg":"trace[845263458] transaction","detail":"{read_only:false; response_revision:24828; number_of_response:1; }","duration":"171.908477ms","start":"2026-10-15T19:49:39.541119Z","end":"2026-10-15T19:49:39.713028Z","steps":["trace[845263458] 'process raft request' (duration: 171.808545ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:49:40.705017Z","caller":"traceutil/trace.go:171","msg":"trace[698660167] linearizableReadLoop","detail":"{readStateIndex:25649; appliedIndex:25648; }","duration":"1.009897998s","start":"2026-10-15T19:49:39.695086Z","end":"2026-10-15T19:49:40.704984Z","steps":["trace[698660167] 'read index received' (duration: 17.934068ms)","trace[698660167] 'applied index is now lower than readState.Index' (duration: 991.96251ms)"],"step_count":2} {"level":"info","ts":"2026-10-15T19:49:40.705097Z","caller":"traceutil/trace.go:171","msg":"trace[260409268] transaction","detail":"{read_only:false; response_revision:24829; number_of_response:1; }","duration":"1.126101537s","start":"2026-10-15T19:49:39.578953Z","end":"2026-10-15T19:49:40.705054Z","steps":["trace[260409268] 'process raft request' (duration: 1.12579937s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:40.705251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.578907Z","time spent":"1.12625713s","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:49:40.705276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089537964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-15T19:49:40.705515Z","caller":"traceutil/trace.go:171","msg":"trace[1142939917] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24829; }","duration":"1.089704378s","start":"2026-10-15T19:49:39.615675Z","end":"2026-10-15T19:49:40.70538Z","steps":["trace[1142939917] 'agreement among raft nodes before linearized reading' (duration: 1.089451563s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:40.705587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.615663Z","time spent":"1.089905443s","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-10-15T19:49:40.729805Z","caller":"traceutil/trace.go:171","msg":"trace[1581755100] transaction","detail":"{read_only:false; response_revision:24830; number_of_response:1; }","duration":"845.707241ms","start":"2026-10-15T19:49:39.884082Z","end":"2026-10-15T19:49:40.729789Z","steps":["trace[1581755100] 'process raft request' (duration: 845.575728ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:49:40.729983Z","caller":"traceutil/trace.go:171","msg":"trace[67089689] transaction","detail":"{read_only:false; response_revision:24831; number_of_response:1; }","duration":"768.973293ms","start":"2026-10-15T19:49:39.960995Z","end":"2026-10-15T19:49:40.729968Z","steps":["trace[67089689] 'process raft request' (duration: 768.757398ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:40.730041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.960952Z","time spent":"769.062535ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:49:40.729985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.884051Z","time spent":"845.839015ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:49:40.730439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03314988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-15T19:49:40.730476Z","caller":"traceutil/trace.go:171","msg":"trace[528585790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24833; }","duration":"1.033212831s","start":"2026-10-15T19:49:39.697255Z","end":"2026-10-15T19:49:40.730468Z","steps":["trace[528585790] 'agreement among raft nodes before linearized reading' (duration: 1.033132149s)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:40.730497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.69724Z","time spent":"1.033253353s","remote":"127.0.0.1:48812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-15T19:49:40.730494Z","caller":"traceutil/trace.go:171","msg":"trace[794691226] transaction","detail":"{read_only:false; response_revision:24833; number_of_response:1; }","duration":"355.472115ms","start":"2026-10-15T19:49:40.374997Z","end":"2026-10-15T19:49:40.730469Z","steps":["trace[794691226] 'process raft request' (duration: 355.352382ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:40.730614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:40.37497Z","time spent":"355.587318ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-15T19:49:40.730567Z","caller":"traceutil/trace.go:171","msg":"trace[806151810] transaction","detail":"{read_only:false; response_revision:24832; number_of_response:1; }","duration":"679.791565ms","start":"2026-10-15T19:49:40.050735Z","end":"2026-10-15T19:49:40.730527Z","steps":["trace[806151810] 'process raft request' (duration: 679.15822ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:40.730789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:40.050704Z","time spent":"680.011029ms","remote":"127.0.0.1:49070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-15T19:49:40.730683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.491505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-dff575876-cq85z\" ","response":"range_response_count:1 size:8647"} {"level":"info","ts":"2026-10-15T19:49:40.730868Z","caller":"traceutil/trace.go:171","msg":"trace[1034294341] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-dff575876-cq85z; range_end:; response_count:1; response_revision:24833; }","duration":"911.73441ms","start":"2026-10-15T19:49:39.819126Z","end":"2026-10-15T19:49:40.730861Z","steps":["trace[1034294341] 'agreement among raft nodes before linearized reading' (duration: 911.448094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:49:40.730893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-15T19:49:39.819093Z","time spent":"911.793342ms","remote":"127.0.0.1:49000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":8671,"request content":"key:\"/registry/pods/openstack/magnum-api-dff575876-cq85z\" "} {"level":"info","ts":"2026-10-15T19:49:40.817106Z","caller":"traceutil/trace.go:171","msg":"trace[1755172823] transaction","detail":"{read_only:false; response_revision:24834; number_of_response:1; }","duration":"106.301788ms","start":"2026-10-15T19:49:40.710783Z","end":"2026-10-15T19:49:40.817085Z","steps":["trace[1755172823] 'process raft request' (duration: 106.138984ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:50:43.207428Z","caller":"traceutil/trace.go:171","msg":"trace[1669634706] transaction","detail":"{read_only:false; response_revision:25429; number_of_response:1; }","duration":"166.806717ms","start":"2026-10-15T19:50:43.040595Z","end":"2026-10-15T19:50:43.207401Z","steps":["trace[1669634706] 'process raft request' (duration: 166.721755ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:50:43.20767Z","caller":"traceutil/trace.go:171","msg":"trace[1417852104] transaction","detail":"{read_only:false; response_revision:25428; number_of_response:1; }","duration":"168.775673ms","start":"2026-10-15T19:50:43.038865Z","end":"2026-10-15T19:50:43.207641Z","steps":["trace[1417852104] 'process raft request' (duration: 168.295802ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:50:43.207703Z","caller":"traceutil/trace.go:171","msg":"trace[1306418320] transaction","detail":"{read_only:false; response_revision:25430; number_of_response:1; }","duration":"149.114395ms","start":"2026-10-15T19:50:43.058565Z","end":"2026-10-15T19:50:43.207679Z","steps":["trace[1306418320] 'process raft request' (duration: 148.800667ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:50:43.208164Z","caller":"traceutil/trace.go:171","msg":"trace[321183794] transaction","detail":"{read_only:false; response_revision:25431; number_of_response:1; }","duration":"145.312346ms","start":"2026-10-15T19:50:43.062836Z","end":"2026-10-15T19:50:43.208148Z","steps":["trace[321183794] 'process raft request' (duration: 144.761953ms)"],"step_count":1} {"level":"warn","ts":"2026-10-15T19:50:43.65352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.035406ms","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-10-15T19:50:43.653616Z","caller":"traceutil/trace.go:171","msg":"trace[602429415] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25432; }","duration":"195.17755ms","start":"2026-10-15T19:50:43.458413Z","end":"2026-10-15T19:50:43.65359Z","steps":["trace[602429415] 'range keys from in-memory index tree' (duration: 194.81944ms)"],"step_count":1} {"level":"info","ts":"2026-10-15T19:50:57.054743Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22235} {"level":"info","ts":"2026-10-15T19:50:57.191583Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22235,"took":"132.373706ms","hash":3615728408,"current-db-size-bytes":56762368,"current-db-size":"57 MB","current-db-size-in-use-bytes":44867584,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-15T19:50:57.191693Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3615728408,"revision":22235,"compact-revision":19908} {"level":"info","ts":"2026-10-15T19:51:12.283333Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067cd.wal"} {"level":"info","ts":"2026-10-15T19:55:57.062713Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25528} {"level":"info","ts":"2026-10-15T19:55:57.224314Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25528,"took":"157.382419ms","hash":2615085867,"current-db-size-bytes":57606144,"current-db-size":"58 MB","current-db-size-in-use-bytes":35692544,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-15T19:55:57.224389Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2615085867,"revision":25528,"compact-revision":22235} {"level":"info","ts":"2026-10-15T19:57:28.850263Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ee8e5a779fc25cea","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T19:57:28.856456Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-15T19:57:28.856548Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-15T20:00:57.072456Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28398} {"level":"info","ts":"2026-10-15T20:00:57.22048Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28398,"took":"143.598318ms","hash":2348853376,"current-db-size-bytes":57606144,"current-db-size":"58 MB","current-db-size-in-use-bytes":30625792,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T20:00:57.220554Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2348853376,"revision":28398,"compact-revision":25528} {"level":"info","ts":"2026-10-15T20:05:57.079706Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30681} {"level":"info","ts":"2026-10-15T20:05:57.213786Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30681,"took":"129.859125ms","hash":2796804934,"current-db-size-bytes":57606144,"current-db-size":"58 MB","current-db-size-in-use-bytes":31764480,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-15T20:05:57.213905Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2796804934,"revision":30681,"compact-revision":28398} {"level":"info","ts":"2026-10-15T20:10:57.088556Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32975} {"level":"info","ts":"2026-10-15T20:10:57.26195Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32975,"took":"168.973176ms","hash":4051716211,"current-db-size-bytes":58650624,"current-db-size":"59 MB","current-db-size-in-use-bytes":31031296,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-15T20:10:57.262028Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4051716211,"revision":32975,"compact-revision":30681} {"level":"info","ts":"2026-10-15T20:15:57.09704Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35272} {"level":"info","ts":"2026-10-15T20:15:57.229707Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35272,"took":"128.838051ms","hash":2393585981,"current-db-size-bytes":59125760,"current-db-size":"59 MB","current-db-size-in-use-bytes":32993280,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T20:15:57.229754Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2393585981,"revision":35272,"compact-revision":32975} {"level":"info","ts":"2026-10-15T20:18:47.890784Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ee8e5a779fc25cea","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-15T20:18:47.897885Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-15T20:18:47.898019Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-15T20:20:57.106763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37497} {"level":"info","ts":"2026-10-15T20:20:57.232036Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37497,"took":"121.459539ms","hash":779514696,"current-db-size-bytes":59977728,"current-db-size":"60 MB","current-db-size-in-use-bytes":32759808,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-15T20:20:57.232105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":779514696,"revision":37497,"compact-revision":35272}