{"level":"warn","ts":"2026-09-09T01:09:02.961305Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T01:09:02.961482Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.241: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.241:2380","--initial-cluster=instance=https://162.253.55.241:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.241:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.241: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-09-09T01:09:02.961588Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-09T01:09:02.961653Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.241:2380"]} {"level":"info","ts":"2026-09-09T01:09:02.961731Z","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-09-09T01:09:02.962916Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.241:2379"]} {"level":"info","ts":"2026-09-09T01:09:02.963021Z","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.241:2380"],"listen-peer-urls":["https://162.253.55.241:2380"],"advertise-client-urls":["https://162.253.55.241:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.241:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.241: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-09-09T01:09:02.965889Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.632393ms"} {"level":"info","ts":"2026-09-09T01:09:02.972322Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f50d212ec16cc59","cluster-id":"30c503bd66239e72"} {"level":"info","ts":"2026-09-09T01:09:02.97253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 switched to configuration voters=()"} {"level":"info","ts":"2026-09-09T01:09:02.972566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became follower at term 0"} {"level":"info","ts":"2026-09-09T01:09:02.972637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f50d212ec16cc59 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-09T01:09:02.972698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became follower at term 1"} {"level":"info","ts":"2026-09-09T01:09:02.972838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 switched to configuration voters=(6868220410452167769)"} {"level":"warn","ts":"2026-09-09T01:09:02.977101Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-09T01:09:02.97889Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-09T01:09:02.980567Z","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-09-09T01:09:02.983313Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f50d212ec16cc59","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-09T01:09:02.983519Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f50d212ec16cc59","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-09T01:09:02.98363Z","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-09-09T01:09:02.983821Z","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-09-09T01:09:02.983844Z","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-09-09T01:09:02.985093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 switched to configuration voters=(6868220410452167769)"} {"level":"info","ts":"2026-09-09T01:09:02.985414Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"30c503bd66239e72","local-member-id":"5f50d212ec16cc59","added-peer-id":"5f50d212ec16cc59","added-peer-peer-urls":["https://162.253.55.241:2380"]} {"level":"info","ts":"2026-09-09T01:09:02.988943Z","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-09-09T01:09:02.989063Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.241:2380"} {"level":"info","ts":"2026-09-09T01:09:02.98912Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.241:2380"} {"level":"info","ts":"2026-09-09T01:09:02.98948Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f50d212ec16cc59","initial-advertise-peer-urls":["https://162.253.55.241:2380"],"listen-peer-urls":["https://162.253.55.241:2380"],"advertise-client-urls":["https://162.253.55.241:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.241:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-09T01:09:02.989562Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-09T01:09:03.773197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 is starting a new election at term 1"} {"level":"info","ts":"2026-09-09T01:09:03.773352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-09T01:09:03.773422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 received MsgPreVoteResp from 5f50d212ec16cc59 at term 1"} {"level":"info","ts":"2026-09-09T01:09:03.773451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became candidate at term 2"} {"level":"info","ts":"2026-09-09T01:09:03.773464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 received MsgVoteResp from 5f50d212ec16cc59 at term 2"} {"level":"info","ts":"2026-09-09T01:09:03.773487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f50d212ec16cc59 became leader at term 2"} {"level":"info","ts":"2026-09-09T01:09:03.773509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f50d212ec16cc59 elected leader 5f50d212ec16cc59 at term 2"} {"level":"info","ts":"2026-09-09T01:09:03.775134Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f50d212ec16cc59","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.241:2379]}","request-path":"/0/members/5f50d212ec16cc59/attributes","cluster-id":"30c503bd66239e72","publish-timeout":"7s"} {"level":"info","ts":"2026-09-09T01:09:03.775153Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T01:09:03.775223Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:09:03.775237Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-09T01:09:03.775493Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-09T01:09:03.775552Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-09T01:09:03.776746Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"30c503bd66239e72","local-member-id":"5f50d212ec16cc59","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:09:03.776963Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:09:03.777008Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-09T01:09:03.778926Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-09T01:09:03.778954Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.241:2379"} {"level":"info","ts":"2026-09-09T01:09:29.855756Z","caller":"traceutil/trace.go:171","msg":"trace[2102741670] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"192.530743ms","start":"2026-09-09T01:09:29.663197Z","end":"2026-09-09T01:09:29.855728Z","steps":["trace[2102741670] 'process raft request' (duration: 192.285757ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:29.855716Z","caller":"traceutil/trace.go:171","msg":"trace[509833106] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"126.369821ms","start":"2026-09-09T01:09:29.729312Z","end":"2026-09-09T01:09:29.855682Z","steps":["trace[509833106] 'read index received' (duration: 126.051713ms)","trace[509833106] 'applied index is now lower than readState.Index' (duration: 316.338µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:09:29.855932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.594037ms","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-09-09T01:09:29.856053Z","caller":"traceutil/trace.go:171","msg":"trace[1255536771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:468; }","duration":"126.791142ms","start":"2026-09-09T01:09:29.729248Z","end":"2026-09-09T01:09:29.856039Z","steps":["trace[1255536771] 'agreement among raft nodes before linearized reading' (duration: 126.558817ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:29.993043Z","caller":"traceutil/trace.go:171","msg":"trace[798500746] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"132.067258ms","start":"2026-09-09T01:09:29.860935Z","end":"2026-09-09T01:09:29.993002Z","steps":["trace[798500746] 'process raft request' (duration: 131.813541ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:44.500951Z","caller":"traceutil/trace.go:171","msg":"trace[510260984] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:585; }","duration":"118.613325ms","start":"2026-09-09T01:09:44.382283Z","end":"2026-09-09T01:09:44.500896Z","steps":["trace[510260984] 'read index received' (duration: 81.429336ms)","trace[510260984] 'applied index is now lower than readState.Index' (duration: 37.182799ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:09:44.501086Z","caller":"traceutil/trace.go:171","msg":"trace[1047211850] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"132.13827ms","start":"2026-09-09T01:09:44.368072Z","end":"2026-09-09T01:09:44.50021Z","steps":["trace[1047211850] 'process raft request' (duration: 95.495404ms)","trace[1047211850] 'compare' (duration: 36.328138ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:09:44.501149Z","caller":"traceutil/trace.go:171","msg":"trace[1485624215] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"129.68548ms","start":"2026-09-09T01:09:44.371428Z","end":"2026-09-09T01:09:44.501113Z","steps":["trace[1485624215] 'process raft request' (duration: 128.629335ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:44.501137Z","caller":"traceutil/trace.go:171","msg":"trace[2037687866] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"120.89279ms","start":"2026-09-09T01:09:44.380227Z","end":"2026-09-09T01:09:44.501119Z","steps":["trace[2037687866] 'process raft request' (duration: 119.933168ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:44.502417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.118791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-09T01:09:44.502497Z","caller":"traceutil/trace.go:171","msg":"trace[483118269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:564; }","duration":"120.220323ms","start":"2026-09-09T01:09:44.382254Z","end":"2026-09-09T01:09:44.502474Z","steps":["trace[483118269] 'agreement among raft nodes before linearized reading' (duration: 119.082627ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:50.240628Z","caller":"traceutil/trace.go:171","msg":"trace[184923918] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"156.558193ms","start":"2026-09-09T01:09:50.084052Z","end":"2026-09-09T01:09:50.24061Z","steps":["trace[184923918] 'process raft request' (duration: 156.156862ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:09:50.344686Z","caller":"traceutil/trace.go:171","msg":"trace[2085629927] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"259.218717ms","start":"2026-09-09T01:09:50.08544Z","end":"2026-09-09T01:09:50.344659Z","steps":["trace[2085629927] 'process raft request' (duration: 176.008398ms)","trace[2085629927] 'compare' (duration: 82.992214ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:09:50.700756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.956418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:09:50.700902Z","caller":"traceutil/trace.go:171","msg":"trace[244173320] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"348.02695ms","start":"2026-09-09T01:09:50.35286Z","end":"2026-09-09T01:09:50.700887Z","steps":["trace[244173320] 'read index received' (duration: 181.093979ms)","trace[244173320] 'applied index is now lower than readState.Index' (duration: 166.931631ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:09:50.700957Z","caller":"traceutil/trace.go:171","msg":"trace[1524581223] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"352.302981ms","start":"2026-09-09T01:09:50.348621Z","end":"2026-09-09T01:09:50.700924Z","steps":["trace[1524581223] 'process raft request' (duration: 185.412132ms)","trace[1524581223] 'compare' (duration: 165.782214ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:09:50.701118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.850845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-09T01:09:50.701164Z","caller":"traceutil/trace.go:171","msg":"trace[488094047] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:643; }","duration":"175.932377ms","start":"2026-09-09T01:09:50.525212Z","end":"2026-09-09T01:09:50.701145Z","steps":["trace[488094047] 'agreement among raft nodes before linearized reading' (duration: 175.808693ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:50.701131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:09:50.348567Z","time spent":"352.488866ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:09:50.700994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.163443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:09:50.70135Z","caller":"traceutil/trace.go:171","msg":"trace[834974969] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:643; }","duration":"348.540833ms","start":"2026-09-09T01:09:50.352775Z","end":"2026-09-09T01:09:50.701316Z","steps":["trace[834974969] 'agreement among raft nodes before linearized reading' (duration: 348.188863ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:09:50.701492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:09:50.352752Z","time spent":"348.698366ms","remote":"127.0.0.1:40794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"info","ts":"2026-09-09T01:09:51.390559Z","caller":"traceutil/trace.go:171","msg":"trace[399141583] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"251.0105ms","start":"2026-09-09T01:09:51.139506Z","end":"2026-09-09T01:09:51.390516Z","steps":["trace[399141583] 'process raft request' (duration: 250.464148ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:11:27.536426Z","caller":"traceutil/trace.go:171","msg":"trace[459231287] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1962; }","duration":"115.967827ms","start":"2026-09-09T01:11:27.420426Z","end":"2026-09-09T01:11:27.536393Z","steps":["trace[459231287] 'read index received' (duration: 115.558797ms)","trace[459231287] 'applied index is now lower than readState.Index' (duration: 407.84µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:11:27.536484Z","caller":"traceutil/trace.go:171","msg":"trace[172622648] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"174.941043ms","start":"2026-09-09T01:11:27.361498Z","end":"2026-09-09T01:11:27.536439Z","steps":["trace[172622648] 'process raft request' (duration: 174.751729ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:11:27.536446Z","caller":"traceutil/trace.go:171","msg":"trace[1525741167] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"174.918274ms","start":"2026-09-09T01:11:27.361481Z","end":"2026-09-09T01:11:27.536399Z","steps":["trace[1525741167] 'process raft request' (duration: 174.533094ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:11:27.536757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.297145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d381e1fbf949af\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-09-09T01:11:27.536857Z","caller":"traceutil/trace.go:171","msg":"trace[2127074063] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18d381e1fbf949af; range_end:; response_count:1; response_revision:1890; }","duration":"116.449718ms","start":"2026-09-09T01:11:27.420358Z","end":"2026-09-09T01:11:27.536808Z","steps":["trace[2127074063] 'agreement among raft nodes before linearized reading' (duration: 116.177032ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:11:39.558123Z","caller":"traceutil/trace.go:171","msg":"trace[1021397338] linearizableReadLoop","detail":"{readStateIndex:2066; appliedIndex:2065; }","duration":"139.522517ms","start":"2026-09-09T01:11:39.418576Z","end":"2026-09-09T01:11:39.558099Z","steps":["trace[1021397338] 'read index received' (duration: 139.2129ms)","trace[1021397338] 'applied index is now lower than readState.Index' (duration: 308.777µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:11:39.558292Z","caller":"traceutil/trace.go:171","msg":"trace[292969063] transaction","detail":"{read_only:false; response_revision:1990; number_of_response:1; }","duration":"179.384757ms","start":"2026-09-09T01:11:39.378847Z","end":"2026-09-09T01:11:39.558232Z","steps":["trace[292969063] 'process raft request' (duration: 179.008399ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:11:39.558781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.170232ms","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-09-09T01:11:39.559321Z","caller":"traceutil/trace.go:171","msg":"trace[186805927] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1990; }","duration":"140.792127ms","start":"2026-09-09T01:11:39.418502Z","end":"2026-09-09T01:11:39.559294Z","steps":["trace[186805927] 'agreement among raft nodes before linearized reading' (duration: 139.738672ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:12:11.927549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.216719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:12:11.927819Z","caller":"traceutil/trace.go:171","msg":"trace[26983686] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"113.007268ms","start":"2026-09-09T01:12:11.814792Z","end":"2026-09-09T01:12:11.927799Z","steps":["trace[26983686] 'process raft request' (duration: 112.886546ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:12:11.927769Z","caller":"traceutil/trace.go:171","msg":"trace[203106472] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"127.324711ms","start":"2026-09-09T01:12:11.800387Z","end":"2026-09-09T01:12:11.927712Z","steps":["trace[203106472] 'process raft request' (duration: 20.865057ms)","trace[203106472] 'compare' (duration: 105.840589ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:12:11.928214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.998105ms","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-09-09T01:12:11.928265Z","caller":"traceutil/trace.go:171","msg":"trace[287879662] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2180; }","duration":"118.066956ms","start":"2026-09-09T01:12:11.810182Z","end":"2026-09-09T01:12:11.928249Z","steps":["trace[287879662] 'agreement among raft nodes before linearized reading' (duration: 117.939033ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:12:11.928062Z","caller":"traceutil/trace.go:171","msg":"trace[1245799292] linearizableReadLoop","detail":"{readStateIndex:2261; appliedIndex:2260; }","duration":"117.79577ms","start":"2026-09-09T01:12:11.810209Z","end":"2026-09-09T01:12:11.928005Z","steps":["trace[1245799292] 'read index received' (duration: 11.032598ms)","trace[1245799292] 'applied index is now lower than readState.Index' (duration: 106.758492ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:12:12.488675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.160722ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-09T01:12:12.488997Z","caller":"traceutil/trace.go:171","msg":"trace[2083453569] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"288.34926ms","start":"2026-09-09T01:12:12.200629Z","end":"2026-09-09T01:12:12.488979Z","steps":["trace[2083453569] 'process raft request' (duration: 288.146375ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:12:12.489292Z","caller":"traceutil/trace.go:171","msg":"trace[1126968367] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"178.400715ms","start":"2026-09-09T01:12:12.310858Z","end":"2026-09-09T01:12:12.489258Z","steps":["trace[1126968367] 'process raft request' (duration: 178.078227ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:13:40.647493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.356563ms","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-09-09T01:13:40.647582Z","caller":"traceutil/trace.go:171","msg":"trace[875334458] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2853; }","duration":"166.528416ms","start":"2026-09-09T01:13:40.481036Z","end":"2026-09-09T01:13:40.647564Z","steps":["trace[875334458] 'range keys from in-memory index tree' (duration: 166.134987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:13:40.647621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.880299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:13:40.647698Z","caller":"traceutil/trace.go:171","msg":"trace[1491532414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2853; }","duration":"142.988691ms","start":"2026-09-09T01:13:40.50469Z","end":"2026-09-09T01:13:40.647679Z","steps":["trace[1491532414] 'range keys from in-memory index tree' (duration: 142.728616ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:13:40.919588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.024499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:13:40.919674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.152084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:13:40.919718Z","caller":"traceutil/trace.go:171","msg":"trace[1088315104] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2854; }","duration":"104.226403ms","start":"2026-09-09T01:13:40.815466Z","end":"2026-09-09T01:13:40.919692Z","steps":["trace[1088315104] 'count revisions from in-memory index tree' (duration: 103.882885ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:13:40.919813Z","caller":"traceutil/trace.go:171","msg":"trace[891843930] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2854; }","duration":"149.432051ms","start":"2026-09-09T01:13:40.77035Z","end":"2026-09-09T01:13:40.919782Z","steps":["trace[891843930] 'count revisions from in-memory index tree' (duration: 149.051892ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:13:40.97684Z","caller":"traceutil/trace.go:171","msg":"trace[1163157572] linearizableReadLoop","detail":"{readStateIndex:2965; appliedIndex:2964; }","duration":"149.114073ms","start":"2026-09-09T01:13:40.827685Z","end":"2026-09-09T01:13:40.976799Z","steps":["trace[1163157572] 'read index received' (duration: 148.857257ms)","trace[1163157572] 'applied index is now lower than readState.Index' (duration: 255.236µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:13:40.976867Z","caller":"traceutil/trace.go:171","msg":"trace[516441699] transaction","detail":"{read_only:false; response_revision:2855; number_of_response:1; }","duration":"154.888597ms","start":"2026-09-09T01:13:40.821955Z","end":"2026-09-09T01:13:40.976843Z","steps":["trace[516441699] 'process raft request' (duration: 154.57566ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:13:40.977102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.38646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:13:40.977173Z","caller":"traceutil/trace.go:171","msg":"trace[1946472779] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2855; }","duration":"149.538993ms","start":"2026-09-09T01:13:40.827616Z","end":"2026-09-09T01:13:40.977155Z","steps":["trace[1946472779] 'agreement among raft nodes before linearized reading' (duration: 149.335609ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:16:48.530564Z","caller":"traceutil/trace.go:171","msg":"trace[1261343481] transaction","detail":"{read_only:false; response_revision:4045; number_of_response:1; }","duration":"331.883065ms","start":"2026-09-09T01:16:48.198658Z","end":"2026-09-09T01:16:48.530541Z","steps":["trace[1261343481] 'process raft request' (duration: 331.741711ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:16:48.5307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:16:48.198632Z","time spent":"332.003257ms","remote":"127.0.0.1:40466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16967,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:16:48.532731Z","caller":"traceutil/trace.go:171","msg":"trace[1649769228] transaction","detail":"{read_only:false; response_revision:4046; number_of_response:1; }","duration":"163.177823ms","start":"2026-09-09T01:16:48.369529Z","end":"2026-09-09T01:16:48.532706Z","steps":["trace[1649769228] 'process raft request' (duration: 163.02053ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:17.052254Z","caller":"traceutil/trace.go:171","msg":"trace[256872789] transaction","detail":"{read_only:false; response_revision:5106; number_of_response:1; }","duration":"106.315055ms","start":"2026-09-09T01:18:16.945918Z","end":"2026-09-09T01:18:17.052233Z","steps":["trace[256872789] 'process raft request' (duration: 39.823133ms)","trace[256872789] 'compare' (duration: 66.381209ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:18:17.917217Z","caller":"traceutil/trace.go:171","msg":"trace[253795117] transaction","detail":"{read_only:false; response_revision:5174; number_of_response:1; }","duration":"121.981198ms","start":"2026-09-09T01:18:17.795211Z","end":"2026-09-09T01:18:17.917192Z","steps":["trace[253795117] 'process raft request' (duration: 114.010713ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:17.917807Z","caller":"traceutil/trace.go:171","msg":"trace[2021351348] transaction","detail":"{read_only:false; response_revision:5176; number_of_response:1; }","duration":"122.034869ms","start":"2026-09-09T01:18:17.79576Z","end":"2026-09-09T01:18:17.917795Z","steps":["trace[2021351348] 'process raft request' (duration: 121.957487ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:17.917789Z","caller":"traceutil/trace.go:171","msg":"trace[1209551486] transaction","detail":"{read_only:false; response_revision:5175; number_of_response:1; }","duration":"122.003258ms","start":"2026-09-09T01:18:17.795761Z","end":"2026-09-09T01:18:17.917764Z","steps":["trace[1209551486] 'process raft request' (duration: 121.874615ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:17.917943Z","caller":"traceutil/trace.go:171","msg":"trace[363462055] transaction","detail":"{read_only:false; response_revision:5178; number_of_response:1; }","duration":"119.667643ms","start":"2026-09-09T01:18:17.798254Z","end":"2026-09-09T01:18:17.917921Z","steps":["trace[363462055] 'process raft request' (duration: 119.540681ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:17.917946Z","caller":"traceutil/trace.go:171","msg":"trace[1353383188] transaction","detail":"{read_only:false; response_revision:5177; number_of_response:1; }","duration":"120.191466ms","start":"2026-09-09T01:18:17.797709Z","end":"2026-09-09T01:18:17.9179Z","steps":["trace[1353383188] 'process raft request' (duration: 120.042973ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:17.918093Z","caller":"traceutil/trace.go:171","msg":"trace[1140518079] transaction","detail":"{read_only:false; response_revision:5179; number_of_response:1; }","duration":"119.330396ms","start":"2026-09-09T01:18:17.798754Z","end":"2026-09-09T01:18:17.918084Z","steps":["trace[1140518079] 'process raft request' (duration: 119.07755ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:17.918199Z","caller":"traceutil/trace.go:171","msg":"trace[484462684] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"118.829564ms","start":"2026-09-09T01:18:17.799348Z","end":"2026-09-09T01:18:17.918177Z","steps":["trace[484462684] 'process raft request' (duration: 118.677011ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:17.918219Z","caller":"traceutil/trace.go:171","msg":"trace[766295228] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"116.224665ms","start":"2026-09-09T01:18:17.80197Z","end":"2026-09-09T01:18:17.918195Z","steps":["trace[766295228] 'process raft request' (duration: 116.147423ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:17.918403Z","caller":"traceutil/trace.go:171","msg":"trace[1516298298] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"115.838796ms","start":"2026-09-09T01:18:17.802548Z","end":"2026-09-09T01:18:17.918387Z","steps":["trace[1516298298] 'process raft request' (duration: 115.61608ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:17.918508Z","caller":"traceutil/trace.go:171","msg":"trace[1256075790] linearizableReadLoop","detail":"{readStateIndex:5379; appliedIndex:5371; }","duration":"116.224094ms","start":"2026-09-09T01:18:17.802273Z","end":"2026-09-09T01:18:17.918497Z","steps":["trace[1256075790] 'read index received' (duration: 107.079202ms)","trace[1256075790] 'applied index is now lower than readState.Index' (duration: 9.144242ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:18:17.918635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.347197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/monitoring/loki-gateway\" ","response":"range_response_count:1 size:4305"} {"level":"info","ts":"2026-09-09T01:18:17.918659Z","caller":"traceutil/trace.go:171","msg":"trace[305007270] range","detail":"{range_begin:/registry/deployments/monitoring/loki-gateway; range_end:; response_count:1; response_revision:5184; }","duration":"116.383378ms","start":"2026-09-09T01:18:17.802268Z","end":"2026-09-09T01:18:17.918652Z","steps":["trace[305007270] 'agreement among raft nodes before linearized reading' (duration: 116.267895ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:18:19.461137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.099285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qpttf\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-09-09T01:18:19.461219Z","caller":"traceutil/trace.go:171","msg":"trace[1968896246] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qpttf; range_end:; response_count:1; response_revision:5238; }","duration":"164.212597ms","start":"2026-09-09T01:18:19.296986Z","end":"2026-09-09T01:18:19.461198Z","steps":["trace[1968896246] 'range keys from in-memory index tree' (duration: 163.966031ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:18:19.461209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.074101ms","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-09-09T01:18:19.461258Z","caller":"traceutil/trace.go:171","msg":"trace[1788978499] linearizableReadLoop","detail":"{readStateIndex:5438; appliedIndex:5437; }","duration":"116.149551ms","start":"2026-09-09T01:18:19.345085Z","end":"2026-09-09T01:18:19.461235Z","steps":["trace[1788978499] 'read index received' (duration: 113.391398ms)","trace[1788978499] 'applied index is now lower than readState.Index' (duration: 2.757133ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:18:19.461297Z","caller":"traceutil/trace.go:171","msg":"trace[1376826306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5238; }","duration":"242.203804ms","start":"2026-09-09T01:18:19.219063Z","end":"2026-09-09T01:18:19.461267Z","steps":["trace[1376826306] 'range keys from in-memory index tree' (duration: 241.868826ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:19.461341Z","caller":"traceutil/trace.go:171","msg":"trace[38801748] transaction","detail":"{read_only:false; response_revision:5239; number_of_response:1; }","duration":"127.187519ms","start":"2026-09-09T01:18:19.334147Z","end":"2026-09-09T01:18:19.461334Z","steps":["trace[38801748] 'process raft request' (duration: 124.301161ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:18:19.461676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.580522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728735"} {"level":"info","ts":"2026-09-09T01:18:19.461698Z","caller":"traceutil/trace.go:171","msg":"trace[146173351] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5239; }","duration":"116.651125ms","start":"2026-09-09T01:18:19.34504Z","end":"2026-09-09T01:18:19.461691Z","steps":["trace[146173351] 'agreement among raft nodes before linearized reading' (duration: 116.373479ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:23.996562Z","caller":"traceutil/trace.go:171","msg":"trace[288478958] transaction","detail":"{read_only:false; response_revision:5337; number_of_response:1; }","duration":"181.925258ms","start":"2026-09-09T01:18:23.814608Z","end":"2026-09-09T01:18:23.996533Z","steps":["trace[288478958] 'process raft request' (duration: 131.637342ms)","trace[288478958] 'compare' (duration: 49.905856ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:18:23.996862Z","caller":"traceutil/trace.go:171","msg":"trace[1468846698] transaction","detail":"{read_only:false; response_revision:5338; number_of_response:1; }","duration":"182.256646ms","start":"2026-09-09T01:18:23.814588Z","end":"2026-09-09T01:18:23.996844Z","steps":["trace[1468846698] 'process raft request' (duration: 181.973949ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:18:42.585326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.4028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-09T01:18:42.585795Z","caller":"traceutil/trace.go:171","msg":"trace[378862086] transaction","detail":"{read_only:false; response_revision:5681; number_of_response:1; }","duration":"144.749306ms","start":"2026-09-09T01:18:42.441014Z","end":"2026-09-09T01:18:42.585764Z","steps":["trace[378862086] 'process raft request' (duration: 33.826894ms)","trace[378862086] 'compare' (duration: 110.288538ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:18:42.94031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.247126ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-09T01:18:42.94043Z","caller":"traceutil/trace.go:171","msg":"trace[2072458935] linearizableReadLoop","detail":"{readStateIndex:5894; appliedIndex:5893; }","duration":"243.942266ms","start":"2026-09-09T01:18:42.696466Z","end":"2026-09-09T01:18:42.940409Z","steps":["trace[2072458935] 'read index received' (duration: 42.559597ms)","trace[2072458935] 'applied index is now lower than readState.Index' (duration: 201.380659ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:18:42.940695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.944917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-09T01:18:42.940716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.234343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-09T01:18:42.940765Z","caller":"traceutil/trace.go:171","msg":"trace[453667769] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:5681; }","duration":"131.067259ms","start":"2026-09-09T01:18:42.809676Z","end":"2026-09-09T01:18:42.940743Z","steps":["trace[453667769] 'agreement among raft nodes before linearized reading' (duration: 130.845514ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:42.940813Z","caller":"traceutil/trace.go:171","msg":"trace[2135982164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5681; }","duration":"244.364275ms","start":"2026-09-09T01:18:42.696422Z","end":"2026-09-09T01:18:42.940786Z","steps":["trace[2135982164] 'agreement among raft nodes before linearized reading' (duration: 244.039998ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:43.314963Z","caller":"traceutil/trace.go:171","msg":"trace[81310541] transaction","detail":"{read_only:false; response_revision:5683; number_of_response:1; }","duration":"367.029219ms","start":"2026-09-09T01:18:42.947899Z","end":"2026-09-09T01:18:43.314929Z","steps":["trace[81310541] 'process raft request' (duration: 272.342723ms)","trace[81310541] 'compare' (duration: 94.504112ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:18:43.315158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:18:42.94785Z","time spent":"367.200473ms","remote":"127.0.0.1:40478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:18:43.368721Z","caller":"traceutil/trace.go:171","msg":"trace[1375208949] transaction","detail":"{read_only:false; response_revision:5684; number_of_response:1; }","duration":"328.646259ms","start":"2026-09-09T01:18:43.040055Z","end":"2026-09-09T01:18:43.368702Z","steps":["trace[1375208949] 'process raft request' (duration: 328.526596ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:18:43.368839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:18:43.040022Z","time spent":"328.756272ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:18:54.264838Z","caller":"traceutil/trace.go:171","msg":"trace[1087639058] transaction","detail":"{read_only:false; response_revision:5826; number_of_response:1; }","duration":"116.967695ms","start":"2026-09-09T01:18:54.147847Z","end":"2026-09-09T01:18:54.264815Z","steps":["trace[1087639058] 'process raft request' (duration: 116.923804ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:54.264972Z","caller":"traceutil/trace.go:171","msg":"trace[2042065492] transaction","detail":"{read_only:false; response_revision:5825; number_of_response:1; }","duration":"117.329942ms","start":"2026-09-09T01:18:54.147601Z","end":"2026-09-09T01:18:54.264931Z","steps":["trace[2042065492] 'process raft request' (duration: 117.118608ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:18:54.265018Z","caller":"traceutil/trace.go:171","msg":"trace[1039414655] transaction","detail":"{read_only:false; response_revision:5824; number_of_response:1; }","duration":"117.513026ms","start":"2026-09-09T01:18:54.147467Z","end":"2026-09-09T01:18:54.26498Z","steps":["trace[1039414655] 'process raft request' (duration: 117.137648ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:19:03.811927Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2982} {"level":"info","ts":"2026-09-09T01:19:03.887737Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2982,"took":"74.453098ms","hash":2342171860,"current-db-size-bytes":57061376,"current-db-size":"57 MB","current-db-size-in-use-bytes":32239616,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-09T01:19:03.887819Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2342171860,"revision":2982,"compact-revision":-1} {"level":"info","ts":"2026-09-09T01:24:03.819573Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5899} {"level":"info","ts":"2026-09-09T01:24:03.90801Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5899,"took":"86.717598ms","hash":551258524,"current-db-size-bytes":57061376,"current-db-size":"57 MB","current-db-size-in-use-bytes":26558464,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-09T01:24:03.908176Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":551258524,"revision":5899,"compact-revision":2982} {"level":"warn","ts":"2026-09-09T01:24:45.94172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.353625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:24:45.941758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.394657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.941816Z","caller":"traceutil/trace.go:171","msg":"trace[1630214635] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8490; }","duration":"230.497918ms","start":"2026-09-09T01:24:45.711299Z","end":"2026-09-09T01:24:45.941796Z","steps":["trace[1630214635] 'range keys from in-memory index tree' (duration: 230.276282ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.941811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.849185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.941835Z","caller":"traceutil/trace.go:171","msg":"trace[130578059] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8490; }","duration":"158.50776ms","start":"2026-09-09T01:24:45.783309Z","end":"2026-09-09T01:24:45.941816Z","steps":["trace[130578059] 'range keys from in-memory index tree' (duration: 158.331626ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.941862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.905365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:24:45.941856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.483059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:24:45.941925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.369538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:24:45.941926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.398707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.941941Z","caller":"traceutil/trace.go:171","msg":"trace[1080825997] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8490; }","duration":"158.588852ms","start":"2026-09-09T01:24:45.78333Z","end":"2026-09-09T01:24:45.941918Z","steps":["trace[1080825997] 'count revisions from in-memory index tree' (duration: 158.404587ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.356477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.942038Z","caller":"traceutil/trace.go:171","msg":"trace[77040716] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8490; }","duration":"174.571102ms","start":"2026-09-09T01:24:45.767446Z","end":"2026-09-09T01:24:45.942017Z","steps":["trace[77040716] 'range keys from in-memory index tree' (duration: 174.308506ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.942051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.789624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:24:45.942092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.053279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:24:45.942125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.763913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.94218Z","caller":"traceutil/trace.go:171","msg":"trace[219727065] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8490; }","duration":"313.842285ms","start":"2026-09-09T01:24:45.628327Z","end":"2026-09-09T01:24:45.942169Z","steps":["trace[219727065] 'range keys from in-memory index tree' (duration: 313.719032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.942189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.298054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:24:45.942221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.625633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:24:45.942234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:24:45.628314Z","time spent":"313.904116ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:500 "} {"level":"warn","ts":"2026-09-09T01:24:45.94224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.023271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:24:45.94226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.941984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:24:45.942285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.694795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:24:45.942273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.882245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.942307Z","caller":"traceutil/trace.go:171","msg":"trace[543928753] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8490; }","duration":"276.718175ms","start":"2026-09-09T01:24:45.665583Z","end":"2026-09-09T01:24:45.942301Z","steps":["trace[543928753] 'range keys from in-memory index tree' (duration: 276.628943ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:45.942303Z","caller":"traceutil/trace.go:171","msg":"trace[1722696495] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:8490; }","duration":"156.106544ms","start":"2026-09-09T01:24:45.786181Z","end":"2026-09-09T01:24:45.942288Z","steps":["trace[1722696495] 'range keys from in-memory index tree' (duration: 155.95477ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:45.942323Z","caller":"traceutil/trace.go:171","msg":"trace[2056764302] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8490; }","duration":"291.950796ms","start":"2026-09-09T01:24:45.650359Z","end":"2026-09-09T01:24:45.94231Z","steps":["trace[2056764302] 'range keys from in-memory index tree' (duration: 291.840064ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.942355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.446587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.942413Z","caller":"traceutil/trace.go:171","msg":"trace[170839031] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8490; }","duration":"136.520588ms","start":"2026-09-09T01:24:45.805881Z","end":"2026-09-09T01:24:45.942401Z","steps":["trace[170839031] 'range keys from in-memory index tree' (duration: 136.415977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.942441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.014695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.941927Z","caller":"traceutil/trace.go:171","msg":"trace[747322562] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8490; }","duration":"197.984527ms","start":"2026-09-09T01:24:45.743923Z","end":"2026-09-09T01:24:45.941908Z","steps":["trace[747322562] 'count revisions from in-memory index tree' (duration: 197.830523ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:45.94249Z","caller":"traceutil/trace.go:171","msg":"trace[1902272907] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8490; }","duration":"251.088356ms","start":"2026-09-09T01:24:45.691388Z","end":"2026-09-09T01:24:45.942476Z","steps":["trace[1902272907] 'range keys from in-memory index tree' (duration: 250.971824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.942488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.320275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:24:45.942535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.460018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.942541Z","caller":"traceutil/trace.go:171","msg":"trace[43657951] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8490; }","duration":"327.385476ms","start":"2026-09-09T01:24:45.615139Z","end":"2026-09-09T01:24:45.942524Z","steps":["trace[43657951] 'range keys from in-memory index tree' (duration: 327.283534ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:45.942573Z","caller":"traceutil/trace.go:171","msg":"trace[439183233] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8490; }","duration":"276.509379ms","start":"2026-09-09T01:24:45.666054Z","end":"2026-09-09T01:24:45.942563Z","steps":["trace[439183233] 'range keys from in-memory index tree' (duration: 276.433017ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.94259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:24:45.615135Z","time spent":"327.439167ms","remote":"127.0.0.1:46600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:10000 "} {"level":"warn","ts":"2026-09-09T01:24:45.942129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.242929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:24:45.942687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.493151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.94231Z","caller":"traceutil/trace.go:171","msg":"trace[543026195] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8490; }","duration":"304.999505ms","start":"2026-09-09T01:24:45.637297Z","end":"2026-09-09T01:24:45.942297Z","steps":["trace[543026195] 'range keys from in-memory index tree' (duration: 304.915994ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:45.942745Z","caller":"traceutil/trace.go:171","msg":"trace[1662823387] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8490; }","duration":"137.594274ms","start":"2026-09-09T01:24:45.805136Z","end":"2026-09-09T01:24:45.942731Z","steps":["trace[1662823387] 'count revisions from in-memory index tree' (duration: 137.44156ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.942546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.690966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:24:45.942797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:24:45.637293Z","time spent":"305.477177ms","remote":"127.0.0.1:46622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 "} {"level":"info","ts":"2026-09-09T01:24:45.942835Z","caller":"traceutil/trace.go:171","msg":"trace[1237986148] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8490; }","duration":"231.994844ms","start":"2026-09-09T01:24:45.710823Z","end":"2026-09-09T01:24:45.942818Z","steps":["trace[1237986148] 'count revisions from in-memory index tree' (duration: 231.641554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.942857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.700186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:24:45.942879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.852469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.942256Z","caller":"traceutil/trace.go:171","msg":"trace[328984364] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8490; }","duration":"276.672304ms","start":"2026-09-09T01:24:45.665574Z","end":"2026-09-09T01:24:45.942246Z","steps":["trace[328984364] 'count revisions from in-memory index tree' (duration: 276.578952ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:45.942933Z","caller":"traceutil/trace.go:171","msg":"trace[1919753318] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8490; }","duration":"314.91789ms","start":"2026-09-09T01:24:45.628002Z","end":"2026-09-09T01:24:45.94292Z","steps":["trace[1919753318] 'range keys from in-memory index tree' (duration: 314.827489ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.942941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.984102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:24:45.943005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:24:45.627999Z","time spent":"314.990482ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:10000 "} {"level":"info","ts":"2026-09-09T01:24:45.943026Z","caller":"traceutil/trace.go:171","msg":"trace[1026608745] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8490; }","duration":"315.091835ms","start":"2026-09-09T01:24:45.62792Z","end":"2026-09-09T01:24:45.943012Z","steps":["trace[1026608745] 'count revisions from in-memory index tree' (duration: 314.87848ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.943074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:24:45.627907Z","time spent":"315.151617ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true "} {"level":"warn","ts":"2026-09-09T01:24:45.942219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.110198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.943198Z","caller":"traceutil/trace.go:171","msg":"trace[1319158342] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8490; }","duration":"306.09388ms","start":"2026-09-09T01:24:45.637086Z","end":"2026-09-09T01:24:45.943179Z","steps":["trace[1319158342] 'range keys from in-memory index tree' (duration: 305.044407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.943266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:24:45.637075Z","time spent":"306.167343ms","remote":"127.0.0.1:46622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 "} {"level":"warn","ts":"2026-09-09T01:24:45.94218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.051067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.943329Z","caller":"traceutil/trace.go:171","msg":"trace[367650996] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8490; }","duration":"306.209234ms","start":"2026-09-09T01:24:45.637103Z","end":"2026-09-09T01:24:45.943312Z","steps":["trace[367650996] 'count revisions from in-memory index tree' (duration: 305.013956ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.942052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.941886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-09T01:24:45.943394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:24:45.637098Z","time spent":"306.276526ms","remote":"127.0.0.1:46622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-09-09T01:24:45.942578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.222975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.943457Z","caller":"traceutil/trace.go:171","msg":"trace[1423676312] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:8490; }","duration":"232.100716ms","start":"2026-09-09T01:24:45.71134Z","end":"2026-09-09T01:24:45.943441Z","steps":["trace[1423676312] 'range keys from in-memory index tree' (duration: 231.204464ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:45.943394Z","caller":"traceutil/trace.go:171","msg":"trace[107641218] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8490; }","duration":"328.294749ms","start":"2026-09-09T01:24:45.615084Z","end":"2026-09-09T01:24:45.943379Z","steps":["trace[107641218] 'count revisions from in-memory index tree' (duration: 326.914586ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.943534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:24:45.61508Z","time spent":"328.438242ms","remote":"127.0.0.1:46600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true "} {"level":"info","ts":"2026-09-09T01:24:45.942696Z","caller":"traceutil/trace.go:171","msg":"trace[129834545] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8490; }","duration":"291.819362ms","start":"2026-09-09T01:24:45.650861Z","end":"2026-09-09T01:24:45.94268Z","steps":["trace[129834545] 'range keys from in-memory index tree' (duration: 291.205108ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:45.941997Z","caller":"traceutil/trace.go:171","msg":"trace[1891768399] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8490; }","duration":"220.44906ms","start":"2026-09-09T01:24:45.721536Z","end":"2026-09-09T01:24:45.941985Z","steps":["trace[1891768399] 'range keys from in-memory index tree' (duration: 220.350018ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:45.941879Z","caller":"traceutil/trace.go:171","msg":"trace[1924637579] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8490; }","duration":"219.927928ms","start":"2026-09-09T01:24:45.721929Z","end":"2026-09-09T01:24:45.941857Z","steps":["trace[1924637579] 'range keys from in-memory index tree' (duration: 219.808644ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.942006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.558247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.94404Z","caller":"traceutil/trace.go:171","msg":"trace[276824752] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8490; }","duration":"293.587605ms","start":"2026-09-09T01:24:45.650433Z","end":"2026-09-09T01:24:45.944021Z","steps":["trace[276824752] 'count revisions from in-memory index tree' (duration: 291.494206ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.942052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.533312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.944203Z","caller":"traceutil/trace.go:171","msg":"trace[241986167] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:8490; }","duration":"222.656392ms","start":"2026-09-09T01:24:45.721505Z","end":"2026-09-09T01:24:45.944161Z","steps":["trace[241986167] 'count revisions from in-memory index tree' (duration: 220.465081ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:45.942075Z","caller":"traceutil/trace.go:171","msg":"trace[352276527] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8490; }","duration":"174.574681ms","start":"2026-09-09T01:24:45.76748Z","end":"2026-09-09T01:24:45.942055Z","steps":["trace[352276527] 'count revisions from in-memory index tree' (duration: 174.285975ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:45.942135Z","caller":"traceutil/trace.go:171","msg":"trace[1296849918] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:8490; }","duration":"327.122971ms","start":"2026-09-09T01:24:45.614997Z","end":"2026-09-09T01:24:45.94212Z","steps":["trace[1296849918] 'range keys from in-memory index tree' (duration: 326.916046ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.944347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:24:45.614979Z","time spent":"329.348213ms","remote":"127.0.0.1:46600","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" limit:500 "} {"level":"info","ts":"2026-09-09T01:24:45.942131Z","caller":"traceutil/trace.go:171","msg":"trace[1216635377] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:8490; }","duration":"173.887676ms","start":"2026-09-09T01:24:45.768223Z","end":"2026-09-09T01:24:45.942111Z","steps":["trace[1216635377] 'range keys from in-memory index tree' (duration: 173.744831ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.942192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.573003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qpttf\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-09-09T01:24:45.944459Z","caller":"traceutil/trace.go:171","msg":"trace[532639673] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qpttf; range_end:; response_count:1; response_revision:8490; }","duration":"281.847726ms","start":"2026-09-09T01:24:45.662592Z","end":"2026-09-09T01:24:45.94444Z","steps":["trace[532639673] 'range keys from in-memory index tree' (duration: 279.505651ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:45.942226Z","caller":"traceutil/trace.go:171","msg":"trace[1567326273] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8490; }","duration":"198.358866ms","start":"2026-09-09T01:24:45.743859Z","end":"2026-09-09T01:24:45.942218Z","steps":["trace[1567326273] 'range keys from in-memory index tree' (duration: 198.178922ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.94249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.308522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.944645Z","caller":"traceutil/trace.go:171","msg":"trace[720004481] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8490; }","duration":"253.485684ms","start":"2026-09-09T01:24:45.691143Z","end":"2026-09-09T01:24:45.944629Z","steps":["trace[720004481] 'count revisions from in-memory index tree' (duration: 251.272962ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.942733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.63834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.944773Z","caller":"traceutil/trace.go:171","msg":"trace[1107513697] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:8490; }","duration":"253.70787ms","start":"2026-09-09T01:24:45.691049Z","end":"2026-09-09T01:24:45.944757Z","steps":["trace[1107513697] 'range keys from in-memory index tree' (duration: 251.496257ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:45.942889Z","caller":"traceutil/trace.go:171","msg":"trace[940366068] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:8490; }","duration":"137.745097ms","start":"2026-09-09T01:24:45.805134Z","end":"2026-09-09T01:24:45.942879Z","steps":["trace[940366068] 'range keys from in-memory index tree' (duration: 137.621915ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:45.945048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.870167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:45.945168Z","caller":"traceutil/trace.go:171","msg":"trace[1062766438] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:8490; }","duration":"200.721923ms","start":"2026-09-09T01:24:45.744428Z","end":"2026-09-09T01:24:45.94515Z","steps":["trace[1062766438] 'range keys from in-memory index tree' (duration: 198.829567ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:46.162751Z","caller":"traceutil/trace.go:171","msg":"trace[1106563499] transaction","detail":"{read_only:false; response_revision:8491; number_of_response:1; }","duration":"142.554812ms","start":"2026-09-09T01:24:46.020165Z","end":"2026-09-09T01:24:46.16272Z","steps":["trace[1106563499] 'process raft request' (duration: 142.330207ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:57.868926Z","caller":"traceutil/trace.go:171","msg":"trace[55880180] linearizableReadLoop","detail":"{readStateIndex:8862; appliedIndex:8861; }","duration":"164.552834ms","start":"2026-09-09T01:24:57.704348Z","end":"2026-09-09T01:24:57.8689Z","steps":["trace[55880180] 'read index received' (duration: 164.37584ms)","trace[55880180] 'applied index is now lower than readState.Index' (duration: 175.694µs)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:24:57.869081Z","caller":"traceutil/trace.go:171","msg":"trace[1847258532] transaction","detail":"{read_only:false; response_revision:8553; number_of_response:1; }","duration":"223.804489ms","start":"2026-09-09T01:24:57.645238Z","end":"2026-09-09T01:24:57.869043Z","steps":["trace[1847258532] 'process raft request' (duration: 223.495732ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:24:57.869147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.778609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:24:57.869546Z","caller":"traceutil/trace.go:171","msg":"trace[101737393] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:8553; }","duration":"165.207419ms","start":"2026-09-09T01:24:57.704296Z","end":"2026-09-09T01:24:57.869503Z","steps":["trace[101737393] 'agreement among raft nodes before linearized reading' (duration: 164.786019ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:24:59.628545Z","caller":"traceutil/trace.go:171","msg":"trace[1378959483] transaction","detail":"{read_only:false; response_revision:8562; number_of_response:1; }","duration":"106.833325ms","start":"2026-09-09T01:24:59.521685Z","end":"2026-09-09T01:24:59.628518Z","steps":["trace[1378959483] 'process raft request' (duration: 106.61586ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:25:28.244348Z","caller":"traceutil/trace.go:171","msg":"trace[1247514301] linearizableReadLoop","detail":"{readStateIndex:9087; appliedIndex:9086; }","duration":"101.390906ms","start":"2026-09-09T01:25:28.142937Z","end":"2026-09-09T01:25:28.244328Z","steps":["trace[1247514301] 'read index received' (duration: 68.739761ms)","trace[1247514301] 'applied index is now lower than readState.Index' (duration: 32.650345ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:25:28.244412Z","caller":"traceutil/trace.go:171","msg":"trace[1172478872] transaction","detail":"{read_only:false; response_revision:8770; number_of_response:1; }","duration":"273.789645ms","start":"2026-09-09T01:25:27.970596Z","end":"2026-09-09T01:25:28.244386Z","steps":["trace[1172478872] 'process raft request' (duration: 241.076408ms)","trace[1172478872] 'compare' (duration: 32.556852ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:25:28.244529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.57524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:25:28.244566Z","caller":"traceutil/trace.go:171","msg":"trace[470370508] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8770; }","duration":"101.671762ms","start":"2026-09-09T01:25:28.142883Z","end":"2026-09-09T01:25:28.244555Z","steps":["trace[470370508] 'agreement among raft nodes before linearized reading' (duration: 101.56553ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:25:34.394844Z","caller":"traceutil/trace.go:171","msg":"trace[1029030221] transaction","detail":"{read_only:false; response_revision:8802; number_of_response:1; }","duration":"138.435314ms","start":"2026-09-09T01:25:34.256386Z","end":"2026-09-09T01:25:34.394821Z","steps":["trace[1029030221] 'process raft request' (duration: 138.24799ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:25:34.402432Z","caller":"traceutil/trace.go:171","msg":"trace[270421861] transaction","detail":"{read_only:false; response_revision:8803; number_of_response:1; }","duration":"142.86962ms","start":"2026-09-09T01:25:34.259528Z","end":"2026-09-09T01:25:34.402398Z","steps":["trace[270421861] 'process raft request' (duration: 142.602183ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:25:34.402698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.334902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qpttf\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-09-09T01:25:34.402771Z","caller":"traceutil/trace.go:171","msg":"trace[1892744579] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qpttf; range_end:; response_count:1; response_revision:8803; }","duration":"122.471306ms","start":"2026-09-09T01:25:34.280284Z","end":"2026-09-09T01:25:34.402755Z","steps":["trace[1892744579] 'agreement among raft nodes before linearized reading' (duration: 122.276951ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:25:34.402534Z","caller":"traceutil/trace.go:171","msg":"trace[1833519574] linearizableReadLoop","detail":"{readStateIndex:9121; appliedIndex:9119; }","duration":"121.946674ms","start":"2026-09-09T01:25:34.280321Z","end":"2026-09-09T01:25:34.402268Z","steps":["trace[1833519574] 'read index received' (duration: 114.376953ms)","trace[1833519574] 'applied index is now lower than readState.Index' (duration: 7.568571ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:25:34.670994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.627543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-09T01:25:34.671056Z","caller":"traceutil/trace.go:171","msg":"trace[478850278] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8803; }","duration":"112.743286ms","start":"2026-09-09T01:25:34.558297Z","end":"2026-09-09T01:25:34.67104Z","steps":["trace[478850278] 'range keys from in-memory index tree' (duration: 112.435577ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:25:34.671236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.281711ms","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-09-09T01:25:34.671299Z","caller":"traceutil/trace.go:171","msg":"trace[845055569] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8803; }","duration":"138.383093ms","start":"2026-09-09T01:25:34.532899Z","end":"2026-09-09T01:25:34.671282Z","steps":["trace[845055569] 'range keys from in-memory index tree' (duration: 138.078906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:25:34.671441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.916296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-09T01:25:34.671524Z","caller":"traceutil/trace.go:171","msg":"trace[1654473803] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8803; }","duration":"131.042389ms","start":"2026-09-09T01:25:34.540462Z","end":"2026-09-09T01:25:34.671504Z","steps":["trace[1654473803] 'range keys from in-memory index tree' (duration: 130.666631ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:25:41.741711Z","caller":"traceutil/trace.go:171","msg":"trace[1230978978] linearizableReadLoop","detail":"{readStateIndex:9204; appliedIndex:9203; }","duration":"149.694761ms","start":"2026-09-09T01:25:41.591995Z","end":"2026-09-09T01:25:41.74169Z","steps":["trace[1230978978] 'read index received' (duration: 149.451045ms)","trace[1230978978] 'applied index is now lower than readState.Index' (duration: 242.606µs)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:25:41.741955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.901986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-rkncs\" ","response":"range_response_count:1 size:2803"} {"level":"info","ts":"2026-09-09T01:25:41.742051Z","caller":"traceutil/trace.go:171","msg":"trace[1540833458] transaction","detail":"{read_only:false; response_revision:8882; number_of_response:1; }","duration":"151.821802ms","start":"2026-09-09T01:25:41.589937Z","end":"2026-09-09T01:25:41.741759Z","steps":["trace[1540833458] 'process raft request' (duration: 151.582656ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:25:41.742071Z","caller":"traceutil/trace.go:171","msg":"trace[413577870] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-rkncs; range_end:; response_count:1; response_revision:8882; }","duration":"150.0714ms","start":"2026-09-09T01:25:41.591986Z","end":"2026-09-09T01:25:41.742057Z","steps":["trace[413577870] 'agreement among raft nodes before linearized reading' (duration: 149.821574ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:25:41.741945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.927403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-09T01:25:41.742213Z","caller":"traceutil/trace.go:171","msg":"trace[116400524] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:8882; }","duration":"111.24047ms","start":"2026-09-09T01:25:41.630936Z","end":"2026-09-09T01:25:41.742176Z","steps":["trace[116400524] 'agreement among raft nodes before linearized reading' (duration: 110.895842ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:25:42.543228Z","caller":"traceutil/trace.go:171","msg":"trace[2139665156] transaction","detail":"{read_only:false; response_revision:8886; number_of_response:1; }","duration":"114.946247ms","start":"2026-09-09T01:25:42.428232Z","end":"2026-09-09T01:25:42.543178Z","steps":["trace[2139665156] 'process raft request' (duration: 114.698041ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:25:55.661673Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002472.wal"} {"level":"info","ts":"2026-09-09T01:27:03.143101Z","caller":"traceutil/trace.go:171","msg":"trace[1031706297] transaction","detail":"{read_only:false; response_revision:9654; number_of_response:1; }","duration":"177.438258ms","start":"2026-09-09T01:27:02.965604Z","end":"2026-09-09T01:27:03.143042Z","steps":["trace[1031706297] 'process raft request' (duration: 177.17018ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:27:03.1537Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f50d212ec16cc59","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T01:27:03.22172Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-09T01:27:03.221932Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-09T01:29:03.826112Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8067} {"level":"info","ts":"2026-09-09T01:29:03.894575Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8067,"took":"66.370351ms","hash":3597565712,"current-db-size-bytes":57061376,"current-db-size":"57 MB","current-db-size-in-use-bytes":28696576,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-09T01:29:03.894651Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3597565712,"revision":8067,"compact-revision":5899} {"level":"info","ts":"2026-09-09T01:29:20.042402Z","caller":"traceutil/trace.go:171","msg":"trace[947479535] transaction","detail":"{read_only:false; response_revision:10693; number_of_response:1; }","duration":"153.203239ms","start":"2026-09-09T01:29:19.889012Z","end":"2026-09-09T01:29:20.042215Z","steps":["trace[947479535] 'process raft request' (duration: 110.908447ms)","trace[947479535] 'compare' (duration: 41.685288ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:29:20.042636Z","caller":"traceutil/trace.go:171","msg":"trace[1392703485] linearizableReadLoop","detail":"{readStateIndex:11073; appliedIndex:11071; }","duration":"146.075382ms","start":"2026-09-09T01:29:19.896545Z","end":"2026-09-09T01:29:20.042621Z","steps":["trace[1392703485] 'read index received' (duration: 25.360259ms)","trace[1392703485] 'applied index is now lower than readState.Index' (duration: 120.713363ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:29:20.043026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.471772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-09-09T01:29:20.043089Z","caller":"traceutil/trace.go:171","msg":"trace[1487614257] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:10694; }","duration":"146.578994ms","start":"2026-09-09T01:29:19.896498Z","end":"2026-09-09T01:29:20.043077Z","steps":["trace[1487614257] 'agreement among raft nodes before linearized reading' (duration: 146.41789ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:29:20.043368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.487717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:29:20.043401Z","caller":"traceutil/trace.go:171","msg":"trace[1711433151] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:10694; }","duration":"107.559939ms","start":"2026-09-09T01:29:19.935834Z","end":"2026-09-09T01:29:20.043394Z","steps":["trace[1711433151] 'agreement among raft nodes before linearized reading' (duration: 107.509988ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:29:20.043433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.923589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-09-09T01:29:20.043533Z","caller":"traceutil/trace.go:171","msg":"trace[1444808941] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:10694; }","duration":"115.067522ms","start":"2026-09-09T01:29:19.928443Z","end":"2026-09-09T01:29:20.043511Z","steps":["trace[1444808941] 'agreement among raft nodes before linearized reading' (duration: 114.813677ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:34:03.838294Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10525} {"level":"info","ts":"2026-09-09T01:34:03.937405Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10525,"took":"95.91223ms","hash":3569563899,"current-db-size-bytes":57061376,"current-db-size":"57 MB","current-db-size-in-use-bytes":27123712,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-09T01:34:03.937493Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3569563899,"revision":10525,"compact-revision":8067} {"level":"info","ts":"2026-09-09T01:37:44.246182Z","caller":"traceutil/trace.go:171","msg":"trace[918551141] transaction","detail":"{read_only:false; response_revision:14240; number_of_response:1; }","duration":"155.596286ms","start":"2026-09-09T01:37:44.090535Z","end":"2026-09-09T01:37:44.246132Z","steps":["trace[918551141] 'process raft request' (duration: 155.441212ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:37:44.363461Z","caller":"traceutil/trace.go:171","msg":"trace[190663182] linearizableReadLoop","detail":"{readStateIndex:14738; appliedIndex:14736; }","duration":"184.911858ms","start":"2026-09-09T01:37:44.178515Z","end":"2026-09-09T01:37:44.363427Z","steps":["trace[190663182] 'read index received' (duration: 67.451569ms)","trace[190663182] 'applied index is now lower than readState.Index' (duration: 117.458969ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:37:44.363626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.074183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-09T01:37:44.363681Z","caller":"traceutil/trace.go:171","msg":"trace[952487237] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:14241; }","duration":"185.193016ms","start":"2026-09-09T01:37:44.17847Z","end":"2026-09-09T01:37:44.363663Z","steps":["trace[952487237] 'agreement among raft nodes before linearized reading' (duration: 185.074272ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:37:44.363712Z","caller":"traceutil/trace.go:171","msg":"trace[401386534] transaction","detail":"{read_only:false; response_revision:14241; number_of_response:1; }","duration":"268.376748ms","start":"2026-09-09T01:37:44.095302Z","end":"2026-09-09T01:37:44.363679Z","steps":["trace[401386534] 'process raft request' (duration: 266.33795ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:37:57.493286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.306655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-09T01:37:57.493393Z","caller":"traceutil/trace.go:171","msg":"trace[118111997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14324; }","duration":"132.48916ms","start":"2026-09-09T01:37:57.360887Z","end":"2026-09-09T01:37:57.493376Z","steps":["trace[118111997] 'range keys from in-memory index tree' (duration: 132.094819ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:38:02.418807Z","caller":"traceutil/trace.go:171","msg":"trace[1716852130] transaction","detail":"{read_only:false; response_revision:14355; number_of_response:1; }","duration":"104.259914ms","start":"2026-09-09T01:38:02.314507Z","end":"2026-09-09T01:38:02.418767Z","steps":["trace[1716852130] 'process raft request' (duration: 103.886316ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:38:03.330375Z","caller":"traceutil/trace.go:171","msg":"trace[291029941] transaction","detail":"{read_only:false; response_revision:14357; number_of_response:1; }","duration":"300.935225ms","start":"2026-09-09T01:38:03.029411Z","end":"2026-09-09T01:38:03.330346Z","steps":["trace[291029941] 'process raft request' (duration: 300.71572ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:38:03.330542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:38:03.029374Z","time spent":"301.080779ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:38:03.503192Z","caller":"traceutil/trace.go:171","msg":"trace[1715126071] linearizableReadLoop","detail":"{readStateIndex:14861; appliedIndex:14859; }","duration":"333.597603ms","start":"2026-09-09T01:38:03.169563Z","end":"2026-09-09T01:38:03.503161Z","steps":["trace[1715126071] 'read index received' (duration: 160.591913ms)","trace[1715126071] 'applied index is now lower than readState.Index' (duration: 173.00457ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:38:03.503493Z","caller":"traceutil/trace.go:171","msg":"trace[2075507710] transaction","detail":"{read_only:false; response_revision:14358; number_of_response:1; }","duration":"470.969857ms","start":"2026-09-09T01:38:03.032354Z","end":"2026-09-09T01:38:03.503324Z","steps":["trace[2075507710] 'process raft request' (duration: 461.236271ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:38:03.503586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.945062ms","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":"warn","ts":"2026-09-09T01:38:03.503669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:38:03.032336Z","time spent":"471.248544ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:38:03.503721Z","caller":"traceutil/trace.go:171","msg":"trace[1773096871] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14358; }","duration":"334.138647ms","start":"2026-09-09T01:38:03.169527Z","end":"2026-09-09T01:38:03.503666Z","steps":["trace[1773096871] 'agreement among raft nodes before linearized reading' (duration: 333.840299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:38:03.503691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.672373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qpttf\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-09-09T01:38:03.503797Z","caller":"traceutil/trace.go:171","msg":"trace[423700688] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qpttf; range_end:; response_count:1; response_revision:14358; }","duration":"286.855087ms","start":"2026-09-09T01:38:03.216918Z","end":"2026-09-09T01:38:03.503773Z","steps":["trace[423700688] 'agreement among raft nodes before linearized reading' (duration: 286.673413ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:38:03.503823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:38:03.169513Z","time spent":"334.254439ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":576,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-09T01:39:03.847231Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12785} {"level":"info","ts":"2026-09-09T01:39:03.930567Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12785,"took":"79.419386ms","hash":4291543165,"current-db-size-bytes":57061376,"current-db-size":"57 MB","current-db-size-in-use-bytes":29777920,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-09T01:39:03.930638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4291543165,"revision":12785,"compact-revision":10525} {"level":"info","ts":"2026-09-09T01:39:14.903382Z","caller":"traceutil/trace.go:171","msg":"trace[250844946] transaction","detail":"{read_only:false; response_revision:15279; number_of_response:1; }","duration":"118.933045ms","start":"2026-09-09T01:39:14.784409Z","end":"2026-09-09T01:39:14.903342Z","steps":["trace[250844946] 'process raft request' (duration: 79.809645ms)","trace[250844946] 'compare' (duration: 38.880574ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:39:49.16706Z","caller":"traceutil/trace.go:171","msg":"trace[2114525033] transaction","detail":"{read_only:false; response_revision:15460; number_of_response:1; }","duration":"122.695425ms","start":"2026-09-09T01:39:49.044335Z","end":"2026-09-09T01:39:49.167031Z","steps":["trace[2114525033] 'process raft request' (duration: 122.493881ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:39:49.450337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.985603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-hmldf\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-09-09T01:39:49.450434Z","caller":"traceutil/trace.go:171","msg":"trace[122831005] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-hmldf; range_end:; response_count:1; response_revision:15460; }","duration":"102.124416ms","start":"2026-09-09T01:39:49.348284Z","end":"2026-09-09T01:39:49.450409Z","steps":["trace[122831005] 'range keys from in-memory index tree' (duration: 101.812038ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:44:03.853841Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15184} {"level":"info","ts":"2026-09-09T01:44:03.962826Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15184,"took":"103.696836ms","hash":2660117765,"current-db-size-bytes":57061376,"current-db-size":"57 MB","current-db-size-in-use-bytes":31223808,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-09T01:44:03.962918Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2660117765,"revision":15184,"compact-revision":12785} {"level":"info","ts":"2026-09-09T01:45:33.892975Z","caller":"traceutil/trace.go:171","msg":"trace[958150345] transaction","detail":"{read_only:false; response_revision:18024; number_of_response:1; }","duration":"132.067185ms","start":"2026-09-09T01:45:33.760882Z","end":"2026-09-09T01:45:33.892949Z","steps":["trace[958150345] 'process raft request' (duration: 131.837319ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:45:34.023565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.634598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-09T01:45:34.023689Z","caller":"traceutil/trace.go:171","msg":"trace[1327335339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18024; }","duration":"113.807601ms","start":"2026-09-09T01:45:33.909853Z","end":"2026-09-09T01:45:34.023661Z","steps":["trace[1327335339] 'range keys from in-memory index tree' (duration: 113.442512ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:45:34.361661Z","caller":"traceutil/trace.go:171","msg":"trace[1389274741] transaction","detail":"{read_only:false; response_revision:18025; number_of_response:1; }","duration":"323.862334ms","start":"2026-09-09T01:45:34.037774Z","end":"2026-09-09T01:45:34.361636Z","steps":["trace[1389274741] 'process raft request' (duration: 323.607598ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:45:34.361924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:45:34.03775Z","time spent":"324.055328ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:45:34.600372Z","caller":"traceutil/trace.go:171","msg":"trace[1188300864] linearizableReadLoop","detail":"{readStateIndex:18653; appliedIndex:18651; }","duration":"282.31493ms","start":"2026-09-09T01:45:34.318008Z","end":"2026-09-09T01:45:34.600323Z","steps":["trace[1188300864] 'read index received' (duration: 43.309705ms)","trace[1188300864] 'applied index is now lower than readState.Index' (duration: 239.002995ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:45:34.600391Z","caller":"traceutil/trace.go:171","msg":"trace[2047065172] transaction","detail":"{read_only:false; response_revision:18026; number_of_response:1; }","duration":"345.880916ms","start":"2026-09-09T01:45:34.254475Z","end":"2026-09-09T01:45:34.600356Z","steps":["trace[2047065172] 'process raft request' (duration: 324.174892ms)","trace[2047065172] 'compare' (duration: 21.225862ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:45:34.600612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:45:34.254441Z","time spent":"346.078621ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T01:45:34.600624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.605067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-09T01:45:34.600714Z","caller":"traceutil/trace.go:171","msg":"trace[1131870104] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18026; }","duration":"282.737371ms","start":"2026-09-09T01:45:34.317952Z","end":"2026-09-09T01:45:34.600689Z","steps":["trace[1131870104] 'agreement among raft nodes before linearized reading' (duration: 282.495995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:45:34.600947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.893969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-09T01:45:34.601009Z","caller":"traceutil/trace.go:171","msg":"trace[892643456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18026; }","duration":"265.991201ms","start":"2026-09-09T01:45:34.335003Z","end":"2026-09-09T01:45:34.600994Z","steps":["trace[892643456] 'agreement among raft nodes before linearized reading' (duration: 265.552162ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:48:01.96229Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5f50d212ec16cc59","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-09T01:48:01.974718Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-09T01:48:01.974872Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-09T01:49:03.86388Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17388} {"level":"info","ts":"2026-09-09T01:49:03.974073Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17388,"took":"105.764091ms","hash":2443786056,"current-db-size-bytes":57061376,"current-db-size":"57 MB","current-db-size-in-use-bytes":34709504,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-09T01:49:03.974143Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2443786056,"revision":17388,"compact-revision":15184} {"level":"info","ts":"2026-09-09T01:50:52.040013Z","caller":"traceutil/trace.go:171","msg":"trace[1193712103] transaction","detail":"{read_only:false; response_revision:20556; number_of_response:1; }","duration":"135.95082ms","start":"2026-09-09T01:50:51.904034Z","end":"2026-09-09T01:50:52.039985Z","steps":["trace[1193712103] 'process raft request' (duration: 135.608971ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:50:52.0413Z","caller":"traceutil/trace.go:171","msg":"trace[2007407889] transaction","detail":"{read_only:false; response_revision:20557; number_of_response:1; }","duration":"126.848078ms","start":"2026-09-09T01:50:51.914432Z","end":"2026-09-09T01:50:52.04128Z","steps":["trace[2007407889] 'process raft request' (duration: 126.743185ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:51:08.252117Z","caller":"traceutil/trace.go:171","msg":"trace[945884610] transaction","detail":"{read_only:false; response_revision:20638; number_of_response:1; }","duration":"148.307685ms","start":"2026-09-09T01:51:08.103732Z","end":"2026-09-09T01:51:08.252039Z","steps":["trace[945884610] 'process raft request' (duration: 148.109191ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:51:08.577775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.116065ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-09T01:51:08.577875Z","caller":"traceutil/trace.go:171","msg":"trace[516241994] linearizableReadLoop","detail":"{readStateIndex:21351; appliedIndex:21349; }","duration":"351.382166ms","start":"2026-09-09T01:51:08.226477Z","end":"2026-09-09T01:51:08.577859Z","steps":["trace[516241994] 'read index received' (duration: 25.332109ms)","trace[516241994] 'applied index is now lower than readState.Index' (duration: 326.049127ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:51:08.578066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.587ms","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-09-09T01:51:08.578099Z","caller":"traceutil/trace.go:171","msg":"trace[257328645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20639; }","duration":"351.661902ms","start":"2026-09-09T01:51:08.226427Z","end":"2026-09-09T01:51:08.578089Z","steps":["trace[257328645] 'agreement among raft nodes before linearized reading' (duration: 351.474938ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:51:08.578127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:51:08.226092Z","time spent":"352.02734ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-09T01:51:08.578233Z","caller":"traceutil/trace.go:171","msg":"trace[1006829521] transaction","detail":"{read_only:false; response_revision:20639; number_of_response:1; }","duration":"393.861942ms","start":"2026-09-09T01:51:08.18432Z","end":"2026-09-09T01:51:08.578182Z","steps":["trace[1006829521] 'process raft request' (duration: 247.249126ms)","trace[1006829521] 'compare' (duration: 145.924311ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:51:08.578416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:51:08.184288Z","time spent":"394.039337ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:51:09.003936Z","caller":"traceutil/trace.go:171","msg":"trace[2077903706] transaction","detail":"{read_only:false; response_revision:20645; number_of_response:1; }","duration":"232.39361ms","start":"2026-09-09T01:51:08.771514Z","end":"2026-09-09T01:51:09.003908Z","steps":["trace[2077903706] 'process raft request' (duration: 232.214276ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:51:09.12891Z","caller":"traceutil/trace.go:171","msg":"trace[941412624] transaction","detail":"{read_only:false; response_revision:20646; number_of_response:1; }","duration":"354.991259ms","start":"2026-09-09T01:51:08.773873Z","end":"2026-09-09T01:51:09.128864Z","steps":["trace[941412624] 'process raft request' (duration: 335.22408ms)","trace[941412624] 'compare' (duration: 19.472732ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T01:51:09.128942Z","caller":"traceutil/trace.go:171","msg":"trace[237306787] linearizableReadLoop","detail":"{readStateIndex:21358; appliedIndex:21356; }","duration":"281.890661ms","start":"2026-09-09T01:51:08.847008Z","end":"2026-09-09T01:51:09.128898Z","steps":["trace[237306787] 'read index received' (duration: 156.701591ms)","trace[237306787] 'applied index is now lower than readState.Index' (duration: 125.18488ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:51:09.129067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.045554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-09T01:51:09.129098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:51:08.77385Z","time spent":"355.153703ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:51:09.129136Z","caller":"traceutil/trace.go:171","msg":"trace[1518441473] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20647; }","duration":"282.157937ms","start":"2026-09-09T01:51:08.846962Z","end":"2026-09-09T01:51:09.12912Z","steps":["trace[1518441473] 'agreement among raft nodes before linearized reading' (duration: 282.041204ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:54:03.871126Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19767} {"level":"info","ts":"2026-09-09T01:54:03.9929Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19767,"took":"116.651545ms","hash":2962285755,"current-db-size-bytes":57061376,"current-db-size":"57 MB","current-db-size-in-use-bytes":29745152,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-09T01:54:03.992975Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2962285755,"revision":19767,"compact-revision":17388} {"level":"info","ts":"2026-09-09T01:55:03.881984Z","caller":"traceutil/trace.go:171","msg":"trace[570455191] transaction","detail":"{read_only:false; response_revision:23063; number_of_response:1; }","duration":"373.807831ms","start":"2026-09-09T01:55:03.508112Z","end":"2026-09-09T01:55:03.88192Z","steps":["trace[570455191] 'process raft request' (duration: 360.641525ms)","trace[570455191] 'compare' (duration: 11.845885ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:55:03.882499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:55:03.508075Z","time spent":"374.042635ms","remote":"127.0.0.1:40454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:55:03.893695Z","caller":"traceutil/trace.go:171","msg":"trace[902339060] transaction","detail":"{read_only:false; response_revision:23064; number_of_response:1; }","duration":"385.128783ms","start":"2026-09-09T01:55:03.508546Z","end":"2026-09-09T01:55:03.893675Z","steps":["trace[902339060] 'process raft request' (duration: 384.875867ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:55:03.893803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:55:03.50852Z","time spent":"385.230095ms","remote":"127.0.0.1:40550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1525,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:55:03.894201Z","caller":"traceutil/trace.go:171","msg":"trace[1061604333] transaction","detail":"{read_only:false; response_revision:23065; number_of_response:1; }","duration":"377.683401ms","start":"2026-09-09T01:55:03.516498Z","end":"2026-09-09T01:55:03.894181Z","steps":["trace[1061604333] 'process raft request' (duration: 377.120097ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:55:03.894249Z","caller":"traceutil/trace.go:171","msg":"trace[1904501797] linearizableReadLoop","detail":"{readStateIndex:23854; appliedIndex:23849; }","duration":"266.510602ms","start":"2026-09-09T01:55:03.627702Z","end":"2026-09-09T01:55:03.894212Z","steps":["trace[1904501797] 'read index received' (duration: 241.065201ms)","trace[1904501797] 'applied index is now lower than readState.Index' (duration: 25.443841ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T01:55:03.894313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:55:03.516409Z","time spent":"377.847244ms","remote":"127.0.0.1:40478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4606,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:55:03.894378Z","caller":"traceutil/trace.go:171","msg":"trace[267338699] transaction","detail":"{read_only:false; response_revision:23066; number_of_response:1; }","duration":"328.167642ms","start":"2026-09-09T01:55:03.566167Z","end":"2026-09-09T01:55:03.894335Z","steps":["trace[267338699] 'process raft request' (duration: 327.770912ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T01:55:03.894516Z","caller":"traceutil/trace.go:171","msg":"trace[692163722] transaction","detail":"{read_only:false; response_revision:23067; number_of_response:1; }","duration":"314.346542ms","start":"2026-09-09T01:55:03.580132Z","end":"2026-09-09T01:55:03.894478Z","steps":["trace[692163722] 'process raft request' (duration: 313.993284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:55:03.894564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.84107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-09T01:55:03.894571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:55:03.566136Z","time spent":"328.332876ms","remote":"127.0.0.1:40538","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-09-09T01:55:03.894621Z","caller":"traceutil/trace.go:171","msg":"trace[96823790] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:23067; }","duration":"266.975973ms","start":"2026-09-09T01:55:03.627629Z","end":"2026-09-09T01:55:03.894605Z","steps":["trace[96823790] 'agreement among raft nodes before linearized reading' (duration: 266.732268ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T01:55:03.894656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T01:55:03.580071Z","time spent":"314.506135ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T01:59:03.878676Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21991} {"level":"info","ts":"2026-09-09T01:59:03.99237Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21991,"took":"109.674311ms","hash":4096927738,"current-db-size-bytes":57061376,"current-db-size":"57 MB","current-db-size-in-use-bytes":42516480,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-09T01:59:03.992483Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4096927738,"revision":21991,"compact-revision":19767} {"level":"info","ts":"2026-09-09T02:02:05.956939Z","caller":"traceutil/trace.go:171","msg":"trace[1336806609] linearizableReadLoop","detail":"{readStateIndex:27472; appliedIndex:27471; }","duration":"180.535844ms","start":"2026-09-09T02:02:05.776375Z","end":"2026-09-09T02:02:05.956911Z","steps":["trace[1336806609] 'read index received' (duration: 153.363405ms)","trace[1336806609] 'applied index is now lower than readState.Index' (duration: 27.171049ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T02:02:05.956989Z","caller":"traceutil/trace.go:171","msg":"trace[2016315014] transaction","detail":"{read_only:false; response_revision:26591; number_of_response:1; }","duration":"208.94276ms","start":"2026-09-09T02:02:05.748012Z","end":"2026-09-09T02:02:05.956954Z","steps":["trace[2016315014] 'process raft request' (duration: 181.706ms)","trace[2016315014] 'compare' (duration: 26.965764ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T02:02:05.957159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.675016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:replication-controller\" ","response":"range_response_count:1 size:764"} {"level":"info","ts":"2026-09-09T02:02:05.957202Z","caller":"traceutil/trace.go:171","msg":"trace[1106824268] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:replication-controller; range_end:; response_count:1; response_revision:26591; }","duration":"151.784749ms","start":"2026-09-09T02:02:05.805404Z","end":"2026-09-09T02:02:05.957189Z","steps":["trace[1106824268] 'agreement among raft nodes before linearized reading' (duration: 151.659306ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T02:02:05.957175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.746038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T02:02:05.957279Z","caller":"traceutil/trace.go:171","msg":"trace[1968739287] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:26591; }","duration":"180.884691ms","start":"2026-09-09T02:02:05.776367Z","end":"2026-09-09T02:02:05.957251Z","steps":["trace[1968739287] 'agreement among raft nodes before linearized reading' (duration: 180.686937ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T02:02:06.834011Z","caller":"traceutil/trace.go:171","msg":"trace[1903472265] transaction","detail":"{read_only:false; response_revision:26600; number_of_response:1; }","duration":"105.588385ms","start":"2026-09-09T02:02:06.728383Z","end":"2026-09-09T02:02:06.833971Z","steps":["trace[1903472265] 'process raft request' (duration: 75.672575ms)","trace[1903472265] 'compare' (duration: 29.529681ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T02:02:11.922879Z","caller":"traceutil/trace.go:171","msg":"trace[1788020635] transaction","detail":"{read_only:false; response_revision:26632; number_of_response:1; }","duration":"221.04647ms","start":"2026-09-09T02:02:11.701803Z","end":"2026-09-09T02:02:11.92285Z","steps":["trace[1788020635] 'process raft request' (duration: 220.863967ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T02:02:12.010059Z","caller":"traceutil/trace.go:171","msg":"trace[1649156504] transaction","detail":"{read_only:false; response_revision:26634; number_of_response:1; }","duration":"303.934308ms","start":"2026-09-09T02:02:11.70609Z","end":"2026-09-09T02:02:12.010025Z","steps":["trace[1649156504] 'process raft request' (duration: 303.815195ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T02:02:12.010078Z","caller":"traceutil/trace.go:171","msg":"trace[859895724] transaction","detail":"{read_only:false; response_revision:26633; number_of_response:1; }","duration":"304.748275ms","start":"2026-09-09T02:02:11.705294Z","end":"2026-09-09T02:02:12.010042Z","steps":["trace[859895724] 'process raft request' (duration: 304.367867ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T02:02:12.010299Z","caller":"traceutil/trace.go:171","msg":"trace[2036995829] transaction","detail":"{read_only:false; response_revision:26635; number_of_response:1; }","duration":"303.956487ms","start":"2026-09-09T02:02:11.706331Z","end":"2026-09-09T02:02:12.010288Z","steps":["trace[2036995829] 'process raft request' (duration: 303.640151ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T02:02:12.010269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T02:02:11.706066Z","time spent":"304.104241ms","remote":"127.0.0.1:40538","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-09-09T02:02:12.01037Z","caller":"traceutil/trace.go:171","msg":"trace[1334875440] linearizableReadLoop","detail":"{readStateIndex:27518; appliedIndex:27513; }","duration":"183.402138ms","start":"2026-09-09T02:02:11.826942Z","end":"2026-09-09T02:02:12.010344Z","steps":["trace[1334875440] 'read index received' (duration: 95.750715ms)","trace[1334875440] 'applied index is now lower than readState.Index' (duration: 87.650103ms)"],"step_count":2} {"level":"warn","ts":"2026-09-09T02:02:12.010422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T02:02:11.706319Z","time spent":"304.02205ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-09T02:02:12.010407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-09T02:02:11.705267Z","time spent":"305.054691ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-09T02:02:12.010465Z","caller":"traceutil/trace.go:171","msg":"trace[878939495] transaction","detail":"{read_only:false; response_revision:26636; number_of_response:1; }","duration":"216.323026ms","start":"2026-09-09T02:02:11.794111Z","end":"2026-09-09T02:02:12.010435Z","steps":["trace[878939495] 'process raft request' (duration: 216.09985ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T02:02:12.010492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.538391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-09T02:02:12.01058Z","caller":"traceutil/trace.go:171","msg":"trace[1296531396] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:26636; }","duration":"183.677644ms","start":"2026-09-09T02:02:11.826883Z","end":"2026-09-09T02:02:12.010561Z","steps":["trace[1296531396] 'agreement among raft nodes before linearized reading' (duration: 183.547962ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T02:02:12.263688Z","caller":"traceutil/trace.go:171","msg":"trace[891791013] linearizableReadLoop","detail":"{readStateIndex:27519; appliedIndex:27518; }","duration":"197.719698ms","start":"2026-09-09T02:02:12.065947Z","end":"2026-09-09T02:02:12.263667Z","steps":["trace[891791013] 'read index received' (duration: 195.889598ms)","trace[891791013] 'applied index is now lower than readState.Index' (duration: 1.82935ms)"],"step_count":2} {"level":"info","ts":"2026-09-09T02:02:12.263731Z","caller":"traceutil/trace.go:171","msg":"trace[1265085852] transaction","detail":"{read_only:false; response_revision:26637; number_of_response:1; }","duration":"280.088283ms","start":"2026-09-09T02:02:11.983606Z","end":"2026-09-09T02:02:12.263695Z","steps":["trace[1265085852] 'process raft request' (duration: 278.248742ms)"],"step_count":1} {"level":"warn","ts":"2026-09-09T02:02:12.26403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.072727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:19 size:8170"} {"level":"info","ts":"2026-09-09T02:02:12.26407Z","caller":"traceutil/trace.go:171","msg":"trace[758143840] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:19; response_revision:26637; }","duration":"198.168739ms","start":"2026-09-09T02:02:12.06589Z","end":"2026-09-09T02:02:12.264059Z","steps":["trace[758143840] 'agreement among raft nodes before linearized reading' (duration: 197.880562ms)"],"step_count":1} {"level":"info","ts":"2026-09-09T02:02:23.976695Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006bd7.wal"} {"level":"info","ts":"2026-09-09T02:04:03.88603Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25166} {"level":"info","ts":"2026-09-09T02:04:04.034806Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25166,"took":"145.115444ms","hash":1677176445,"current-db-size-bytes":57061376,"current-db-size":"57 MB","current-db-size-in-use-bytes":31080448,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-09T02:04:04.034866Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1677176445,"revision":25166,"compact-revision":21991}