{"level":"warn","ts":"2026-07-02T05:09:18.509137Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T05:09:18.509218Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-07-02T05:09:18.509294Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T05:09:18.509309Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-07-02T05:09:18.509326Z","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-07-02T05:09:18.509876Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-07-02T05:09:18.509949Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-07-02T05:09:18.51336Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.171359ms"} {"level":"info","ts":"2026-07-02T05:09:18.519054Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-07-02T05:09:18.519126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T05:09:18.519145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-07-02T05:09:18.519157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T05:09:18.519166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-07-02T05:09:18.519201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-07-02T05:09:18.525842Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T05:09:18.527845Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T05:09:18.529124Z","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-07-02T05:09:18.531809Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T05:09:18.532364Z","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-07-02T05:09:18.532523Z","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-07-02T05:09:18.532548Z","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-07-02T05:09:18.532496Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T05:09:18.535119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-07-02T05:09:18.535428Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-07-02T05:09:18.538539Z","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-07-02T05:09:18.538633Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-07-02T05:09:18.538722Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-07-02T05:09:18.538791Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T05:09:18.538836Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T05:09:19.319472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T05:09:19.319557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T05:09:19.319604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-07-02T05:09:19.319626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-07-02T05:09:19.319637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-07-02T05:09:19.319653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-07-02T05:09:19.319673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-07-02T05:09:19.320935Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T05:09:19.320948Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:09:19.321111Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:09:19.321189Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T05:09:19.322096Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:09:19.322284Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:09:19.322333Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T05:09:19.324177Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T05:09:19.325188Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-07-02T05:09:19.329819Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T05:09:19.329872Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T05:10:56.746469Z","caller":"traceutil/trace.go:171","msg":"trace[1213506861] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"118.559078ms","start":"2026-07-02T05:10:56.627884Z","end":"2026-07-02T05:10:56.746443Z","steps":["trace[1213506861] 'process raft request' (duration: 118.411536ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:57.516118Z","caller":"traceutil/trace.go:171","msg":"trace[369383783] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"183.989273ms","start":"2026-07-02T05:10:57.332106Z","end":"2026-07-02T05:10:57.516095Z","steps":["trace[369383783] 'read index received' (duration: 182.873942ms)","trace[369383783] 'applied index is now lower than readState.Index' (duration: 1.095251ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:10:57.516547Z","caller":"traceutil/trace.go:171","msg":"trace[2103229394] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"367.04607ms","start":"2026-07-02T05:10:57.149094Z","end":"2026-07-02T05:10:57.51614Z","steps":["trace[2103229394] 'process raft request' (duration: 366.187314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:10:57.517586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:10:57.149064Z","time spent":"367.745433ms","remote":"127.0.0.1:57220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:10:57.518301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.453402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-89klp\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-02T05:10:57.518411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.301298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T05:10:57.518442Z","caller":"traceutil/trace.go:171","msg":"trace[131723183] range","detail":"{range_begin:/registry/pods/kube-system/cilium-89klp; range_end:; response_count:1; response_revision:712; }","duration":"132.665396ms","start":"2026-07-02T05:10:57.385748Z","end":"2026-07-02T05:10:57.518414Z","steps":["trace[131723183] 'agreement among raft nodes before linearized reading' (duration: 132.363471ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:10:57.518506Z","caller":"traceutil/trace.go:171","msg":"trace[758427389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:712; }","duration":"186.410569ms","start":"2026-07-02T05:10:57.332049Z","end":"2026-07-02T05:10:57.51846Z","steps":["trace[758427389] 'agreement among raft nodes before linearized reading' (duration: 184.267028ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:12:26.018194Z","caller":"traceutil/trace.go:171","msg":"trace[589665971] linearizableReadLoop","detail":"{readStateIndex:2009; appliedIndex:2008; }","duration":"131.66126ms","start":"2026-07-02T05:12:25.88651Z","end":"2026-07-02T05:12:26.018171Z","steps":["trace[589665971] 'read index received' (duration: 131.519927ms)","trace[589665971] 'applied index is now lower than readState.Index' (duration: 140.253µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:12:26.018235Z","caller":"traceutil/trace.go:171","msg":"trace[1828217621] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"150.454008ms","start":"2026-07-02T05:12:25.867755Z","end":"2026-07-02T05:12:26.018209Z","steps":["trace[1828217621] 'process raft request' (duration: 150.280575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:12:26.018365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.832564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:12:26.018427Z","caller":"traceutil/trace.go:171","msg":"trace[1385850328] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1925; }","duration":"131.952415ms","start":"2026-07-02T05:12:25.886454Z","end":"2026-07-02T05:12:26.018406Z","steps":["trace[1385850328] 'agreement among raft nodes before linearized reading' (duration: 131.845883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:09.907008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.592245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:13:09.907141Z","caller":"traceutil/trace.go:171","msg":"trace[1720395911] transaction","detail":"{read_only:false; response_revision:2221; number_of_response:1; }","duration":"206.229333ms","start":"2026-07-02T05:13:09.700896Z","end":"2026-07-02T05:13:09.907125Z","steps":["trace[1720395911] 'process raft request' (duration: 101.039896ms)","trace[1720395911] 'compare' (duration: 104.450693ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:10.578817Z","caller":"traceutil/trace.go:171","msg":"trace[1854349501] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"201.937381ms","start":"2026-07-02T05:13:10.376847Z","end":"2026-07-02T05:13:10.578785Z","steps":["trace[1854349501] 'process raft request' (duration: 201.652185ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:10.59214Z","caller":"traceutil/trace.go:171","msg":"trace[1266994810] linearizableReadLoop","detail":"{readStateIndex:2321; appliedIndex:2318; }","duration":"123.138738ms","start":"2026-07-02T05:13:10.468978Z","end":"2026-07-02T05:13:10.592117Z","steps":["trace[1266994810] 'read index received' (duration: 109.587851ms)","trace[1266994810] 'applied index is now lower than readState.Index' (duration: 13.549717ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:13:10.59219Z","caller":"traceutil/trace.go:171","msg":"trace[792810051] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"215.05958ms","start":"2026-07-02T05:13:10.377099Z","end":"2026-07-02T05:13:10.592159Z","steps":["trace[792810051] 'process raft request' (duration: 214.844296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:13:10.592362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.362143ms","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-07-02T05:13:10.592328Z","caller":"traceutil/trace.go:171","msg":"trace[1452427545] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"215.142972ms","start":"2026-07-02T05:13:10.377148Z","end":"2026-07-02T05:13:10.592291Z","steps":["trace[1452427545] 'process raft request' (duration: 214.926868ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:13:10.592754Z","caller":"traceutil/trace.go:171","msg":"trace[1896280175] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2227; }","duration":"123.784331ms","start":"2026-07-02T05:13:10.468948Z","end":"2026-07-02T05:13:10.592732Z","steps":["trace[1896280175] 'agreement among raft nodes before linearized reading' (duration: 123.299851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:14:33.318979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.318046ms","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-07-02T05:14:33.319101Z","caller":"traceutil/trace.go:171","msg":"trace[221795055] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2724; }","duration":"164.50023ms","start":"2026-07-02T05:14:33.154577Z","end":"2026-07-02T05:14:33.319077Z","steps":["trace[221795055] 'range keys from in-memory index tree' (duration: 164.098952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:18:34.942751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.965123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T05:18:34.942864Z","caller":"traceutil/trace.go:171","msg":"trace[1304874717] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:4919; }","duration":"118.145917ms","start":"2026-07-02T05:18:34.824688Z","end":"2026-07-02T05:18:34.942834Z","steps":["trace[1304874717] 'count revisions from in-memory index tree' (duration: 117.861241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:18:34.942931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.999481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:18:34.943014Z","caller":"traceutil/trace.go:171","msg":"trace[2053536827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4919; }","duration":"109.137734ms","start":"2026-07-02T05:18:34.833858Z","end":"2026-07-02T05:18:34.942996Z","steps":["trace[2053536827] 'range keys from in-memory index tree' (duration: 108.90429ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:18:36.762851Z","caller":"traceutil/trace.go:171","msg":"trace[90203997] linearizableReadLoop","detail":"{readStateIndex:5166; appliedIndex:5165; }","duration":"108.620442ms","start":"2026-07-02T05:18:36.654204Z","end":"2026-07-02T05:18:36.762824Z","steps":["trace[90203997] 'read index received' (duration: 108.350637ms)","trace[90203997] 'applied index is now lower than readState.Index' (duration: 268.875µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:18:36.762878Z","caller":"traceutil/trace.go:171","msg":"trace[2060779361] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"109.271035ms","start":"2026-07-02T05:18:36.653571Z","end":"2026-07-02T05:18:36.762842Z","steps":["trace[2060779361] 'process raft request' (duration: 108.96967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:18:36.763104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.880237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-84cf869c48-gtlf6\" ","response":"range_response_count:1 size:11257"} {"level":"info","ts":"2026-07-02T05:18:36.763528Z","caller":"traceutil/trace.go:171","msg":"trace[692447030] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-84cf869c48-gtlf6; range_end:; response_count:1; response_revision:4969; }","duration":"109.268486ms","start":"2026-07-02T05:18:36.654148Z","end":"2026-07-02T05:18:36.763416Z","steps":["trace[692447030] 'agreement among raft nodes before linearized reading' (duration: 108.791296ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:18:46.372293Z","caller":"traceutil/trace.go:171","msg":"trace[1456241264] transaction","detail":"{read_only:false; response_revision:5146; number_of_response:1; }","duration":"110.312546ms","start":"2026-07-02T05:18:46.261949Z","end":"2026-07-02T05:18:46.372262Z","steps":["trace[1456241264] 'process raft request' (duration: 110.179784ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:18:46.372259Z","caller":"traceutil/trace.go:171","msg":"trace[699926519] transaction","detail":"{read_only:false; response_revision:5145; number_of_response:1; }","duration":"110.712824ms","start":"2026-07-02T05:18:46.261518Z","end":"2026-07-02T05:18:46.372231Z","steps":["trace[699926519] 'process raft request' (duration: 38.062376ms)","trace[699926519] 'compare' (duration: 72.28986ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:18:47.149966Z","caller":"traceutil/trace.go:171","msg":"trace[1493606174] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"223.648739ms","start":"2026-07-02T05:18:46.926286Z","end":"2026-07-02T05:18:47.149935Z","steps":["trace[1493606174] 'process raft request' (duration: 223.363313ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:18:47.150271Z","caller":"traceutil/trace.go:171","msg":"trace[527674901] transaction","detail":"{read_only:false; response_revision:5159; number_of_response:1; }","duration":"223.764951ms","start":"2026-07-02T05:18:46.926455Z","end":"2026-07-02T05:18:47.15022Z","steps":["trace[527674901] 'process raft request' (duration: 223.413354ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:18:47.150325Z","caller":"traceutil/trace.go:171","msg":"trace[1157288989] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"223.2698ms","start":"2026-07-02T05:18:46.927023Z","end":"2026-07-02T05:18:47.150293Z","steps":["trace[1157288989] 'process raft request' (duration: 223.195019ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:18:47.150328Z","caller":"traceutil/trace.go:171","msg":"trace[1972126675] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"223.72612ms","start":"2026-07-02T05:18:46.926565Z","end":"2026-07-02T05:18:47.150291Z","steps":["trace[1972126675] 'process raft request' (duration: 223.524517ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:18:47.150604Z","caller":"traceutil/trace.go:171","msg":"trace[395268166] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"143.823885ms","start":"2026-07-02T05:18:47.006766Z","end":"2026-07-02T05:18:47.15059Z","steps":["trace[395268166] 'process raft request' (duration: 143.711733ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:18:53.918556Z","caller":"traceutil/trace.go:171","msg":"trace[2076991442] transaction","detail":"{read_only:false; response_revision:5309; number_of_response:1; }","duration":"101.727202ms","start":"2026-07-02T05:18:53.816708Z","end":"2026-07-02T05:18:53.918435Z","steps":["trace[2076991442] 'process raft request' (duration: 101.369725ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:19:19.347833Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2650} {"level":"info","ts":"2026-07-02T05:19:19.414572Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2650,"took":"65.078323ms","hash":1673603240,"current-db-size-bytes":56246272,"current-db-size":"56 MB","current-db-size-in-use-bytes":31330304,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T05:19:19.414647Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1673603240,"revision":2650,"compact-revision":-1} {"level":"info","ts":"2026-07-02T05:24:19.357353Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5573} {"level":"info","ts":"2026-07-02T05:24:19.435099Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5573,"took":"76.293892ms","hash":586152037,"current-db-size-bytes":56246272,"current-db-size":"56 MB","current-db-size-in-use-bytes":24584192,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-07-02T05:24:19.435158Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":586152037,"revision":5573,"compact-revision":2650} {"level":"info","ts":"2026-07-02T05:24:58.883096Z","caller":"traceutil/trace.go:171","msg":"trace[812659143] linearizableReadLoop","detail":"{readStateIndex:8437; appliedIndex:8436; }","duration":"298.504873ms","start":"2026-07-02T05:24:58.584552Z","end":"2026-07-02T05:24:58.883057Z","steps":["trace[812659143] 'read index received' (duration: 298.33208ms)","trace[812659143] 'applied index is now lower than readState.Index' (duration: 171.773µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:24:58.883127Z","caller":"traceutil/trace.go:171","msg":"trace[1575092912] transaction","detail":"{read_only:false; response_revision:8141; number_of_response:1; }","duration":"301.194775ms","start":"2026-07-02T05:24:58.581903Z","end":"2026-07-02T05:24:58.883097Z","steps":["trace[1575092912] 'process raft request' (duration: 300.96933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:24:58.883362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:24:58.581863Z","time spent":"301.40912ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:24:58.883486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.922491ms","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-07-02T05:24:58.883575Z","caller":"traceutil/trace.go:171","msg":"trace[2021115837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8141; }","duration":"299.076284ms","start":"2026-07-02T05:24:58.584487Z","end":"2026-07-02T05:24:58.883564Z","steps":["trace[2021115837] 'agreement among raft nodes before linearized reading' (duration: 298.720207ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:24:58.932601Z","caller":"traceutil/trace.go:171","msg":"trace[1164590138] transaction","detail":"{read_only:false; response_revision:8142; number_of_response:1; }","duration":"265.703541ms","start":"2026-07-02T05:24:58.666879Z","end":"2026-07-02T05:24:58.932583Z","steps":["trace[1164590138] 'process raft request' (duration: 244.11946ms)","trace[1164590138] 'compare' (duration: 21.448378ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:24:58.933015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.6092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T05:24:58.933055Z","caller":"traceutil/trace.go:171","msg":"trace[685628043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8144; }","duration":"344.660431ms","start":"2026-07-02T05:24:58.588385Z","end":"2026-07-02T05:24:58.933046Z","steps":["trace[685628043] 'agreement among raft nodes before linearized reading' (duration: 344.471828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:24:58.933085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:24:58.588362Z","time spent":"344.713452ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T05:24:58.933025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.301988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-02T05:24:58.933125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.22382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-02T05:24:58.933215Z","caller":"traceutil/trace.go:171","msg":"trace[2142698674] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8144; }","duration":"266.341092ms","start":"2026-07-02T05:24:58.666851Z","end":"2026-07-02T05:24:58.933193Z","steps":["trace[2142698674] 'agreement among raft nodes before linearized reading' (duration: 266.125818ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:24:58.933222Z","caller":"traceutil/trace.go:171","msg":"trace[776145371] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8144; }","duration":"178.470962ms","start":"2026-07-02T05:24:58.754697Z","end":"2026-07-02T05:24:58.933168Z","steps":["trace[776145371] 'agreement among raft nodes before linearized reading' (duration: 178.275328ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:24:59.823982Z","caller":"traceutil/trace.go:171","msg":"trace[1760034566] transaction","detail":"{read_only:false; response_revision:8149; number_of_response:1; }","duration":"237.550425ms","start":"2026-07-02T05:24:59.586404Z","end":"2026-07-02T05:24:59.823954Z","steps":["trace[1760034566] 'process raft request' (duration: 237.401792ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:26.705741Z","caller":"traceutil/trace.go:171","msg":"trace[1430942953] transaction","detail":"{read_only:false; response_revision:8345; number_of_response:1; }","duration":"120.955709ms","start":"2026-07-02T05:25:26.584755Z","end":"2026-07-02T05:25:26.705711Z","steps":["trace[1430942953] 'process raft request' (duration: 118.474342ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:26.721843Z","caller":"traceutil/trace.go:171","msg":"trace[429742505] transaction","detail":"{read_only:false; response_revision:8346; number_of_response:1; }","duration":"136.506084ms","start":"2026-07-02T05:25:26.58531Z","end":"2026-07-02T05:25:26.721816Z","steps":["trace[429742505] 'process raft request' (duration: 136.339661ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:40.22464Z","caller":"traceutil/trace.go:171","msg":"trace[1104615469] transaction","detail":"{read_only:false; response_revision:8461; number_of_response:1; }","duration":"161.768945ms","start":"2026-07-02T05:25:40.062839Z","end":"2026-07-02T05:25:40.224608Z","steps":["trace[1104615469] 'process raft request' (duration: 154.904165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:25:41.164226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.934476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:25:41.164375Z","caller":"traceutil/trace.go:171","msg":"trace[1515629386] transaction","detail":"{read_only:false; response_revision:8463; number_of_response:1; }","duration":"287.206579ms","start":"2026-07-02T05:25:40.877142Z","end":"2026-07-02T05:25:41.164348Z","steps":["trace[1515629386] 'process raft request' (duration: 163.037758ms)","trace[1515629386] 'compare' (duration: 123.65171ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:25:41.184853Z","caller":"traceutil/trace.go:171","msg":"trace[1990412367] transaction","detail":"{read_only:false; response_revision:8464; number_of_response:1; }","duration":"297.918832ms","start":"2026-07-02T05:25:40.8869Z","end":"2026-07-02T05:25:41.184819Z","steps":["trace[1990412367] 'process raft request' (duration: 297.681248ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:41.185044Z","caller":"traceutil/trace.go:171","msg":"trace[1003519415] linearizableReadLoop","detail":"{readStateIndex:8772; appliedIndex:8770; }","duration":"166.26908ms","start":"2026-07-02T05:25:41.018752Z","end":"2026-07-02T05:25:41.185022Z","steps":["trace[1003519415] 'read index received' (duration: 21.454227ms)","trace[1003519415] 'applied index is now lower than readState.Index' (duration: 144.813203ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:25:41.185168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.415132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T05:25:41.185212Z","caller":"traceutil/trace.go:171","msg":"trace[1649556] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:8465; }","duration":"166.505004ms","start":"2026-07-02T05:25:41.018695Z","end":"2026-07-02T05:25:41.1852Z","steps":["trace[1649556] 'agreement among raft nodes before linearized reading' (duration: 166.420373ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:41.185179Z","caller":"traceutil/trace.go:171","msg":"trace[695822999] transaction","detail":"{read_only:false; response_revision:8465; number_of_response:1; }","duration":"154.109559ms","start":"2026-07-02T05:25:41.031029Z","end":"2026-07-02T05:25:41.185138Z","steps":["trace[695822999] 'process raft request' (duration: 153.714912ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:41.196935Z","caller":"traceutil/trace.go:171","msg":"trace[1496845185] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"135.720179ms","start":"2026-07-02T05:25:41.061186Z","end":"2026-07-02T05:25:41.196906Z","steps":["trace[1496845185] 'process raft request' (duration: 135.519216ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:25:41.873774Z","caller":"traceutil/trace.go:171","msg":"trace[1301674174] transaction","detail":"{read_only:false; response_revision:8472; number_of_response:1; }","duration":"172.5242ms","start":"2026-07-02T05:25:41.701209Z","end":"2026-07-02T05:25:41.873733Z","steps":["trace[1301674174] 'process raft request' (duration: 172.282935ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:26:25.657775Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000241d.wal"} {"level":"info","ts":"2026-07-02T05:27:53.65629Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T05:27:53.662107Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-02T05:27:53.66223Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-02T05:29:19.363205Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7757} {"level":"info","ts":"2026-07-02T05:29:19.426211Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7757,"took":"60.703384ms","hash":3661430772,"current-db-size-bytes":56246272,"current-db-size":"56 MB","current-db-size-in-use-bytes":29249536,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-02T05:29:19.42629Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3661430772,"revision":7757,"compact-revision":5573} {"level":"info","ts":"2026-07-02T05:30:26.444479Z","caller":"traceutil/trace.go:171","msg":"trace[1026923258] linearizableReadLoop","detail":"{readStateIndex:11400; appliedIndex:11398; }","duration":"220.422345ms","start":"2026-07-02T05:30:26.224018Z","end":"2026-07-02T05:30:26.44444Z","steps":["trace[1026923258] 'read index received' (duration: 15.674498ms)","trace[1026923258] 'applied index is now lower than readState.Index' (duration: 204.746317ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:30:26.444614Z","caller":"traceutil/trace.go:171","msg":"trace[2061231993] transaction","detail":"{read_only:false; response_revision:11017; number_of_response:1; }","duration":"222.196289ms","start":"2026-07-02T05:30:26.222345Z","end":"2026-07-02T05:30:26.444541Z","steps":["trace[2061231993] 'process raft request' (duration: 189.558517ms)","trace[2061231993] 'compare' (duration: 32.255265ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:30:26.444793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.745621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mj8jn\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-07-02T05:30:26.444889Z","caller":"traceutil/trace.go:171","msg":"trace[1386829961] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mj8jn; range_end:; response_count:1; response_revision:11017; }","duration":"220.914985ms","start":"2026-07-02T05:30:26.223951Z","end":"2026-07-02T05:30:26.444866Z","steps":["trace[1386829961] 'agreement among raft nodes before linearized reading' (duration: 220.607999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:30:26.444785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.866834ms","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-07-02T05:30:26.445034Z","caller":"traceutil/trace.go:171","msg":"trace[316948806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11017; }","duration":"169.172669ms","start":"2026-07-02T05:30:26.275839Z","end":"2026-07-02T05:30:26.445012Z","steps":["trace[316948806] 'agreement among raft nodes before linearized reading' (duration: 168.814223ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:34:19.369642Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10353} {"level":"info","ts":"2026-07-02T05:34:19.460523Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10353,"took":"86.937645ms","hash":2249792326,"current-db-size-bytes":56246272,"current-db-size":"56 MB","current-db-size-in-use-bytes":26640384,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-02T05:34:19.460647Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2249792326,"revision":10353,"compact-revision":7757} {"level":"warn","ts":"2026-07-02T05:36:45.013466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.102472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T05:36:45.013605Z","caller":"traceutil/trace.go:171","msg":"trace[606078226] linearizableReadLoop","detail":"{readStateIndex:14049; appliedIndex:14048; }","duration":"335.031336ms","start":"2026-07-02T05:36:44.67855Z","end":"2026-07-02T05:36:45.013581Z","steps":["trace[606078226] 'read index received' (duration: 189.670019ms)","trace[606078226] 'applied index is now lower than readState.Index' (duration: 145.359537ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:36:45.01374Z","caller":"traceutil/trace.go:171","msg":"trace[10087282] transaction","detail":"{read_only:false; response_revision:13576; number_of_response:1; }","duration":"373.717183ms","start":"2026-07-02T05:36:44.639934Z","end":"2026-07-02T05:36:45.013651Z","steps":["trace[10087282] 'process raft request' (duration: 228.305115ms)","trace[10087282] 'compare' (duration: 144.824987ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:36:45.013856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.304111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T05:36:45.013891Z","caller":"traceutil/trace.go:171","msg":"trace[1701782749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13576; }","duration":"335.357073ms","start":"2026-07-02T05:36:44.678523Z","end":"2026-07-02T05:36:45.01388Z","steps":["trace[1701782749] 'agreement among raft nodes before linearized reading' (duration: 335.19608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:36:45.013921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:36:44.678511Z","time spent":"335.403893ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T05:36:45.01409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:36:44.639914Z","time spent":"373.911877ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:36:45.014266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.50629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-02T05:36:45.014374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.487347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T05:36:45.014363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.767148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/openstack/ovn-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:36:45.014451Z","caller":"traceutil/trace.go:171","msg":"trace[724517646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13576; }","duration":"330.751397ms","start":"2026-07-02T05:36:44.68367Z","end":"2026-07-02T05:36:45.014421Z","steps":["trace[724517646] 'agreement among raft nodes before linearized reading' (duration: 330.283517ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:36:45.014469Z","caller":"traceutil/trace.go:171","msg":"trace[404810756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13576; }","duration":"179.62384ms","start":"2026-07-02T05:36:44.834821Z","end":"2026-07-02T05:36:45.014445Z","steps":["trace[404810756] 'agreement among raft nodes before linearized reading' (duration: 179.464416ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:36:45.014583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:36:44.683661Z","time spent":"330.897589ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T05:36:45.014478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.663039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-02T05:36:45.014688Z","caller":"traceutil/trace.go:171","msg":"trace[1217508320] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13576; }","duration":"263.924654ms","start":"2026-07-02T05:36:44.750742Z","end":"2026-07-02T05:36:45.014666Z","steps":["trace[1217508320] 'agreement among raft nodes before linearized reading' (duration: 263.557426ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:36:45.014778Z","caller":"traceutil/trace.go:171","msg":"trace[1811215426] range","detail":"{range_begin:/registry/daemonsets/openstack/ovn-controller; range_end:; response_count:0; response_revision:13576; }","duration":"132.989862ms","start":"2026-07-02T05:36:44.881551Z","end":"2026-07-02T05:36:45.014541Z","steps":["trace[1811215426] 'agreement among raft nodes before linearized reading' (duration: 132.730326ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:36:53.823888Z","caller":"traceutil/trace.go:171","msg":"trace[564552125] linearizableReadLoop","detail":"{readStateIndex:14213; appliedIndex:14212; }","duration":"200.489466ms","start":"2026-07-02T05:36:53.623369Z","end":"2026-07-02T05:36:53.823858Z","steps":["trace[564552125] 'read index received' (duration: 188.639301ms)","trace[564552125] 'applied index is now lower than readState.Index' (duration: 11.848835ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:36:53.823894Z","caller":"traceutil/trace.go:171","msg":"trace[685312600] transaction","detail":"{read_only:false; response_revision:13737; number_of_response:1; }","duration":"235.757427ms","start":"2026-07-02T05:36:53.588103Z","end":"2026-07-02T05:36:53.823861Z","steps":["trace[685312600] 'process raft request' (duration: 224.054514ms)","trace[685312600] 'compare' (duration: 11.477059ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:36:53.824198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.822002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mj8jn\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-07-02T05:36:53.824235Z","caller":"traceutil/trace.go:171","msg":"trace[1594304592] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mj8jn; range_end:; response_count:1; response_revision:13737; }","duration":"200.919454ms","start":"2026-07-02T05:36:53.623304Z","end":"2026-07-02T05:36:53.824224Z","steps":["trace[1594304592] 'agreement among raft nodes before linearized reading' (duration: 200.70713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:36:53.824253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.529055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-northd-7785c59fb4-jrdhf\" ","response":"range_response_count:1 size:6825"} {"level":"warn","ts":"2026-07-02T05:36:53.824251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.000052ms","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-07-02T05:36:53.824297Z","caller":"traceutil/trace.go:171","msg":"trace[658646364] range","detail":"{range_begin:/registry/pods/openstack/ovn-northd-7785c59fb4-jrdhf; range_end:; response_count:1; response_revision:13737; }","duration":"140.616037ms","start":"2026-07-02T05:36:53.683671Z","end":"2026-07-02T05:36:53.824287Z","steps":["trace[658646364] 'agreement among raft nodes before linearized reading' (duration: 140.459874ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:36:53.82433Z","caller":"traceutil/trace.go:171","msg":"trace[496849717] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13737; }","duration":"184.130914ms","start":"2026-07-02T05:36:53.640178Z","end":"2026-07-02T05:36:53.824309Z","steps":["trace[496849717] 'agreement among raft nodes before linearized reading' (duration: 183.8835ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:36:53.973203Z","caller":"traceutil/trace.go:171","msg":"trace[919975319] linearizableReadLoop","detail":"{readStateIndex:14214; appliedIndex:14213; }","duration":"144.531052ms","start":"2026-07-02T05:36:53.828648Z","end":"2026-07-02T05:36:53.973179Z","steps":["trace[919975319] 'read index received' (duration: 144.323407ms)","trace[919975319] 'applied index is now lower than readState.Index' (duration: 206.895µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:36:53.973349Z","caller":"traceutil/trace.go:171","msg":"trace[67300668] transaction","detail":"{read_only:false; response_revision:13738; number_of_response:1; }","duration":"145.54797ms","start":"2026-07-02T05:36:53.82777Z","end":"2026-07-02T05:36:53.973318Z","steps":["trace[67300668] 'process raft request' (duration: 145.269274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:36:53.974003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.331407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:66 size:612532"} {"level":"info","ts":"2026-07-02T05:36:53.974051Z","caller":"traceutil/trace.go:171","msg":"trace[802377483] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:66; response_revision:13738; }","duration":"145.426208ms","start":"2026-07-02T05:36:53.828612Z","end":"2026-07-02T05:36:53.974038Z","steps":["trace[802377483] 'agreement among raft nodes before linearized reading' (duration: 144.688744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:36:54.006735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.813828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:36:54.006746Z","caller":"traceutil/trace.go:171","msg":"trace[1503735683] transaction","detail":"{read_only:false; response_revision:13739; number_of_response:1; }","duration":"172.016915ms","start":"2026-07-02T05:36:53.83469Z","end":"2026-07-02T05:36:54.006707Z","steps":["trace[1503735683] 'process raft request' (duration: 171.82437ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:36:54.006803Z","caller":"traceutil/trace.go:171","msg":"trace[608090766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13740; }","duration":"174.92404ms","start":"2026-07-02T05:36:53.831862Z","end":"2026-07-02T05:36:54.006786Z","steps":["trace[608090766] 'agreement among raft nodes before linearized reading' (duration: 174.813547ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:36:54.006929Z","caller":"traceutil/trace.go:171","msg":"trace[2069004032] transaction","detail":"{read_only:false; response_revision:13740; number_of_response:1; }","duration":"150.880932ms","start":"2026-07-02T05:36:53.855986Z","end":"2026-07-02T05:36:54.006867Z","steps":["trace[2069004032] 'process raft request' (duration: 150.634456ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:38:29.598752Z","caller":"traceutil/trace.go:171","msg":"trace[2004853834] transaction","detail":"{read_only:false; response_revision:14798; number_of_response:1; }","duration":"447.855675ms","start":"2026-07-02T05:38:29.150877Z","end":"2026-07-02T05:38:29.598733Z","steps":["trace[2004853834] 'process raft request' (duration: 447.770863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:29.598863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:38:29.150858Z","time spent":"447.949416ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:38:29.599065Z","caller":"traceutil/trace.go:171","msg":"trace[610398589] linearizableReadLoop","detail":"{readStateIndex:15306; appliedIndex:15306; }","duration":"440.751819ms","start":"2026-07-02T05:38:29.158307Z","end":"2026-07-02T05:38:29.599059Z","steps":["trace[610398589] 'read index received' (duration: 440.749069ms)","trace[610398589] 'applied index is now lower than readState.Index' (duration: 2.33µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:38:29.599154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.1147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-metadata\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-07-02T05:38:29.599173Z","caller":"traceutil/trace.go:171","msg":"trace[1212975106] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-metadata; range_end:; response_count:1; response_revision:14798; }","duration":"344.172121ms","start":"2026-07-02T05:38:29.254996Z","end":"2026-07-02T05:38:29.599168Z","steps":["trace[1212975106] 'agreement among raft nodes before linearized reading' (duration: 344.104089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:29.599188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:38:29.254977Z","time spent":"344.207972ms","remote":"127.0.0.1:57320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":704,"request content":"key:\"/registry/services/endpoints/openstack/nova-metadata\" "} {"level":"warn","ts":"2026-07-02T05:38:29.599316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.007573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-02T05:38:29.599333Z","caller":"traceutil/trace.go:171","msg":"trace[2017229396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14798; }","duration":"441.040424ms","start":"2026-07-02T05:38:29.158288Z","end":"2026-07-02T05:38:29.599329Z","steps":["trace[2017229396] 'agreement among raft nodes before linearized reading' (duration: 440.995993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:29.599345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:38:29.15828Z","time spent":"441.061934ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T05:38:29.599594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.845229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-02T05:38:29.599611Z","caller":"traceutil/trace.go:171","msg":"trace[26544848] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14798; }","duration":"141.88135ms","start":"2026-07-02T05:38:29.457726Z","end":"2026-07-02T05:38:29.599607Z","steps":["trace[26544848] 'agreement among raft nodes before linearized reading' (duration: 141.842609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:29.599724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.484189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-07-02T05:38:29.599748Z","caller":"traceutil/trace.go:171","msg":"trace[1624490040] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14798; }","duration":"145.52495ms","start":"2026-07-02T05:38:29.454219Z","end":"2026-07-02T05:38:29.599744Z","steps":["trace[1624490040] 'agreement among raft nodes before linearized reading' (duration: 145.476179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:29.599814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.900237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:38:29.599826Z","caller":"traceutil/trace.go:171","msg":"trace[1008778639] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14798; }","duration":"145.933617ms","start":"2026-07-02T05:38:29.453889Z","end":"2026-07-02T05:38:29.599823Z","steps":["trace[1008778639] 'agreement among raft nodes before linearized reading' (duration: 145.913247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:29.599901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.846404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-07-02T05:38:29.599917Z","caller":"traceutil/trace.go:171","msg":"trace[143706949] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14798; }","duration":"240.899065ms","start":"2026-07-02T05:38:29.359014Z","end":"2026-07-02T05:38:29.599913Z","steps":["trace[143706949] 'agreement among raft nodes before linearized reading' (duration: 240.862014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:29.600004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.263649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-07-02T05:38:29.600019Z","caller":"traceutil/trace.go:171","msg":"trace[1636120494] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14798; }","duration":"337.29396ms","start":"2026-07-02T05:38:29.262722Z","end":"2026-07-02T05:38:29.600016Z","steps":["trace[1636120494] 'agreement among raft nodes before linearized reading' (duration: 337.255349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:29.600031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:38:29.262715Z","time spent":"337.31314ms","remote":"127.0.0.1:57398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-07-02T05:38:29.600095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.269198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:38:29.60011Z","caller":"traceutil/trace.go:171","msg":"trace[965000529] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:0; response_revision:14798; }","duration":"338.301478ms","start":"2026-07-02T05:38:29.261805Z","end":"2026-07-02T05:38:29.600106Z","steps":["trace[965000529] 'agreement among raft nodes before linearized reading' (duration: 338.277798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:29.600122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:38:29.261794Z","time spent":"338.324729ms","remote":"127.0.0.1:57320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":27,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-07-02T05:38:29.600181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.550114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T05:38:29.600197Z","caller":"traceutil/trace.go:171","msg":"trace[761033280] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14798; }","duration":"338.582724ms","start":"2026-07-02T05:38:29.26161Z","end":"2026-07-02T05:38:29.600193Z","steps":["trace[761033280] 'agreement among raft nodes before linearized reading' (duration: 338.561174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:29.600209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:38:29.2616Z","time spent":"338.606244ms","remote":"127.0.0.1:57398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-07-02T05:38:29.60028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.575004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-07-02T05:38:29.600298Z","caller":"traceutil/trace.go:171","msg":"trace[263412136] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14798; }","duration":"339.615074ms","start":"2026-07-02T05:38:29.260678Z","end":"2026-07-02T05:38:29.600293Z","steps":["trace[263412136] 'agreement among raft nodes before linearized reading' (duration: 339.576733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:29.600309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:38:29.260667Z","time spent":"339.639384ms","remote":"127.0.0.1:57320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"info","ts":"2026-07-02T05:38:29.741804Z","caller":"traceutil/trace.go:171","msg":"trace[2118264999] linearizableReadLoop","detail":"{readStateIndex:15310; appliedIndex:15307; }","duration":"110.216397ms","start":"2026-07-02T05:38:29.631556Z","end":"2026-07-02T05:38:29.741773Z","steps":["trace[2118264999] 'read index received' (duration: 109.871681ms)","trace[2118264999] 'applied index is now lower than readState.Index' (duration: 343.256µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:38:29.741981Z","caller":"traceutil/trace.go:171","msg":"trace[798221001] transaction","detail":"{read_only:false; response_revision:14800; number_of_response:1; }","duration":"134.622122ms","start":"2026-07-02T05:38:29.607346Z","end":"2026-07-02T05:38:29.741968Z","steps":["trace[798221001] 'process raft request' (duration: 134.118653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:38:29.742215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.645285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-07-02T05:38:29.742301Z","caller":"traceutil/trace.go:171","msg":"trace[7266075] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:14802; }","duration":"110.756418ms","start":"2026-07-02T05:38:29.631521Z","end":"2026-07-02T05:38:29.742278Z","steps":["trace[7266075] 'agreement among raft nodes before linearized reading' (duration: 110.564234ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:38:29.742487Z","caller":"traceutil/trace.go:171","msg":"trace[1083251187] transaction","detail":"{read_only:false; response_revision:14801; number_of_response:1; }","duration":"133.329288ms","start":"2026-07-02T05:38:29.609148Z","end":"2026-07-02T05:38:29.742478Z","steps":["trace[1083251187] 'process raft request' (duration: 132.492142ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:38:29.742613Z","caller":"traceutil/trace.go:171","msg":"trace[2039731990] transaction","detail":"{read_only:false; response_revision:14802; number_of_response:1; }","duration":"133.257506ms","start":"2026-07-02T05:38:29.609345Z","end":"2026-07-02T05:38:29.742602Z","steps":["trace[2039731990] 'process raft request' (duration: 132.374749ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:39:19.375491Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12521} {"level":"info","ts":"2026-07-02T05:39:19.453295Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12521,"took":"74.900105ms","hash":1557356143,"current-db-size-bytes":56246272,"current-db-size":"56 MB","current-db-size-in-use-bytes":29847552,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-02T05:39:19.453364Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1557356143,"revision":12521,"compact-revision":10353} {"level":"info","ts":"2026-07-02T05:44:15.429894Z","caller":"traceutil/trace.go:171","msg":"trace[1598557294] transaction","detail":"{read_only:false; response_revision:17547; number_of_response:1; }","duration":"103.285068ms","start":"2026-07-02T05:44:15.326584Z","end":"2026-07-02T05:44:15.429869Z","steps":["trace[1598557294] 'process raft request' (duration: 102.808159ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:44:17.511614Z","caller":"traceutil/trace.go:171","msg":"trace[199039955] transaction","detail":"{read_only:false; response_revision:17562; number_of_response:1; }","duration":"145.999101ms","start":"2026-07-02T05:44:17.365571Z","end":"2026-07-02T05:44:17.51157Z","steps":["trace[199039955] 'process raft request' (duration: 145.687565ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:44:19.382027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15121} {"level":"info","ts":"2026-07-02T05:44:19.486944Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15121,"took":"99.599657ms","hash":3299823711,"current-db-size-bytes":56246272,"current-db-size":"56 MB","current-db-size-in-use-bytes":31907840,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T05:44:19.487051Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3299823711,"revision":15121,"compact-revision":12521} {"level":"info","ts":"2026-07-02T05:47:32.647325Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T05:47:32.653693Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-02T05:47:32.653817Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-02T05:49:19.388887Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17578} {"level":"info","ts":"2026-07-02T05:49:19.489243Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17578,"took":"96.264403ms","hash":785544632,"current-db-size-bytes":56246272,"current-db-size":"56 MB","current-db-size-in-use-bytes":34934784,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-02T05:49:19.489304Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":785544632,"revision":17578,"compact-revision":15121} {"level":"info","ts":"2026-07-02T05:50:59.188176Z","caller":"traceutil/trace.go:171","msg":"trace[378425282] transaction","detail":"{read_only:false; response_revision:20817; number_of_response:1; }","duration":"135.815705ms","start":"2026-07-02T05:50:59.052338Z","end":"2026-07-02T05:50:59.188153Z","steps":["trace[378425282] 'process raft request' (duration: 134.732285ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:50:59.440464Z","caller":"traceutil/trace.go:171","msg":"trace[1063750449] transaction","detail":"{read_only:false; response_revision:20818; number_of_response:1; }","duration":"263.527077ms","start":"2026-07-02T05:50:59.176908Z","end":"2026-07-02T05:50:59.440435Z","steps":["trace[1063750449] 'process raft request' (duration: 263.336504ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:50:59.903364Z","caller":"traceutil/trace.go:171","msg":"trace[430031791] transaction","detail":"{read_only:false; response_revision:20820; number_of_response:1; }","duration":"268.287348ms","start":"2026-07-02T05:50:59.635042Z","end":"2026-07-02T05:50:59.903329Z","steps":["trace[430031791] 'process raft request' (duration: 212.69494ms)","trace[430031791] 'compare' (duration: 55.346662ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:50:59.903545Z","caller":"traceutil/trace.go:171","msg":"trace[1593564565] linearizableReadLoop","detail":"{readStateIndex:21517; appliedIndex:21515; }","duration":"252.152321ms","start":"2026-07-02T05:50:59.651364Z","end":"2026-07-02T05:50:59.903516Z","steps":["trace[1593564565] 'read index received' (duration: 79.786228ms)","trace[1593564565] 'applied index is now lower than readState.Index' (duration: 172.364523ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:50:59.903622Z","caller":"traceutil/trace.go:171","msg":"trace[254594128] transaction","detail":"{read_only:false; response_revision:20821; number_of_response:1; }","duration":"181.640508ms","start":"2026-07-02T05:50:59.721958Z","end":"2026-07-02T05:50:59.903599Z","steps":["trace[254594128] 'process raft request' (duration: 181.305221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:50:59.90425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.985102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-02T05:50:59.904367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.334194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:50:59.904324Z","caller":"traceutil/trace.go:171","msg":"trace[1144153741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20821; }","duration":"154.099673ms","start":"2026-07-02T05:50:59.750208Z","end":"2026-07-02T05:50:59.904307Z","steps":["trace[1144153741] 'agreement among raft nodes before linearized reading' (duration: 153.599764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:50:59.904647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.544772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-02T05:50:59.904732Z","caller":"traceutil/trace.go:171","msg":"trace[261146617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20821; }","duration":"143.836488ms","start":"2026-07-02T05:50:59.760877Z","end":"2026-07-02T05:50:59.904713Z","steps":["trace[261146617] 'agreement among raft nodes before linearized reading' (duration: 142.997202ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:50:59.905601Z","caller":"traceutil/trace.go:171","msg":"trace[1192516062] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20821; }","duration":"254.095917ms","start":"2026-07-02T05:50:59.651293Z","end":"2026-07-02T05:50:59.905389Z","steps":["trace[1192516062] 'agreement among raft nodes before linearized reading' (duration: 252.350794ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:51:00.168818Z","caller":"traceutil/trace.go:171","msg":"trace[1388365898] transaction","detail":"{read_only:false; response_revision:20824; number_of_response:1; }","duration":"259.547031ms","start":"2026-07-02T05:50:59.909262Z","end":"2026-07-02T05:51:00.168809Z","steps":["trace[1388365898] 'process raft request' (duration: 259.290926ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:51:00.169038Z","caller":"traceutil/trace.go:171","msg":"trace[2105257854] transaction","detail":"{read_only:false; response_revision:20825; number_of_response:1; }","duration":"259.229565ms","start":"2026-07-02T05:50:59.90977Z","end":"2026-07-02T05:51:00.168999Z","steps":["trace[2105257854] 'process raft request' (duration: 258.97472ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:51:00.168667Z","caller":"traceutil/trace.go:171","msg":"trace[1294242928] transaction","detail":"{read_only:false; response_revision:20823; number_of_response:1; }","duration":"259.941609ms","start":"2026-07-02T05:50:59.908702Z","end":"2026-07-02T05:51:00.168644Z","steps":["trace[1294242928] 'process raft request' (duration: 230.52051ms)","trace[1294242928] 'compare' (duration: 28.976801ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:54:19.39494Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20052} {"level":"info","ts":"2026-07-02T05:54:19.501871Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20052,"took":"102.595303ms","hash":2584494343,"current-db-size-bytes":56246272,"current-db-size":"56 MB","current-db-size-in-use-bytes":33021952,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-02T05:54:19.501931Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2584494343,"revision":20052,"compact-revision":17578} {"level":"info","ts":"2026-07-02T05:54:36.516378Z","caller":"traceutil/trace.go:171","msg":"trace[2029174003] linearizableReadLoop","detail":"{readStateIndex:23911; appliedIndex:23910; }","duration":"227.357848ms","start":"2026-07-02T05:54:36.288991Z","end":"2026-07-02T05:54:36.516349Z","steps":["trace[2029174003] 'read index received' (duration: 227.194625ms)","trace[2029174003] 'applied index is now lower than readState.Index' (duration: 161.613µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:54:36.516507Z","caller":"traceutil/trace.go:171","msg":"trace[758471358] transaction","detail":"{read_only:false; response_revision:23139; number_of_response:1; }","duration":"235.203418ms","start":"2026-07-02T05:54:36.281289Z","end":"2026-07-02T05:54:36.516493Z","steps":["trace[758471358] 'process raft request' (duration: 234.899332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:54:36.516924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.92635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-07-02T05:54:36.517001Z","caller":"traceutil/trace.go:171","msg":"trace[1211806153] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:23139; }","duration":"228.066552ms","start":"2026-07-02T05:54:36.288916Z","end":"2026-07-02T05:54:36.516982Z","steps":["trace[1211806153] 'agreement among raft nodes before linearized reading' (duration: 227.757177ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:54:36.534063Z","caller":"traceutil/trace.go:171","msg":"trace[2050019413] transaction","detail":"{read_only:false; response_revision:23140; number_of_response:1; }","duration":"239.847057ms","start":"2026-07-02T05:54:36.294198Z","end":"2026-07-02T05:54:36.534046Z","steps":["trace[2050019413] 'process raft request' (duration: 239.571812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:54:36.534222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.801138ms","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-07-02T05:54:36.534363Z","caller":"traceutil/trace.go:171","msg":"trace[380444473] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23140; }","duration":"102.998812ms","start":"2026-07-02T05:54:36.431343Z","end":"2026-07-02T05:54:36.534341Z","steps":["trace[380444473] 'agreement among raft nodes before linearized reading' (duration: 102.612854ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:33.673624Z","caller":"traceutil/trace.go:171","msg":"trace[1694277193] transaction","detail":"{read_only:false; response_revision:24981; number_of_response:1; }","duration":"103.981083ms","start":"2026-07-02T05:57:33.569602Z","end":"2026-07-02T05:57:33.673583Z","steps":["trace[1694277193] 'process raft request' (duration: 103.81921ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:33.673642Z","caller":"traceutil/trace.go:171","msg":"trace[158469618] transaction","detail":"{read_only:false; response_revision:24980; number_of_response:1; }","duration":"104.739636ms","start":"2026-07-02T05:57:33.568861Z","end":"2026-07-02T05:57:33.673601Z","steps":["trace[158469618] 'process raft request' (duration: 34.730011ms)","trace[158469618] 'compare' (duration: 69.656799ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:33.674034Z","caller":"traceutil/trace.go:171","msg":"trace[1649160127] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"102.158107ms","start":"2026-07-02T05:57:33.571853Z","end":"2026-07-02T05:57:33.674011Z","steps":["trace[1649160127] 'process raft request' (duration: 101.721869ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:33.674098Z","caller":"traceutil/trace.go:171","msg":"trace[1872449263] transaction","detail":"{read_only:false; response_revision:24983; number_of_response:1; }","duration":"102.270331ms","start":"2026-07-02T05:57:33.571791Z","end":"2026-07-02T05:57:33.674061Z","steps":["trace[1872449263] 'process raft request' (duration: 101.73666ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:33.674098Z","caller":"traceutil/trace.go:171","msg":"trace[1624491448] transaction","detail":"{read_only:false; response_revision:24985; number_of_response:1; }","duration":"101.001707ms","start":"2026-07-02T05:57:33.573055Z","end":"2026-07-02T05:57:33.674057Z","steps":["trace[1624491448] 'process raft request' (duration: 100.574278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:33.674191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.326783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/monitoring/\" range_end:\"/registry/resourcequotas/monitoring0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:57:33.674249Z","caller":"traceutil/trace.go:171","msg":"trace[252264263] range","detail":"{range_begin:/registry/resourcequotas/monitoring/; range_end:/registry/resourcequotas/monitoring0; response_count:0; response_revision:24986; }","duration":"100.392725ms","start":"2026-07-02T05:57:33.573824Z","end":"2026-07-02T05:57:33.674216Z","steps":["trace[252264263] 'agreement among raft nodes before linearized reading' (duration: 100.299173ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:33.673891Z","caller":"traceutil/trace.go:171","msg":"trace[1722779787] transaction","detail":"{read_only:false; response_revision:24982; number_of_response:1; }","duration":"102.429192ms","start":"2026-07-02T05:57:33.571437Z","end":"2026-07-02T05:57:33.673866Z","steps":["trace[1722779787] 'process raft request' (duration: 102.044975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:45.646029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.805806ms","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-07-02T05:57:45.646118Z","caller":"traceutil/trace.go:171","msg":"trace[791009682] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25151; }","duration":"257.904488ms","start":"2026-07-02T05:57:45.388195Z","end":"2026-07-02T05:57:45.6461Z","steps":["trace[791009682] 'range keys from in-memory index tree' (duration: 257.653953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:45.930477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.264893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-02T05:57:45.930583Z","caller":"traceutil/trace.go:171","msg":"trace[1493819368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25152; }","duration":"110.418846ms","start":"2026-07-02T05:57:45.820141Z","end":"2026-07-02T05:57:45.930559Z","steps":["trace[1493819368] 'range keys from in-memory index tree' (duration: 110.05944ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:46.044784Z","caller":"traceutil/trace.go:171","msg":"trace[2118557746] transaction","detail":"{read_only:false; response_revision:25153; number_of_response:1; }","duration":"110.11188ms","start":"2026-07-02T05:57:45.934638Z","end":"2026-07-02T05:57:46.04475Z","steps":["trace[2118557746] 'process raft request' (duration: 109.826624ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:46.180674Z","caller":"traceutil/trace.go:171","msg":"trace[1063558245] transaction","detail":"{read_only:false; response_revision:25154; number_of_response:1; }","duration":"131.852264ms","start":"2026-07-02T05:57:46.04879Z","end":"2026-07-02T05:57:46.180643Z","steps":["trace[1063558245] 'process raft request' (duration: 123.74393ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:46.371919Z","caller":"traceutil/trace.go:171","msg":"trace[635168590] transaction","detail":"{read_only:false; response_revision:25155; number_of_response:1; }","duration":"142.033158ms","start":"2026-07-02T05:57:46.229856Z","end":"2026-07-02T05:57:46.371889Z","steps":["trace[635168590] 'process raft request' (duration: 95.864388ms)","trace[635168590] 'compare' (duration: 46.050758ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:50.899603Z","caller":"traceutil/trace.go:171","msg":"trace[1013989442] linearizableReadLoop","detail":"{readStateIndex:26018; appliedIndex:26017; }","duration":"372.672096ms","start":"2026-07-02T05:57:50.526876Z","end":"2026-07-02T05:57:50.899548Z","steps":["trace[1013989442] 'read index received' (duration: 320.493872ms)","trace[1013989442] 'applied index is now lower than readState.Index' (duration: 52.176584ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:50.899619Z","caller":"traceutil/trace.go:171","msg":"trace[347449150] transaction","detail":"{read_only:false; response_revision:25193; number_of_response:1; }","duration":"471.063323ms","start":"2026-07-02T05:57:50.428515Z","end":"2026-07-02T05:57:50.899578Z","steps":["trace[347449150] 'process raft request' (duration: 418.817728ms)","trace[347449150] 'compare' (duration: 52.067012ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:50.899783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:50.428497Z","time spent":"471.204006ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:57:50.899735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.84057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:57:50.899972Z","caller":"traceutil/trace.go:171","msg":"trace[1564488250] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:25193; }","duration":"373.124826ms","start":"2026-07-02T05:57:50.526824Z","end":"2026-07-02T05:57:50.899948Z","steps":["trace[1564488250] 'agreement among raft nodes before linearized reading' (duration: 372.82858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:50.900114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:50.526803Z","time spent":"373.293949ms","remote":"127.0.0.1:40344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true "} {"level":"info","ts":"2026-07-02T05:57:51.151402Z","caller":"traceutil/trace.go:171","msg":"trace[1541076774] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"550.441856ms","start":"2026-07-02T05:57:50.60093Z","end":"2026-07-02T05:57:51.151371Z","steps":["trace[1541076774] 'process raft request' (duration: 537.48062ms)","trace[1541076774] 'compare' (duration: 12.234823ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:51.151568Z","caller":"traceutil/trace.go:171","msg":"trace[1600107406] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"467.931502ms","start":"2026-07-02T05:57:50.683599Z","end":"2026-07-02T05:57:51.15153Z","steps":["trace[1600107406] 'process raft request' (duration: 467.549345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:51.151683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.897977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:57:51.151644Z","caller":"traceutil/trace.go:171","msg":"trace[715729946] linearizableReadLoop","detail":"{readStateIndex:26021; appliedIndex:26018; }","duration":"251.929843ms","start":"2026-07-02T05:57:50.899682Z","end":"2026-07-02T05:57:51.151611Z","steps":["trace[715729946] 'read index received' (duration: 238.661281ms)","trace[715729946] 'applied index is now lower than readState.Index' (duration: 13.266302ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:51.15171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:50.683576Z","time spent":"468.060085ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:57:51.15175Z","caller":"traceutil/trace.go:171","msg":"trace[1141282463] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:25196; }","duration":"343.980458ms","start":"2026-07-02T05:57:50.807756Z","end":"2026-07-02T05:57:51.151737Z","steps":["trace[1141282463] 'agreement among raft nodes before linearized reading' (duration: 343.867466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:51.151745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.771329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-07-02T05:57:51.151792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:50.807706Z","time spent":"344.074451ms","remote":"127.0.0.1:38028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"info","ts":"2026-07-02T05:57:51.151793Z","caller":"traceutil/trace.go:171","msg":"trace[1357034122] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25196; }","duration":"413.857442ms","start":"2026-07-02T05:57:50.737925Z","end":"2026-07-02T05:57:51.151783Z","steps":["trace[1357034122] 'agreement among raft nodes before linearized reading' (duration: 413.694448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:51.151832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:50.737912Z","time spent":"413.912023ms","remote":"127.0.0.1:57330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-02T05:57:51.151926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.168807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-02T05:57:51.152112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.152009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-02T05:57:51.15211Z","caller":"traceutil/trace.go:171","msg":"trace[1758443797] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:25196; }","duration":"178.25472ms","start":"2026-07-02T05:57:50.973714Z","end":"2026-07-02T05:57:51.151968Z","steps":["trace[1758443797] 'agreement among raft nodes before linearized reading' (duration: 178.158408ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:51.152145Z","caller":"traceutil/trace.go:171","msg":"trace[1589433812] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:25196; }","duration":"466.212691ms","start":"2026-07-02T05:57:50.685925Z","end":"2026-07-02T05:57:51.152137Z","steps":["trace[1589433812] 'agreement among raft nodes before linearized reading' (duration: 466.089188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:51.152168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:50.685914Z","time spent":"466.247421ms","remote":"127.0.0.1:57180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":141,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-07-02T05:57:51.15159Z","caller":"traceutil/trace.go:171","msg":"trace[184980528] transaction","detail":"{read_only:false; response_revision:25196; number_of_response:1; }","duration":"418.800266ms","start":"2026-07-02T05:57:50.732769Z","end":"2026-07-02T05:57:51.151569Z","steps":["trace[184980528] 'process raft request' (duration: 418.663624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:51.152248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.418195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:57:51.15246Z","caller":"traceutil/trace.go:171","msg":"trace[820474248] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25196; }","duration":"111.68427ms","start":"2026-07-02T05:57:51.04076Z","end":"2026-07-02T05:57:51.152444Z","steps":["trace[820474248] 'agreement among raft nodes before linearized reading' (duration: 111.344894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:51.152489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:50.732741Z","time spent":"419.563491ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:57:51.15162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:50.600902Z","time spent":"550.63722ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:57:51.153297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.30833ms","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-07-02T05:57:51.15335Z","caller":"traceutil/trace.go:171","msg":"trace[1674957488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25196; }","duration":"457.408532ms","start":"2026-07-02T05:57:50.695928Z","end":"2026-07-02T05:57:51.153336Z","steps":["trace[1674957488] 'agreement among raft nodes before linearized reading' (duration: 455.913274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:51.153848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:50.695903Z","time spent":"457.552674ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-02T05:57:51.432911Z","caller":"traceutil/trace.go:171","msg":"trace[900059973] transaction","detail":"{read_only:false; response_revision:25198; number_of_response:1; }","duration":"127.618804ms","start":"2026-07-02T05:57:51.305266Z","end":"2026-07-02T05:57:51.432885Z","steps":["trace[900059973] 'process raft request' (duration: 127.454931ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:51.695743Z","caller":"traceutil/trace.go:171","msg":"trace[1476063636] linearizableReadLoop","detail":"{readStateIndex:26025; appliedIndex:26022; }","duration":"350.250619ms","start":"2026-07-02T05:57:51.34547Z","end":"2026-07-02T05:57:51.69572Z","steps":["trace[1476063636] 'read index received' (duration: 87.299465ms)","trace[1476063636] 'applied index is now lower than readState.Index' (duration: 262.950234ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:51.695865Z","caller":"traceutil/trace.go:171","msg":"trace[1503463386] transaction","detail":"{read_only:false; response_revision:25200; number_of_response:1; }","duration":"360.912073ms","start":"2026-07-02T05:57:51.334942Z","end":"2026-07-02T05:57:51.695854Z","steps":["trace[1503463386] 'process raft request' (duration: 360.724099ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:51.695884Z","caller":"traceutil/trace.go:171","msg":"trace[1883927381] transaction","detail":"{read_only:false; response_revision:25199; number_of_response:1; }","duration":"362.27715ms","start":"2026-07-02T05:57:51.333573Z","end":"2026-07-02T05:57:51.69585Z","steps":["trace[1883927381] 'process raft request' (duration: 361.939393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:51.695972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:51.33493Z","time spent":"360.966324ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:57:51.69601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.526264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:57:51.696104Z","caller":"traceutil/trace.go:171","msg":"trace[1276952345] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:25200; }","duration":"350.662337ms","start":"2026-07-02T05:57:51.345434Z","end":"2026-07-02T05:57:51.696096Z","steps":["trace[1276952345] 'agreement among raft nodes before linearized reading' (duration: 350.461384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:51.696039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:51.333552Z","time spent":"362.406712ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:57:51.696124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:51.345421Z","time spent":"350.697928ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":19,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:57:51.876915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.263974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:57:51.877265Z","caller":"traceutil/trace.go:171","msg":"trace[1497943346] transaction","detail":"{read_only:false; response_revision:25201; number_of_response:1; }","duration":"176.390993ms","start":"2026-07-02T05:57:51.700854Z","end":"2026-07-02T05:57:51.877245Z","steps":["trace[1497943346] 'process raft request' (duration: 45.679031ms)","trace[1497943346] 'compare' (duration: 130.114791ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:52.388563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.052261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:57:52.38863Z","caller":"traceutil/trace.go:171","msg":"trace[1167475036] transaction","detail":"{read_only:false; response_revision:25203; number_of_response:1; }","duration":"158.761898ms","start":"2026-07-02T05:57:52.229855Z","end":"2026-07-02T05:57:52.388617Z","steps":["trace[1167475036] 'process raft request' (duration: 51.596184ms)","trace[1167475036] 'compare' (duration: 106.98155ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:52.649955Z","caller":"traceutil/trace.go:171","msg":"trace[328997802] linearizableReadLoop","detail":"{readStateIndex:26030; appliedIndex:26029; }","duration":"213.785266ms","start":"2026-07-02T05:57:52.436143Z","end":"2026-07-02T05:57:52.649928Z","steps":["trace[328997802] 'read index received' (duration: 213.675524ms)","trace[328997802] 'applied index is now lower than readState.Index' (duration: 108.432µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:52.650219Z","caller":"traceutil/trace.go:171","msg":"trace[1447144118] transaction","detail":"{read_only:false; response_revision:25205; number_of_response:1; }","duration":"214.561222ms","start":"2026-07-02T05:57:52.435644Z","end":"2026-07-02T05:57:52.650205Z","steps":["trace[1447144118] 'process raft request' (duration: 214.106273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:52.650518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.358148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-02T05:57:52.650659Z","caller":"traceutil/trace.go:171","msg":"trace[331297782] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25205; }","duration":"214.531202ms","start":"2026-07-02T05:57:52.436111Z","end":"2026-07-02T05:57:52.650642Z","steps":["trace[331297782] 'agreement among raft nodes before linearized reading' (duration: 214.261916ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:52.797054Z","caller":"traceutil/trace.go:171","msg":"trace[1736756457] linearizableReadLoop","detail":"{readStateIndex:26031; appliedIndex:26030; }","duration":"146.997814ms","start":"2026-07-02T05:57:52.650029Z","end":"2026-07-02T05:57:52.797026Z","steps":["trace[1736756457] 'read index received' (duration: 120.136451ms)","trace[1736756457] 'applied index is now lower than readState.Index' (duration: 26.860503ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:52.797269Z","caller":"traceutil/trace.go:171","msg":"trace[429744439] transaction","detail":"{read_only:false; response_revision:25206; number_of_response:1; }","duration":"354.793367ms","start":"2026-07-02T05:57:52.44243Z","end":"2026-07-02T05:57:52.797224Z","steps":["trace[429744439] 'process raft request' (duration: 327.71946ms)","trace[429744439] 'compare' (duration: 26.75055ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:52.797318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.356217ms","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:6"} {"level":"info","ts":"2026-07-02T05:57:52.797383Z","caller":"traceutil/trace.go:171","msg":"trace[1454304305] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:25206; }","duration":"300.48841ms","start":"2026-07-02T05:57:52.496878Z","end":"2026-07-02T05:57:52.797366Z","steps":["trace[1454304305] 'agreement among raft nodes before linearized reading' (duration: 300.319247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:52.797424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:52.496862Z","time spent":"300.551671ms","remote":"127.0.0.1:48122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:57:52.797421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.707075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:140"} {"level":"warn","ts":"2026-07-02T05:57:52.797465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:52.44242Z","time spent":"354.940459ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:57:52.797477Z","caller":"traceutil/trace.go:171","msg":"trace[822228646] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:25206; }","duration":"342.796917ms","start":"2026-07-02T05:57:52.454661Z","end":"2026-07-02T05:57:52.797458Z","steps":["trace[822228646] 'agreement among raft nodes before linearized reading' (duration: 342.631714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:52.797517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:52.45465Z","time spent":"342.858918ms","remote":"127.0.0.1:57160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/199.204.45.223\" "} {"level":"warn","ts":"2026-07-02T05:57:53.191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.774701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"warn","ts":"2026-07-02T05:57:53.191367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:52.798547Z","time spent":"392.815482ms","remote":"127.0.0.1:57160","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-02T05:57:53.191609Z","caller":"traceutil/trace.go:171","msg":"trace[1961763520] transaction","detail":"{read_only:false; response_revision:25207; number_of_response:1; }","duration":"537.072022ms","start":"2026-07-02T05:57:52.654517Z","end":"2026-07-02T05:57:53.191589Z","steps":["trace[1961763520] 'process raft request' (duration: 360.618897ms)","trace[1961763520] 'compare' (duration: 175.408664ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:53.191684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:52.654496Z","time spent":"537.153193ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:57:53.191745Z","caller":"traceutil/trace.go:171","msg":"trace[823050751] linearizableReadLoop","detail":"{readStateIndex:26032; appliedIndex:26031; }","duration":"394.580504ms","start":"2026-07-02T05:57:52.797131Z","end":"2026-07-02T05:57:53.191712Z","steps":["trace[823050751] 'read index received' (duration: 218.092179ms)","trace[823050751] 'applied index is now lower than readState.Index' (duration: 176.486105ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:53.192026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.338684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-02T05:57:53.192069Z","caller":"traceutil/trace.go:171","msg":"trace[465473589] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25207; }","duration":"464.430847ms","start":"2026-07-02T05:57:52.727627Z","end":"2026-07-02T05:57:53.192058Z","steps":["trace[465473589] 'agreement among raft nodes before linearized reading' (duration: 464.231193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:53.192111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:52.727613Z","time spent":"464.488447ms","remote":"127.0.0.1:57236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":379,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-07-02T05:57:53.546434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.048743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:57:53.546679Z","caller":"traceutil/trace.go:171","msg":"trace[1852323016] linearizableReadLoop","detail":"{readStateIndex:26036; appliedIndex:26033; }","duration":"354.828866ms","start":"2026-07-02T05:57:53.191828Z","end":"2026-07-02T05:57:53.546657Z","steps":["trace[1852323016] 'read index received' (duration: 8.868269ms)","trace[1852323016] 'applied index is now lower than readState.Index' (duration: 345.959327ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:53.546666Z","caller":"traceutil/trace.go:171","msg":"trace[96633201] transaction","detail":"{read_only:false; response_revision:25209; number_of_response:1; }","duration":"387.51859ms","start":"2026-07-02T05:57:53.159106Z","end":"2026-07-02T05:57:53.546625Z","steps":["trace[96633201] 'process raft request' (duration: 186.179411ms)","trace[96633201] 'compare' (duration: 200.87962ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:53.546844Z","caller":"traceutil/trace.go:171","msg":"trace[1077817867] transaction","detail":"{read_only:false; response_revision:25211; number_of_response:1; }","duration":"353.044142ms","start":"2026-07-02T05:57:53.193732Z","end":"2026-07-02T05:57:53.546776Z","steps":["trace[1077817867] 'process raft request' (duration: 352.865949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:53.54694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.531775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:57:53.546976Z","caller":"traceutil/trace.go:171","msg":"trace[371081611] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:25211; }","duration":"736.592296ms","start":"2026-07-02T05:57:52.810374Z","end":"2026-07-02T05:57:53.546966Z","steps":["trace[371081611] 'agreement among raft nodes before linearized reading' (duration: 736.527464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:53.547012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:52.810357Z","time spent":"736.646278ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-07-02T05:57:53.547017Z","caller":"traceutil/trace.go:171","msg":"trace[1479890479] transaction","detail":"{read_only:false; response_revision:25210; number_of_response:1; }","duration":"386.805057ms","start":"2026-07-02T05:57:53.16017Z","end":"2026-07-02T05:57:53.546975Z","steps":["trace[1479890479] 'process raft request' (duration: 386.369258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:53.547083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:53.193706Z","time spent":"353.241178ms","remote":"127.0.0.1:57160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:57:53.54718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:53.15907Z","time spent":"387.685002ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:57:53.547224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:53.16016Z","time spent":"386.95358ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:57:53.547511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.748801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-02T05:57:53.547579Z","caller":"traceutil/trace.go:171","msg":"trace[1063886133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25211; }","duration":"628.872403ms","start":"2026-07-02T05:57:52.918691Z","end":"2026-07-02T05:57:53.547563Z","steps":["trace[1063886133] 'agreement among raft nodes before linearized reading' (duration: 628.571378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:53.547773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:52.918664Z","time spent":"629.095897ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T05:57:53.705723Z","caller":"traceutil/trace.go:171","msg":"trace[434491811] transaction","detail":"{read_only:false; response_revision:25212; number_of_response:1; }","duration":"265.831899ms","start":"2026-07-02T05:57:53.439825Z","end":"2026-07-02T05:57:53.705657Z","steps":["trace[434491811] 'process raft request' (duration: 251.875983ms)","trace[434491811] 'compare' (duration: 13.367685ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:53.706134Z","caller":"traceutil/trace.go:171","msg":"trace[2012358865] linearizableReadLoop","detail":"{readStateIndex:26038; appliedIndex:26037; }","duration":"159.40321ms","start":"2026-07-02T05:57:53.546716Z","end":"2026-07-02T05:57:53.706119Z","steps":["trace[2012358865] 'read index received' (duration: 144.990615ms)","trace[2012358865] 'applied index is now lower than readState.Index' (duration: 14.409855ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:53.706289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.490427ms","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-07-02T05:57:53.706327Z","caller":"traceutil/trace.go:171","msg":"trace[328680243] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25212; }","duration":"497.588758ms","start":"2026-07-02T05:57:53.208726Z","end":"2026-07-02T05:57:53.706315Z","steps":["trace[328680243] 'agreement among raft nodes before linearized reading' (duration: 497.457845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:53.706353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:53.208701Z","time spent":"497.645559ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-02T05:57:53.707541Z","caller":"traceutil/trace.go:171","msg":"trace[1118139897] transaction","detail":"{read_only:false; response_revision:25213; number_of_response:1; }","duration":"154.608949ms","start":"2026-07-02T05:57:53.552922Z","end":"2026-07-02T05:57:53.707531Z","steps":["trace[1118139897] 'process raft request' (duration: 154.231631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:53.70809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.462083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-07-02T05:57:53.708209Z","caller":"traceutil/trace.go:171","msg":"trace[555816438] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25215; }","duration":"157.079355ms","start":"2026-07-02T05:57:53.551072Z","end":"2026-07-02T05:57:53.708151Z","steps":["trace[555816438] 'agreement among raft nodes before linearized reading' (duration: 156.365502ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:53.903826Z","caller":"traceutil/trace.go:171","msg":"trace[450032636] linearizableReadLoop","detail":"{readStateIndex:26042; appliedIndex:26041; }","duration":"190.793038ms","start":"2026-07-02T05:57:53.712994Z","end":"2026-07-02T05:57:53.903787Z","steps":["trace[450032636] 'read index received' (duration: 190.556444ms)","trace[450032636] 'applied index is now lower than readState.Index' (duration: 226.654µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:53.903958Z","caller":"traceutil/trace.go:171","msg":"trace[610031176] transaction","detail":"{read_only:false; response_revision:25216; number_of_response:1; }","duration":"192.703495ms","start":"2026-07-02T05:57:53.711242Z","end":"2026-07-02T05:57:53.903946Z","steps":["trace[610031176] 'process raft request' (duration: 192.323518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:53.904169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.154065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-02T05:57:53.904267Z","caller":"traceutil/trace.go:171","msg":"trace[1711433083] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:25216; }","duration":"191.295138ms","start":"2026-07-02T05:57:53.71295Z","end":"2026-07-02T05:57:53.904245Z","steps":["trace[1711433083] 'agreement among raft nodes before linearized reading' (duration: 191.022623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:53.987723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.501852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-07-02T05:57:53.987824Z","caller":"traceutil/trace.go:171","msg":"trace[1857644474] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25217; }","duration":"212.633515ms","start":"2026-07-02T05:57:53.775166Z","end":"2026-07-02T05:57:53.9878Z","steps":["trace[1857644474] 'agreement among raft nodes before linearized reading' (duration: 212.273128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:53.9881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.322142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:57:53.988146Z","caller":"traceutil/trace.go:171","msg":"trace[1049940622] transaction","detail":"{read_only:false; response_revision:25217; number_of_response:1; }","duration":"107.323627ms","start":"2026-07-02T05:57:53.880778Z","end":"2026-07-02T05:57:53.988102Z","steps":["trace[1049940622] 'process raft request' (duration: 106.473371ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:53.988225Z","caller":"traceutil/trace.go:171","msg":"trace[348648757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25217; }","duration":"155.519845ms","start":"2026-07-02T05:57:53.832677Z","end":"2026-07-02T05:57:53.988197Z","steps":["trace[348648757] 'agreement among raft nodes before linearized reading' (duration: 155.313782ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:54.340845Z","caller":"traceutil/trace.go:171","msg":"trace[1544084774] linearizableReadLoop","detail":"{readStateIndex:26044; appliedIndex:26043; }","duration":"111.298293ms","start":"2026-07-02T05:57:54.229519Z","end":"2026-07-02T05:57:54.340817Z","steps":["trace[1544084774] 'read index received' (duration: 110.995497ms)","trace[1544084774] 'applied index is now lower than readState.Index' (duration: 301.536µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:54.341208Z","caller":"traceutil/trace.go:171","msg":"trace[1782165998] transaction","detail":"{read_only:false; response_revision:25218; number_of_response:1; }","duration":"141.470997ms","start":"2026-07-02T05:57:54.199721Z","end":"2026-07-02T05:57:54.341192Z","steps":["trace[1782165998] 'process raft request' (duration: 140.910616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:54.341502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.975165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-02T05:57:54.341535Z","caller":"traceutil/trace.go:171","msg":"trace[1850074962] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25218; }","duration":"112.063537ms","start":"2026-07-02T05:57:54.229463Z","end":"2026-07-02T05:57:54.341526Z","steps":["trace[1850074962] 'agreement among raft nodes before linearized reading' (duration: 111.922155ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:54.451144Z","caller":"traceutil/trace.go:171","msg":"trace[737219713] transaction","detail":"{read_only:false; response_revision:25219; number_of_response:1; }","duration":"103.321561ms","start":"2026-07-02T05:57:54.347789Z","end":"2026-07-02T05:57:54.451111Z","steps":["trace[737219713] 'process raft request' (duration: 89.257922ms)","trace[737219713] 'compare' (duration: 13.502768ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:54.841836Z","caller":"traceutil/trace.go:171","msg":"trace[724923244] linearizableReadLoop","detail":"{readStateIndex:26047; appliedIndex:26046; }","duration":"295.401944ms","start":"2026-07-02T05:57:54.54641Z","end":"2026-07-02T05:57:54.841812Z","steps":["trace[724923244] 'read index received' (duration: 295.051377ms)","trace[724923244] 'applied index is now lower than readState.Index' (duration: 349.427µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:54.841975Z","caller":"traceutil/trace.go:171","msg":"trace[160383249] transaction","detail":"{read_only:false; response_revision:25221; number_of_response:1; }","duration":"385.754596ms","start":"2026-07-02T05:57:54.456189Z","end":"2026-07-02T05:57:54.841944Z","steps":["trace[160383249] 'process raft request' (duration: 385.342409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:54.842052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.622858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:57:54.842103Z","caller":"traceutil/trace.go:171","msg":"trace[357984758] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:25221; }","duration":"295.72859ms","start":"2026-07-02T05:57:54.546356Z","end":"2026-07-02T05:57:54.842085Z","steps":["trace[357984758] 'agreement among raft nodes before linearized reading' (duration: 295.596257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:54.842123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:54.456174Z","time spent":"385.866099ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:57:54.842489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.935782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T05:57:54.842532Z","caller":"traceutil/trace.go:171","msg":"trace[1261933130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25221; }","duration":"189.012894ms","start":"2026-07-02T05:57:54.653504Z","end":"2026-07-02T05:57:54.842517Z","steps":["trace[1261933130] 'agreement among raft nodes before linearized reading' (duration: 188.872231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:54.842908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.577977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:57:54.842949Z","caller":"traceutil/trace.go:171","msg":"trace[142568194] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:25221; }","duration":"187.653569ms","start":"2026-07-02T05:57:54.655281Z","end":"2026-07-02T05:57:54.842934Z","steps":["trace[142568194] 'agreement among raft nodes before linearized reading' (duration: 187.586848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:55.151806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.582388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:57:55.152009Z","caller":"traceutil/trace.go:171","msg":"trace[1106694899] transaction","detail":"{read_only:false; response_revision:25223; number_of_response:1; }","duration":"301.166922ms","start":"2026-07-02T05:57:54.850824Z","end":"2026-07-02T05:57:55.151991Z","steps":["trace[1106694899] 'process raft request' (duration: 301.111201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:55.152083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:54.850814Z","time spent":"301.237984ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:57:55.152351Z","caller":"traceutil/trace.go:171","msg":"trace[122783582] transaction","detail":"{read_only:false; response_revision:25222; number_of_response:1; }","duration":"302.674461ms","start":"2026-07-02T05:57:54.849649Z","end":"2026-07-02T05:57:55.152324Z","steps":["trace[122783582] 'process raft request' (duration: 137.483751ms)","trace[122783582] 'compare' (duration: 164.419565ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:55.152497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:54.84963Z","time spent":"302.798234ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:57:55.347917Z","caller":"traceutil/trace.go:171","msg":"trace[2127377620] transaction","detail":"{read_only:false; response_revision:25224; number_of_response:1; }","duration":"142.17005ms","start":"2026-07-02T05:57:55.205716Z","end":"2026-07-02T05:57:55.347886Z","steps":["trace[2127377620] 'process raft request' (duration: 127.723945ms)","trace[2127377620] 'compare' (duration: 14.15336ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:55.68541Z","caller":"traceutil/trace.go:171","msg":"trace[533571561] transaction","detail":"{read_only:false; response_revision:25225; number_of_response:1; }","duration":"132.262262ms","start":"2026-07-02T05:57:55.553131Z","end":"2026-07-02T05:57:55.685393Z","steps":["trace[533571561] 'process raft request' (duration: 132.16867ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:55.890538Z","caller":"traceutil/trace.go:171","msg":"trace[457716008] linearizableReadLoop","detail":"{readStateIndex:26052; appliedIndex:26050; }","duration":"277.143655ms","start":"2026-07-02T05:57:55.613374Z","end":"2026-07-02T05:57:55.890518Z","steps":["trace[457716008] 'read index received' (duration: 71.88021ms)","trace[457716008] 'applied index is now lower than readState.Index' (duration: 205.262815ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:55.890632Z","caller":"traceutil/trace.go:171","msg":"trace[674277028] transaction","detail":"{read_only:false; response_revision:25226; number_of_response:1; }","duration":"333.015371ms","start":"2026-07-02T05:57:55.557606Z","end":"2026-07-02T05:57:55.890621Z","steps":["trace[674277028] 'process raft request' (duration: 332.776296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:55.890755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:55.557581Z","time spent":"333.077542ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:57:55.890814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.329269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:57:55.890901Z","caller":"traceutil/trace.go:171","msg":"trace[512695362] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:25226; }","duration":"277.555642ms","start":"2026-07-02T05:57:55.613322Z","end":"2026-07-02T05:57:55.890878Z","steps":["trace[512695362] 'agreement among raft nodes before linearized reading' (duration: 277.309268ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:56.050749Z","caller":"traceutil/trace.go:171","msg":"trace[1049830476] transaction","detail":"{read_only:false; response_revision:25227; number_of_response:1; }","duration":"339.695187ms","start":"2026-07-02T05:57:55.71103Z","end":"2026-07-02T05:57:56.050725Z","steps":["trace[1049830476] 'process raft request' (duration: 270.858145ms)","trace[1049830476] 'compare' (duration: 68.535707ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:56.050773Z","caller":"traceutil/trace.go:171","msg":"trace[143955137] transaction","detail":"{read_only:false; response_revision:25228; number_of_response:1; }","duration":"337.373693ms","start":"2026-07-02T05:57:55.713371Z","end":"2026-07-02T05:57:56.050745Z","steps":["trace[143955137] 'process raft request' (duration: 337.252861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:56.05087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:55.711009Z","time spent":"339.80034ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:57:56.050871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:55.713349Z","time spent":"337.463635ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:57:56.050919Z","caller":"traceutil/trace.go:171","msg":"trace[1831149786] transaction","detail":"{read_only:false; response_revision:25230; number_of_response:1; }","duration":"137.174486ms","start":"2026-07-02T05:57:55.913718Z","end":"2026-07-02T05:57:56.050892Z","steps":["trace[1831149786] 'process raft request' (duration: 137.114114ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:56.051019Z","caller":"traceutil/trace.go:171","msg":"trace[1630218655] linearizableReadLoop","detail":"{readStateIndex:26055; appliedIndex:26052; }","duration":"160.413719ms","start":"2026-07-02T05:57:55.890594Z","end":"2026-07-02T05:57:56.051008Z","steps":["trace[1630218655] 'read index received' (duration: 91.203029ms)","trace[1630218655] 'applied index is now lower than readState.Index' (duration: 69.20965ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:57:56.050969Z","caller":"traceutil/trace.go:171","msg":"trace[1233446821] transaction","detail":"{read_only:false; response_revision:25229; number_of_response:1; }","duration":"337.505096ms","start":"2026-07-02T05:57:55.713453Z","end":"2026-07-02T05:57:56.050958Z","steps":["trace[1233446821] 'process raft request' (duration: 337.258351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:56.051116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.773362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:57:56.051124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:55.713447Z","time spent":"337.657199ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:57:56.051145Z","caller":"traceutil/trace.go:171","msg":"trace[1216501801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25230; }","duration":"218.831203ms","start":"2026-07-02T05:57:55.832306Z","end":"2026-07-02T05:57:56.051137Z","steps":["trace[1216501801] 'agreement among raft nodes before linearized reading' (duration: 218.778322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:56.051211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.593042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:57:56.051237Z","caller":"traceutil/trace.go:171","msg":"trace[1842176689] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:25230; }","duration":"116.647454ms","start":"2026-07-02T05:57:55.934583Z","end":"2026-07-02T05:57:56.051231Z","steps":["trace[1842176689] 'agreement among raft nodes before linearized reading' (duration: 116.603953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:56.051258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-07-02T05:57:56.05131Z","caller":"traceutil/trace.go:171","msg":"trace[1735347639] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25230; }","duration":"143.262232ms","start":"2026-07-02T05:57:55.908035Z","end":"2026-07-02T05:57:56.051298Z","steps":["trace[1735347639] 'agreement among raft nodes before linearized reading' (duration: 143.088889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:56.051395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.942823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-02T05:57:56.051416Z","caller":"traceutil/trace.go:171","msg":"trace[2108558629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25230; }","duration":"339.983004ms","start":"2026-07-02T05:57:55.711427Z","end":"2026-07-02T05:57:56.05141Z","steps":["trace[2108558629] 'agreement among raft nodes before linearized reading' (duration: 339.899412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:56.051434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:55.71142Z","time spent":"340.009355ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T05:57:56.051592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.922903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:57:56.051631Z","caller":"traceutil/trace.go:171","msg":"trace[1822971719] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:25230; }","duration":"135.003895ms","start":"2026-07-02T05:57:55.916619Z","end":"2026-07-02T05:57:56.051623Z","steps":["trace[1822971719] 'agreement among raft nodes before linearized reading' (duration: 134.851112ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:56.411541Z","caller":"traceutil/trace.go:171","msg":"trace[1715316515] linearizableReadLoop","detail":"{readStateIndex:26058; appliedIndex:26057; }","duration":"167.745549ms","start":"2026-07-02T05:57:56.243771Z","end":"2026-07-02T05:57:56.411517Z","steps":["trace[1715316515] 'read index received' (duration: 161.851367ms)","trace[1715316515] 'applied index is now lower than readState.Index' (duration: 5.892972ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:56.411736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.953373ms","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-07-02T05:57:56.411765Z","caller":"traceutil/trace.go:171","msg":"trace[2063158335] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25232; }","duration":"167.999993ms","start":"2026-07-02T05:57:56.243758Z","end":"2026-07-02T05:57:56.411758Z","steps":["trace[2063158335] 'agreement among raft nodes before linearized reading' (duration: 167.848421ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:56.411756Z","caller":"traceutil/trace.go:171","msg":"trace[1305341083] transaction","detail":"{read_only:false; response_revision:25232; number_of_response:1; }","duration":"355.171633ms","start":"2026-07-02T05:57:56.056552Z","end":"2026-07-02T05:57:56.411724Z","steps":["trace[1305341083] 'process raft request' (duration: 348.935314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:56.411925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:56.056534Z","time spent":"355.272655ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:57:56.846152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.719973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:57:56.846257Z","caller":"traceutil/trace.go:171","msg":"trace[235895888] linearizableReadLoop","detail":"{readStateIndex:26059; appliedIndex:26058; }","duration":"371.722968ms","start":"2026-07-02T05:57:56.474517Z","end":"2026-07-02T05:57:56.84624Z","steps":["trace[235895888] 'read index received' (duration: 252.731749ms)","trace[235895888] 'applied index is now lower than readState.Index' (duration: 118.990149ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:56.846361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.854021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:57:56.846394Z","caller":"traceutil/trace.go:171","msg":"trace[1676231996] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25233; }","duration":"371.923792ms","start":"2026-07-02T05:57:56.474461Z","end":"2026-07-02T05:57:56.846384Z","steps":["trace[1676231996] 'agreement among raft nodes before linearized reading' (duration: 371.864721ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:56.846364Z","caller":"traceutil/trace.go:171","msg":"trace[626877158] transaction","detail":"{read_only:false; response_revision:25233; number_of_response:1; }","duration":"386.888838ms","start":"2026-07-02T05:57:56.459429Z","end":"2026-07-02T05:57:56.846318Z","steps":["trace[626877158] 'process raft request' (duration: 267.92166ms)","trace[626877158] 'compare' (duration: 118.615711ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:56.846432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:56.474438Z","time spent":"371.986783ms","remote":"127.0.0.1:57592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-02T05:57:56.846525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.833992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"warn","ts":"2026-07-02T05:57:56.846533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:56.459416Z","time spent":"387.03336ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:57:56.846593Z","caller":"traceutil/trace.go:171","msg":"trace[29707345] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25233; }","duration":"305.961654ms","start":"2026-07-02T05:57:56.540615Z","end":"2026-07-02T05:57:56.846577Z","steps":["trace[29707345] 'agreement among raft nodes before linearized reading' (duration: 305.75723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:57:56.846575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.510489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-02T05:57:56.846627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:57:56.540583Z","time spent":"306.039416ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-07-02T05:57:56.84674Z","caller":"traceutil/trace.go:171","msg":"trace[641367829] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25233; }","duration":"290.704242ms","start":"2026-07-02T05:57:56.55596Z","end":"2026-07-02T05:57:56.846665Z","steps":["trace[641367829] 'agreement among raft nodes before linearized reading' (duration: 290.481948ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:57.088126Z","caller":"traceutil/trace.go:171","msg":"trace[1603722475] linearizableReadLoop","detail":"{readStateIndex:26061; appliedIndex:26060; }","duration":"168.298789ms","start":"2026-07-02T05:57:56.919808Z","end":"2026-07-02T05:57:57.088107Z","steps":["trace[1603722475] 'read index received' (duration: 144.935103ms)","trace[1603722475] 'applied index is now lower than readState.Index' (duration: 23.362676ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:57:57.088304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.473152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-07-02T05:57:57.088352Z","caller":"traceutil/trace.go:171","msg":"trace[499999706] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25235; }","duration":"168.563184ms","start":"2026-07-02T05:57:56.919781Z","end":"2026-07-02T05:57:57.088344Z","steps":["trace[499999706] 'agreement among raft nodes before linearized reading' (duration: 168.400911ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:57:57.088509Z","caller":"traceutil/trace.go:171","msg":"trace[1375814688] transaction","detail":"{read_only:false; response_revision:25235; number_of_response:1; }","duration":"175.560398ms","start":"2026-07-02T05:57:56.912939Z","end":"2026-07-02T05:57:57.088499Z","steps":["trace[1375814688] 'process raft request' (duration: 151.802384ms)","trace[1375814688] 'compare' (duration: 23.277815ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:03.982413Z","caller":"traceutil/trace.go:171","msg":"trace[748082240] linearizableReadLoop","detail":"{readStateIndex:26110; appliedIndex:26109; }","duration":"213.798847ms","start":"2026-07-02T05:58:03.768589Z","end":"2026-07-02T05:58:03.982388Z","steps":["trace[748082240] 'read index received' (duration: 213.633214ms)","trace[748082240] 'applied index is now lower than readState.Index' (duration: 164.743µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:58:03.982549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.94055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:58:03.982525Z","caller":"traceutil/trace.go:171","msg":"trace[1023905098] transaction","detail":"{read_only:false; response_revision:25282; number_of_response:1; }","duration":"268.013101ms","start":"2026-07-02T05:58:03.714474Z","end":"2026-07-02T05:58:03.982487Z","steps":["trace[1023905098] 'process raft request' (duration: 267.766707ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:03.982583Z","caller":"traceutil/trace.go:171","msg":"trace[169741451] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25282; }","duration":"213.990041ms","start":"2026-07-02T05:58:03.768582Z","end":"2026-07-02T05:58:03.982572Z","steps":["trace[169741451] 'agreement among raft nodes before linearized reading' (duration: 213.919539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:04.594081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.529049ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:58:04.594243Z","caller":"traceutil/trace.go:171","msg":"trace[2080648042] linearizableReadLoop","detail":"{readStateIndex:26111; appliedIndex:26110; }","duration":"611.746426ms","start":"2026-07-02T05:58:03.982476Z","end":"2026-07-02T05:58:04.594223Z","steps":["trace[2080648042] 'read index received' (duration: 479.913091ms)","trace[2080648042] 'applied index is now lower than readState.Index' (duration: 131.831695ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:04.594326Z","caller":"traceutil/trace.go:171","msg":"trace[137837902] transaction","detail":"{read_only:false; response_revision:25283; number_of_response:1; }","duration":"671.819232ms","start":"2026-07-02T05:58:03.922475Z","end":"2026-07-02T05:58:04.594294Z","steps":["trace[137837902] 'process raft request' (duration: 539.914916ms)","trace[137837902] 'compare' (duration: 131.008629ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:58:04.594406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.456584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:58:04.594468Z","caller":"traceutil/trace.go:171","msg":"trace[536911564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25283; }","duration":"758.554966ms","start":"2026-07-02T05:58:03.835902Z","end":"2026-07-02T05:58:04.594457Z","steps":["trace[536911564] 'agreement among raft nodes before linearized reading' (duration: 758.432934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:04.594476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:03.922447Z","time spent":"671.946904ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:58:04.594502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:03.835881Z","time spent":"758.612387ms","remote":"127.0.0.1:57136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:58:04.594846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.899063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:58:04.594923Z","caller":"traceutil/trace.go:171","msg":"trace[1514480893] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25283; }","duration":"790.021295ms","start":"2026-07-02T05:58:03.804889Z","end":"2026-07-02T05:58:04.594911Z","steps":["trace[1514480893] 'agreement among raft nodes before linearized reading' (duration: 789.910373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:04.595006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:03.804863Z","time spent":"790.128907ms","remote":"127.0.0.1:38200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:58:05.094368Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521227236820602407,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T05:58:05.247274Z","caller":"traceutil/trace.go:171","msg":"trace[1787575572] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"1.166125137s","start":"2026-07-02T05:58:04.081121Z","end":"2026-07-02T05:58:05.247246Z","steps":["trace[1787575572] 'process raft request' (duration: 1.165905033s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:05.247521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:04.081082Z","time spent":"1.16627253s","remote":"127.0.0.1:57422","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-07-02T05:58:05.247831Z","caller":"traceutil/trace.go:171","msg":"trace[181583604] transaction","detail":"{read_only:false; response_revision:25285; number_of_response:1; }","duration":"1.144753698s","start":"2026-07-02T05:58:04.103057Z","end":"2026-07-02T05:58:05.24781Z","steps":["trace[181583604] 'process raft request' (duration: 1.144143187s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:05.247932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:04.10302Z","time spent":"1.144865061s","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:58:05.248714Z","caller":"traceutil/trace.go:171","msg":"trace[837359] linearizableReadLoop","detail":"{readStateIndex:26115; appliedIndex:26111; }","duration":"654.384339ms","start":"2026-07-02T05:58:04.594303Z","end":"2026-07-02T05:58:05.248688Z","steps":["trace[837359] 'read index received' (duration: 652.628265ms)","trace[837359] 'applied index is now lower than readState.Index' (duration: 1.755084ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:05.248764Z","caller":"traceutil/trace.go:171","msg":"trace[765912700] transaction","detail":"{read_only:false; response_revision:25287; number_of_response:1; }","duration":"891.814837ms","start":"2026-07-02T05:58:04.356923Z","end":"2026-07-02T05:58:05.248738Z","steps":["trace[765912700] 'process raft request' (duration: 891.682994ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:05.248934Z","caller":"traceutil/trace.go:171","msg":"trace[1055709865] transaction","detail":"{read_only:false; response_revision:25286; number_of_response:1; }","duration":"1.145128157s","start":"2026-07-02T05:58:04.103786Z","end":"2026-07-02T05:58:05.248914Z","steps":["trace[1055709865] 'process raft request' (duration: 1.143856592s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:05.248954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:04.356893Z","time spent":"891.93498ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:58:05.248991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.883454ms","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":"warn","ts":"2026-07-02T05:58:05.249043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.91478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:58:05.249076Z","caller":"traceutil/trace.go:171","msg":"trace[728648748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25287; }","duration":"652.983572ms","start":"2026-07-02T05:58:04.59608Z","end":"2026-07-02T05:58:05.249064Z","steps":["trace[728648748] 'agreement among raft nodes before linearized reading' (duration: 652.941371ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:05.24907Z","caller":"traceutil/trace.go:171","msg":"trace[1462758616] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25287; }","duration":"799.010367ms","start":"2026-07-02T05:58:04.45004Z","end":"2026-07-02T05:58:05.24905Z","steps":["trace[1462758616] 'agreement among raft nodes before linearized reading' (duration: 798.785812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:05.249084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:04.103772Z","time spent":"1.145237529s","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:58:05.249119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:04.596057Z","time spent":"653.054503ms","remote":"127.0.0.1:57132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:58:05.24913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:04.450019Z","time spent":"799.097048ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T05:58:05.248988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109843294s","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-07-02T05:58:05.249264Z","caller":"traceutil/trace.go:171","msg":"trace[1394786552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25287; }","duration":"1.11015861s","start":"2026-07-02T05:58:04.139085Z","end":"2026-07-02T05:58:05.249244Z","steps":["trace[1394786552] 'agreement among raft nodes before linearized reading' (duration: 1.109756343s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:05.249326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:04.139069Z","time spent":"1.110240792s","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T05:58:05.249492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.734172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:58:05.249532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.245774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:58:05.249582Z","caller":"traceutil/trace.go:171","msg":"trace[1746267165] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:25287; }","duration":"475.937486ms","start":"2026-07-02T05:58:04.773628Z","end":"2026-07-02T05:58:05.249565Z","steps":["trace[1746267165] 'agreement among raft nodes before linearized reading' (duration: 475.764453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:05.249583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.073608ms","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-07-02T05:58:05.249625Z","caller":"traceutil/trace.go:171","msg":"trace[1272810043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25287; }","duration":"122.17914ms","start":"2026-07-02T05:58:05.127435Z","end":"2026-07-02T05:58:05.249614Z","steps":["trace[1272810043] 'agreement among raft nodes before linearized reading' (duration: 122.065218ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:05.249624Z","caller":"traceutil/trace.go:171","msg":"trace[676778121] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25287; }","duration":"646.389047ms","start":"2026-07-02T05:58:04.603218Z","end":"2026-07-02T05:58:05.249607Z","steps":["trace[676778121] 'agreement among raft nodes before linearized reading' (duration: 646.222714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:05.249712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.206858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T05:58:05.249716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:04.773605Z","time spent":"476.012287ms","remote":"127.0.0.1:52912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-07-02T05:58:05.249756Z","caller":"traceutil/trace.go:171","msg":"trace[102249007] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:25287; }","duration":"490.30889ms","start":"2026-07-02T05:58:04.759432Z","end":"2026-07-02T05:58:05.249741Z","steps":["trace[102249007] 'agreement among raft nodes before linearized reading' (duration: 490.214068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:05.249763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.872978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-02T05:58:05.249733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:04.603198Z","time spent":"646.522989ms","remote":"127.0.0.1:38018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-07-02T05:58:05.249795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:04.759412Z","time spent":"490.374051ms","remote":"127.0.0.1:36192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:58:05.249757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.931468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-07-02T05:58:05.249896Z","caller":"traceutil/trace.go:171","msg":"trace[1920256696] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25287; }","duration":"301.131992ms","start":"2026-07-02T05:58:04.948733Z","end":"2026-07-02T05:58:05.249865Z","steps":["trace[1920256696] 'agreement among raft nodes before linearized reading' (duration: 300.686994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:05.249959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:04.948711Z","time spent":"301.237844ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-07-02T05:58:05.249815Z","caller":"traceutil/trace.go:171","msg":"trace[1661711804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25287; }","duration":"353.963189ms","start":"2026-07-02T05:58:04.895838Z","end":"2026-07-02T05:58:05.249801Z","steps":["trace[1661711804] 'agreement among raft nodes before linearized reading' (duration: 353.684424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:05.2501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:04.895817Z","time spent":"354.258875ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-02T05:58:05.637786Z","caller":"traceutil/trace.go:171","msg":"trace[107282270] transaction","detail":"{read_only:false; response_revision:25288; number_of_response:1; }","duration":"383.85571ms","start":"2026-07-02T05:58:05.253896Z","end":"2026-07-02T05:58:05.637751Z","steps":["trace[107282270] 'process raft request' (duration: 299.146134ms)","trace[107282270] 'compare' (duration: 84.303828ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:58:05.637912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:05.253881Z","time spent":"383.967162ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:58:06.247964Z","caller":"traceutil/trace.go:171","msg":"trace[1541138800] transaction","detail":"{read_only:false; response_revision:25291; number_of_response:1; }","duration":"993.155429ms","start":"2026-07-02T05:58:05.254784Z","end":"2026-07-02T05:58:06.247939Z","steps":["trace[1541138800] 'process raft request' (duration: 993.083917ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:06.248059Z","caller":"traceutil/trace.go:171","msg":"trace[1736869622] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"993.705629ms","start":"2026-07-02T05:58:05.254311Z","end":"2026-07-02T05:58:06.248017Z","steps":["trace[1736869622] 'process raft request' (duration: 990.520758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.248107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:05.25474Z","time spent":"993.294781ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:58:06.248198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:05.254299Z","time spent":"993.823771ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:58:06.248346Z","caller":"traceutil/trace.go:171","msg":"trace[1789129674] transaction","detail":"{read_only:false; response_revision:25290; number_of_response:1; }","duration":"993.548367ms","start":"2026-07-02T05:58:05.254786Z","end":"2026-07-02T05:58:06.248335Z","steps":["trace[1789129674] 'process raft request' (duration: 992.981555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.248401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:05.254775Z","time spent":"993.595328ms","remote":"127.0.0.1:57422","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-07-02T05:58:06.248488Z","caller":"traceutil/trace.go:171","msg":"trace[1691622932] transaction","detail":"{read_only:false; response_revision:25292; number_of_response:1; }","duration":"993.471566ms","start":"2026-07-02T05:58:05.255004Z","end":"2026-07-02T05:58:06.248476Z","steps":["trace[1691622932] 'process raft request' (duration: 992.904074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.248552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:05.254982Z","time spent":"993.532606ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:58:06.24875Z","caller":"traceutil/trace.go:171","msg":"trace[1675315300] linearizableReadLoop","detail":"{readStateIndex:26120; appliedIndex:26115; }","duration":"992.254523ms","start":"2026-07-02T05:58:05.256486Z","end":"2026-07-02T05:58:06.24874Z","steps":["trace[1675315300] 'read index received' (duration: 296.556485ms)","trace[1675315300] 'applied index is now lower than readState.Index' (duration: 695.696918ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:06.248809Z","caller":"traceutil/trace.go:171","msg":"trace[1191979890] transaction","detail":"{read_only:false; response_revision:25293; number_of_response:1; }","duration":"837.245746ms","start":"2026-07-02T05:58:05.411556Z","end":"2026-07-02T05:58:06.248801Z","steps":["trace[1191979890] 'process raft request' (duration: 836.841748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.248859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:05.411527Z","time spent":"837.300317ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:58:06.248872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.389335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:58:06.248906Z","caller":"traceutil/trace.go:171","msg":"trace[1265932506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25293; }","duration":"992.480026ms","start":"2026-07-02T05:58:05.256416Z","end":"2026-07-02T05:58:06.248896Z","steps":["trace[1265932506] 'agreement among raft nodes before linearized reading' (duration: 992.416285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.248935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:05.256406Z","time spent":"992.523728ms","remote":"127.0.0.1:57136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T05:58:06.305728Z","caller":"traceutil/trace.go:171","msg":"trace[2128879149] transaction","detail":"{read_only:false; response_revision:25294; number_of_response:1; }","duration":"318.680267ms","start":"2026-07-02T05:58:05.987021Z","end":"2026-07-02T05:58:06.305701Z","steps":["trace[2128879149] 'process raft request' (duration: 318.31236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.305874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:05.986987Z","time spent":"318.80854ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:58:06.306076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.151792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:58:06.306144Z","caller":"traceutil/trace.go:171","msg":"trace[868870796] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:25294; }","duration":"452.220593ms","start":"2026-07-02T05:58:05.853911Z","end":"2026-07-02T05:58:06.306131Z","steps":["trace[868870796] 'agreement among raft nodes before linearized reading' (duration: 452.126231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.306182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:05.853884Z","time spent":"452.288614ms","remote":"127.0.0.1:52946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:58:06.306355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.21238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:58:06.306391Z","caller":"traceutil/trace.go:171","msg":"trace[961356795] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:25294; }","duration":"487.287632ms","start":"2026-07-02T05:58:05.819095Z","end":"2026-07-02T05:58:06.306383Z","steps":["trace[961356795] 'agreement among raft nodes before linearized reading' (duration: 487.229471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.306425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:05.819072Z","time spent":"487.345974ms","remote":"127.0.0.1:38114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:58:06.306586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.925153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:58:06.306621Z","caller":"traceutil/trace.go:171","msg":"trace[1491065282] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25294; }","duration":"584.989855ms","start":"2026-07-02T05:58:05.721622Z","end":"2026-07-02T05:58:06.306612Z","steps":["trace[1491065282] 'agreement among raft nodes before linearized reading' (duration: 584.936294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.306653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:05.721603Z","time spent":"585.042277ms","remote":"127.0.0.1:38190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:58:06.306792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.010078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T05:58:06.306892Z","caller":"traceutil/trace.go:171","msg":"trace[626872477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25294; }","duration":"990.147751ms","start":"2026-07-02T05:58:05.316723Z","end":"2026-07-02T05:58:06.306871Z","steps":["trace[626872477] 'agreement among raft nodes before linearized reading' (duration: 989.848955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.306977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:05.316704Z","time spent":"990.260413ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T05:58:06.307214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.553324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:58:06.307287Z","caller":"traceutil/trace.go:171","msg":"trace[1298071189] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:25294; }","duration":"706.655667ms","start":"2026-07-02T05:58:05.600616Z","end":"2026-07-02T05:58:06.307272Z","steps":["trace[1298071189] 'agreement among raft nodes before linearized reading' (duration: 706.559415ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.307354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:05.600591Z","time spent":"706.750808ms","remote":"127.0.0.1:52956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:58:06.30757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.943977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:58:06.307673Z","caller":"traceutil/trace.go:171","msg":"trace[1497649653] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:25294; }","duration":"829.021548ms","start":"2026-07-02T05:58:05.478613Z","end":"2026-07-02T05:58:06.307635Z","steps":["trace[1497649653] 'agreement among raft nodes before linearized reading' (duration: 828.922216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.307733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:05.47856Z","time spent":"829.160881ms","remote":"127.0.0.1:40570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":30,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:58:06.307973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.133086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:58:06.30837Z","caller":"traceutil/trace.go:171","msg":"trace[112858489] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25294; }","duration":"889.605495ms","start":"2026-07-02T05:58:05.418752Z","end":"2026-07-02T05:58:06.308358Z","steps":["trace[112858489] 'agreement among raft nodes before linearized reading' (duration: 889.146876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.308276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.07507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"warn","ts":"2026-07-02T05:58:06.308431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.649272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-02T05:58:06.308468Z","caller":"traceutil/trace.go:171","msg":"trace[638241174] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25294; }","duration":"937.322005ms","start":"2026-07-02T05:58:05.371126Z","end":"2026-07-02T05:58:06.308448Z","steps":["trace[638241174] 'agreement among raft nodes before linearized reading' (duration: 937.05714ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:06.308495Z","caller":"traceutil/trace.go:171","msg":"trace[1788820234] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25294; }","duration":"989.727023ms","start":"2026-07-02T05:58:05.318749Z","end":"2026-07-02T05:58:06.308476Z","steps":["trace[1788820234] 'agreement among raft nodes before linearized reading' (duration: 989.55259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.308526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:05.318743Z","time spent":"989.776064ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-02T05:58:06.308529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:05.371079Z","time spent":"937.432957ms","remote":"127.0.0.1:57336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2011,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-07-02T05:58:06.308538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:05.418743Z","time spent":"889.691716ms","remote":"127.0.0.1:57578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:58:06.640146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.643053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:58:06.640253Z","caller":"traceutil/trace.go:171","msg":"trace[1647266267] transaction","detail":"{read_only:false; response_revision:25295; number_of_response:1; }","duration":"328.117597ms","start":"2026-07-02T05:58:06.312116Z","end":"2026-07-02T05:58:06.640233Z","steps":["trace[1647266267] 'process raft request' (duration: 112.975156ms)","trace[1647266267] 'compare' (duration: 214.191043ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:58:06.640327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:06.312094Z","time spent":"328.190458ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:58:06.727235Z","caller":"traceutil/trace.go:171","msg":"trace[69325839] transaction","detail":"{read_only:false; response_revision:25296; number_of_response:1; }","duration":"413.204389ms","start":"2026-07-02T05:58:06.314011Z","end":"2026-07-02T05:58:06.727216Z","steps":["trace[69325839] 'process raft request' (duration: 413.003225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.727319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:06.313991Z","time spent":"413.286871ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:58:06.727343Z","caller":"traceutil/trace.go:171","msg":"trace[83244712] transaction","detail":"{read_only:false; response_revision:25300; number_of_response:1; }","duration":"126.92379ms","start":"2026-07-02T05:58:06.600395Z","end":"2026-07-02T05:58:06.727319Z","steps":["trace[83244712] 'process raft request' (duration: 126.878309ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:06.727545Z","caller":"traceutil/trace.go:171","msg":"trace[2123324948] transaction","detail":"{read_only:false; response_revision:25298; number_of_response:1; }","duration":"411.858264ms","start":"2026-07-02T05:58:06.315678Z","end":"2026-07-02T05:58:06.727537Z","steps":["trace[2123324948] 'process raft request' (duration: 411.519657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.727593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:06.315669Z","time spent":"411.899725ms","remote":"127.0.0.1:57452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":870,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:58:06.727633Z","caller":"traceutil/trace.go:171","msg":"trace[466273215] transaction","detail":"{read_only:false; response_revision:25299; number_of_response:1; }","duration":"345.480809ms","start":"2026-07-02T05:58:06.382142Z","end":"2026-07-02T05:58:06.727622Z","steps":["trace[466273215] 'process raft request' (duration: 345.08846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.727737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:06.382112Z","time spent":"345.54551ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:58:06.72778Z","caller":"traceutil/trace.go:171","msg":"trace[70028468] transaction","detail":"{read_only:false; response_revision:25297; number_of_response:1; }","duration":"412.312433ms","start":"2026-07-02T05:58:06.315458Z","end":"2026-07-02T05:58:06.727771Z","steps":["trace[70028468] 'process raft request' (duration: 411.689231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.727833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:06.315447Z","time spent":"412.364395ms","remote":"127.0.0.1:57452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":925,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:58:06.727875Z","caller":"traceutil/trace.go:171","msg":"trace[668069667] linearizableReadLoop","detail":"{readStateIndex:26126; appliedIndex:26122; }","duration":"409.945518ms","start":"2026-07-02T05:58:06.317919Z","end":"2026-07-02T05:58:06.727865Z","steps":["trace[668069667] 'read index received' (duration: 107.196525ms)","trace[668069667] 'applied index is now lower than readState.Index' (duration: 302.747833ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:58:06.728133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.207213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:58:06.728166Z","caller":"traceutil/trace.go:171","msg":"trace[776563790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25300; }","duration":"410.240874ms","start":"2026-07-02T05:58:06.317916Z","end":"2026-07-02T05:58:06.728156Z","steps":["trace[776563790] 'agreement among raft nodes before linearized reading' (duration: 410.184893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.728168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.692534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:58:06.728194Z","caller":"traceutil/trace.go:171","msg":"trace[785864587] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25300; }","duration":"376.740315ms","start":"2026-07-02T05:58:06.351446Z","end":"2026-07-02T05:58:06.728186Z","steps":["trace[785864587] 'agreement among raft nodes before linearized reading' (duration: 376.695754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:06.728193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:06.31789Z","time spent":"410.297585ms","remote":"127.0.0.1:57136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T05:58:06.728214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:06.351432Z","time spent":"376.777836ms","remote":"127.0.0.1:38018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-07-02T05:58:06.979737Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000663f.wal"} {"level":"info","ts":"2026-07-02T05:58:13.608053Z","caller":"traceutil/trace.go:171","msg":"trace[156214911] transaction","detail":"{read_only:false; response_revision:25425; number_of_response:1; }","duration":"334.685732ms","start":"2026-07-02T05:58:13.273339Z","end":"2026-07-02T05:58:13.608025Z","steps":["trace[156214911] 'process raft request' (duration: 285.387712ms)","trace[156214911] 'compare' (duration: 49.121787ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:58:13.608215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:13.273312Z","time spent":"334.824335ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:58:13.608222Z","caller":"traceutil/trace.go:171","msg":"trace[1471213460] linearizableReadLoop","detail":"{readStateIndex:26256; appliedIndex:26254; }","duration":"170.96442ms","start":"2026-07-02T05:58:13.437218Z","end":"2026-07-02T05:58:13.608183Z","steps":["trace[1471213460] 'read index received' (duration: 121.480456ms)","trace[1471213460] 'applied index is now lower than readState.Index' (duration: 49.480144ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:13.608264Z","caller":"traceutil/trace.go:171","msg":"trace[1003360057] transaction","detail":"{read_only:false; response_revision:25426; number_of_response:1; }","duration":"331.899419ms","start":"2026-07-02T05:58:13.276334Z","end":"2026-07-02T05:58:13.608234Z","steps":["trace[1003360057] 'process raft request' (duration: 331.649965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:13.608419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:13.276324Z","time spent":"332.009212ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:58:13.608496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.265556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10359"} {"level":"info","ts":"2026-07-02T05:58:13.608591Z","caller":"traceutil/trace.go:171","msg":"trace[1341902517] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25426; }","duration":"171.394708ms","start":"2026-07-02T05:58:13.437162Z","end":"2026-07-02T05:58:13.608556Z","steps":["trace[1341902517] 'agreement among raft nodes before linearized reading' (duration: 171.151654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:44.217493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.405112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:58:44.217598Z","caller":"traceutil/trace.go:171","msg":"trace[1353515128] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25674; }","duration":"383.550944ms","start":"2026-07-02T05:58:43.834021Z","end":"2026-07-02T05:58:44.217572Z","steps":["trace[1353515128] 'count revisions from in-memory index tree' (duration: 383.32332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:44.217655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:43.834001Z","time spent":"383.642177ms","remote":"127.0.0.1:40104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:58:44.217796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.843675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:58:44.217876Z","caller":"traceutil/trace.go:171","msg":"trace[609216599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25674; }","duration":"377.966957ms","start":"2026-07-02T05:58:43.839888Z","end":"2026-07-02T05:58:44.217854Z","steps":["trace[609216599] 'range keys from in-memory index tree' (duration: 377.768674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:44.217915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:43.839869Z","time spent":"378.038599ms","remote":"127.0.0.1:57136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T05:58:44.218472Z","caller":"traceutil/trace.go:171","msg":"trace[1162595080] linearizableReadLoop","detail":"{readStateIndex:26511; appliedIndex:26510; }","duration":"313.921227ms","start":"2026-07-02T05:58:43.904522Z","end":"2026-07-02T05:58:44.218443Z","steps":["trace[1162595080] 'read index received' (duration: 253.805739ms)","trace[1162595080] 'applied index is now lower than readState.Index' (duration: 60.114278ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:44.218621Z","caller":"traceutil/trace.go:171","msg":"trace[1650604482] transaction","detail":"{read_only:false; response_revision:25675; number_of_response:1; }","duration":"350.368041ms","start":"2026-07-02T05:58:43.868231Z","end":"2026-07-02T05:58:44.218599Z","steps":["trace[1650604482] 'process raft request' (duration: 290.217794ms)","trace[1650604482] 'compare' (duration: 59.235169ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:58:44.218756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:43.86821Z","time spent":"350.462543ms","remote":"127.0.0.1:40570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418662,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:58:44.218849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.318414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-02T05:58:44.218884Z","caller":"traceutil/trace.go:171","msg":"trace[1959411754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25675; }","duration":"314.399306ms","start":"2026-07-02T05:58:43.904476Z","end":"2026-07-02T05:58:44.218875Z","steps":["trace[1959411754] 'agreement among raft nodes before linearized reading' (duration: 314.055179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:44.218926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:43.904458Z","time spent":"314.462136ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T05:58:44.397105Z","caller":"traceutil/trace.go:171","msg":"trace[2122441396] transaction","detail":"{read_only:false; response_revision:25676; number_of_response:1; }","duration":"174.729211ms","start":"2026-07-02T05:58:44.222354Z","end":"2026-07-02T05:58:44.397083Z","steps":["trace[2122441396] 'process raft request' (duration: 140.792254ms)","trace[2122441396] 'compare' (duration: 33.847666ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:58:44.65059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.409856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:58:44.650728Z","caller":"traceutil/trace.go:171","msg":"trace[487317048] linearizableReadLoop","detail":"{readStateIndex:26514; appliedIndex:26512; }","duration":"220.173489ms","start":"2026-07-02T05:58:44.430542Z","end":"2026-07-02T05:58:44.650715Z","steps":["trace[487317048] 'read index received' (duration: 77.650481ms)","trace[487317048] 'applied index is now lower than readState.Index' (duration: 142.522228ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:58:44.650895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.350952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-02T05:58:44.650895Z","caller":"traceutil/trace.go:171","msg":"trace[1888776703] transaction","detail":"{read_only:false; response_revision:25678; number_of_response:1; }","duration":"229.330343ms","start":"2026-07-02T05:58:44.421538Z","end":"2026-07-02T05:58:44.650869Z","steps":["trace[1888776703] 'process raft request' (duration: 229.132199ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:44.650897Z","caller":"traceutil/trace.go:171","msg":"trace[790659093] transaction","detail":"{read_only:false; response_revision:25677; number_of_response:1; }","duration":"248.01925ms","start":"2026-07-02T05:58:44.402852Z","end":"2026-07-02T05:58:44.650871Z","steps":["trace[790659093] 'process raft request' (duration: 105.263488ms)","trace[790659093] 'compare' (duration: 142.207402ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:58:44.650932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.121306ms","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-07-02T05:58:44.650945Z","caller":"traceutil/trace.go:171","msg":"trace[437365396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25678; }","duration":"220.436164ms","start":"2026-07-02T05:58:44.430493Z","end":"2026-07-02T05:58:44.650929Z","steps":["trace[437365396] 'agreement among raft nodes before linearized reading' (duration: 220.306592ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:44.650963Z","caller":"traceutil/trace.go:171","msg":"trace[1086769399] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25678; }","duration":"211.169207ms","start":"2026-07-02T05:58:44.439784Z","end":"2026-07-02T05:58:44.650953Z","steps":["trace[1086769399] 'agreement among raft nodes before linearized reading' (duration: 211.090105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:44.651116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.818971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-02T05:58:44.651104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.236932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-07-02T05:58:44.651179Z","caller":"traceutil/trace.go:171","msg":"trace[146153756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25678; }","duration":"209.937034ms","start":"2026-07-02T05:58:44.441226Z","end":"2026-07-02T05:58:44.651163Z","steps":["trace[146153756] 'agreement among raft nodes before linearized reading' (duration: 209.72909ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:44.651195Z","caller":"traceutil/trace.go:171","msg":"trace[803899290] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25678; }","duration":"185.382935ms","start":"2026-07-02T05:58:44.46579Z","end":"2026-07-02T05:58:44.651173Z","steps":["trace[803899290] 'agreement among raft nodes before linearized reading' (duration: 185.13203ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:44.805646Z","caller":"traceutil/trace.go:171","msg":"trace[273374797] transaction","detail":"{read_only:false; response_revision:25679; number_of_response:1; }","duration":"149.919758ms","start":"2026-07-02T05:58:44.655704Z","end":"2026-07-02T05:58:44.805624Z","steps":["trace[273374797] 'process raft request' (duration: 149.771345ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:44.806203Z","caller":"traceutil/trace.go:171","msg":"trace[193306614] transaction","detail":"{read_only:false; response_revision:25680; number_of_response:1; }","duration":"150.351986ms","start":"2026-07-02T05:58:44.655831Z","end":"2026-07-02T05:58:44.806183Z","steps":["trace[193306614] 'process raft request' (duration: 150.220734ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:44.80652Z","caller":"traceutil/trace.go:171","msg":"trace[1502060230] transaction","detail":"{read_only:false; response_revision:25681; number_of_response:1; }","duration":"150.668423ms","start":"2026-07-02T05:58:44.655833Z","end":"2026-07-02T05:58:44.806502Z","steps":["trace[1502060230] 'process raft request' (duration: 150.315766ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:44.806732Z","caller":"traceutil/trace.go:171","msg":"trace[1601361095] transaction","detail":"{read_only:false; response_revision:25682; number_of_response:1; }","duration":"150.876887ms","start":"2026-07-02T05:58:44.655839Z","end":"2026-07-02T05:58:44.806716Z","steps":["trace[1601361095] 'process raft request' (duration: 150.48297ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:45.814122Z","caller":"traceutil/trace.go:171","msg":"trace[521031728] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"387.333076ms","start":"2026-07-02T05:58:45.426769Z","end":"2026-07-02T05:58:45.814102Z","steps":["trace[521031728] 'process raft request' (duration: 387.194343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:45.814245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:45.426744Z","time spent":"387.439477ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:58:46.096158Z","caller":"traceutil/trace.go:171","msg":"trace[1039395414] transaction","detail":"{read_only:false; response_revision:25688; number_of_response:1; }","duration":"357.901306ms","start":"2026-07-02T05:58:45.738235Z","end":"2026-07-02T05:58:46.096136Z","steps":["trace[1039395414] 'process raft request' (duration: 357.733443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:46.096277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:45.738203Z","time spent":"358.009527ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T05:58:46.096384Z","caller":"traceutil/trace.go:171","msg":"trace[1895535686] linearizableReadLoop","detail":"{readStateIndex:26525; appliedIndex:26523; }","duration":"305.587787ms","start":"2026-07-02T05:58:45.790791Z","end":"2026-07-02T05:58:46.096378Z","steps":["trace[1895535686] 'read index received' (duration: 23.823573ms)","trace[1895535686] 'applied index is now lower than readState.Index' (duration: 281.763324ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:46.096487Z","caller":"traceutil/trace.go:171","msg":"trace[1290345094] transaction","detail":"{read_only:false; response_revision:25689; number_of_response:1; }","duration":"351.258728ms","start":"2026-07-02T05:58:45.745222Z","end":"2026-07-02T05:58:46.096481Z","steps":["trace[1290345094] 'process raft request' (duration: 350.885161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:46.096524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:45.745214Z","time spent":"351.289339ms","remote":"127.0.0.1:57422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T05:58:46.096603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.813021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T05:58:46.096625Z","caller":"traceutil/trace.go:171","msg":"trace[113620813] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:25689; }","duration":"305.889763ms","start":"2026-07-02T05:58:45.790728Z","end":"2026-07-02T05:58:46.096618Z","steps":["trace[113620813] 'agreement among raft nodes before linearized reading' (duration: 305.847012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:46.096644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T05:58:45.790708Z","time spent":"305.930463ms","remote":"127.0.0.1:34286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-07-02T05:58:46.096737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.582746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:58:46.096809Z","caller":"traceutil/trace.go:171","msg":"trace[1622335149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25689; }","duration":"264.697037ms","start":"2026-07-02T05:58:45.832093Z","end":"2026-07-02T05:58:46.09679Z","steps":["trace[1622335149] 'agreement among raft nodes before linearized reading' (duration: 264.589545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:46.834039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.833091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:58:46.834207Z","caller":"traceutil/trace.go:171","msg":"trace[1454192148] transaction","detail":"{read_only:false; response_revision:25692; number_of_response:1; }","duration":"178.34675ms","start":"2026-07-02T05:58:46.655841Z","end":"2026-07-02T05:58:46.834187Z","steps":["trace[1454192148] 'process raft request' (duration: 75.272095ms)","trace[1454192148] 'compare' (duration: 102.703438ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:46.834839Z","caller":"traceutil/trace.go:171","msg":"trace[1055866722] transaction","detail":"{read_only:false; response_revision:25693; number_of_response:1; }","duration":"176.850892ms","start":"2026-07-02T05:58:46.657972Z","end":"2026-07-02T05:58:46.834823Z","steps":["trace[1055866722] 'process raft request' (duration: 176.168059ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:47.080589Z","caller":"traceutil/trace.go:171","msg":"trace[1008706634] linearizableReadLoop","detail":"{readStateIndex:26533; appliedIndex:26529; }","duration":"235.613772ms","start":"2026-07-02T05:58:46.844939Z","end":"2026-07-02T05:58:47.080553Z","steps":["trace[1008706634] 'read index received' (duration: 53.209444ms)","trace[1008706634] 'applied index is now lower than readState.Index' (duration: 182.402728ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:47.080666Z","caller":"traceutil/trace.go:171","msg":"trace[1274719959] transaction","detail":"{read_only:false; response_revision:25695; number_of_response:1; }","duration":"241.562446ms","start":"2026-07-02T05:58:46.839064Z","end":"2026-07-02T05:58:47.080626Z","steps":["trace[1274719959] 'process raft request' (duration: 227.61391ms)","trace[1274719959] 'compare' (duration: 13.044349ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:47.080778Z","caller":"traceutil/trace.go:171","msg":"trace[1737654741] transaction","detail":"{read_only:false; response_revision:25696; number_of_response:1; }","duration":"241.600987ms","start":"2026-07-02T05:58:46.839138Z","end":"2026-07-02T05:58:47.080739Z","steps":["trace[1737654741] 'process raft request' (duration: 241.159989ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:47.081026Z","caller":"traceutil/trace.go:171","msg":"trace[680189211] transaction","detail":"{read_only:false; response_revision:25697; number_of_response:1; }","duration":"239.881435ms","start":"2026-07-02T05:58:46.841136Z","end":"2026-07-02T05:58:47.081018Z","steps":["trace[680189211] 'process raft request' (duration: 239.325334ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:47.081114Z","caller":"traceutil/trace.go:171","msg":"trace[1426900131] transaction","detail":"{read_only:false; response_revision:25698; number_of_response:1; }","duration":"213.36141ms","start":"2026-07-02T05:58:46.867745Z","end":"2026-07-02T05:58:47.081107Z","steps":["trace[1426900131] 'process raft request' (duration: 212.768238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:47.081413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.357887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T05:58:47.081449Z","caller":"traceutil/trace.go:171","msg":"trace[1799328450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25698; }","duration":"236.516591ms","start":"2026-07-02T05:58:46.844923Z","end":"2026-07-02T05:58:47.081439Z","steps":["trace[1799328450] 'agreement among raft nodes before linearized reading' (duration: 236.256696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:47.081612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.553217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-02T05:58:47.081637Z","caller":"traceutil/trace.go:171","msg":"trace[1233348792] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25698; }","duration":"133.609958ms","start":"2026-07-02T05:58:46.948014Z","end":"2026-07-02T05:58:47.081624Z","steps":["trace[1233348792] 'agreement among raft nodes before linearized reading' (duration: 133.549777ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:47.246702Z","caller":"traceutil/trace.go:171","msg":"trace[1310292047] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"158.970382ms","start":"2026-07-02T05:58:47.087709Z","end":"2026-07-02T05:58:47.24668Z","steps":["trace[1310292047] 'process raft request' (duration: 158.829369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:47.486211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.470576ms","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-07-02T05:58:47.486312Z","caller":"traceutil/trace.go:171","msg":"trace[941877558] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:25700; }","duration":"121.64319ms","start":"2026-07-02T05:58:47.364646Z","end":"2026-07-02T05:58:47.486289Z","steps":["trace[941877558] 'count revisions from in-memory index tree' (duration: 121.351484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:48.018641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.467619ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-02T05:58:48.018939Z","caller":"traceutil/trace.go:171","msg":"trace[371584619] linearizableReadLoop","detail":"{readStateIndex:26538; appliedIndex:26536; }","duration":"186.113369ms","start":"2026-07-02T05:58:47.832808Z","end":"2026-07-02T05:58:48.018922Z","steps":["trace[371584619] 'read index received' (duration: 112.030306ms)","trace[371584619] 'applied index is now lower than readState.Index' (duration: 74.082043ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:48.019177Z","caller":"traceutil/trace.go:171","msg":"trace[1091082429] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"198.352832ms","start":"2026-07-02T05:58:47.820799Z","end":"2026-07-02T05:58:48.019152Z","steps":["trace[1091082429] 'process raft request' (duration: 197.966675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:48.019198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.390314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:58:48.019245Z","caller":"traceutil/trace.go:171","msg":"trace[1264020049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25701; }","duration":"186.483105ms","start":"2026-07-02T05:58:47.832753Z","end":"2026-07-02T05:58:48.019236Z","steps":["trace[1264020049] 'agreement among raft nodes before linearized reading' (duration: 186.2098ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:48.221233Z","caller":"traceutil/trace.go:171","msg":"trace[1033539575] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"117.872249ms","start":"2026-07-02T05:58:48.103326Z","end":"2026-07-02T05:58:48.221198Z","steps":["trace[1033539575] 'process raft request' (duration: 116.849989ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:48.232281Z","caller":"traceutil/trace.go:171","msg":"trace[1153324843] linearizableReadLoop","detail":"{readStateIndex:26541; appliedIndex:26539; }","duration":"118.915187ms","start":"2026-07-02T05:58:48.113348Z","end":"2026-07-02T05:58:48.232263Z","steps":["trace[1153324843] 'read index received' (duration: 106.846007ms)","trace[1153324843] 'applied index is now lower than readState.Index' (duration: 12.06834ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:58:48.232418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.05578ms","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-07-02T05:58:48.232435Z","caller":"traceutil/trace.go:171","msg":"trace[3260959] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25704; }","duration":"119.105872ms","start":"2026-07-02T05:58:48.113324Z","end":"2026-07-02T05:58:48.23243Z","steps":["trace[3260959] 'agreement among raft nodes before linearized reading' (duration: 118.989399ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:48.232474Z","caller":"traceutil/trace.go:171","msg":"trace[1663139543] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"127.573423ms","start":"2026-07-02T05:58:48.104872Z","end":"2026-07-02T05:58:48.232445Z","steps":["trace[1663139543] 'process raft request' (duration: 127.327908ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:48.972487Z","caller":"traceutil/trace.go:171","msg":"trace[1036250669] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"133.464336ms","start":"2026-07-02T05:58:48.838995Z","end":"2026-07-02T05:58:48.972459Z","steps":["trace[1036250669] 'process raft request' (duration: 133.290092ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:49.078528Z","caller":"traceutil/trace.go:171","msg":"trace[88261817] linearizableReadLoop","detail":"{readStateIndex:26544; appliedIndex:26542; }","duration":"188.316152ms","start":"2026-07-02T05:58:48.890195Z","end":"2026-07-02T05:58:49.078511Z","steps":["trace[88261817] 'read index received' (duration: 82.157088ms)","trace[88261817] 'applied index is now lower than readState.Index' (duration: 106.158444ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:49.078529Z","caller":"traceutil/trace.go:171","msg":"trace[620619848] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"237.917197ms","start":"2026-07-02T05:58:48.840579Z","end":"2026-07-02T05:58:49.078496Z","steps":["trace[620619848] 'process raft request' (duration: 154.955384ms)","trace[620619848] 'compare' (duration: 82.84612ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:58:49.078739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.622721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-02T05:58:49.078769Z","caller":"traceutil/trace.go:171","msg":"trace[1496979980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25708; }","duration":"173.697983ms","start":"2026-07-02T05:58:48.905063Z","end":"2026-07-02T05:58:49.078761Z","steps":["trace[1496979980] 'agreement among raft nodes before linearized reading' (duration: 173.56608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:49.078755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.518826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:58:49.078845Z","caller":"traceutil/trace.go:171","msg":"trace[403240221] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25708; }","duration":"188.633178ms","start":"2026-07-02T05:58:48.890189Z","end":"2026-07-02T05:58:49.078822Z","steps":["trace[403240221] 'agreement among raft nodes before linearized reading' (duration: 188.419644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:49.078907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.394854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:58:49.07896Z","caller":"traceutil/trace.go:171","msg":"trace[975271039] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:25708; }","duration":"133.471735ms","start":"2026-07-02T05:58:48.945474Z","end":"2026-07-02T05:58:49.078946Z","steps":["trace[975271039] 'agreement among raft nodes before linearized reading' (duration: 133.388884ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:49.314683Z","caller":"traceutil/trace.go:171","msg":"trace[2130414798] transaction","detail":"{read_only:false; response_revision:25711; number_of_response:1; }","duration":"138.459971ms","start":"2026-07-02T05:58:49.176198Z","end":"2026-07-02T05:58:49.314658Z","steps":["trace[2130414798] 'process raft request' (duration: 138.309708ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:49.367689Z","caller":"traceutil/trace.go:171","msg":"trace[1378443590] transaction","detail":"{read_only:false; response_revision:25712; number_of_response:1; }","duration":"191.092344ms","start":"2026-07-02T05:58:49.176571Z","end":"2026-07-02T05:58:49.367664Z","steps":["trace[1378443590] 'process raft request' (duration: 190.928471ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:49.367951Z","caller":"traceutil/trace.go:171","msg":"trace[918364470] linearizableReadLoop","detail":"{readStateIndex:26550; appliedIndex:26547; }","duration":"188.625866ms","start":"2026-07-02T05:58:49.179292Z","end":"2026-07-02T05:58:49.367917Z","steps":["trace[918364470] 'read index received' (duration: 135.29827ms)","trace[918364470] 'applied index is now lower than readState.Index' (duration: 53.324866ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:49.368006Z","caller":"traceutil/trace.go:171","msg":"trace[10160434] transaction","detail":"{read_only:false; response_revision:25713; number_of_response:1; }","duration":"191.341288ms","start":"2026-07-02T05:58:49.176642Z","end":"2026-07-02T05:58:49.367984Z","steps":["trace[10160434] 'process raft request' (duration: 190.979151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:49.368223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.805037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-07-02T05:58:49.368244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.672027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"warn","ts":"2026-07-02T05:58:49.368236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.924262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-02T05:58:49.368279Z","caller":"traceutil/trace.go:171","msg":"trace[1694070999] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25713; }","duration":"117.927419ms","start":"2026-07-02T05:58:49.250339Z","end":"2026-07-02T05:58:49.368267Z","steps":["trace[1694070999] 'agreement among raft nodes before linearized reading' (duration: 117.758096ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:49.368291Z","caller":"traceutil/trace.go:171","msg":"trace[34108673] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:25713; }","duration":"157.746608ms","start":"2026-07-02T05:58:49.210526Z","end":"2026-07-02T05:58:49.368272Z","steps":["trace[34108673] 'agreement among raft nodes before linearized reading' (duration: 157.557744ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:49.368298Z","caller":"traceutil/trace.go:171","msg":"trace[1281044158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25713; }","duration":"189.012213ms","start":"2026-07-02T05:58:49.179271Z","end":"2026-07-02T05:58:49.368283Z","steps":["trace[1281044158] 'agreement among raft nodes before linearized reading' (duration: 188.788939ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:49.509438Z","caller":"traceutil/trace.go:171","msg":"trace[1815971143] transaction","detail":"{read_only:false; response_revision:25715; number_of_response:1; }","duration":"136.430951ms","start":"2026-07-02T05:58:49.372985Z","end":"2026-07-02T05:58:49.509416Z","steps":["trace[1815971143] 'process raft request' (duration: 136.35048ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:49.509596Z","caller":"traceutil/trace.go:171","msg":"trace[89579057] transaction","detail":"{read_only:false; response_revision:25714; number_of_response:1; }","duration":"136.788888ms","start":"2026-07-02T05:58:49.372778Z","end":"2026-07-02T05:58:49.509567Z","steps":["trace[89579057] 'process raft request' (duration: 52.052753ms)","trace[89579057] 'compare' (duration: 84.311257ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:50.228615Z","caller":"traceutil/trace.go:171","msg":"trace[1328369462] transaction","detail":"{read_only:false; response_revision:25716; number_of_response:1; }","duration":"204.51146ms","start":"2026-07-02T05:58:50.024081Z","end":"2026-07-02T05:58:50.228592Z","steps":["trace[1328369462] 'process raft request' (duration: 204.332657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:50.675863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.278815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-02T05:58:50.675958Z","caller":"traceutil/trace.go:171","msg":"trace[510408826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25719; }","duration":"130.444158ms","start":"2026-07-02T05:58:50.545494Z","end":"2026-07-02T05:58:50.675938Z","steps":["trace[510408826] 'range keys from in-memory index tree' (duration: 130.04066ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:51.112146Z","caller":"traceutil/trace.go:171","msg":"trace[2079025044] transaction","detail":"{read_only:false; response_revision:25721; number_of_response:1; }","duration":"134.534497ms","start":"2026-07-02T05:58:50.97759Z","end":"2026-07-02T05:58:51.112124Z","steps":["trace[2079025044] 'process raft request' (duration: 134.389803ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:51.337966Z","caller":"traceutil/trace.go:171","msg":"trace[497729265] transaction","detail":"{read_only:false; response_revision:25724; number_of_response:1; }","duration":"161.759865ms","start":"2026-07-02T05:58:51.176162Z","end":"2026-07-02T05:58:51.337922Z","steps":["trace[497729265] 'process raft request' (duration: 97.962158ms)","trace[497729265] 'compare' (duration: 63.606654ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:58:51.471338Z","caller":"traceutil/trace.go:171","msg":"trace[1213492845] transaction","detail":"{read_only:false; response_revision:25725; number_of_response:1; }","duration":"128.812986ms","start":"2026-07-02T05:58:51.342499Z","end":"2026-07-02T05:58:51.471312Z","steps":["trace[1213492845] 'process raft request' (duration: 124.224538ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:51.630424Z","caller":"traceutil/trace.go:171","msg":"trace[663593400] linearizableReadLoop","detail":"{readStateIndex:26564; appliedIndex:26562; }","duration":"126.601785ms","start":"2026-07-02T05:58:51.503793Z","end":"2026-07-02T05:58:51.630394Z","steps":["trace[663593400] 'read index received' (duration: 23.641001ms)","trace[663593400] 'applied index is now lower than readState.Index' (duration: 102.959204ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:58:51.630613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.796498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:58:51.630651Z","caller":"traceutil/trace.go:171","msg":"trace[223867986] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25727; }","duration":"126.895441ms","start":"2026-07-02T05:58:51.503743Z","end":"2026-07-02T05:58:51.630639Z","steps":["trace[223867986] 'agreement among raft nodes before linearized reading' (duration: 126.795339ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:51.63074Z","caller":"traceutil/trace.go:171","msg":"trace[363522194] transaction","detail":"{read_only:false; response_revision:25727; number_of_response:1; }","duration":"153.585929ms","start":"2026-07-02T05:58:51.47687Z","end":"2026-07-02T05:58:51.630455Z","steps":["trace[363522194] 'process raft request' (duration: 124.003475ms)","trace[363522194] 'compare' (duration: 29.326568ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T05:58:51.630909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.085894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-02T05:58:51.631151Z","caller":"traceutil/trace.go:171","msg":"trace[1770932611] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25727; }","duration":"117.347688ms","start":"2026-07-02T05:58:51.513783Z","end":"2026-07-02T05:58:51.63113Z","steps":["trace[1770932611] 'agreement among raft nodes before linearized reading' (duration: 117.048632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:58:51.630693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.188155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-02T05:58:51.6314Z","caller":"traceutil/trace.go:171","msg":"trace[1925830101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25727; }","duration":"118.948709ms","start":"2026-07-02T05:58:51.512439Z","end":"2026-07-02T05:58:51.631387Z","steps":["trace[1925830101] 'agreement among raft nodes before linearized reading' (duration: 118.126783ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:52.392296Z","caller":"traceutil/trace.go:171","msg":"trace[1471750669] transaction","detail":"{read_only:false; response_revision:25732; number_of_response:1; }","duration":"137.985751ms","start":"2026-07-02T05:58:52.254285Z","end":"2026-07-02T05:58:52.39227Z","steps":["trace[1471750669] 'process raft request' (duration: 133.549887ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:52.392458Z","caller":"traceutil/trace.go:171","msg":"trace[201254345] transaction","detail":"{read_only:false; response_revision:25733; number_of_response:1; }","duration":"123.561117ms","start":"2026-07-02T05:58:52.268876Z","end":"2026-07-02T05:58:52.392437Z","steps":["trace[201254345] 'process raft request' (duration: 123.336822ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:58:53.470539Z","caller":"traceutil/trace.go:171","msg":"trace[1635318523] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"127.295457ms","start":"2026-07-02T05:58:53.343191Z","end":"2026-07-02T05:58:53.470486Z","steps":["trace[1635318523] 'process raft request' (duration: 87.387346ms)","trace[1635318523] 'compare' (duration: 39.668576ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:59:11.67981Z","caller":"traceutil/trace.go:171","msg":"trace[1206142758] linearizableReadLoop","detail":"{readStateIndex:26724; appliedIndex:26723; }","duration":"110.658361ms","start":"2026-07-02T05:59:11.569127Z","end":"2026-07-02T05:59:11.679785Z","steps":["trace[1206142758] 'read index received' (duration: 110.486786ms)","trace[1206142758] 'applied index is now lower than readState.Index' (duration: 170.675µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:59:11.679927Z","caller":"traceutil/trace.go:171","msg":"trace[2118924256] transaction","detail":"{read_only:false; response_revision:25882; number_of_response:1; }","duration":"138.538173ms","start":"2026-07-02T05:59:11.541355Z","end":"2026-07-02T05:59:11.679893Z","steps":["trace[2118924256] 'process raft request' (duration: 138.278247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:59:11.680135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.963146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-07-02T05:59:11.680223Z","caller":"traceutil/trace.go:171","msg":"trace[1042369094] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25882; }","duration":"111.116829ms","start":"2026-07-02T05:59:11.569084Z","end":"2026-07-02T05:59:11.680201Z","steps":["trace[1042369094] 'agreement among raft nodes before linearized reading' (duration: 110.841474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:59:11.902002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.408435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T05:59:11.902382Z","caller":"traceutil/trace.go:171","msg":"trace[1536490580] transaction","detail":"{read_only:false; response_revision:25883; number_of_response:1; }","duration":"216.513278ms","start":"2026-07-02T05:59:11.685838Z","end":"2026-07-02T05:59:11.902352Z","steps":["trace[1536490580] 'process raft request' (duration: 62.652654ms)","trace[1536490580] 'compare' (duration: 153.263283ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:59:11.902528Z","caller":"traceutil/trace.go:171","msg":"trace[1202455836] linearizableReadLoop","detail":"{readStateIndex:26726; appliedIndex:26724; }","duration":"181.268176ms","start":"2026-07-02T05:59:11.721209Z","end":"2026-07-02T05:59:11.902477Z","steps":["trace[1202455836] 'read index received' (duration: 27.29975ms)","trace[1202455836] 'applied index is now lower than readState.Index' (duration: 153.965466ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:59:11.902616Z","caller":"traceutil/trace.go:171","msg":"trace[893269533] transaction","detail":"{read_only:false; response_revision:25884; number_of_response:1; }","duration":"216.255913ms","start":"2026-07-02T05:59:11.686305Z","end":"2026-07-02T05:59:11.902561Z","steps":["trace[893269533] 'process raft request' (duration: 215.829155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:59:11.902817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.578632ms","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-07-02T05:59:11.902886Z","caller":"traceutil/trace.go:171","msg":"trace[178072949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25884; }","duration":"181.696665ms","start":"2026-07-02T05:59:11.72117Z","end":"2026-07-02T05:59:11.902866Z","steps":["trace[178072949] 'agreement among raft nodes before linearized reading' (duration: 181.46748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:59:12.163241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.969866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:59:12.163305Z","caller":"traceutil/trace.go:171","msg":"trace[1187569042] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25887; }","duration":"154.089338ms","start":"2026-07-02T05:59:12.0092Z","end":"2026-07-02T05:59:12.16329Z","steps":["trace[1187569042] 'count revisions from in-memory index tree' (duration: 153.887505ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:59:12.61876Z","caller":"traceutil/trace.go:171","msg":"trace[1501933561] linearizableReadLoop","detail":"{readStateIndex:26731; appliedIndex:26730; }","duration":"152.659082ms","start":"2026-07-02T05:59:12.466074Z","end":"2026-07-02T05:59:12.618734Z","steps":["trace[1501933561] 'read index received' (duration: 78.778082ms)","trace[1501933561] 'applied index is now lower than readState.Index' (duration: 73.8766ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T05:59:12.618826Z","caller":"traceutil/trace.go:171","msg":"trace[340514098] transaction","detail":"{read_only:false; response_revision:25890; number_of_response:1; }","duration":"141.879546ms","start":"2026-07-02T05:59:12.476922Z","end":"2026-07-02T05:59:12.618801Z","steps":["trace[340514098] 'process raft request' (duration: 141.830625ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:59:12.618914Z","caller":"traceutil/trace.go:171","msg":"trace[164381681] transaction","detail":"{read_only:false; response_revision:25889; number_of_response:1; }","duration":"144.965265ms","start":"2026-07-02T05:59:12.473934Z","end":"2026-07-02T05:59:12.618899Z","steps":["trace[164381681] 'process raft request' (duration: 144.675199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T05:59:12.618938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.829825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T05:59:12.619042Z","caller":"traceutil/trace.go:171","msg":"trace[30926901] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25890; }","duration":"152.942087ms","start":"2026-07-02T05:59:12.466068Z","end":"2026-07-02T05:59:12.61901Z","steps":["trace[30926901] 'agreement among raft nodes before linearized reading' (duration: 152.732723ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T05:59:19.401348Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22535} {"level":"info","ts":"2026-07-02T05:59:19.529474Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22535,"took":"123.739801ms","hash":4142218265,"current-db-size-bytes":56246272,"current-db-size":"56 MB","current-db-size-in-use-bytes":42491904,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-07-02T05:59:19.529554Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4142218265,"revision":22535,"compact-revision":20052} {"level":"info","ts":"2026-07-02T06:00:46.560725Z","caller":"traceutil/trace.go:171","msg":"trace[903204174] transaction","detail":"{read_only:false; response_revision:26984; number_of_response:1; }","duration":"104.91189ms","start":"2026-07-02T06:00:46.455792Z","end":"2026-07-02T06:00:46.560704Z","steps":["trace[903204174] 'process raft request' (duration: 104.831009ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:46.560926Z","caller":"traceutil/trace.go:171","msg":"trace[101080563] transaction","detail":"{read_only:false; response_revision:26985; number_of_response:1; }","duration":"105.020082ms","start":"2026-07-02T06:00:46.455884Z","end":"2026-07-02T06:00:46.560904Z","steps":["trace[101080563] 'process raft request' (duration: 104.787397ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:00:46.560749Z","caller":"traceutil/trace.go:171","msg":"trace[856186542] transaction","detail":"{read_only:false; response_revision:26983; number_of_response:1; }","duration":"106.543401ms","start":"2026-07-02T06:00:46.454181Z","end":"2026-07-02T06:00:46.560724Z","steps":["trace[856186542] 'compare' (duration: 98.803423ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T06:04:19.408477Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25938} {"level":"info","ts":"2026-07-02T06:04:19.565495Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25938,"took":"152.484028ms","hash":3294217669,"current-db-size-bytes":57090048,"current-db-size":"57 MB","current-db-size-in-use-bytes":37130240,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-02T06:04:19.565561Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3294217669,"revision":25938,"compact-revision":22535} {"level":"info","ts":"2026-07-02T06:04:42.483662Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T06:04:42.489156Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-02T06:04:42.489241Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-02T06:09:19.414832Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28904} {"level":"info","ts":"2026-07-02T06:09:19.55891Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28904,"took":"139.173829ms","hash":1763523720,"current-db-size-bytes":57090048,"current-db-size":"57 MB","current-db-size-in-use-bytes":31764480,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T06:09:19.558995Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1763523720,"revision":28904,"compact-revision":25938} {"level":"info","ts":"2026-07-02T06:14:19.42166Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31199} {"level":"info","ts":"2026-07-02T06:14:19.57742Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31199,"took":"148.974895ms","hash":4040600336,"current-db-size-bytes":57090048,"current-db-size":"57 MB","current-db-size-in-use-bytes":31203328,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T06:14:19.577507Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4040600336,"revision":31199,"compact-revision":28904} {"level":"info","ts":"2026-07-02T06:19:19.427662Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33498} {"level":"info","ts":"2026-07-02T06:19:19.578982Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33498,"took":"145.82949ms","hash":3437439994,"current-db-size-bytes":58130432,"current-db-size":"58 MB","current-db-size-in-use-bytes":32735232,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-02T06:19:19.579054Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3437439994,"revision":33498,"compact-revision":31199} {"level":"info","ts":"2026-07-02T06:24:19.43517Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35829} {"level":"info","ts":"2026-07-02T06:24:19.577637Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35829,"took":"138.146626ms","hash":1897782846,"current-db-size-bytes":58650624,"current-db-size":"59 MB","current-db-size-in-use-bytes":33280000,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-02T06:24:19.577726Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1897782846,"revision":35829,"compact-revision":33498} {"level":"info","ts":"2026-07-02T06:25:45.02187Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"11f26225a5bb9fe3","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T06:25:45.02632Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-02T06:25:45.026413Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}