{"level":"warn","ts":"2026-07-02T16:29:16.404609Z","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-02T16:29:16.404687Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.203:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.203:2380","--initial-cluster=instance=https://199.19.213.203:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.203:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.203: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-02T16:29:16.404749Z","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-02T16:29:16.404764Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.203:2380"]} {"level":"info","ts":"2026-07-02T16:29:16.404787Z","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-02T16:29:16.405417Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.203:2379"]} {"level":"info","ts":"2026-07-02T16:29:16.405511Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.203:2380"],"listen-peer-urls":["https://199.19.213.203:2380"],"advertise-client-urls":["https://199.19.213.203:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.203:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.203: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-02T16:29:16.408116Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.342859ms"} {"level":"info","ts":"2026-07-02T16:29:16.412903Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b5a1dacc8ba86a4f","cluster-id":"a3ec0b489c26f222"} {"level":"info","ts":"2026-07-02T16:29:16.41298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T16:29:16.413002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became follower at term 0"} {"level":"info","ts":"2026-07-02T16:29:16.413015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b5a1dacc8ba86a4f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T16:29:16.413025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became follower at term 1"} {"level":"info","ts":"2026-07-02T16:29:16.413072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f switched to configuration voters=(13087982564166625871)"} {"level":"warn","ts":"2026-07-02T16:29:16.418425Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T16:29:16.420146Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T16:29:16.421316Z","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-02T16:29:16.423494Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b5a1dacc8ba86a4f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T16:29:16.424063Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b5a1dacc8ba86a4f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T16:29:16.424154Z","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-02T16:29:16.424286Z","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-02T16:29:16.424303Z","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-02T16:29:16.42823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f switched to configuration voters=(13087982564166625871)"} {"level":"info","ts":"2026-07-02T16:29:16.428383Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a3ec0b489c26f222","local-member-id":"b5a1dacc8ba86a4f","added-peer-id":"b5a1dacc8ba86a4f","added-peer-peer-urls":["https://199.19.213.203:2380"]} {"level":"info","ts":"2026-07-02T16:29:16.429258Z","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-02T16:29:16.42952Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b5a1dacc8ba86a4f","initial-advertise-peer-urls":["https://199.19.213.203:2380"],"listen-peer-urls":["https://199.19.213.203:2380"],"advertise-client-urls":["https://199.19.213.203:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.203:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T16:29:16.429465Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.203:2380"} {"level":"info","ts":"2026-07-02T16:29:16.429539Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T16:29:16.429611Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.203:2380"} {"level":"info","ts":"2026-07-02T16:29:17.214097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T16:29:17.214168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T16:29:17.214206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f received MsgPreVoteResp from b5a1dacc8ba86a4f at term 1"} {"level":"info","ts":"2026-07-02T16:29:17.214229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became candidate at term 2"} {"level":"info","ts":"2026-07-02T16:29:17.214242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f received MsgVoteResp from b5a1dacc8ba86a4f at term 2"} {"level":"info","ts":"2026-07-02T16:29:17.214259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b5a1dacc8ba86a4f became leader at term 2"} {"level":"info","ts":"2026-07-02T16:29:17.214273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b5a1dacc8ba86a4f elected leader b5a1dacc8ba86a4f at term 2"} {"level":"info","ts":"2026-07-02T16:29:17.215375Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b5a1dacc8ba86a4f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.203:2379]}","request-path":"/0/members/b5a1dacc8ba86a4f/attributes","cluster-id":"a3ec0b489c26f222","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T16:29:17.215385Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T16:29:17.215563Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T16:29:17.215609Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T16:29:17.216004Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T16:29:17.216032Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T16:29:17.216144Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a3ec0b489c26f222","local-member-id":"b5a1dacc8ba86a4f","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T16:29:17.216298Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T16:29:17.216342Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T16:29:17.218915Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.203:2379"} {"level":"info","ts":"2026-07-02T16:29:17.219515Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T16:30:00.87351Z","caller":"traceutil/trace.go:171","msg":"trace[1645009010] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"195.93062ms","start":"2026-07-02T16:30:00.677555Z","end":"2026-07-02T16:30:00.873486Z","steps":["trace[1645009010] 'process raft request' (duration: 195.301036ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:30:01.122578Z","caller":"traceutil/trace.go:171","msg":"trace[2019396395] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"240.981869ms","start":"2026-07-02T16:30:00.881571Z","end":"2026-07-02T16:30:01.122553Z","steps":["trace[2019396395] 'process raft request' (duration: 211.876457ms)","trace[2019396395] 'compare' (duration: 28.994789ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:30:01.130737Z","caller":"traceutil/trace.go:171","msg":"trace[1929397000] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:655; }","duration":"170.945116ms","start":"2026-07-02T16:30:00.959747Z","end":"2026-07-02T16:30:01.130692Z","steps":["trace[1929397000] 'read index received' (duration: 133.712337ms)","trace[1929397000] 'applied index is now lower than readState.Index' (duration: 37.231089ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:30:01.130784Z","caller":"traceutil/trace.go:171","msg":"trace[123562984] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"196.541722ms","start":"2026-07-02T16:30:00.93421Z","end":"2026-07-02T16:30:01.130751Z","steps":["trace[123562984] 'process raft request' (duration: 196.304387ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:30:01.131004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.243713ms","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-02T16:30:01.131117Z","caller":"traceutil/trace.go:171","msg":"trace[2132521160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"171.402856ms","start":"2026-07-02T16:30:00.959694Z","end":"2026-07-02T16:30:01.131097Z","steps":["trace[2132521160] 'agreement among raft nodes before linearized reading' (duration: 171.13506ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:30:01.497749Z","caller":"traceutil/trace.go:171","msg":"trace[1414687497] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:657; }","duration":"356.281304ms","start":"2026-07-02T16:30:01.141434Z","end":"2026-07-02T16:30:01.497716Z","steps":["trace[1414687497] 'read index received' (duration: 15.23324ms)","trace[1414687497] 'applied index is now lower than readState.Index' (duration: 341.046724ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:30:01.49783Z","caller":"traceutil/trace.go:171","msg":"trace[2003974557] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"360.967177ms","start":"2026-07-02T16:30:01.13682Z","end":"2026-07-02T16:30:01.497788Z","steps":["trace[2003974557] 'process raft request' (duration: 284.360162ms)","trace[2003974557] 'compare' (duration: 76.257957ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:30:01.498029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.291223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7zz5b\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-02T16:30:01.49821Z","caller":"traceutil/trace.go:171","msg":"trace[652967125] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7zz5b; range_end:; response_count:1; response_revision:634; }","duration":"165.504697ms","start":"2026-07-02T16:30:01.332684Z","end":"2026-07-02T16:30:01.498188Z","steps":["trace[652967125] 'agreement among raft nodes before linearized reading' (duration: 165.240842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:30:01.498217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.489209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T16:30:01.498292Z","caller":"traceutil/trace.go:171","msg":"trace[223542502] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:634; }","duration":"356.894328ms","start":"2026-07-02T16:30:01.141372Z","end":"2026-07-02T16:30:01.498266Z","steps":["trace[223542502] 'agreement among raft nodes before linearized reading' (duration: 356.50753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:30:01.498343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:30:01.141349Z","time spent":"356.975459ms","remote":"127.0.0.1:47408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-07-02T16:30:01.498523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:30:01.136782Z","time spent":"361.13206ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T16:30:17.121844Z","caller":"traceutil/trace.go:171","msg":"trace[462224572] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"136.61654ms","start":"2026-07-02T16:30:16.985163Z","end":"2026-07-02T16:30:17.121779Z","steps":["trace[462224572] 'process raft request' (duration: 118.555808ms)","trace[462224572] 'compare' (duration: 17.802747ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:30:45.611232Z","caller":"traceutil/trace.go:171","msg":"trace[55370795] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"253.604222ms","start":"2026-07-02T16:30:45.357597Z","end":"2026-07-02T16:30:45.611201Z","steps":["trace[55370795] 'process raft request' (duration: 217.606089ms)","trace[55370795] 'compare' (duration: 35.687676ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:31:32.928621Z","caller":"traceutil/trace.go:171","msg":"trace[2091613358] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"274.596016ms","start":"2026-07-02T16:31:32.65399Z","end":"2026-07-02T16:31:32.928586Z","steps":["trace[2091613358] 'process raft request' (duration: 274.424972ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:31:32.960286Z","caller":"traceutil/trace.go:171","msg":"trace[1294268597] linearizableReadLoop","detail":"{readStateIndex:1950; appliedIndex:1948; }","duration":"153.031355ms","start":"2026-07-02T16:31:32.807223Z","end":"2026-07-02T16:31:32.960254Z","steps":["trace[1294268597] 'read index received' (duration: 121.249252ms)","trace[1294268597] 'applied index is now lower than readState.Index' (duration: 31.780773ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:31:32.960435Z","caller":"traceutil/trace.go:171","msg":"trace[704991626] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"300.002379ms","start":"2026-07-02T16:31:32.660389Z","end":"2026-07-02T16:31:32.960392Z","steps":["trace[704991626] 'process raft request' (duration: 299.709712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:31:32.960662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:31:32.660375Z","time spent":"300.166803ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T16:31:32.960735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.483745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6266714e-4f7b-4fbc-93c5-ce8bf6755f94\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-02T16:31:32.960793Z","caller":"traceutil/trace.go:171","msg":"trace[1829046473] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6266714e-4f7b-4fbc-93c5-ce8bf6755f94; range_end:; response_count:1; response_revision:1876; }","duration":"153.614598ms","start":"2026-07-02T16:31:32.807162Z","end":"2026-07-02T16:31:32.960777Z","steps":["trace[1829046473] 'agreement among raft nodes before linearized reading' (duration: 153.215969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:31:45.712536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.371795ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-02T16:32:28.261007Z","caller":"traceutil/trace.go:171","msg":"trace[1993249728] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"142.797032ms","start":"2026-07-02T16:32:28.118175Z","end":"2026-07-02T16:32:28.260972Z","steps":["trace[1993249728] 'process raft request' (duration: 142.559287ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:32:28.426695Z","caller":"traceutil/trace.go:171","msg":"trace[878239475] linearizableReadLoop","detail":"{readStateIndex:2313; appliedIndex:2312; }","duration":"123.502882ms","start":"2026-07-02T16:32:28.303131Z","end":"2026-07-02T16:32:28.426634Z","steps":["trace[878239475] 'read index received' (duration: 107.405292ms)","trace[878239475] 'applied index is now lower than readState.Index' (duration: 16.09601ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:32:28.426807Z","caller":"traceutil/trace.go:171","msg":"trace[73019941] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"155.648971ms","start":"2026-07-02T16:32:28.271104Z","end":"2026-07-02T16:32:28.426753Z","steps":["trace[73019941] 'process raft request' (duration: 139.547861ms)","trace[73019941] 'compare' (duration: 15.751662ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:32:28.426996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.846669ms","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-02T16:32:28.42712Z","caller":"traceutil/trace.go:171","msg":"trace[1259198891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2226; }","duration":"124.037213ms","start":"2026-07-02T16:32:28.303056Z","end":"2026-07-02T16:32:28.427093Z","steps":["trace[1259198891] 'agreement among raft nodes before linearized reading' (duration: 123.744647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:32:28.707679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.629814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T16:32:28.70781Z","caller":"traceutil/trace.go:171","msg":"trace[1776098481] linearizableReadLoop","detail":"{readStateIndex:2314; appliedIndex:2313; }","duration":"229.021183ms","start":"2026-07-02T16:32:28.478769Z","end":"2026-07-02T16:32:28.70779Z","steps":["trace[1776098481] 'read index received' (duration: 76.142284ms)","trace[1776098481] 'applied index is now lower than readState.Index' (duration: 152.877629ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:32:28.707867Z","caller":"traceutil/trace.go:171","msg":"trace[93091249] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"274.532702ms","start":"2026-07-02T16:32:28.433289Z","end":"2026-07-02T16:32:28.707821Z","steps":["trace[93091249] 'process raft request' (duration: 121.660803ms)","trace[93091249] 'compare' (duration: 152.136113ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:32:28.707995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.235609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-02T16:32:28.708035Z","caller":"traceutil/trace.go:171","msg":"trace[87318147] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2227; }","duration":"229.3315ms","start":"2026-07-02T16:32:28.478692Z","end":"2026-07-02T16:32:28.708023Z","steps":["trace[87318147] 'agreement among raft nodes before linearized reading' (duration: 229.148277ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:37:50.384007Z","caller":"traceutil/trace.go:171","msg":"trace[1323812478] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"108.848961ms","start":"2026-07-02T16:37:50.275139Z","end":"2026-07-02T16:37:50.383988Z","steps":["trace[1323812478] 'process raft request' (duration: 108.731668ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:37:50.474919Z","caller":"traceutil/trace.go:171","msg":"trace[1046122491] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"152.595062ms","start":"2026-07-02T16:37:50.322301Z","end":"2026-07-02T16:37:50.474897Z","steps":["trace[1046122491] 'process raft request' (duration: 152.5076ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:37:50.474923Z","caller":"traceutil/trace.go:171","msg":"trace[1172890997] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"167.628478ms","start":"2026-07-02T16:37:50.307257Z","end":"2026-07-02T16:37:50.474886Z","steps":["trace[1172890997] 'process raft request' (duration: 146.824356ms)","trace[1172890997] 'compare' (duration: 20.594307ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:37:50.475236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.110179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.203\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-02T16:37:50.475333Z","caller":"traceutil/trace.go:171","msg":"trace[1734110095] range","detail":"{range_begin:/registry/masterleases/199.19.213.203; range_end:; response_count:1; response_revision:5089; }","duration":"116.219261ms","start":"2026-07-02T16:37:50.359092Z","end":"2026-07-02T16:37:50.475311Z","steps":["trace[1734110095] 'agreement among raft nodes before linearized reading' (duration: 115.942105ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:02.809322Z","caller":"traceutil/trace.go:171","msg":"trace[240205760] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"137.770309ms","start":"2026-07-02T16:38:02.671466Z","end":"2026-07-02T16:38:02.809236Z","steps":["trace[240205760] 'process raft request' (duration: 74.25747ms)","trace[240205760] 'compare' (duration: 63.185222ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:38:03.076885Z","caller":"traceutil/trace.go:171","msg":"trace[1154364264] transaction","detail":"{read_only:false; response_revision:5326; number_of_response:1; }","duration":"100.175592ms","start":"2026-07-02T16:38:02.976686Z","end":"2026-07-02T16:38:03.076862Z","steps":["trace[1154364264] 'process raft request' (duration: 100.0824ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:03.076925Z","caller":"traceutil/trace.go:171","msg":"trace[284787285] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"100.348516ms","start":"2026-07-02T16:38:02.97655Z","end":"2026-07-02T16:38:03.076898Z","steps":["trace[284787285] 'process raft request' (duration: 97.443493ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:03.077484Z","caller":"traceutil/trace.go:171","msg":"trace[691406331] transaction","detail":"{read_only:false; response_revision:5327; number_of_response:1; }","duration":"100.156503ms","start":"2026-07-02T16:38:02.977301Z","end":"2026-07-02T16:38:03.077458Z","steps":["trace[691406331] 'process raft request' (duration: 99.522118ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:18.041028Z","caller":"traceutil/trace.go:171","msg":"trace[284960397] linearizableReadLoop","detail":"{readStateIndex:5802; appliedIndex:5801; }","duration":"198.785423ms","start":"2026-07-02T16:38:17.842171Z","end":"2026-07-02T16:38:18.040957Z","steps":["trace[284960397] 'read index received' (duration: 198.555189ms)","trace[284960397] 'applied index is now lower than readState.Index' (duration: 228.364µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:38:18.041767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.56581ms","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-02T16:38:18.04186Z","caller":"traceutil/trace.go:171","msg":"trace[1493455243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5593; }","duration":"199.722133ms","start":"2026-07-02T16:38:17.842119Z","end":"2026-07-02T16:38:18.041841Z","steps":["trace[1493455243] 'agreement among raft nodes before linearized reading' (duration: 199.406397ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:18.042271Z","caller":"traceutil/trace.go:171","msg":"trace[543492634] transaction","detail":"{read_only:false; response_revision:5593; number_of_response:1; }","duration":"298.850655ms","start":"2026-07-02T16:38:17.743403Z","end":"2026-07-02T16:38:18.042254Z","steps":["trace[543492634] 'process raft request' (duration: 297.332722ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:18.188282Z","caller":"traceutil/trace.go:171","msg":"trace[779351672] linearizableReadLoop","detail":"{readStateIndex:5803; appliedIndex:5802; }","duration":"147.078132ms","start":"2026-07-02T16:38:18.041179Z","end":"2026-07-02T16:38:18.188257Z","steps":["trace[779351672] 'read index received' (duration: 115.615459ms)","trace[779351672] 'applied index is now lower than readState.Index' (duration: 31.461233ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:38:18.188321Z","caller":"traceutil/trace.go:171","msg":"trace[721601809] transaction","detail":"{read_only:false; response_revision:5594; number_of_response:1; }","duration":"207.246777ms","start":"2026-07-02T16:38:17.981035Z","end":"2026-07-02T16:38:18.188282Z","steps":["trace[721601809] 'process raft request' (duration: 175.739462ms)","trace[721601809] 'compare' (duration: 31.253499ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:38:18.18885Z","caller":"traceutil/trace.go:171","msg":"trace[1622940689] transaction","detail":"{read_only:false; response_revision:5595; number_of_response:1; }","duration":"142.820549ms","start":"2026-07-02T16:38:18.046015Z","end":"2026-07-02T16:38:18.188836Z","steps":["trace[1622940689] 'process raft request' (duration: 142.197166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:38:18.188983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.876406ms","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-02T16:38:18.189107Z","caller":"traceutil/trace.go:171","msg":"trace[814637102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5595; }","duration":"346.043159ms","start":"2026-07-02T16:38:17.843038Z","end":"2026-07-02T16:38:18.189081Z","steps":["trace[814637102] 'agreement among raft nodes before linearized reading' (duration: 345.365844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:38:18.189162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:38:17.843021Z","time spent":"346.118821ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T16:38:18.371545Z","caller":"traceutil/trace.go:171","msg":"trace[1434462352] transaction","detail":"{read_only:false; response_revision:5599; number_of_response:1; }","duration":"104.007936ms","start":"2026-07-02T16:38:18.267509Z","end":"2026-07-02T16:38:18.371517Z","steps":["trace[1434462352] 'process raft request' (duration: 97.846133ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:18.384479Z","caller":"traceutil/trace.go:171","msg":"trace[823569390] transaction","detail":"{read_only:false; response_revision:5600; number_of_response:1; }","duration":"115.595949ms","start":"2026-07-02T16:38:18.268845Z","end":"2026-07-02T16:38:18.384441Z","steps":["trace[823569390] 'process raft request' (duration: 115.345254ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:18.384586Z","caller":"traceutil/trace.go:171","msg":"trace[8798055] transaction","detail":"{read_only:false; response_revision:5601; number_of_response:1; }","duration":"115.038597ms","start":"2026-07-02T16:38:18.269521Z","end":"2026-07-02T16:38:18.38456Z","steps":["trace[8798055] 'process raft request' (duration: 114.828632ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:18.384757Z","caller":"traceutil/trace.go:171","msg":"trace[1027025989] transaction","detail":"{read_only:false; response_revision:5603; number_of_response:1; }","duration":"113.689477ms","start":"2026-07-02T16:38:18.271038Z","end":"2026-07-02T16:38:18.384728Z","steps":["trace[1027025989] 'process raft request' (duration: 113.463272ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:18.384899Z","caller":"traceutil/trace.go:171","msg":"trace[1537895935] transaction","detail":"{read_only:false; response_revision:5604; number_of_response:1; }","duration":"109.326793ms","start":"2026-07-02T16:38:18.275554Z","end":"2026-07-02T16:38:18.384881Z","steps":["trace[1537895935] 'process raft request' (duration: 109.039306ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:38:18.384755Z","caller":"traceutil/trace.go:171","msg":"trace[1873810128] transaction","detail":"{read_only:false; response_revision:5602; number_of_response:1; }","duration":"114.135597ms","start":"2026-07-02T16:38:18.270563Z","end":"2026-07-02T16:38:18.384699Z","steps":["trace[1873810128] 'process raft request' (duration: 113.859071ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:39:17.242637Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3015} {"level":"info","ts":"2026-07-02T16:39:17.326151Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3015,"took":"81.828945ms","hash":2350756638,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":31789056,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T16:39:17.326221Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2350756638,"revision":3015,"compact-revision":-1} {"level":"info","ts":"2026-07-02T16:44:17.249889Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6101} {"level":"info","ts":"2026-07-02T16:44:17.347991Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6101,"took":"96.381442ms","hash":1087977613,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":21905408,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-07-02T16:44:17.348263Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1087977613,"revision":6101,"compact-revision":3015} {"level":"info","ts":"2026-07-02T16:44:33.959507Z","caller":"traceutil/trace.go:171","msg":"trace[2079794681] linearizableReadLoop","detail":"{readStateIndex:8713; appliedIndex:8712; }","duration":"101.34372ms","start":"2026-07-02T16:44:33.858132Z","end":"2026-07-02T16:44:33.959476Z","steps":["trace[2079794681] 'read index received' (duration: 101.162466ms)","trace[2079794681] 'applied index is now lower than readState.Index' (duration: 179.374µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:44:33.959675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.516083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T16:44:33.959713Z","caller":"traceutil/trace.go:171","msg":"trace[423299085] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8411; }","duration":"101.602935ms","start":"2026-07-02T16:44:33.858098Z","end":"2026-07-02T16:44:33.959701Z","steps":["trace[423299085] 'agreement among raft nodes before linearized reading' (duration: 101.505953ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:44:33.95971Z","caller":"traceutil/trace.go:171","msg":"trace[1174398931] transaction","detail":"{read_only:false; response_revision:8411; number_of_response:1; }","duration":"107.521624ms","start":"2026-07-02T16:44:33.852111Z","end":"2026-07-02T16:44:33.959633Z","steps":["trace[1174398931] 'process raft request' (duration: 107.179517ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:44:34.063942Z","caller":"traceutil/trace.go:171","msg":"trace[2028885649] linearizableReadLoop","detail":"{readStateIndex:8714; appliedIndex:8713; }","duration":"100.533363ms","start":"2026-07-02T16:44:33.963368Z","end":"2026-07-02T16:44:34.063901Z","steps":["trace[2028885649] 'read index received' (duration: 84.37036ms)","trace[2028885649] 'applied index is now lower than readState.Index' (duration: 16.161333ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:44:34.06402Z","caller":"traceutil/trace.go:171","msg":"trace[772086007] transaction","detail":"{read_only:false; response_revision:8412; number_of_response:1; }","duration":"108.962236ms","start":"2026-07-02T16:44:33.954982Z","end":"2026-07-02T16:44:34.063944Z","steps":["trace[772086007] 'process raft request' (duration: 92.827215ms)","trace[772086007] 'compare' (duration: 15.931917ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:44:34.064092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.734017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T16:44:34.064173Z","caller":"traceutil/trace.go:171","msg":"trace[1469424279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8412; }","duration":"100.856329ms","start":"2026-07-02T16:44:33.963296Z","end":"2026-07-02T16:44:34.064153Z","steps":["trace[1469424279] 'agreement among raft nodes before linearized reading' (duration: 100.725656ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:44:45.717078Z","caller":"traceutil/trace.go:171","msg":"trace[2008527549] transaction","detail":"{read_only:false; response_revision:8471; number_of_response:1; }","duration":"116.131481ms","start":"2026-07-02T16:44:45.600908Z","end":"2026-07-02T16:44:45.71704Z","steps":["trace[2008527549] 'process raft request' (duration: 115.808855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:44:45.767596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.129126ms","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-02T16:44:45.767702Z","caller":"traceutil/trace.go:171","msg":"trace[2044230480] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8471; }","duration":"113.27329ms","start":"2026-07-02T16:44:45.654402Z","end":"2026-07-02T16:44:45.767675Z","steps":["trace[2044230480] 'agreement among raft nodes before linearized reading' (duration: 62.601185ms)","trace[2044230480] 'range keys from in-memory index tree' (duration: 50.42789ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:44:46.487606Z","caller":"traceutil/trace.go:171","msg":"trace[236942118] transaction","detail":"{read_only:false; response_revision:8476; number_of_response:1; }","duration":"256.159695ms","start":"2026-07-02T16:44:46.23141Z","end":"2026-07-02T16:44:46.48757Z","steps":["trace[236942118] 'process raft request' (duration: 190.188397ms)","trace[236942118] 'compare' (duration: 65.744993ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:44:46.48763Z","caller":"traceutil/trace.go:171","msg":"trace[744783103] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"255.998402ms","start":"2026-07-02T16:44:46.231594Z","end":"2026-07-02T16:44:46.487592Z","steps":["trace[744783103] 'process raft request' (duration: 255.862499ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:45:18.102649Z","caller":"traceutil/trace.go:171","msg":"trace[197471829] linearizableReadLoop","detail":"{readStateIndex:9014; appliedIndex:9013; }","duration":"107.033918ms","start":"2026-07-02T16:45:17.995592Z","end":"2026-07-02T16:45:18.102626Z","steps":["trace[197471829] 'read index received' (duration: 65.193934ms)","trace[197471829] 'applied index is now lower than readState.Index' (duration: 41.838854ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:45:18.102908Z","caller":"traceutil/trace.go:171","msg":"trace[797876432] transaction","detail":"{read_only:false; response_revision:8700; number_of_response:1; }","duration":"119.199474ms","start":"2026-07-02T16:45:17.983698Z","end":"2026-07-02T16:45:18.102897Z","steps":["trace[797876432] 'process raft request' (duration: 77.083534ms)","trace[797876432] 'compare' (duration: 41.733762ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:45:18.103099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.494338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pff49\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-07-02T16:45:18.103126Z","caller":"traceutil/trace.go:171","msg":"trace[105004270] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pff49; range_end:; response_count:1; response_revision:8700; }","duration":"107.56609ms","start":"2026-07-02T16:45:17.995551Z","end":"2026-07-02T16:45:18.103118Z","steps":["trace[105004270] 'agreement among raft nodes before linearized reading' (duration: 107.467597ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:45:27.233948Z","caller":"traceutil/trace.go:171","msg":"trace[631771380] transaction","detail":"{read_only:false; response_revision:8792; number_of_response:1; }","duration":"101.360044ms","start":"2026-07-02T16:45:27.132559Z","end":"2026-07-02T16:45:27.233919Z","steps":["trace[631771380] 'process raft request' (duration: 101.19363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:45:27.816139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.145085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T16:45:27.816248Z","caller":"traceutil/trace.go:171","msg":"trace[811713828] transaction","detail":"{read_only:false; response_revision:8795; number_of_response:1; }","duration":"190.073291ms","start":"2026-07-02T16:45:27.626154Z","end":"2026-07-02T16:45:27.816227Z","steps":["trace[811713828] 'process raft request' (duration: 58.751603ms)","trace[811713828] 'compare' (duration: 130.942159ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:45:28.750724Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023a8.wal"} {"level":"info","ts":"2026-07-02T16:46:57.641853Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b5a1dacc8ba86a4f","local-member-applied-index":10002,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T16:46:57.647409Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10002} {"level":"info","ts":"2026-07-02T16:46:57.647485Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5002} {"level":"info","ts":"2026-07-02T16:49:17.259796Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8181} {"level":"info","ts":"2026-07-02T16:49:17.31926Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8181,"took":"57.748456ms","hash":1565167431,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":29683712,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-02T16:49:17.319311Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1565167431,"revision":8181,"compact-revision":6101} {"level":"info","ts":"2026-07-02T16:50:07.507299Z","caller":"traceutil/trace.go:171","msg":"trace[1543705999] transaction","detail":"{read_only:false; response_revision:11279; number_of_response:1; }","duration":"135.02444ms","start":"2026-07-02T16:50:07.37225Z","end":"2026-07-02T16:50:07.507274Z","steps":["trace[1543705999] 'process raft request' (duration: 75.624107ms)","trace[1543705999] 'compare' (duration: 59.198958ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:50:07.507431Z","caller":"traceutil/trace.go:171","msg":"trace[1910375903] transaction","detail":"{read_only:false; response_revision:11280; number_of_response:1; }","duration":"121.804842ms","start":"2026-07-02T16:50:07.385597Z","end":"2026-07-02T16:50:07.507402Z","steps":["trace[1910375903] 'process raft request' (duration: 121.616758ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:50:07.50739Z","caller":"traceutil/trace.go:171","msg":"trace[1680854440] linearizableReadLoop","detail":"{readStateIndex:11672; appliedIndex:11670; }","duration":"105.246932ms","start":"2026-07-02T16:50:07.402123Z","end":"2026-07-02T16:50:07.50737Z","steps":["trace[1680854440] 'read index received' (duration: 45.765557ms)","trace[1680854440] 'applied index is now lower than readState.Index' (duration: 59.479495ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:50:07.50749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.354364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T16:50:07.50754Z","caller":"traceutil/trace.go:171","msg":"trace[1796401979] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:11280; }","duration":"105.470066ms","start":"2026-07-02T16:50:07.402057Z","end":"2026-07-02T16:50:07.507527Z","steps":["trace[1796401979] 'agreement among raft nodes before linearized reading' (duration: 105.373054ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:50:29.436849Z","caller":"traceutil/trace.go:171","msg":"trace[1018139358] transaction","detail":"{read_only:false; response_revision:11400; number_of_response:1; }","duration":"153.033932ms","start":"2026-07-02T16:50:29.283781Z","end":"2026-07-02T16:50:29.436815Z","steps":["trace[1018139358] 'process raft request' (duration: 143.147567ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:54:17.266154Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10760} {"level":"info","ts":"2026-07-02T16:54:17.352472Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10760,"took":"84.417956ms","hash":473008945,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":27234304,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-02T16:54:17.352588Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":473008945,"revision":10760,"compact-revision":8181} {"level":"warn","ts":"2026-07-02T16:56:23.74352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.405345ms","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-02T16:56:23.743624Z","caller":"traceutil/trace.go:171","msg":"trace[1519123024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13820; }","duration":"112.523038ms","start":"2026-07-02T16:56:23.63108Z","end":"2026-07-02T16:56:23.743603Z","steps":["trace[1519123024] 'range keys from in-memory index tree' (duration: 112.205771ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:56:57.792623Z","caller":"traceutil/trace.go:171","msg":"trace[1439890277] transaction","detail":"{read_only:false; response_revision:14008; number_of_response:1; }","duration":"130.99ms","start":"2026-07-02T16:56:57.661592Z","end":"2026-07-02T16:56:57.792582Z","steps":["trace[1439890277] 'process raft request' (duration: 107.371686ms)","trace[1439890277] 'compare' (duration: 23.476651ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:56:58.814623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.02864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T16:56:58.814708Z","caller":"traceutil/trace.go:171","msg":"trace[1483719783] linearizableReadLoop","detail":"{readStateIndex:14503; appliedIndex:14502; }","duration":"379.696149ms","start":"2026-07-02T16:56:58.434998Z","end":"2026-07-02T16:56:58.814694Z","steps":["trace[1483719783] 'read index received' (duration: 151.535755ms)","trace[1483719783] 'applied index is now lower than readState.Index' (duration: 228.159344ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:56:58.814776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.77617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T16:56:58.814793Z","caller":"traceutil/trace.go:171","msg":"trace[508320505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14014; }","duration":"379.794781ms","start":"2026-07-02T16:56:58.434992Z","end":"2026-07-02T16:56:58.814787Z","steps":["trace[508320505] 'agreement among raft nodes before linearized reading' (duration: 379.75633ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:56:58.814773Z","caller":"traceutil/trace.go:171","msg":"trace[669555553] transaction","detail":"{read_only:false; response_revision:14014; number_of_response:1; }","duration":"416.681794ms","start":"2026-07-02T16:56:58.398062Z","end":"2026-07-02T16:56:58.814744Z","steps":["trace[669555553] 'process raft request' (duration: 188.455919ms)","trace[669555553] 'compare' (duration: 227.922597ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:56:58.814813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:56:58.434942Z","time spent":"379.867113ms","remote":"127.0.0.1:46914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T16:56:58.814874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:56:58.398046Z","time spent":"416.778646ms","remote":"127.0.0.1:47128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12483,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T16:56:58.820146Z","caller":"traceutil/trace.go:171","msg":"trace[1503404486] transaction","detail":"{read_only:false; response_revision:14015; number_of_response:1; }","duration":"362.085816ms","start":"2026-07-02T16:56:58.45802Z","end":"2026-07-02T16:56:58.820106Z","steps":["trace[1503404486] 'process raft request' (duration: 361.857701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:56:58.820166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.918098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T16:56:58.820235Z","caller":"traceutil/trace.go:171","msg":"trace[320179461] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14015; }","duration":"371.024171ms","start":"2026-07-02T16:56:58.449194Z","end":"2026-07-02T16:56:58.820218Z","steps":["trace[320179461] 'agreement among raft nodes before linearized reading' (duration: 370.909167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:56:58.820275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:56:58.449179Z","time spent":"371.088762ms","remote":"127.0.0.1:47358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-02T16:56:58.820305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T16:56:58.458005Z","time spent":"362.216609ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T16:57:20.614582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.145119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-02T16:57:20.614656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.161239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-02T16:57:20.614726Z","caller":"traceutil/trace.go:171","msg":"trace[732811001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14317; }","duration":"175.369514ms","start":"2026-07-02T16:57:20.439342Z","end":"2026-07-02T16:57:20.614711Z","steps":["trace[732811001] 'range keys from in-memory index tree' (duration: 174.934365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:57:20.614708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.300855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.203\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-07-02T16:57:20.614753Z","caller":"traceutil/trace.go:171","msg":"trace[433579088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14317; }","duration":"175.329122ms","start":"2026-07-02T16:57:20.439402Z","end":"2026-07-02T16:57:20.614731Z","steps":["trace[433579088] 'range keys from in-memory index tree' (duration: 174.905264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:57:20.61474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.028717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-02T16:57:20.614775Z","caller":"traceutil/trace.go:171","msg":"trace[216017859] range","detail":"{range_begin:/registry/masterleases/199.19.213.203; range_end:; response_count:1; response_revision:14317; }","duration":"193.419467ms","start":"2026-07-02T16:57:20.421338Z","end":"2026-07-02T16:57:20.614758Z","steps":["trace[216017859] 'range keys from in-memory index tree' (duration: 193.065548ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:57:20.614828Z","caller":"traceutil/trace.go:171","msg":"trace[934355883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14317; }","duration":"181.165861ms","start":"2026-07-02T16:57:20.433642Z","end":"2026-07-02T16:57:20.614808Z","steps":["trace[934355883] 'range keys from in-memory index tree' (duration: 180.821842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:57:20.61482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.966994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T16:57:20.614801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.604907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T16:57:20.614583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.839278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1bfebf84-21ba-47b8-b7bd-ee40af0a5cf2\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-07-02T16:57:20.614894Z","caller":"traceutil/trace.go:171","msg":"trace[345812762] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1bfebf84-21ba-47b8-b7bd-ee40af0a5cf2; range_end:; response_count:1; response_revision:14317; }","duration":"269.202707ms","start":"2026-07-02T16:57:20.345682Z","end":"2026-07-02T16:57:20.614885Z","steps":["trace[345812762] 'range keys from in-memory index tree' (duration: 268.650134ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:57:20.614896Z","caller":"traceutil/trace.go:171","msg":"trace[956488732] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:14317; }","duration":"196.747771ms","start":"2026-07-02T16:57:20.41813Z","end":"2026-07-02T16:57:20.614878Z","steps":["trace[956488732] 'count revisions from in-memory index tree' (duration: 196.502944ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:57:20.614894Z","caller":"traceutil/trace.go:171","msg":"trace[1908811259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14317; }","duration":"180.086037ms","start":"2026-07-02T16:57:20.434787Z","end":"2026-07-02T16:57:20.614873Z","steps":["trace[1908811259] 'range keys from in-memory index tree' (duration: 179.924353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:58:45.873513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.506431ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-02T16:58:46.340274Z","caller":"traceutil/trace.go:171","msg":"trace[1499477191] transaction","detail":"{read_only:false; response_revision:15127; number_of_response:1; }","duration":"164.690299ms","start":"2026-07-02T16:58:46.175538Z","end":"2026-07-02T16:58:46.340228Z","steps":["trace[1499477191] 'process raft request' (duration: 124.924535ms)","trace[1499477191] 'compare' (duration: 39.454198ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T16:58:46.340336Z","caller":"traceutil/trace.go:171","msg":"trace[1719737890] linearizableReadLoop","detail":"{readStateIndex:15652; appliedIndex:15651; }","duration":"138.880188ms","start":"2026-07-02T16:58:46.201434Z","end":"2026-07-02T16:58:46.340314Z","steps":["trace[1719737890] 'read index received' (duration: 99.007022ms)","trace[1719737890] 'applied index is now lower than readState.Index' (duration: 39.872086ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T16:58:46.340508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.073912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-j9bz5\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-07-02T16:58:46.340558Z","caller":"traceutil/trace.go:171","msg":"trace[927597190] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-j9bz5; range_end:; response_count:1; response_revision:15128; }","duration":"139.167644ms","start":"2026-07-02T16:58:46.201377Z","end":"2026-07-02T16:58:46.340545Z","steps":["trace[927597190] 'agreement among raft nodes before linearized reading' (duration: 139.048162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:58:46.340666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.866005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-rpknq\" ","response":"range_response_count:1 size:20659"} {"level":"info","ts":"2026-07-02T16:58:46.340718Z","caller":"traceutil/trace.go:171","msg":"trace[1915640129] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-rpknq; range_end:; response_count:1; response_revision:15128; }","duration":"125.953366ms","start":"2026-07-02T16:58:46.214747Z","end":"2026-07-02T16:58:46.3407Z","steps":["trace[1915640129] 'agreement among raft nodes before linearized reading' (duration: 125.765142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T16:58:46.340941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.053551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-j9bz5\" ","response":"range_response_count:1 size:22132"} {"level":"info","ts":"2026-07-02T16:58:46.340975Z","caller":"traceutil/trace.go:171","msg":"trace[523765243] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-j9bz5; range_end:; response_count:1; response_revision:15128; }","duration":"110.112182ms","start":"2026-07-02T16:58:46.230856Z","end":"2026-07-02T16:58:46.340968Z","steps":["trace[523765243] 'agreement among raft nodes before linearized reading' (duration: 110.039271ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T16:59:17.27823Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12878} {"level":"info","ts":"2026-07-02T16:59:17.366144Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12878,"took":"85.980368ms","hash":1707262840,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":30294016,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-02T16:59:17.366361Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1707262840,"revision":12878,"compact-revision":10760} {"level":"info","ts":"2026-07-02T17:04:17.285447Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15311} {"level":"info","ts":"2026-07-02T17:04:17.394532Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15311,"took":"105.961653ms","hash":2435698504,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":32190464,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T17:04:17.394643Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2435698504,"revision":15311,"compact-revision":12878} {"level":"warn","ts":"2026-07-02T17:04:39.151071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.592972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-02T17:04:39.151157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.874192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-rpknq\" ","response":"range_response_count:1 size:20762"} {"level":"info","ts":"2026-07-02T17:04:39.151198Z","caller":"traceutil/trace.go:171","msg":"trace[24826927] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:17791; }","duration":"155.772936ms","start":"2026-07-02T17:04:38.995405Z","end":"2026-07-02T17:04:39.151178Z","steps":["trace[24826927] 'count revisions from in-memory index tree' (duration: 155.435358ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:04:39.151266Z","caller":"traceutil/trace.go:171","msg":"trace[1603297139] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-rpknq; range_end:; response_count:1; response_revision:17791; }","duration":"118.059436ms","start":"2026-07-02T17:04:39.033167Z","end":"2026-07-02T17:04:39.151226Z","steps":["trace[1603297139] 'range keys from in-memory index tree' (duration: 117.648997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:04:40.125977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.336392ms","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-02T17:04:40.12606Z","caller":"traceutil/trace.go:171","msg":"trace[185674156] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17793; }","duration":"256.464585ms","start":"2026-07-02T17:04:39.869579Z","end":"2026-07-02T17:04:40.126043Z","steps":["trace[185674156] 'range keys from in-memory index tree' (duration: 256.134257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:04:40.126116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.839918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:04:40.126193Z","caller":"traceutil/trace.go:171","msg":"trace[1333790175] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:17793; }","duration":"213.96648ms","start":"2026-07-02T17:04:39.912207Z","end":"2026-07-02T17:04:40.126174Z","steps":["trace[1333790175] 'count revisions from in-memory index tree' (duration: 213.736935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:04:40.12663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.64628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29716860-qzf7m\" ","response":"range_response_count:1 size:7041"} {"level":"info","ts":"2026-07-02T17:04:40.126663Z","caller":"traceutil/trace.go:171","msg":"trace[75700159] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29716860-qzf7m; range_end:; response_count:1; response_revision:17793; }","duration":"113.711161ms","start":"2026-07-02T17:04:40.012944Z","end":"2026-07-02T17:04:40.126655Z","steps":["trace[75700159] 'range keys from in-memory index tree' (duration: 113.299612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:04:48.966159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.05766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:04:48.966267Z","caller":"traceutil/trace.go:171","msg":"trace[222500881] linearizableReadLoop","detail":"{readStateIndex:18601; appliedIndex:18600; }","duration":"172.295386ms","start":"2026-07-02T17:04:48.793956Z","end":"2026-07-02T17:04:48.966251Z","steps":["trace[222500881] 'read index received' (duration: 52.099073ms)","trace[222500881] 'applied index is now lower than readState.Index' (duration: 120.194713ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:04:48.966455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.50344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:651"} {"level":"info","ts":"2026-07-02T17:04:48.966502Z","caller":"traceutil/trace.go:171","msg":"trace[496813572] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:17983; }","duration":"172.573732ms","start":"2026-07-02T17:04:48.793915Z","end":"2026-07-02T17:04:48.966489Z","steps":["trace[496813572] 'agreement among raft nodes before linearized reading' (duration: 172.415748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:04:48.966493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.197895ms","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-02T17:04:48.966578Z","caller":"traceutil/trace.go:171","msg":"trace[1943396956] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17983; }","duration":"139.321008ms","start":"2026-07-02T17:04:48.827231Z","end":"2026-07-02T17:04:48.966552Z","steps":["trace[1943396956] 'agreement among raft nodes before linearized reading' (duration: 139.120594ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:04:48.966365Z","caller":"traceutil/trace.go:171","msg":"trace[452576156] transaction","detail":"{read_only:false; response_revision:17983; number_of_response:1; }","duration":"173.216475ms","start":"2026-07-02T17:04:48.793112Z","end":"2026-07-02T17:04:48.966328Z","steps":["trace[452576156] 'process raft request' (duration: 52.90851ms)","trace[452576156] 'compare' (duration: 119.854816ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:07:35.747936Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b5a1dacc8ba86a4f","local-member-applied-index":20003,"local-member-snapshot-index":10002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T17:07:35.752677Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-07-02T17:07:35.752754Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-07-02T17:09:17.291559Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17639} {"level":"info","ts":"2026-07-02T17:09:17.385549Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17639,"took":"91.287563ms","hash":1023515272,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":34848768,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-02T17:09:17.385676Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1023515272,"revision":17639,"compact-revision":15311} {"level":"info","ts":"2026-07-02T17:10:29.806048Z","caller":"traceutil/trace.go:171","msg":"trace[1660898083] transaction","detail":"{read_only:false; response_revision:20541; number_of_response:1; }","duration":"137.938096ms","start":"2026-07-02T17:10:29.668081Z","end":"2026-07-02T17:10:29.806019Z","steps":["trace[1660898083] 'process raft request' (duration: 137.786033ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:10:29.996956Z","caller":"traceutil/trace.go:171","msg":"trace[1598634364] linearizableReadLoop","detail":"{readStateIndex:21241; appliedIndex:21239; }","duration":"261.595739ms","start":"2026-07-02T17:10:29.73533Z","end":"2026-07-02T17:10:29.996926Z","steps":["trace[1598634364] 'read index received' (duration: 70.544952ms)","trace[1598634364] 'applied index is now lower than readState.Index' (duration: 191.049617ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:10:29.99708Z","caller":"traceutil/trace.go:171","msg":"trace[450910069] transaction","detail":"{read_only:false; response_revision:20542; number_of_response:1; }","duration":"319.830093ms","start":"2026-07-02T17:10:29.677224Z","end":"2026-07-02T17:10:29.997055Z","steps":["trace[450910069] 'process raft request' (duration: 319.509486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:10:29.997195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:10:29.677195Z","time spent":"319.941196ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:10:29.997219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.859305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-02T17:10:29.997266Z","caller":"traceutil/trace.go:171","msg":"trace[1964166394] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20542; }","duration":"261.973717ms","start":"2026-07-02T17:10:29.73528Z","end":"2026-07-02T17:10:29.997254Z","steps":["trace[1964166394] 'agreement among raft nodes before linearized reading' (duration: 261.780593ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:10:30.156265Z","caller":"traceutil/trace.go:171","msg":"trace[525289718] transaction","detail":"{read_only:false; response_revision:20543; number_of_response:1; }","duration":"226.734543ms","start":"2026-07-02T17:10:29.929496Z","end":"2026-07-02T17:10:30.15623Z","steps":["trace[525289718] 'process raft request' (duration: 209.504139ms)","trace[525289718] 'compare' (duration: 17.001379ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:10:30.156504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.997477ms","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-02T17:10:30.156608Z","caller":"traceutil/trace.go:171","msg":"trace[1879390304] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20543; }","duration":"209.10257ms","start":"2026-07-02T17:10:29.947476Z","end":"2026-07-02T17:10:30.156579Z","steps":["trace[1879390304] 'agreement among raft nodes before linearized reading' (duration: 208.843655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:10:30.156739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.650633ms","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-02T17:10:30.156777Z","caller":"traceutil/trace.go:171","msg":"trace[395879953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20543; }","duration":"327.740415ms","start":"2026-07-02T17:10:29.829027Z","end":"2026-07-02T17:10:30.156767Z","steps":["trace[395879953] 'agreement among raft nodes before linearized reading' (duration: 327.581322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:10:30.156804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:10:29.829004Z","time spent":"327.793946ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-02T17:10:30.156187Z","caller":"traceutil/trace.go:171","msg":"trace[1222923450] linearizableReadLoop","detail":"{readStateIndex:21242; appliedIndex:21241; }","duration":"159.123235ms","start":"2026-07-02T17:10:29.997032Z","end":"2026-07-02T17:10:30.156155Z","steps":["trace[1222923450] 'read index received' (duration: 141.865469ms)","trace[1222923450] 'applied index is now lower than readState.Index' (duration: 17.256266ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:10:30.157462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.928086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-02T17:10:30.157528Z","caller":"traceutil/trace.go:171","msg":"trace[39843503] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20543; }","duration":"157.028289ms","start":"2026-07-02T17:10:30.000482Z","end":"2026-07-02T17:10:30.157511Z","steps":["trace[39843503] 'agreement among raft nodes before linearized reading' (duration: 156.824345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:10:30.449522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.715166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:10:30.44969Z","caller":"traceutil/trace.go:171","msg":"trace[1957373843] linearizableReadLoop","detail":"{readStateIndex:21244; appliedIndex:21243; }","duration":"232.185479ms","start":"2026-07-02T17:10:30.21748Z","end":"2026-07-02T17:10:30.449665Z","steps":["trace[1957373843] 'read index received' (duration: 96.075225ms)","trace[1957373843] 'applied index is now lower than readState.Index' (duration: 136.108104ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:10:30.449721Z","caller":"traceutil/trace.go:171","msg":"trace[1029900017] transaction","detail":"{read_only:false; response_revision:20545; number_of_response:1; }","duration":"289.095056ms","start":"2026-07-02T17:10:30.160579Z","end":"2026-07-02T17:10:30.449674Z","steps":["trace[1029900017] 'process raft request' (duration: 153.110414ms)","trace[1029900017] 'compare' (duration: 135.586263ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:10:30.449835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.348514ms","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-02T17:10:30.449881Z","caller":"traceutil/trace.go:171","msg":"trace[1393488061] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20545; }","duration":"232.395276ms","start":"2026-07-02T17:10:30.217471Z","end":"2026-07-02T17:10:30.449867Z","steps":["trace[1393488061] 'agreement among raft nodes before linearized reading' (duration: 232.312283ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:10:30.683707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.254483ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-07-02T17:10:30.683842Z","caller":"traceutil/trace.go:171","msg":"trace[27375266] linearizableReadLoop","detail":"{readStateIndex:21245; appliedIndex:21244; }","duration":"214.820164ms","start":"2026-07-02T17:10:30.469Z","end":"2026-07-02T17:10:30.68382Z","steps":["trace[27375266] 'read index received' (duration: 104.413587ms)","trace[27375266] 'applied index is now lower than readState.Index' (duration: 110.404737ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:10:30.684049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.037118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-07-02T17:10:30.684089Z","caller":"traceutil/trace.go:171","msg":"trace[1008657649] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:20545; }","duration":"215.10681ms","start":"2026-07-02T17:10:30.468967Z","end":"2026-07-02T17:10:30.684074Z","steps":["trace[1008657649] 'agreement among raft nodes before linearized reading' (duration: 214.909266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:10:31.026671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.76994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:10:31.026785Z","caller":"traceutil/trace.go:171","msg":"trace[1388474268] linearizableReadLoop","detail":"{readStateIndex:21247; appliedIndex:21246; }","duration":"195.965875ms","start":"2026-07-02T17:10:30.830803Z","end":"2026-07-02T17:10:31.026769Z","steps":["trace[1388474268] 'read index received' (duration: 93.00412ms)","trace[1388474268] 'applied index is now lower than readState.Index' (duration: 102.959925ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:10:31.026854Z","caller":"traceutil/trace.go:171","msg":"trace[1785997254] transaction","detail":"{read_only:false; response_revision:20547; number_of_response:1; }","duration":"265.83506ms","start":"2026-07-02T17:10:30.760968Z","end":"2026-07-02T17:10:31.026804Z","steps":["trace[1785997254] 'process raft request' (duration: 162.824755ms)","trace[1785997254] 'compare' (duration: 102.510695ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:10:31.026964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.1581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T17:10:31.027003Z","caller":"traceutil/trace.go:171","msg":"trace[1789945318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20547; }","duration":"196.22285ms","start":"2026-07-02T17:10:30.830768Z","end":"2026-07-02T17:10:31.026991Z","steps":["trace[1789945318] 'agreement among raft nodes before linearized reading' (duration: 196.072726ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:10:31.070713Z","caller":"traceutil/trace.go:171","msg":"trace[2067084774] transaction","detail":"{read_only:false; response_revision:20548; number_of_response:1; }","duration":"183.537124ms","start":"2026-07-02T17:10:30.887141Z","end":"2026-07-02T17:10:31.070678Z","steps":["trace[2067084774] 'process raft request' (duration: 183.035813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:10:31.070876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.080284ms","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-02T17:10:31.070995Z","caller":"traceutil/trace.go:171","msg":"trace[429673788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20548; }","duration":"230.244298ms","start":"2026-07-02T17:10:30.840726Z","end":"2026-07-02T17:10:31.070971Z","steps":["trace[429673788] 'agreement among raft nodes before linearized reading' (duration: 229.895291ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:14:17.296479Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20113} {"level":"info","ts":"2026-07-02T17:14:17.401445Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20113,"took":"101.923222ms","hash":494881247,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":41492480,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-07-02T17:14:17.401507Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":494881247,"revision":20113,"compact-revision":17639} {"level":"warn","ts":"2026-07-02T17:14:21.747475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.333966ms","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-02T17:14:21.74758Z","caller":"traceutil/trace.go:171","msg":"trace[651537344] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22953; }","duration":"105.49123ms","start":"2026-07-02T17:14:21.642064Z","end":"2026-07-02T17:14:21.747556Z","steps":["trace[651537344] 'range keys from in-memory index tree' (duration: 105.134892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:57.280313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.719015ms","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-02T17:17:57.280397Z","caller":"traceutil/trace.go:171","msg":"trace[326738010] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25137; }","duration":"111.858308ms","start":"2026-07-02T17:17:57.168522Z","end":"2026-07-02T17:17:57.28038Z","steps":["trace[326738010] 'range keys from in-memory index tree' (duration: 111.514391ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:57.38404Z","caller":"traceutil/trace.go:171","msg":"trace[438230303] transaction","detail":"{read_only:false; response_revision:25138; number_of_response:1; }","duration":"100.186905ms","start":"2026-07-02T17:17:57.283831Z","end":"2026-07-02T17:17:57.384017Z","steps":["trace[438230303] 'process raft request' (duration: 100.042912ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:57.529479Z","caller":"traceutil/trace.go:171","msg":"trace[215083587] transaction","detail":"{read_only:false; response_revision:25140; number_of_response:1; }","duration":"140.739016ms","start":"2026-07-02T17:17:57.388715Z","end":"2026-07-02T17:17:57.529454Z","steps":["trace[215083587] 'process raft request' (duration: 126.715792ms)","trace[215083587] 'compare' (duration: 13.80677ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:57.564316Z","caller":"traceutil/trace.go:171","msg":"trace[1018515895] linearizableReadLoop","detail":"{readStateIndex:25970; appliedIndex:25968; }","duration":"130.989494ms","start":"2026-07-02T17:17:57.433304Z","end":"2026-07-02T17:17:57.564294Z","steps":["trace[1018515895] 'read index received' (duration: 82.085403ms)","trace[1018515895] 'applied index is now lower than readState.Index' (duration: 48.902871ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:57.564355Z","caller":"traceutil/trace.go:171","msg":"trace[317326997] transaction","detail":"{read_only:false; response_revision:25141; number_of_response:1; }","duration":"165.757309ms","start":"2026-07-02T17:17:57.398561Z","end":"2026-07-02T17:17:57.564318Z","steps":["trace[317326997] 'process raft request' (duration: 165.469153ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:57.564472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.126277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:17:57.564502Z","caller":"traceutil/trace.go:171","msg":"trace[2102069835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25142; }","duration":"131.229399ms","start":"2026-07-02T17:17:57.433264Z","end":"2026-07-02T17:17:57.564493Z","steps":["trace[2102069835] 'agreement among raft nodes before linearized reading' (duration: 131.135527ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:57.718628Z","caller":"traceutil/trace.go:171","msg":"trace[1514350541] transaction","detail":"{read_only:false; response_revision:25143; number_of_response:1; }","duration":"118.647516ms","start":"2026-07-02T17:17:57.599952Z","end":"2026-07-02T17:17:57.7186Z","steps":["trace[1514350541] 'process raft request' (duration: 99.228084ms)","trace[1514350541] 'compare' (duration: 19.145236ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:57.914271Z","caller":"traceutil/trace.go:171","msg":"trace[1892461818] linearizableReadLoop","detail":"{readStateIndex:25975; appliedIndex:25974; }","duration":"119.407132ms","start":"2026-07-02T17:17:57.794838Z","end":"2026-07-02T17:17:57.914245Z","steps":["trace[1892461818] 'read index received' (duration: 60.991364ms)","trace[1892461818] 'applied index is now lower than readState.Index' (duration: 58.414868ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:17:57.914401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.540165ms","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-02T17:17:57.914437Z","caller":"traceutil/trace.go:171","msg":"trace[178866118] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25146; }","duration":"119.630267ms","start":"2026-07-02T17:17:57.794794Z","end":"2026-07-02T17:17:57.914425Z","steps":["trace[178866118] 'agreement among raft nodes before linearized reading' (duration: 119.552555ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:57.915076Z","caller":"traceutil/trace.go:171","msg":"trace[1726539623] transaction","detail":"{read_only:false; response_revision:25146; number_of_response:1; }","duration":"174.191512ms","start":"2026-07-02T17:17:57.740166Z","end":"2026-07-02T17:17:57.914357Z","steps":["trace[1726539623] 'process raft request' (duration: 115.558829ms)","trace[1726539623] 'compare' (duration: 58.393128ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:58.525307Z","caller":"traceutil/trace.go:171","msg":"trace[693783344] linearizableReadLoop","detail":"{readStateIndex:25976; appliedIndex:25975; }","duration":"198.451948ms","start":"2026-07-02T17:17:58.326834Z","end":"2026-07-02T17:17:58.525286Z","steps":["trace[693783344] 'read index received' (duration: 198.337216ms)","trace[693783344] 'applied index is now lower than readState.Index' (duration: 113.612µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:58.525572Z","caller":"traceutil/trace.go:171","msg":"trace[576934968] transaction","detail":"{read_only:false; response_revision:25147; number_of_response:1; }","duration":"239.961069ms","start":"2026-07-02T17:17:58.285596Z","end":"2026-07-02T17:17:58.525557Z","steps":["trace[576934968] 'process raft request' (duration: 239.55369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:58.526127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.282896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:17:58.52618Z","caller":"traceutil/trace.go:171","msg":"trace[1005863021] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:25147; }","duration":"199.347778ms","start":"2026-07-02T17:17:58.326817Z","end":"2026-07-02T17:17:58.526164Z","steps":["trace[1005863021] 'agreement among raft nodes before linearized reading' (duration: 199.257676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:59.125293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.796168ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:17:59.125397Z","caller":"traceutil/trace.go:171","msg":"trace[234410023] transaction","detail":"{read_only:false; response_revision:25150; number_of_response:1; }","duration":"326.62724ms","start":"2026-07-02T17:17:58.798745Z","end":"2026-07-02T17:17:59.125372Z","steps":["trace[234410023] 'process raft request' (duration: 149.669569ms)","trace[234410023] 'compare' (duration: 176.325938ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:17:59.125514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:17:58.798735Z","time spent":"326.702982ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:17:59.446555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.377659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:17:59.447069Z","caller":"traceutil/trace.go:171","msg":"trace[1978807369] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:25150; }","duration":"164.91859ms","start":"2026-07-02T17:17:59.282124Z","end":"2026-07-02T17:17:59.447043Z","steps":["trace[1978807369] 'count revisions from in-memory index tree' (duration: 164.285767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:17:59.446966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.818778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-02T17:17:59.447255Z","caller":"traceutil/trace.go:171","msg":"trace[575807463] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25150; }","duration":"177.106505ms","start":"2026-07-02T17:17:59.270131Z","end":"2026-07-02T17:17:59.447237Z","steps":["trace[575807463] 'range keys from in-memory index tree' (duration: 176.668745ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:59.583042Z","caller":"traceutil/trace.go:171","msg":"trace[1498371028] transaction","detail":"{read_only:false; response_revision:25152; number_of_response:1; }","duration":"131.910743ms","start":"2026-07-02T17:17:59.451083Z","end":"2026-07-02T17:17:59.582994Z","steps":["trace[1498371028] 'process raft request' (duration: 131.675988ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:59.583138Z","caller":"traceutil/trace.go:171","msg":"trace[1438524568] linearizableReadLoop","detail":"{readStateIndex:25981; appliedIndex:25980; }","duration":"131.428853ms","start":"2026-07-02T17:17:59.451481Z","end":"2026-07-02T17:17:59.58291Z","steps":["trace[1438524568] 'read index received' (duration: 131.189018ms)","trace[1438524568] 'applied index is now lower than readState.Index' (duration: 238.615µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:17:59.583441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.939994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-02T17:17:59.58353Z","caller":"traceutil/trace.go:171","msg":"trace[1103665509] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25152; }","duration":"132.058417ms","start":"2026-07-02T17:17:59.451449Z","end":"2026-07-02T17:17:59.583508Z","steps":["trace[1103665509] 'agreement among raft nodes before linearized reading' (duration: 131.656419ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:59.583524Z","caller":"traceutil/trace.go:171","msg":"trace[614624029] transaction","detail":"{read_only:false; response_revision:25153; number_of_response:1; }","duration":"118.624264ms","start":"2026-07-02T17:17:59.464882Z","end":"2026-07-02T17:17:59.583506Z","steps":["trace[614624029] 'process raft request' (duration: 118.525052ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:17:59.733421Z","caller":"traceutil/trace.go:171","msg":"trace[349117045] transaction","detail":"{read_only:false; response_revision:25155; number_of_response:1; }","duration":"143.935965ms","start":"2026-07-02T17:17:59.589464Z","end":"2026-07-02T17:17:59.7334Z","steps":["trace[349117045] 'process raft request' (duration: 127.727434ms)","trace[349117045] 'compare' (duration: 16.005086ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:17:59.73361Z","caller":"traceutil/trace.go:171","msg":"trace[1966668773] transaction","detail":"{read_only:false; response_revision:25156; number_of_response:1; }","duration":"143.580048ms","start":"2026-07-02T17:17:59.590012Z","end":"2026-07-02T17:17:59.733592Z","steps":["trace[1966668773] 'process raft request' (duration: 143.352202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:00.019333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.133227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:18:00.019511Z","caller":"traceutil/trace.go:171","msg":"trace[1527715987] transaction","detail":"{read_only:false; response_revision:25157; number_of_response:1; }","duration":"296.375294ms","start":"2026-07-02T17:17:59.723116Z","end":"2026-07-02T17:18:00.019491Z","steps":["trace[1527715987] 'process raft request' (duration: 147.004181ms)","trace[1527715987] 'compare' (duration: 149.020195ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:00.019534Z","caller":"traceutil/trace.go:171","msg":"trace[151405285] linearizableReadLoop","detail":"{readStateIndex:25988; appliedIndex:25985; }","duration":"280.208203ms","start":"2026-07-02T17:17:59.739313Z","end":"2026-07-02T17:18:00.019521Z","steps":["trace[151405285] 'read index received' (duration: 130.87042ms)","trace[151405285] 'applied index is now lower than readState.Index' (duration: 149.336823ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:00.019655Z","caller":"traceutil/trace.go:171","msg":"trace[561976737] transaction","detail":"{read_only:false; response_revision:25158; number_of_response:1; }","duration":"296.508147ms","start":"2026-07-02T17:17:59.723137Z","end":"2026-07-02T17:18:00.019645Z","steps":["trace[561976737] 'process raft request' (duration: 296.288202ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:00.019745Z","caller":"traceutil/trace.go:171","msg":"trace[2042046798] transaction","detail":"{read_only:false; response_revision:25159; number_of_response:1; }","duration":"296.472457ms","start":"2026-07-02T17:17:59.723265Z","end":"2026-07-02T17:18:00.019737Z","steps":["trace[2042046798] 'process raft request' (duration: 296.212361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:00.020113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.792515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-02T17:18:00.02015Z","caller":"traceutil/trace.go:171","msg":"trace[1009572662] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:25159; }","duration":"280.866167ms","start":"2026-07-02T17:17:59.739271Z","end":"2026-07-02T17:18:00.020138Z","steps":["trace[1009572662] 'agreement among raft nodes before linearized reading' (duration: 280.53387ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:00.023351Z","caller":"traceutil/trace.go:171","msg":"trace[816338968] transaction","detail":"{read_only:false; response_revision:25160; number_of_response:1; }","duration":"104.231572ms","start":"2026-07-02T17:17:59.919094Z","end":"2026-07-02T17:18:00.023325Z","steps":["trace[816338968] 'process raft request' (duration: 104.1023ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:00.162011Z","caller":"traceutil/trace.go:171","msg":"trace[362733589] linearizableReadLoop","detail":"{readStateIndex:25990; appliedIndex:25988; }","duration":"142.429211ms","start":"2026-07-02T17:18:00.019558Z","end":"2026-07-02T17:18:00.161987Z","steps":["trace[362733589] 'read index received' (duration: 3.572937ms)","trace[362733589] 'applied index is now lower than readState.Index' (duration: 138.855264ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:00.162113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T17:18:00.16215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.451476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:00.162138Z","caller":"traceutil/trace.go:171","msg":"trace[617440998] transaction","detail":"{read_only:false; response_revision:25161; number_of_response:1; }","duration":"241.183296ms","start":"2026-07-02T17:17:59.920906Z","end":"2026-07-02T17:18:00.16209Z","steps":["trace[617440998] 'process raft request' (duration: 237.769092ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:00.16221Z","caller":"traceutil/trace.go:171","msg":"trace[2073948378] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25161; }","duration":"414.555269ms","start":"2026-07-02T17:17:59.74764Z","end":"2026-07-02T17:18:00.162196Z","steps":["trace[2073948378] 'agreement among raft nodes before linearized reading' (duration: 414.446186ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:00.162214Z","caller":"traceutil/trace.go:171","msg":"trace[1559922948] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25161; }","duration":"184.416262ms","start":"2026-07-02T17:17:59.977771Z","end":"2026-07-02T17:18:00.162187Z","steps":["trace[1559922948] 'agreement among raft nodes before linearized reading' (duration: 184.227798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:00.162244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:17:59.747619Z","time spent":"414.617871ms","remote":"127.0.0.1:47132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-02T17:18:00.16263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.540701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:00.162676Z","caller":"traceutil/trace.go:171","msg":"trace[412910722] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25161; }","duration":"216.644403ms","start":"2026-07-02T17:17:59.946019Z","end":"2026-07-02T17:18:00.162664Z","steps":["trace[412910722] 'agreement among raft nodes before linearized reading' (duration: 216.4925ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:00.73618Z","caller":"traceutil/trace.go:171","msg":"trace[650759267] linearizableReadLoop","detail":"{readStateIndex:25992; appliedIndex:25990; }","duration":"207.199909ms","start":"2026-07-02T17:18:00.528948Z","end":"2026-07-02T17:18:00.736148Z","steps":["trace[650759267] 'read index received' (duration: 74.519858ms)","trace[650759267] 'applied index is now lower than readState.Index' (duration: 132.67838ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:00.736243Z","caller":"traceutil/trace.go:171","msg":"trace[406317554] transaction","detail":"{read_only:false; response_revision:25162; number_of_response:1; }","duration":"223.793868ms","start":"2026-07-02T17:18:00.512416Z","end":"2026-07-02T17:18:00.73621Z","steps":["trace[406317554] 'process raft request' (duration: 221.846326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:00.736446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.478564ms","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-02T17:18:00.736494Z","caller":"traceutil/trace.go:171","msg":"trace[458182153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25162; }","duration":"207.591208ms","start":"2026-07-02T17:18:00.528889Z","end":"2026-07-02T17:18:00.73648Z","steps":["trace[458182153] 'agreement among raft nodes before linearized reading' (duration: 207.387282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:01.045768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.659784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:18:01.046071Z","caller":"traceutil/trace.go:171","msg":"trace[1522280919] transaction","detail":"{read_only:false; response_revision:25166; number_of_response:1; }","duration":"289.314921ms","start":"2026-07-02T17:18:00.756737Z","end":"2026-07-02T17:18:01.046052Z","steps":["trace[1522280919] 'process raft request' (duration: 289.27287ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:01.046253Z","caller":"traceutil/trace.go:171","msg":"trace[1714239945] linearizableReadLoop","detail":"{readStateIndex:25994; appliedIndex:25992; }","duration":"309.986689ms","start":"2026-07-02T17:18:00.736249Z","end":"2026-07-02T17:18:01.046236Z","steps":["trace[1714239945] 'read index received' (duration: 133.761664ms)","trace[1714239945] 'applied index is now lower than readState.Index' (duration: 176.222895ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:01.046249Z","caller":"traceutil/trace.go:171","msg":"trace[1886556003] transaction","detail":"{read_only:false; response_revision:25164; number_of_response:1; }","duration":"313.739631ms","start":"2026-07-02T17:18:00.732488Z","end":"2026-07-02T17:18:01.046228Z","steps":["trace[1886556003] 'process raft request' (duration: 313.406034ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:01.046266Z","caller":"traceutil/trace.go:171","msg":"trace[2037368350] transaction","detail":"{read_only:false; response_revision:25163; number_of_response:1; }","duration":"441.141637ms","start":"2026-07-02T17:18:00.605104Z","end":"2026-07-02T17:18:01.046246Z","steps":["trace[2037368350] 'process raft request' (duration: 264.918121ms)","trace[2037368350] 'compare' (duration: 175.48613ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:01.04631Z","caller":"traceutil/trace.go:171","msg":"trace[1323626854] transaction","detail":"{read_only:false; response_revision:25165; number_of_response:1; }","duration":"306.612695ms","start":"2026-07-02T17:18:00.739662Z","end":"2026-07-02T17:18:01.046275Z","steps":["trace[1323626854] 'process raft request' (duration: 306.309489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:01.046356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:00.605082Z","time spent":"441.223278ms","remote":"127.0.0.1:46960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:01.046364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:00.732469Z","time spent":"313.834993ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:01.046356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.589791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:01.046462Z","caller":"traceutil/trace.go:171","msg":"trace[1303400867] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:25166; }","duration":"327.701495ms","start":"2026-07-02T17:18:00.718734Z","end":"2026-07-02T17:18:01.046435Z","steps":["trace[1303400867] 'agreement among raft nodes before linearized reading' (duration: 327.546991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:01.046535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:00.718669Z","time spent":"327.846947ms","remote":"127.0.0.1:59006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":140,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true "} {"level":"warn","ts":"2026-07-02T17:18:01.046471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:00.739646Z","time spent":"306.743739ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:18:01.288094Z","caller":"traceutil/trace.go:171","msg":"trace[310773272] transaction","detail":"{read_only:false; response_revision:25167; number_of_response:1; }","duration":"155.537106ms","start":"2026-07-02T17:18:01.132519Z","end":"2026-07-02T17:18:01.288056Z","steps":["trace[310773272] 'process raft request' (duration: 151.033979ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:01.632005Z","caller":"traceutil/trace.go:171","msg":"trace[1774108028] linearizableReadLoop","detail":"{readStateIndex:25998; appliedIndex:25997; }","duration":"148.241988ms","start":"2026-07-02T17:18:01.483734Z","end":"2026-07-02T17:18:01.631976Z","steps":["trace[1774108028] 'read index received' (duration: 148.080775ms)","trace[1774108028] 'applied index is now lower than readState.Index' (duration: 159.983µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:01.632119Z","caller":"traceutil/trace.go:171","msg":"trace[1128075474] transaction","detail":"{read_only:false; response_revision:25168; number_of_response:1; }","duration":"178.422673ms","start":"2026-07-02T17:18:01.453666Z","end":"2026-07-02T17:18:01.632088Z","steps":["trace[1128075474] 'process raft request' (duration: 178.113876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:01.63222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.458113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T17:18:01.632306Z","caller":"traceutil/trace.go:171","msg":"trace[516818497] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:25168; }","duration":"148.606406ms","start":"2026-07-02T17:18:01.483681Z","end":"2026-07-02T17:18:01.632288Z","steps":["trace[516818497] 'agreement among raft nodes before linearized reading' (duration: 148.441152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:01.876031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.707433ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:18:01.87615Z","caller":"traceutil/trace.go:171","msg":"trace[1672938799] linearizableReadLoop","detail":"{readStateIndex:25999; appliedIndex:25998; }","duration":"244.053638ms","start":"2026-07-02T17:18:01.63208Z","end":"2026-07-02T17:18:01.876134Z","steps":["trace[1672938799] 'read index received' (duration: 62.980848ms)","trace[1672938799] 'applied index is now lower than readState.Index' (duration: 181.07136ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:01.876232Z","caller":"traceutil/trace.go:171","msg":"trace[1981248544] transaction","detail":"{read_only:false; response_revision:25169; number_of_response:1; }","duration":"285.078578ms","start":"2026-07-02T17:18:01.591117Z","end":"2026-07-02T17:18:01.876196Z","steps":["trace[1981248544] 'process raft request' (duration: 104.099909ms)","trace[1981248544] 'compare' (duration: 180.424647ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:01.876298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.102605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:01.876335Z","caller":"traceutil/trace.go:171","msg":"trace[1149735020] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25169; }","duration":"277.189377ms","start":"2026-07-02T17:18:01.599131Z","end":"2026-07-02T17:18:01.876321Z","steps":["trace[1149735020] 'agreement among raft nodes before linearized reading' (duration: 277.105925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:01.877042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.736787ms","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-02T17:18:01.877117Z","caller":"traceutil/trace.go:171","msg":"trace[1652086971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25169; }","duration":"287.869979ms","start":"2026-07-02T17:18:01.58923Z","end":"2026-07-02T17:18:01.8771Z","steps":["trace[1652086971] 'agreement among raft nodes before linearized reading' (duration: 287.641464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:01.877222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.320066ms","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-02T17:18:01.877293Z","caller":"traceutil/trace.go:171","msg":"trace[751736226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25169; }","duration":"140.419589ms","start":"2026-07-02T17:18:01.736856Z","end":"2026-07-02T17:18:01.877275Z","steps":["trace[751736226] 'agreement among raft nodes before linearized reading' (duration: 140.252165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:01.877287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.03435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T17:18:01.877366Z","caller":"traceutil/trace.go:171","msg":"trace[366296621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25169; }","duration":"140.156472ms","start":"2026-07-02T17:18:01.737193Z","end":"2026-07-02T17:18:01.87735Z","steps":["trace[366296621] 'agreement among raft nodes before linearized reading' (duration: 139.913407ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:02.137343Z","caller":"traceutil/trace.go:171","msg":"trace[940022600] transaction","detail":"{read_only:false; response_revision:25173; number_of_response:1; }","duration":"110.760355ms","start":"2026-07-02T17:18:02.026565Z","end":"2026-07-02T17:18:02.137325Z","steps":["trace[940022600] 'process raft request' (duration: 110.614232ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:02.140355Z","caller":"traceutil/trace.go:171","msg":"trace[1223777187] transaction","detail":"{read_only:false; response_revision:25175; number_of_response:1; }","duration":"112.262918ms","start":"2026-07-02T17:18:02.02807Z","end":"2026-07-02T17:18:02.140333Z","steps":["trace[1223777187] 'process raft request' (duration: 112.214877ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:02.140386Z","caller":"traceutil/trace.go:171","msg":"trace[785567952] transaction","detail":"{read_only:false; response_revision:25174; number_of_response:1; }","duration":"113.429563ms","start":"2026-07-02T17:18:02.02694Z","end":"2026-07-02T17:18:02.140369Z","steps":["trace[785567952] 'process raft request' (duration: 113.240439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:13.973799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.525276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:13.973882Z","caller":"traceutil/trace.go:171","msg":"trace[521989342] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25259; }","duration":"166.657818ms","start":"2026-07-02T17:18:13.807207Z","end":"2026-07-02T17:18:13.973865Z","steps":["trace[521989342] 'range keys from in-memory index tree' (duration: 166.372463ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:14.268866Z","caller":"traceutil/trace.go:171","msg":"trace[967579409] linearizableReadLoop","detail":"{readStateIndex:26092; appliedIndex:26091; }","duration":"314.519557ms","start":"2026-07-02T17:18:13.95432Z","end":"2026-07-02T17:18:14.26884Z","steps":["trace[967579409] 'read index received' (duration: 314.427565ms)","trace[967579409] 'applied index is now lower than readState.Index' (duration: 90.812µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:14.268925Z","caller":"traceutil/trace.go:171","msg":"trace[1957341558] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"349.823385ms","start":"2026-07-02T17:18:13.919052Z","end":"2026-07-02T17:18:14.268875Z","steps":["trace[1957341558] 'process raft request' (duration: 349.61057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:14.269036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.696882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T17:18:14.269071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:13.919021Z","time spent":"349.960267ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:18:14.269128Z","caller":"traceutil/trace.go:171","msg":"trace[674193089] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:25260; }","duration":"314.817324ms","start":"2026-07-02T17:18:13.954268Z","end":"2026-07-02T17:18:14.269086Z","steps":["trace[674193089] 'agreement among raft nodes before linearized reading' (duration: 314.687662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:14.269174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:13.954241Z","time spent":"314.922586ms","remote":"127.0.0.1:36710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":30,"request content":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true "} {"level":"info","ts":"2026-07-02T17:18:14.571703Z","caller":"traceutil/trace.go:171","msg":"trace[1111492643] transaction","detail":"{read_only:false; response_revision:25261; number_of_response:1; }","duration":"334.846928ms","start":"2026-07-02T17:18:14.23682Z","end":"2026-07-02T17:18:14.571667Z","steps":["trace[1111492643] 'process raft request' (duration: 278.315411ms)","trace[1111492643] 'compare' (duration: 56.109758ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:14.571718Z","caller":"traceutil/trace.go:171","msg":"trace[961967813] transaction","detail":"{read_only:false; response_revision:25262; number_of_response:1; }","duration":"333.299715ms","start":"2026-07-02T17:18:14.238385Z","end":"2026-07-02T17:18:14.571685Z","steps":["trace[961967813] 'process raft request' (duration: 333.134222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:14.57183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:14.236803Z","time spent":"334.960961ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:18:14.57184Z","caller":"traceutil/trace.go:171","msg":"trace[141173719] linearizableReadLoop","detail":"{readStateIndex:26096; appliedIndex:26092; }","duration":"302.871095ms","start":"2026-07-02T17:18:14.268947Z","end":"2026-07-02T17:18:14.571818Z","steps":["trace[141173719] 'read index received' (duration: 246.223976ms)","trace[141173719] 'applied index is now lower than readState.Index' (duration: 56.646129ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:14.571905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:14.238373Z","time spent":"333.429148ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:18:14.571964Z","caller":"traceutil/trace.go:171","msg":"trace[1527152155] transaction","detail":"{read_only:false; response_revision:25263; number_of_response:1; }","duration":"331.493506ms","start":"2026-07-02T17:18:14.24046Z","end":"2026-07-02T17:18:14.571953Z","steps":["trace[1527152155] 'process raft request' (duration: 331.152089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:14.572059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:14.240449Z","time spent":"331.539957ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:14.572074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.2755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T17:18:14.572067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.904598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:14.572116Z","caller":"traceutil/trace.go:171","msg":"trace[1515135751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25264; }","duration":"599.3432ms","start":"2026-07-02T17:18:13.972761Z","end":"2026-07-02T17:18:14.572105Z","steps":["trace[1515135751] 'agreement among raft nodes before linearized reading' (duration: 599.256529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:14.572138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:13.97274Z","time spent":"599.393782ms","remote":"127.0.0.1:46914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T17:18:14.572135Z","caller":"traceutil/trace.go:171","msg":"trace[1746863312] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25264; }","duration":"591.016201ms","start":"2026-07-02T17:18:13.9811Z","end":"2026-07-02T17:18:14.572116Z","steps":["trace[1746863312] 'agreement among raft nodes before linearized reading' (duration: 590.916339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:14.572198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.383399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"warn","ts":"2026-07-02T17:18:14.572191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.423733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:14.572229Z","caller":"traceutil/trace.go:171","msg":"trace[742090384] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25264; }","duration":"352.459791ms","start":"2026-07-02T17:18:14.21976Z","end":"2026-07-02T17:18:14.57222Z","steps":["trace[742090384] 'agreement among raft nodes before linearized reading' (duration: 352.344008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:14.572259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:14.21974Z","time spent":"352.513402ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1890,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"info","ts":"2026-07-02T17:18:14.572252Z","caller":"traceutil/trace.go:171","msg":"trace[1638016605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25264; }","duration":"137.508055ms","start":"2026-07-02T17:18:14.434726Z","end":"2026-07-02T17:18:14.572234Z","steps":["trace[1638016605] 'agreement among raft nodes before linearized reading' (duration: 137.411823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:14.572232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:13.981084Z","time spent":"591.131893ms","remote":"127.0.0.1:47132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-07-02T17:18:14.572284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.671789ms","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-02T17:18:14.572326Z","caller":"traceutil/trace.go:171","msg":"trace[768801383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25264; }","duration":"559.72639ms","start":"2026-07-02T17:18:14.01259Z","end":"2026-07-02T17:18:14.572316Z","steps":["trace[768801383] 'agreement among raft nodes before linearized reading' (duration: 559.582207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:14.572362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:14.012583Z","time spent":"559.772851ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T17:18:14.572346Z","caller":"traceutil/trace.go:171","msg":"trace[1519038792] transaction","detail":"{read_only:false; response_revision:25264; number_of_response:1; }","duration":"328.308337ms","start":"2026-07-02T17:18:14.244018Z","end":"2026-07-02T17:18:14.572326Z","steps":["trace[1519038792] 'process raft request' (duration: 327.707624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:14.572369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.641028ms","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-02T17:18:14.572459Z","caller":"traceutil/trace.go:171","msg":"trace[2054397410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25264; }","duration":"553.812091ms","start":"2026-07-02T17:18:14.018625Z","end":"2026-07-02T17:18:14.572437Z","steps":["trace[2054397410] 'agreement among raft nodes before linearized reading' (duration: 553.528965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:14.572459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:14.244005Z","time spent":"328.397909ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:14.572513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:14.018596Z","time spent":"553.905735ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T17:18:14.572587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.265193ms","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-02T17:18:14.572818Z","caller":"traceutil/trace.go:171","msg":"trace[1981360194] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25264; }","duration":"191.59485ms","start":"2026-07-02T17:18:14.381197Z","end":"2026-07-02T17:18:14.572792Z","steps":["trace[1981360194] 'agreement among raft nodes before linearized reading' (duration: 190.967846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:14.572626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.286163ms","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-02T17:18:14.572909Z","caller":"traceutil/trace.go:171","msg":"trace[1466542600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25264; }","duration":"583.59572ms","start":"2026-07-02T17:18:13.989297Z","end":"2026-07-02T17:18:14.572893Z","steps":["trace[1466542600] 'agreement among raft nodes before linearized reading' (duration: 582.70449ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:14.573177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:13.989287Z","time spent":"583.873996ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-02T17:18:14.983027Z","caller":"traceutil/trace.go:171","msg":"trace[1117542917] linearizableReadLoop","detail":"{readStateIndex:26097; appliedIndex:26096; }","duration":"405.989404ms","start":"2026-07-02T17:18:14.577008Z","end":"2026-07-02T17:18:14.982997Z","steps":["trace[1117542917] 'read index received' (duration: 405.80303ms)","trace[1117542917] 'applied index is now lower than readState.Index' (duration: 185.184µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:14.983086Z","caller":"traceutil/trace.go:171","msg":"trace[2024240124] transaction","detail":"{read_only:false; response_revision:25265; number_of_response:1; }","duration":"406.409953ms","start":"2026-07-02T17:18:14.576641Z","end":"2026-07-02T17:18:14.983051Z","steps":["trace[2024240124] 'process raft request' (duration: 406.202018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:14.983245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:14.576614Z","time spent":"406.545385ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:14.983293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.247199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-6bc9a448-daf5-49f3-9e8c-f7f32d31e6d5\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-07-02T17:18:14.983417Z","caller":"traceutil/trace.go:171","msg":"trace[1650112833] range","detail":"{range_begin:/registry/persistentvolumes/pvc-6bc9a448-daf5-49f3-9e8c-f7f32d31e6d5; range_end:; response_count:1; response_revision:25265; }","duration":"406.397733ms","start":"2026-07-02T17:18:14.576982Z","end":"2026-07-02T17:18:14.983379Z","steps":["trace[1650112833] 'agreement among raft nodes before linearized reading' (duration: 406.116707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:14.983488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:14.576967Z","time spent":"406.505165ms","remote":"127.0.0.1:47096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1347,"request content":"key:\"/registry/persistentvolumes/pvc-6bc9a448-daf5-49f3-9e8c-f7f32d31e6d5\" "} {"level":"warn","ts":"2026-07-02T17:18:15.488835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.045351ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:18:15.489166Z","caller":"traceutil/trace.go:171","msg":"trace[1124543297] transaction","detail":"{read_only:false; response_revision:25266; number_of_response:1; }","duration":"911.244603ms","start":"2026-07-02T17:18:14.577888Z","end":"2026-07-02T17:18:15.489132Z","steps":["trace[1124543297] 'process raft request' (duration: 671.818965ms)","trace[1124543297] 'compare' (duration: 238.761333ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:15.489371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:14.57786Z","time spent":"911.400067ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:18:15.489829Z","caller":"traceutil/trace.go:171","msg":"trace[2106540526] transaction","detail":"{read_only:false; response_revision:25267; number_of_response:1; }","duration":"911.032757ms","start":"2026-07-02T17:18:14.578776Z","end":"2026-07-02T17:18:15.489808Z","steps":["trace[2106540526] 'process raft request' (duration: 910.193179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:15.489934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:14.578761Z","time spent":"911.131639ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:18:15.490157Z","caller":"traceutil/trace.go:171","msg":"trace[64845600] linearizableReadLoop","detail":"{readStateIndex:26100; appliedIndex:26097; }","duration":"507.016107ms","start":"2026-07-02T17:18:14.983097Z","end":"2026-07-02T17:18:15.490113Z","steps":["trace[64845600] 'read index received' (duration: 266.625228ms)","trace[64845600] 'applied index is now lower than readState.Index' (duration: 240.385819ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:15.490196Z","caller":"traceutil/trace.go:171","msg":"trace[294172964] transaction","detail":"{read_only:false; response_revision:25268; number_of_response:1; }","duration":"891.263458ms","start":"2026-07-02T17:18:14.598874Z","end":"2026-07-02T17:18:15.490137Z","steps":["trace[294172964] 'process raft request' (duration: 890.830919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:15.490242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.843108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T17:18:15.490326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.535168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:15.490339Z","caller":"traceutil/trace.go:171","msg":"trace[920819440] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25268; }","duration":"837.988081ms","start":"2026-07-02T17:18:14.652331Z","end":"2026-07-02T17:18:15.490319Z","steps":["trace[920819440] 'agreement among raft nodes before linearized reading' (duration: 837.801597ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:15.490392Z","caller":"traceutil/trace.go:171","msg":"trace[925072118] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25268; }","duration":"911.62603ms","start":"2026-07-02T17:18:14.57875Z","end":"2026-07-02T17:18:15.490376Z","steps":["trace[925072118] 'agreement among raft nodes before linearized reading' (duration: 911.520218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:15.490402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:14.652321Z","time spent":"838.069834ms","remote":"127.0.0.1:58830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true "} {"level":"warn","ts":"2026-07-02T17:18:15.490398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:14.598859Z","time spent":"891.441352ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:15.490435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:14.578741Z","time spent":"911.684441ms","remote":"127.0.0.1:47202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-07-02T17:18:15.806257Z","caller":"traceutil/trace.go:171","msg":"trace[1893797953] transaction","detail":"{read_only:false; response_revision:25270; number_of_response:1; }","duration":"710.963854ms","start":"2026-07-02T17:18:15.095256Z","end":"2026-07-02T17:18:15.80622Z","steps":["trace[1893797953] 'process raft request' (duration: 710.78829ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:15.806294Z","caller":"traceutil/trace.go:171","msg":"trace[904618645] transaction","detail":"{read_only:false; response_revision:25269; number_of_response:1; }","duration":"757.675169ms","start":"2026-07-02T17:18:15.048572Z","end":"2026-07-02T17:18:15.806247Z","steps":["trace[904618645] 'process raft request' (duration: 726.163865ms)","trace[904618645] 'compare' (duration: 30.342479ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:15.806384Z","caller":"traceutil/trace.go:171","msg":"trace[949339671] linearizableReadLoop","detail":"{readStateIndex:26103; appliedIndex:26100; }","duration":"316.108292ms","start":"2026-07-02T17:18:15.490249Z","end":"2026-07-02T17:18:15.806358Z","steps":["trace[949339671] 'read index received' (duration: 284.471216ms)","trace[949339671] 'applied index is now lower than readState.Index' (duration: 31.635966ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:15.806443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:15.095229Z","time spent":"711.120738ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:15.80645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:15.04855Z","time spent":"757.812012ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:18:15.806541Z","caller":"traceutil/trace.go:171","msg":"trace[1483593753] transaction","detail":"{read_only:false; response_revision:25271; number_of_response:1; }","duration":"711.20777ms","start":"2026-07-02T17:18:15.095312Z","end":"2026-07-02T17:18:15.80652Z","steps":["trace[1483593753] 'process raft request' (duration: 710.853862ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:15.806582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.092658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T17:18:15.806605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.082182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-07-02T17:18:15.806636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:15.095287Z","time spent":"711.295801ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:18:15.806643Z","caller":"traceutil/trace.go:171","msg":"trace[298190607] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25271; }","duration":"445.169234ms","start":"2026-07-02T17:18:15.361465Z","end":"2026-07-02T17:18:15.806634Z","steps":["trace[298190607] 'agreement among raft nodes before linearized reading' (duration: 445.013121ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:15.80667Z","caller":"traceutil/trace.go:171","msg":"trace[1346048752] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25271; }","duration":"372.19676ms","start":"2026-07-02T17:18:15.434434Z","end":"2026-07-02T17:18:15.80663Z","steps":["trace[1346048752] 'agreement among raft nodes before linearized reading' (duration: 372.080637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:15.806683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:15.361443Z","time spent":"445.234416ms","remote":"127.0.0.1:47128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12241,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-02T17:18:15.806646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.782584ms","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-02T17:18:15.806647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.369051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-02T17:18:15.806713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:15.434418Z","time spent":"372.286582ms","remote":"127.0.0.1:46914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T17:18:15.80675Z","caller":"traceutil/trace.go:171","msg":"trace[2053777519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25271; }","duration":"704.505313ms","start":"2026-07-02T17:18:15.102226Z","end":"2026-07-02T17:18:15.806731Z","steps":["trace[2053777519] 'agreement among raft nodes before linearized reading' (duration: 704.261638ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:15.806757Z","caller":"traceutil/trace.go:171","msg":"trace[1635558919] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25271; }","duration":"443.924767ms","start":"2026-07-02T17:18:15.362798Z","end":"2026-07-02T17:18:15.806723Z","steps":["trace[1635558919] 'agreement among raft nodes before linearized reading' (duration: 443.677772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:15.8068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:15.362776Z","time spent":"444.013988ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T17:18:15.806805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:15.102216Z","time spent":"704.574544ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T17:18:16.127697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.59243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:18:16.127903Z","caller":"traceutil/trace.go:171","msg":"trace[1704081276] transaction","detail":"{read_only:false; response_revision:25274; number_of_response:1; }","duration":"313.70622ms","start":"2026-07-02T17:18:15.814183Z","end":"2026-07-02T17:18:16.127889Z","steps":["trace[1704081276] 'process raft request' (duration: 313.671799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:16.127971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:15.814173Z","time spent":"313.771262ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:18:16.128084Z","caller":"traceutil/trace.go:171","msg":"trace[809683709] linearizableReadLoop","detail":"{readStateIndex:26104; appliedIndex:26103; }","duration":"321.619292ms","start":"2026-07-02T17:18:15.80644Z","end":"2026-07-02T17:18:16.128059Z","steps":["trace[809683709] 'read index received' (duration: 135.763257ms)","trace[809683709] 'applied index is now lower than readState.Index' (duration: 185.853585ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:16.128179Z","caller":"traceutil/trace.go:171","msg":"trace[1860147425] transaction","detail":"{read_only:false; response_revision:25272; number_of_response:1; }","duration":"446.709248ms","start":"2026-07-02T17:18:15.681458Z","end":"2026-07-02T17:18:16.128167Z","steps":["trace[1860147425] 'process raft request' (duration: 260.582436ms)","trace[1860147425] 'compare' (duration: 185.483188ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:16.128248Z","caller":"traceutil/trace.go:171","msg":"trace[475603926] transaction","detail":"{read_only:false; response_revision:25273; number_of_response:1; }","duration":"317.128864ms","start":"2026-07-02T17:18:15.811109Z","end":"2026-07-02T17:18:16.128238Z","steps":["trace[475603926] 'process raft request' (duration: 316.690405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:16.12827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:15.681437Z","time spent":"446.764249ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:16.1283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:15.811083Z","time spent":"317.190696ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:16.129085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.945711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:16.129185Z","caller":"traceutil/trace.go:171","msg":"trace[523486666] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25274; }","duration":"633.722178ms","start":"2026-07-02T17:18:15.495443Z","end":"2026-07-02T17:18:16.129165Z","steps":["trace[523486666] 'agreement among raft nodes before linearized reading' (duration: 632.90081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:16.129237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:15.495431Z","time spent":"633.794189ms","remote":"127.0.0.1:47202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-07-02T17:18:16.514217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.94189ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-07-02T17:18:16.514555Z","caller":"traceutil/trace.go:171","msg":"trace[2083282666] linearizableReadLoop","detail":"{readStateIndex:26107; appliedIndex:26106; }","duration":"386.380078ms","start":"2026-07-02T17:18:16.128137Z","end":"2026-07-02T17:18:16.514517Z","steps":["trace[2083282666] 'read index received' (duration: 128.06166ms)","trace[2083282666] 'applied index is now lower than readState.Index' (duration: 258.311558ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:16.514614Z","caller":"traceutil/trace.go:171","msg":"trace[1969838666] transaction","detail":"{read_only:false; response_revision:25275; number_of_response:1; }","duration":"239.550731ms","start":"2026-07-02T17:18:16.275033Z","end":"2026-07-02T17:18:16.514584Z","steps":["trace[1969838666] 'process raft request' (duration: 239.294656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:16.514749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.130182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:16.514794Z","caller":"traceutil/trace.go:171","msg":"trace[1656205455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25275; }","duration":"707.215033ms","start":"2026-07-02T17:18:15.807566Z","end":"2026-07-02T17:18:16.514781Z","steps":["trace[1656205455] 'agreement among raft nodes before linearized reading' (duration: 707.06894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:16.514834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:15.807538Z","time spent":"707.289395ms","remote":"127.0.0.1:46928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T17:18:16.514831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.334587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-02T17:18:16.514903Z","caller":"traceutil/trace.go:171","msg":"trace[88749002] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25275; }","duration":"702.442ms","start":"2026-07-02T17:18:15.812442Z","end":"2026-07-02T17:18:16.514884Z","steps":["trace[88749002] 'agreement among raft nodes before linearized reading' (duration: 702.180064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:16.514942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:15.812423Z","time spent":"702.510441ms","remote":"127.0.0.1:47128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12241,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-02T17:18:16.904747Z","caller":"traceutil/trace.go:171","msg":"trace[458642952] transaction","detail":"{read_only:false; response_revision:25276; number_of_response:1; }","duration":"327.639302ms","start":"2026-07-02T17:18:16.577084Z","end":"2026-07-02T17:18:16.904723Z","steps":["trace[458642952] 'process raft request' (duration: 327.466729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:16.904892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:16.577067Z","time spent":"327.744965ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:17.201795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.956313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:18:17.202173Z","caller":"traceutil/trace.go:171","msg":"trace[136420272] linearizableReadLoop","detail":"{readStateIndex:26110; appliedIndex:26108; }","duration":"623.893173ms","start":"2026-07-02T17:18:16.578257Z","end":"2026-07-02T17:18:17.20215Z","steps":["trace[136420272] 'read index received' (duration: 326.382914ms)","trace[136420272] 'applied index is now lower than readState.Index' (duration: 297.508989ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:17.202231Z","caller":"traceutil/trace.go:171","msg":"trace[602268727] transaction","detail":"{read_only:false; response_revision:25278; number_of_response:1; }","duration":"623.373502ms","start":"2026-07-02T17:18:16.578829Z","end":"2026-07-02T17:18:17.202202Z","steps":["trace[602268727] 'process raft request' (duration: 623.093756ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:17.202252Z","caller":"traceutil/trace.go:171","msg":"trace[1788459348] transaction","detail":"{read_only:false; response_revision:25279; number_of_response:1; }","duration":"622.898062ms","start":"2026-07-02T17:18:16.579317Z","end":"2026-07-02T17:18:17.202215Z","steps":["trace[1788459348] 'process raft request' (duration: 622.725489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:17.202333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.055226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:17.202217Z","caller":"traceutil/trace.go:171","msg":"trace[838871996] transaction","detail":"{read_only:false; response_revision:25277; number_of_response:1; }","duration":"624.17932ms","start":"2026-07-02T17:18:16.577988Z","end":"2026-07-02T17:18:17.202168Z","steps":["trace[838871996] 'process raft request' (duration: 469.677587ms)","trace[838871996] 'compare' (duration: 153.759888ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:17.202371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.707955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T17:18:17.202415Z","caller":"traceutil/trace.go:171","msg":"trace[1544031663] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25280; }","duration":"624.163149ms","start":"2026-07-02T17:18:16.578231Z","end":"2026-07-02T17:18:17.202394Z","steps":["trace[1544031663] 'agreement among raft nodes before linearized reading' (duration: 624.025216ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:17.20228Z","caller":"traceutil/trace.go:171","msg":"trace[1857159743] transaction","detail":"{read_only:false; response_revision:25280; number_of_response:1; }","duration":"539.351648ms","start":"2026-07-02T17:18:16.662858Z","end":"2026-07-02T17:18:17.20221Z","steps":["trace[1857159743] 'process raft request' (duration: 539.237566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:17.202417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:16.578822Z","time spent":"623.509705ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:17.202455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:16.578224Z","time spent":"624.22232ms","remote":"127.0.0.1:47132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-07-02T17:18:17.202463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:16.577967Z","time spent":"624.443976ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:17.202455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.300174ms","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-02T17:18:17.202434Z","caller":"traceutil/trace.go:171","msg":"trace[349205383] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25280; }","duration":"281.808907ms","start":"2026-07-02T17:18:16.920606Z","end":"2026-07-02T17:18:17.202415Z","steps":["trace[349205383] 'agreement among raft nodes before linearized reading' (duration: 281.696644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:17.202528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:16.662825Z","time spent":"539.639855ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:18:17.202512Z","caller":"traceutil/trace.go:171","msg":"trace[1361220220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25280; }","duration":"215.395275ms","start":"2026-07-02T17:18:16.987104Z","end":"2026-07-02T17:18:17.202499Z","steps":["trace[1361220220] 'agreement among raft nodes before linearized reading' (duration: 215.212422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:17.202433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:16.579311Z","time spent":"623.033375ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:17.709378Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7660516466701504209,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T17:18:17.807173Z","caller":"traceutil/trace.go:171","msg":"trace[43409677] linearizableReadLoop","detail":"{readStateIndex:26114; appliedIndex:26113; }","duration":"598.80734ms","start":"2026-07-02T17:18:17.20834Z","end":"2026-07-02T17:18:17.807147Z","steps":["trace[43409677] 'read index received' (duration: 585.983992ms)","trace[43409677] 'applied index is now lower than readState.Index' (duration: 12.822018ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:17.807268Z","caller":"traceutil/trace.go:171","msg":"trace[1934425074] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"599.522685ms","start":"2026-07-02T17:18:17.207713Z","end":"2026-07-02T17:18:17.807236Z","steps":["trace[1934425074] 'process raft request' (duration: 586.741808ms)","trace[1934425074] 'compare' (duration: 12.557432ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:17.807458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.421413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-07-02T17:18:17.807944Z","caller":"traceutil/trace.go:171","msg":"trace[1824175689] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25281; }","duration":"570.940524ms","start":"2026-07-02T17:18:17.236983Z","end":"2026-07-02T17:18:17.807923Z","steps":["trace[1824175689] 'agreement among raft nodes before linearized reading' (duration: 570.31971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:17.807462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.091966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18231"} {"level":"warn","ts":"2026-07-02T17:18:17.808037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:17.236968Z","time spent":"571.047877ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1878,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-02T17:18:17.807558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.954193ms","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-02T17:18:17.808093Z","caller":"traceutil/trace.go:171","msg":"trace[1868577948] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25281; }","duration":"599.753921ms","start":"2026-07-02T17:18:17.208314Z","end":"2026-07-02T17:18:17.808068Z","steps":["trace[1868577948] 'agreement among raft nodes before linearized reading' (duration: 598.954513ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:17.808166Z","caller":"traceutil/trace.go:171","msg":"trace[1772420005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25281; }","duration":"313.550187ms","start":"2026-07-02T17:18:17.494562Z","end":"2026-07-02T17:18:17.808112Z","steps":["trace[1772420005] 'agreement among raft nodes before linearized reading' (duration: 312.918642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:17.808194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:17.208304Z","time spent":"599.875104ms","remote":"127.0.0.1:47120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18255,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-02T17:18:17.8076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.836694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T17:18:17.808225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:17.49454Z","time spent":"313.66968ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T17:18:17.808236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.579307ms","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-02T17:18:17.808279Z","caller":"traceutil/trace.go:171","msg":"trace[1221682198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25281; }","duration":"373.53871ms","start":"2026-07-02T17:18:17.434715Z","end":"2026-07-02T17:18:17.808253Z","steps":["trace[1221682198] 'agreement among raft nodes before linearized reading' (duration: 372.864774ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:17.80834Z","caller":"traceutil/trace.go:171","msg":"trace[912109626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25281; }","duration":"313.736321ms","start":"2026-07-02T17:18:17.494584Z","end":"2026-07-02T17:18:17.808321Z","steps":["trace[912109626] 'agreement among raft nodes before linearized reading' (duration: 312.725689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:17.80836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:17.434683Z","time spent":"373.649792ms","remote":"127.0.0.1:46928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T17:18:17.807642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.525258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-02T17:18:17.808393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:17.494569Z","time spent":"313.813652ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T17:18:17.808422Z","caller":"traceutil/trace.go:171","msg":"trace[959134620] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:25281; }","duration":"374.341687ms","start":"2026-07-02T17:18:17.434063Z","end":"2026-07-02T17:18:17.808404Z","steps":["trace[959134620] 'agreement among raft nodes before linearized reading' (duration: 373.552689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:17.80851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:17.434047Z","time spent":"374.446019ms","remote":"127.0.0.1:33444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true "} {"level":"warn","ts":"2026-07-02T17:18:17.808185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:17.207696Z","time spent":"600.018826ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:18:17.808717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.291541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:17.808778Z","caller":"traceutil/trace.go:171","msg":"trace[1711030107] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:25281; }","duration":"220.184251ms","start":"2026-07-02T17:18:17.588579Z","end":"2026-07-02T17:18:17.808763Z","steps":["trace[1711030107] 'agreement among raft nodes before linearized reading' (duration: 218.896162ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:27.376534Z","caller":"traceutil/trace.go:171","msg":"trace[1058797656] transaction","detail":"{read_only:false; response_revision:25351; number_of_response:1; }","duration":"142.952603ms","start":"2026-07-02T17:18:27.23356Z","end":"2026-07-02T17:18:27.376513Z","steps":["trace[1058797656] 'process raft request' (duration: 142.827891ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:28.157733Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000664d.wal"} {"level":"info","ts":"2026-07-02T17:18:28.158127Z","caller":"traceutil/trace.go:171","msg":"trace[1668053628] transaction","detail":"{read_only:false; response_revision:25352; number_of_response:1; }","duration":"922.372164ms","start":"2026-07-02T17:18:27.23573Z","end":"2026-07-02T17:18:28.158102Z","steps":["trace[1668053628] 'process raft request' (duration: 922.18288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:28.158254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:27.235701Z","time spent":"922.473605ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:18:28.158284Z","caller":"traceutil/trace.go:171","msg":"trace[295820531] linearizableReadLoop","detail":"{readStateIndex:26188; appliedIndex:26186; }","duration":"861.956412ms","start":"2026-07-02T17:18:27.296306Z","end":"2026-07-02T17:18:28.158263Z","steps":["trace[295820531] 'read index received' (duration: 80.568649ms)","trace[295820531] 'applied index is now lower than readState.Index' (duration: 781.386443ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:28.158323Z","caller":"traceutil/trace.go:171","msg":"trace[114763409] transaction","detail":"{read_only:false; response_revision:25353; number_of_response:1; }","duration":"922.309762ms","start":"2026-07-02T17:18:27.23598Z","end":"2026-07-02T17:18:28.15829Z","steps":["trace[114763409] 'process raft request' (duration: 922.075287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:28.158468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.161366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"warn","ts":"2026-07-02T17:18:28.158479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:27.235964Z","time spent":"922.433415ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:18:28.158514Z","caller":"traceutil/trace.go:171","msg":"trace[927157590] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:25353; }","duration":"862.243919ms","start":"2026-07-02T17:18:27.296257Z","end":"2026-07-02T17:18:28.158501Z","steps":["trace[927157590] 'agreement among raft nodes before linearized reading' (duration: 862.087966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:28.158554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:27.296241Z","time spent":"862.30344ms","remote":"127.0.0.1:47102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2107,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-07-02T17:18:28.172814Z","caller":"traceutil/trace.go:171","msg":"trace[1467893257] transaction","detail":"{read_only:false; response_revision:25354; number_of_response:1; }","duration":"718.339804ms","start":"2026-07-02T17:18:27.454457Z","end":"2026-07-02T17:18:28.172797Z","steps":["trace[1467893257] 'process raft request' (duration: 717.983317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:28.172906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:27.454436Z","time spent":"718.426826ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:18:28.173058Z","caller":"traceutil/trace.go:171","msg":"trace[1092899626] transaction","detail":"{read_only:false; response_revision:25355; number_of_response:1; }","duration":"325.414885ms","start":"2026-07-02T17:18:27.847632Z","end":"2026-07-02T17:18:28.173047Z","steps":["trace[1092899626] 'process raft request' (duration: 324.893384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:28.173125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:27.847615Z","time spent":"325.476916ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:18:28.173262Z","caller":"traceutil/trace.go:171","msg":"trace[1237707652] transaction","detail":"{read_only:false; response_revision:25356; number_of_response:1; }","duration":"271.521624ms","start":"2026-07-02T17:18:27.90173Z","end":"2026-07-02T17:18:28.173252Z","steps":["trace[1237707652] 'process raft request' (duration: 270.838268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:28.173419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.379132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:28.17346Z","caller":"traceutil/trace.go:171","msg":"trace[277059622] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25356; }","duration":"131.448264ms","start":"2026-07-02T17:18:28.041999Z","end":"2026-07-02T17:18:28.173448Z","steps":["trace[277059622] 'agreement among raft nodes before linearized reading' (duration: 131.381193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:28.173666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.191138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:28.173755Z","caller":"traceutil/trace.go:171","msg":"trace[1089588269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25356; }","duration":"739.34822ms","start":"2026-07-02T17:18:27.434382Z","end":"2026-07-02T17:18:28.17373Z","steps":["trace[1089588269] 'agreement among raft nodes before linearized reading' (duration: 739.168396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:28.17381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:27.434367Z","time spent":"739.433713ms","remote":"127.0.0.1:46914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T17:18:28.174169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.800206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-02T17:18:28.174221Z","caller":"traceutil/trace.go:171","msg":"trace[1261380185] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25356; }","duration":"187.877408ms","start":"2026-07-02T17:18:27.986324Z","end":"2026-07-02T17:18:28.174201Z","steps":["trace[1261380185] 'agreement among raft nodes before linearized reading' (duration: 187.697144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:28.174458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.432909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T17:18:28.174501Z","caller":"traceutil/trace.go:171","msg":"trace[1307625841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25356; }","duration":"263.49143ms","start":"2026-07-02T17:18:27.910998Z","end":"2026-07-02T17:18:28.174489Z","steps":["trace[1307625841] 'agreement among raft nodes before linearized reading' (duration: 263.381788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:28.174624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.624532ms","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-02T17:18:28.174656Z","caller":"traceutil/trace.go:171","msg":"trace[1539154481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25356; }","duration":"268.684503ms","start":"2026-07-02T17:18:27.905964Z","end":"2026-07-02T17:18:28.174648Z","steps":["trace[1539154481] 'agreement among raft nodes before linearized reading' (duration: 268.607751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:28.174652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.88791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:28.174705Z","caller":"traceutil/trace.go:171","msg":"trace[1521891182] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:25356; }","duration":"428.986253ms","start":"2026-07-02T17:18:27.745704Z","end":"2026-07-02T17:18:28.17469Z","steps":["trace[1521891182] 'agreement among raft nodes before linearized reading' (duration: 428.885691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:28.174755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:27.745684Z","time spent":"429.061264ms","remote":"127.0.0.1:53218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-02T17:18:28.174964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.811511ms","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-02T17:18:28.175014Z","caller":"traceutil/trace.go:171","msg":"trace[2014361808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25356; }","duration":"330.862732ms","start":"2026-07-02T17:18:27.84414Z","end":"2026-07-02T17:18:28.175003Z","steps":["trace[2014361808] 'agreement among raft nodes before linearized reading' (duration: 330.74693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:28.175046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:18:27.844092Z","time spent":"330.945464ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-02T17:18:28.409723Z","caller":"traceutil/trace.go:171","msg":"trace[1826435503] linearizableReadLoop","detail":"{readStateIndex:26194; appliedIndex:26191; }","duration":"229.956722ms","start":"2026-07-02T17:18:28.179746Z","end":"2026-07-02T17:18:28.409702Z","steps":["trace[1826435503] 'read index received' (duration: 24.775188ms)","trace[1826435503] 'applied index is now lower than readState.Index' (duration: 205.180404ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:28.40984Z","caller":"traceutil/trace.go:171","msg":"trace[1305294699] transaction","detail":"{read_only:false; response_revision:25360; number_of_response:1; }","duration":"222.298016ms","start":"2026-07-02T17:18:28.187519Z","end":"2026-07-02T17:18:28.409817Z","steps":["trace[1305294699] 'process raft request' (duration: 222.085762ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:28.409832Z","caller":"traceutil/trace.go:171","msg":"trace[681366905] transaction","detail":"{read_only:false; response_revision:25359; number_of_response:1; }","duration":"231.421154ms","start":"2026-07-02T17:18:28.17836Z","end":"2026-07-02T17:18:28.409782Z","steps":["trace[681366905] 'process raft request' (duration: 231.104618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:28.409906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.139876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18231"} {"level":"info","ts":"2026-07-02T17:18:28.409929Z","caller":"traceutil/trace.go:171","msg":"trace[2005443656] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25360; }","duration":"230.244418ms","start":"2026-07-02T17:18:28.179678Z","end":"2026-07-02T17:18:28.409922Z","steps":["trace[2005443656] 'agreement among raft nodes before linearized reading' (duration: 230.125746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:28.409988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.82029ms","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-02T17:18:28.410023Z","caller":"traceutil/trace.go:171","msg":"trace[2019131797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25360; }","duration":"153.923241ms","start":"2026-07-02T17:18:28.256091Z","end":"2026-07-02T17:18:28.410014Z","steps":["trace[2019131797] 'agreement among raft nodes before linearized reading' (duration: 153.749588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:28.410005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.998036ms","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-02T17:18:28.410069Z","caller":"traceutil/trace.go:171","msg":"trace[1443955380] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25360; }","duration":"150.101238ms","start":"2026-07-02T17:18:28.259946Z","end":"2026-07-02T17:18:28.410047Z","steps":["trace[1443955380] 'agreement among raft nodes before linearized reading' (duration: 149.948125ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:28.6027Z","caller":"traceutil/trace.go:171","msg":"trace[513516014] linearizableReadLoop","detail":"{readStateIndex:26197; appliedIndex:26195; }","duration":"168.069198ms","start":"2026-07-02T17:18:28.434605Z","end":"2026-07-02T17:18:28.602674Z","steps":["trace[513516014] 'read index received' (duration: 106.057512ms)","trace[513516014] 'applied index is now lower than readState.Index' (duration: 62.010636ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:28.602981Z","caller":"traceutil/trace.go:171","msg":"trace[1192940336] transaction","detail":"{read_only:false; response_revision:25361; number_of_response:1; }","duration":"188.550713ms","start":"2026-07-02T17:18:28.41441Z","end":"2026-07-02T17:18:28.602961Z","steps":["trace[1192940336] 'process raft request' (duration: 126.237521ms)","trace[1192940336] 'compare' (duration: 61.663659ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:28.603215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.5993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:28.603265Z","caller":"traceutil/trace.go:171","msg":"trace[2140577269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25362; }","duration":"168.694072ms","start":"2026-07-02T17:18:28.434559Z","end":"2026-07-02T17:18:28.603253Z","steps":["trace[2140577269] 'agreement among raft nodes before linearized reading' (duration: 168.61001ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:28.603258Z","caller":"traceutil/trace.go:171","msg":"trace[1629225026] transaction","detail":"{read_only:false; response_revision:25362; number_of_response:1; }","duration":"182.668926ms","start":"2026-07-02T17:18:28.420546Z","end":"2026-07-02T17:18:28.603215Z","steps":["trace[1629225026] 'process raft request' (duration: 182.049183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:28.605918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.367906ms","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-02T17:18:28.605999Z","caller":"traceutil/trace.go:171","msg":"trace[558169600] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25363; }","duration":"133.459398ms","start":"2026-07-02T17:18:28.47252Z","end":"2026-07-02T17:18:28.605979Z","steps":["trace[558169600] 'agreement among raft nodes before linearized reading' (duration: 133.311485ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:33.040672Z","caller":"traceutil/trace.go:171","msg":"trace[398673959] linearizableReadLoop","detail":"{readStateIndex:26288; appliedIndex:26287; }","duration":"112.580613ms","start":"2026-07-02T17:18:32.928075Z","end":"2026-07-02T17:18:33.040655Z","steps":["trace[398673959] 'read index received' (duration: 112.517912ms)","trace[398673959] 'applied index is now lower than readState.Index' (duration: 62.041µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:33.040751Z","caller":"traceutil/trace.go:171","msg":"trace[151199694] transaction","detail":"{read_only:false; response_revision:25451; number_of_response:1; }","duration":"118.305538ms","start":"2026-07-02T17:18:32.922437Z","end":"2026-07-02T17:18:33.040743Z","steps":["trace[151199694] 'process raft request' (duration: 118.094284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:18:33.040934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.843009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-207c0423-2177-4a83-b80b-9c63f248b185\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-07-02T17:18:33.040965Z","caller":"traceutil/trace.go:171","msg":"trace[1784116628] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-207c0423-2177-4a83-b80b-9c63f248b185; range_end:; response_count:1; response_revision:25451; }","duration":"112.897991ms","start":"2026-07-02T17:18:32.928058Z","end":"2026-07-02T17:18:33.040956Z","steps":["trace[1784116628] 'agreement among raft nodes before linearized reading' (duration: 112.834119ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:33.210407Z","caller":"traceutil/trace.go:171","msg":"trace[2140455302] linearizableReadLoop","detail":"{readStateIndex:26289; appliedIndex:26288; }","duration":"169.661833ms","start":"2026-07-02T17:18:33.040717Z","end":"2026-07-02T17:18:33.210379Z","steps":["trace[2140455302] 'read index received' (duration: 119.360971ms)","trace[2140455302] 'applied index is now lower than readState.Index' (duration: 50.299782ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:33.210538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.60955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:33.210577Z","caller":"traceutil/trace.go:171","msg":"trace[1097827909] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25452; }","duration":"232.671991ms","start":"2026-07-02T17:18:32.977895Z","end":"2026-07-02T17:18:33.210567Z","steps":["trace[1097827909] 'agreement among raft nodes before linearized reading' (duration: 232.58329ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:33.210642Z","caller":"traceutil/trace.go:171","msg":"trace[178466096] transaction","detail":"{read_only:false; response_revision:25452; number_of_response:1; }","duration":"251.136642ms","start":"2026-07-02T17:18:32.95947Z","end":"2026-07-02T17:18:33.210606Z","steps":["trace[178466096] 'process raft request' (duration: 200.627126ms)","trace[178466096] 'compare' (duration: 50.138608ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:39.414309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.544303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:18:39.414441Z","caller":"traceutil/trace.go:171","msg":"trace[580679454] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"133.65131ms","start":"2026-07-02T17:18:39.280758Z","end":"2026-07-02T17:18:39.41441Z","steps":["trace[580679454] 'process raft request' (duration: 32.718489ms)","trace[580679454] 'compare' (duration: 100.280097ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:39.64705Z","caller":"traceutil/trace.go:171","msg":"trace[1338164276] linearizableReadLoop","detail":"{readStateIndex:26361; appliedIndex:26360; }","duration":"141.327997ms","start":"2026-07-02T17:18:39.505694Z","end":"2026-07-02T17:18:39.647022Z","steps":["trace[1338164276] 'read index received' (duration: 47.976101ms)","trace[1338164276] 'applied index is now lower than readState.Index' (duration: 93.350636ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:18:39.647174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.460221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:18:39.647213Z","caller":"traceutil/trace.go:171","msg":"trace[608380274] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25522; }","duration":"141.539122ms","start":"2026-07-02T17:18:39.505659Z","end":"2026-07-02T17:18:39.647198Z","steps":["trace[608380274] 'agreement among raft nodes before linearized reading' (duration: 141.45654ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:39.647151Z","caller":"traceutil/trace.go:171","msg":"trace[1439420073] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"158.727445ms","start":"2026-07-02T17:18:39.488388Z","end":"2026-07-02T17:18:39.647115Z","steps":["trace[1439420073] 'process raft request' (duration: 65.369699ms)","trace[1439420073] 'compare' (duration: 93.028639ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:18:40.36256Z","caller":"traceutil/trace.go:171","msg":"trace[1834642773] transaction","detail":"{read_only:false; response_revision:25524; number_of_response:1; }","duration":"164.745886ms","start":"2026-07-02T17:18:40.19777Z","end":"2026-07-02T17:18:40.362516Z","steps":["trace[1834642773] 'process raft request' (duration: 164.42966ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:40.362617Z","caller":"traceutil/trace.go:171","msg":"trace[605063815] transaction","detail":"{read_only:false; response_revision:25525; number_of_response:1; }","duration":"153.604765ms","start":"2026-07-02T17:18:40.208972Z","end":"2026-07-02T17:18:40.362577Z","steps":["trace[605063815] 'process raft request' (duration: 153.465692ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:18:40.362888Z","caller":"traceutil/trace.go:171","msg":"trace[1206402002] transaction","detail":"{read_only:false; response_revision:25526; number_of_response:1; }","duration":"137.897643ms","start":"2026-07-02T17:18:40.224939Z","end":"2026-07-02T17:18:40.362837Z","steps":["trace[1206402002] 'process raft request' (duration: 137.586527ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:04.452343Z","caller":"traceutil/trace.go:171","msg":"trace[886954183] transaction","detail":"{read_only:false; response_revision:25719; number_of_response:1; }","duration":"108.121768ms","start":"2026-07-02T17:19:04.344206Z","end":"2026-07-02T17:19:04.452328Z","steps":["trace[886954183] 'process raft request' (duration: 108.025356ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:04.623499Z","caller":"traceutil/trace.go:171","msg":"trace[892527897] transaction","detail":"{read_only:false; response_revision:25720; number_of_response:1; }","duration":"172.828872ms","start":"2026-07-02T17:19:04.45065Z","end":"2026-07-02T17:19:04.623479Z","steps":["trace[892527897] 'process raft request' (duration: 155.880674ms)","trace[892527897] 'compare' (duration: 16.791505ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:19:04.6237Z","caller":"traceutil/trace.go:171","msg":"trace[1350288187] transaction","detail":"{read_only:false; response_revision:25721; number_of_response:1; }","duration":"171.887301ms","start":"2026-07-02T17:19:04.45179Z","end":"2026-07-02T17:19:04.623678Z","steps":["trace[1350288187] 'process raft request' (duration: 171.650026ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:04.623957Z","caller":"traceutil/trace.go:171","msg":"trace[490244991] linearizableReadLoop","detail":"{readStateIndex:26565; appliedIndex:26563; }","duration":"171.600725ms","start":"2026-07-02T17:19:04.452333Z","end":"2026-07-02T17:19:04.623934Z","steps":["trace[490244991] 'read index received' (duration: 154.212218ms)","trace[490244991] 'applied index is now lower than readState.Index' (duration: 17.386657ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:19:04.624099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.459665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:19:04.624141Z","caller":"traceutil/trace.go:171","msg":"trace[483176701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25722; }","duration":"190.546806ms","start":"2026-07-02T17:19:04.433585Z","end":"2026-07-02T17:19:04.624131Z","steps":["trace[483176701] 'agreement among raft nodes before linearized reading' (duration: 190.468864ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:04.624252Z","caller":"traceutil/trace.go:171","msg":"trace[280199220] transaction","detail":"{read_only:false; response_revision:25722; number_of_response:1; }","duration":"171.85673ms","start":"2026-07-02T17:19:04.452368Z","end":"2026-07-02T17:19:04.624224Z","steps":["trace[280199220] 'process raft request' (duration: 171.234977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:19:04.624557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.052841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:19:04.624609Z","caller":"traceutil/trace.go:171","msg":"trace[1686049094] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25723; }","duration":"169.148803ms","start":"2026-07-02T17:19:04.455448Z","end":"2026-07-02T17:19:04.624597Z","steps":["trace[1686049094] 'agreement among raft nodes before linearized reading' (duration: 169.05122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:19:04.624871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.790036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-02T17:19:04.624912Z","caller":"traceutil/trace.go:171","msg":"trace[716587266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25723; }","duration":"151.861307ms","start":"2026-07-02T17:19:04.473042Z","end":"2026-07-02T17:19:04.624903Z","steps":["trace[716587266] 'agreement among raft nodes before linearized reading' (duration: 151.727835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:19:04.625032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.746567ms","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-02T17:19:04.625065Z","caller":"traceutil/trace.go:171","msg":"trace[2036900814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25723; }","duration":"153.797979ms","start":"2026-07-02T17:19:04.471259Z","end":"2026-07-02T17:19:04.625057Z","steps":["trace[2036900814] 'agreement among raft nodes before linearized reading' (duration: 153.720367ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:04.625057Z","caller":"traceutil/trace.go:171","msg":"trace[647968978] transaction","detail":"{read_only:false; response_revision:25723; number_of_response:1; }","duration":"100.380849ms","start":"2026-07-02T17:19:04.524653Z","end":"2026-07-02T17:19:04.625034Z","steps":["trace[647968978] 'process raft request' (duration: 99.250144ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:04.829517Z","caller":"traceutil/trace.go:171","msg":"trace[1338777523] transaction","detail":"{read_only:false; response_revision:25724; number_of_response:1; }","duration":"199.944702ms","start":"2026-07-02T17:19:04.629544Z","end":"2026-07-02T17:19:04.829489Z","steps":["trace[1338777523] 'process raft request' (duration: 175.100123ms)","trace[1338777523] 'compare' (duration: 24.732946ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:19:04.994873Z","caller":"traceutil/trace.go:171","msg":"trace[705055531] transaction","detail":"{read_only:false; response_revision:25725; number_of_response:1; }","duration":"364.073704ms","start":"2026-07-02T17:19:04.630774Z","end":"2026-07-02T17:19:04.994847Z","steps":["trace[705055531] 'process raft request' (duration: 307.780031ms)","trace[705055531] 'compare' (duration: 55.618407ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:19:04.995096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:19:04.630754Z","time spent":"364.264858ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:19:04.99519Z","caller":"traceutil/trace.go:171","msg":"trace[1702140481] transaction","detail":"{read_only:false; response_revision:25726; number_of_response:1; }","duration":"364.286628ms","start":"2026-07-02T17:19:04.630878Z","end":"2026-07-02T17:19:04.995165Z","steps":["trace[1702140481] 'process raft request' (duration: 363.87675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:19:04.995348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:19:04.630845Z","time spent":"364.422242ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:19:04.995624Z","caller":"traceutil/trace.go:171","msg":"trace[984208152] linearizableReadLoop","detail":"{readStateIndex:26570; appliedIndex:26567; }","duration":"356.99757ms","start":"2026-07-02T17:19:04.638613Z","end":"2026-07-02T17:19:04.995611Z","steps":["trace[984208152] 'read index received' (duration: 166.097816ms)","trace[984208152] 'applied index is now lower than readState.Index' (duration: 190.896134ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:19:04.995944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.451128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-02T17:19:04.99597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.341137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-07-02T17:19:04.995941Z","caller":"traceutil/trace.go:171","msg":"trace[972540339] transaction","detail":"{read_only:false; response_revision:25727; number_of_response:1; }","duration":"274.492349ms","start":"2026-07-02T17:19:04.721408Z","end":"2026-07-02T17:19:04.9959Z","steps":["trace[972540339] 'process raft request' (duration: 273.672662ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:04.996002Z","caller":"traceutil/trace.go:171","msg":"trace[1268487009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25727; }","duration":"227.587821ms","start":"2026-07-02T17:19:04.768399Z","end":"2026-07-02T17:19:04.995987Z","steps":["trace[1268487009] 'agreement among raft nodes before linearized reading' (duration: 227.448378ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:04.996025Z","caller":"traceutil/trace.go:171","msg":"trace[554879091] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25727; }","duration":"357.411478ms","start":"2026-07-02T17:19:04.638596Z","end":"2026-07-02T17:19:04.996008Z","steps":["trace[554879091] 'agreement among raft nodes before linearized reading' (duration: 357.201264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:19:04.99606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:19:04.638589Z","time spent":"357.463459ms","remote":"127.0.0.1:52842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5676,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-02T17:19:04.996082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.805612ms","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-02T17:19:04.996178Z","caller":"traceutil/trace.go:171","msg":"trace[1681540002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25727; }","duration":"225.927894ms","start":"2026-07-02T17:19:04.770236Z","end":"2026-07-02T17:19:04.996164Z","steps":["trace[1681540002] 'agreement among raft nodes before linearized reading' (duration: 225.638818ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:05.298499Z","caller":"traceutil/trace.go:171","msg":"trace[837315184] linearizableReadLoop","detail":"{readStateIndex:26573; appliedIndex:26572; }","duration":"170.41039ms","start":"2026-07-02T17:19:05.12806Z","end":"2026-07-02T17:19:05.29847Z","steps":["trace[837315184] 'read index received' (duration: 94.785178ms)","trace[837315184] 'applied index is now lower than readState.Index' (duration: 75.620811ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:19:05.298602Z","caller":"traceutil/trace.go:171","msg":"trace[386881948] transaction","detail":"{read_only:false; response_revision:25729; number_of_response:1; }","duration":"298.131542ms","start":"2026-07-02T17:19:05.000428Z","end":"2026-07-02T17:19:05.29856Z","steps":["trace[386881948] 'process raft request' (duration: 222.415948ms)","trace[386881948] 'compare' (duration: 75.480468ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:19:05.298695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.603623ms","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:8"} {"level":"info","ts":"2026-07-02T17:19:05.298755Z","caller":"traceutil/trace.go:171","msg":"trace[1969125137] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:25729; }","duration":"170.722266ms","start":"2026-07-02T17:19:05.128017Z","end":"2026-07-02T17:19:05.298739Z","steps":["trace[1969125137] 'agreement among raft nodes before linearized reading' (duration: 170.596574ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:06.007995Z","caller":"traceutil/trace.go:171","msg":"trace[558998632] transaction","detail":"{read_only:false; response_revision:25732; number_of_response:1; }","duration":"123.133944ms","start":"2026-07-02T17:19:05.884829Z","end":"2026-07-02T17:19:06.007963Z","steps":["trace[558998632] 'process raft request' (duration: 87.707215ms)","trace[558998632] 'compare' (duration: 35.216094ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:19:06.857416Z","caller":"traceutil/trace.go:171","msg":"trace[1484890571] transaction","detail":"{read_only:false; response_revision:25735; number_of_response:1; }","duration":"226.917475ms","start":"2026-07-02T17:19:06.630477Z","end":"2026-07-02T17:19:06.857394Z","steps":["trace[1484890571] 'process raft request' (duration: 170.706424ms)","trace[1484890571] 'compare' (duration: 55.865533ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:19:06.857516Z","caller":"traceutil/trace.go:171","msg":"trace[1654507689] transaction","detail":"{read_only:false; response_revision:25737; number_of_response:1; }","duration":"224.713978ms","start":"2026-07-02T17:19:06.632776Z","end":"2026-07-02T17:19:06.85749Z","steps":["trace[1654507689] 'process raft request' (duration: 224.542045ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:06.857933Z","caller":"traceutil/trace.go:171","msg":"trace[1395677721] transaction","detail":"{read_only:false; response_revision:25736; number_of_response:1; }","duration":"226.074968ms","start":"2026-07-02T17:19:06.631841Z","end":"2026-07-02T17:19:06.857916Z","steps":["trace[1395677721] 'process raft request' (duration: 225.392213ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:07.465255Z","caller":"traceutil/trace.go:171","msg":"trace[2123888204] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"158.474021ms","start":"2026-07-02T17:19:07.306757Z","end":"2026-07-02T17:19:07.465231Z","steps":["trace[2123888204] 'process raft request' (duration: 118.979173ms)","trace[2123888204] 'compare' (duration: 39.280892ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:19:08.165444Z","caller":"traceutil/trace.go:171","msg":"trace[725286653] transaction","detail":"{read_only:false; response_revision:25746; number_of_response:1; }","duration":"131.014094ms","start":"2026-07-02T17:19:08.034409Z","end":"2026-07-02T17:19:08.165423Z","steps":["trace[725286653] 'process raft request' (duration: 130.440881ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:08.851951Z","caller":"traceutil/trace.go:171","msg":"trace[1042633843] linearizableReadLoop","detail":"{readStateIndex:26593; appliedIndex:26592; }","duration":"105.307067ms","start":"2026-07-02T17:19:08.74662Z","end":"2026-07-02T17:19:08.851927Z","steps":["trace[1042633843] 'read index received' (duration: 105.082221ms)","trace[1042633843] 'applied index is now lower than readState.Index' (duration: 223.516µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:19:08.851996Z","caller":"traceutil/trace.go:171","msg":"trace[415381354] transaction","detail":"{read_only:false; response_revision:25748; number_of_response:1; }","duration":"147.8746ms","start":"2026-07-02T17:19:08.704087Z","end":"2026-07-02T17:19:08.851961Z","steps":["trace[415381354] 'process raft request' (duration: 147.656755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:19:08.852159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.506981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:19:08.852233Z","caller":"traceutil/trace.go:171","msg":"trace[1566923287] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:25748; }","duration":"105.632864ms","start":"2026-07-02T17:19:08.746583Z","end":"2026-07-02T17:19:08.852216Z","steps":["trace[1566923287] 'agreement among raft nodes before linearized reading' (duration: 105.481641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:19:09.06856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.69623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:19:09.068803Z","caller":"traceutil/trace.go:171","msg":"trace[693919959] linearizableReadLoop","detail":"{readStateIndex:26596; appliedIndex:26594; }","duration":"135.047911ms","start":"2026-07-02T17:19:08.933735Z","end":"2026-07-02T17:19:09.068783Z","steps":["trace[693919959] 'read index received' (duration: 32.074095ms)","trace[693919959] 'applied index is now lower than readState.Index' (duration: 102.972726ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:19:09.06907Z","caller":"traceutil/trace.go:171","msg":"trace[1145769848] transaction","detail":"{read_only:false; response_revision:25750; number_of_response:1; }","duration":"206.456282ms","start":"2026-07-02T17:19:08.862596Z","end":"2026-07-02T17:19:09.069053Z","steps":["trace[1145769848] 'process raft request' (duration: 103.170319ms)","trace[1145769848] 'compare' (duration: 102.302712ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:19:09.069287Z","caller":"traceutil/trace.go:171","msg":"trace[782141728] transaction","detail":"{read_only:false; response_revision:25751; number_of_response:1; }","duration":"206.407162ms","start":"2026-07-02T17:19:08.862868Z","end":"2026-07-02T17:19:09.069275Z","steps":["trace[782141728] 'process raft request' (duration: 205.801229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:19:09.070568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.829891ms","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-02T17:19:09.070602Z","caller":"traceutil/trace.go:171","msg":"trace[392328441] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25751; }","duration":"136.899562ms","start":"2026-07-02T17:19:08.933694Z","end":"2026-07-02T17:19:09.070594Z","steps":["trace[392328441] 'agreement among raft nodes before linearized reading' (duration: 136.790649ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:09.241413Z","caller":"traceutil/trace.go:171","msg":"trace[1991420576] transaction","detail":"{read_only:false; response_revision:25753; number_of_response:1; }","duration":"166.299341ms","start":"2026-07-02T17:19:09.075074Z","end":"2026-07-02T17:19:09.241373Z","steps":["trace[1991420576] 'process raft request' (duration: 109.995448ms)","trace[1991420576] 'compare' (duration: 56.110649ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:19:09.250119Z","caller":"traceutil/trace.go:171","msg":"trace[1822395695] transaction","detail":"{read_only:false; response_revision:25754; number_of_response:1; }","duration":"130.287099ms","start":"2026-07-02T17:19:09.119816Z","end":"2026-07-02T17:19:09.250103Z","steps":["trace[1822395695] 'process raft request' (duration: 130.131846ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:09.250252Z","caller":"traceutil/trace.go:171","msg":"trace[461207554] linearizableReadLoop","detail":"{readStateIndex:26600; appliedIndex:26597; }","duration":"118.767248ms","start":"2026-07-02T17:19:09.131475Z","end":"2026-07-02T17:19:09.250242Z","steps":["trace[461207554] 'read index received' (duration: 53.610303ms)","trace[461207554] 'applied index is now lower than readState.Index' (duration: 65.155615ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:19:09.250327Z","caller":"traceutil/trace.go:171","msg":"trace[1623864130] transaction","detail":"{read_only:false; response_revision:25755; number_of_response:1; }","duration":"122.248384ms","start":"2026-07-02T17:19:09.128068Z","end":"2026-07-02T17:19:09.250316Z","steps":["trace[1623864130] 'process raft request' (duration: 121.992729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:19:09.250565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.072705ms","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-02T17:19:09.250638Z","caller":"traceutil/trace.go:171","msg":"trace[523489351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25755; }","duration":"119.177567ms","start":"2026-07-02T17:19:09.131441Z","end":"2026-07-02T17:19:09.250618Z","steps":["trace[523489351] 'agreement among raft nodes before linearized reading' (duration: 118.996934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:19:09.47893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.45295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-02T17:19:09.479056Z","caller":"traceutil/trace.go:171","msg":"trace[731071571] linearizableReadLoop","detail":"{readStateIndex:26602; appliedIndex:26600; }","duration":"193.872089ms","start":"2026-07-02T17:19:09.285158Z","end":"2026-07-02T17:19:09.47903Z","steps":["trace[731071571] 'read index received' (duration: 32.592428ms)","trace[731071571] 'applied index is now lower than readState.Index' (duration: 161.277281ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:19:09.479172Z","caller":"traceutil/trace.go:171","msg":"trace[697437972] transaction","detail":"{read_only:false; response_revision:25757; number_of_response:1; }","duration":"224.280009ms","start":"2026-07-02T17:19:09.254875Z","end":"2026-07-02T17:19:09.479155Z","steps":["trace[697437972] 'process raft request' (duration: 88.505962ms)","trace[697437972] 'compare' (duration: 135.205265ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:19:09.479383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.217577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:19:09.479632Z","caller":"traceutil/trace.go:171","msg":"trace[797684732] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:25757; }","duration":"194.283228ms","start":"2026-07-02T17:19:09.285144Z","end":"2026-07-02T17:19:09.479428Z","steps":["trace[797684732] 'agreement among raft nodes before linearized reading' (duration: 194.173026ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:09.552713Z","caller":"traceutil/trace.go:171","msg":"trace[143444083] transaction","detail":"{read_only:false; response_revision:25758; number_of_response:1; }","duration":"196.401223ms","start":"2026-07-02T17:19:09.356288Z","end":"2026-07-02T17:19:09.552689Z","steps":["trace[143444083] 'process raft request' (duration: 196.22313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:19:09.552823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.53841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:19:09.552912Z","caller":"traceutil/trace.go:171","msg":"trace[972940227] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:25758; }","duration":"122.678114ms","start":"2026-07-02T17:19:09.430212Z","end":"2026-07-02T17:19:09.55289Z","steps":["trace[972940227] 'agreement among raft nodes before linearized reading' (duration: 122.48498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:19:09.552973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.453253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-02T17:19:09.553026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.689738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:19:09.553089Z","caller":"traceutil/trace.go:171","msg":"trace[381987569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25758; }","duration":"118.80419ms","start":"2026-07-02T17:19:09.434271Z","end":"2026-07-02T17:19:09.553075Z","steps":["trace[381987569] 'agreement among raft nodes before linearized reading' (duration: 118.666477ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:09.553075Z","caller":"traceutil/trace.go:171","msg":"trace[1654393046] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25758; }","duration":"259.574606ms","start":"2026-07-02T17:19:09.293474Z","end":"2026-07-02T17:19:09.553049Z","steps":["trace[1654393046] 'agreement among raft nodes before linearized reading' (duration: 259.419233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:19:09.553129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.450722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-07-02T17:19:09.553328Z","caller":"traceutil/trace.go:171","msg":"trace[1751729245] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25758; }","duration":"124.590275ms","start":"2026-07-02T17:19:09.428611Z","end":"2026-07-02T17:19:09.553201Z","steps":["trace[1751729245] 'agreement among raft nodes before linearized reading' (duration: 124.242318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:19:09.553498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.819355ms","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-02T17:19:09.553768Z","caller":"traceutil/trace.go:171","msg":"trace[1898401911] 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:25758; }","duration":"204.719494ms","start":"2026-07-02T17:19:09.349028Z","end":"2026-07-02T17:19:09.553747Z","steps":["trace[1898401911] 'agreement among raft nodes before linearized reading' (duration: 203.767114ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:09.769452Z","caller":"traceutil/trace.go:171","msg":"trace[1481463156] linearizableReadLoop","detail":"{readStateIndex:26604; appliedIndex:26603; }","duration":"216.767595ms","start":"2026-07-02T17:19:09.552653Z","end":"2026-07-02T17:19:09.76942Z","steps":["trace[1481463156] 'read index received' (duration: 185.236331ms)","trace[1481463156] 'applied index is now lower than readState.Index' (duration: 31.529424ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:19:09.769968Z","caller":"traceutil/trace.go:171","msg":"trace[804353104] transaction","detail":"{read_only:false; response_revision:25759; number_of_response:1; }","duration":"290.594318ms","start":"2026-07-02T17:19:09.479347Z","end":"2026-07-02T17:19:09.769942Z","steps":["trace[804353104] 'process raft request' (duration: 258.530752ms)","trace[804353104] 'compare' (duration: 31.31467ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:19:09.770127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.611863ms","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-02T17:19:09.770172Z","caller":"traceutil/trace.go:171","msg":"trace[1990102434] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25759; }","duration":"252.710186ms","start":"2026-07-02T17:19:09.517449Z","end":"2026-07-02T17:19:09.770159Z","steps":["trace[1990102434] 'agreement among raft nodes before linearized reading' (duration: 252.569353ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:10.0571Z","caller":"traceutil/trace.go:171","msg":"trace[1057176536] linearizableReadLoop","detail":"{readStateIndex:26606; appliedIndex:26604; }","duration":"287.537813ms","start":"2026-07-02T17:19:09.769541Z","end":"2026-07-02T17:19:10.057079Z","steps":["trace[1057176536] 'read index received' (duration: 257.903869ms)","trace[1057176536] 'applied index is now lower than readState.Index' (duration: 29.633064ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:19:10.057138Z","caller":"traceutil/trace.go:171","msg":"trace[260019333] transaction","detail":"{read_only:false; response_revision:25761; number_of_response:1; }","duration":"499.151876ms","start":"2026-07-02T17:19:09.557958Z","end":"2026-07-02T17:19:10.05711Z","steps":["trace[260019333] 'process raft request' (duration: 499.030963ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:10.057179Z","caller":"traceutil/trace.go:171","msg":"trace[1690416363] transaction","detail":"{read_only:false; response_revision:25760; number_of_response:1; }","duration":"499.350251ms","start":"2026-07-02T17:19:09.557796Z","end":"2026-07-02T17:19:10.057146Z","steps":["trace[1690416363] 'process raft request' (duration: 469.572594ms)","trace[1690416363] 'compare' (duration: 29.379308ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T17:19:10.057196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.251995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:19:10.057243Z","caller":"traceutil/trace.go:171","msg":"trace[986819585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25761; }","duration":"503.330007ms","start":"2026-07-02T17:19:09.553901Z","end":"2026-07-02T17:19:10.057231Z","steps":["trace[986819585] 'agreement among raft nodes before linearized reading' (duration: 503.253736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:19:10.057241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.709844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18231"} {"level":"warn","ts":"2026-07-02T17:19:10.057264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:19:09.553884Z","time spent":"503.376018ms","remote":"127.0.0.1:46928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T17:19:10.057265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:19:09.557939Z","time spent":"499.260039ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T17:19:10.057287Z","caller":"traceutil/trace.go:171","msg":"trace[34460836] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:25761; }","duration":"496.767755ms","start":"2026-07-02T17:19:09.560508Z","end":"2026-07-02T17:19:10.057276Z","steps":["trace[34460836] 'agreement among raft nodes before linearized reading' (duration: 496.611452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:19:10.057299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:19:09.557779Z","time spent":"499.454912ms","remote":"127.0.0.1:47200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T17:19:10.057321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T17:19:09.560459Z","time spent":"496.857247ms","remote":"127.0.0.1:47120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1,"response size":18255,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" "} {"level":"warn","ts":"2026-07-02T17:19:10.122536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.387434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-02T17:19:10.122625Z","caller":"traceutil/trace.go:171","msg":"trace[1633526939] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:25762; }","duration":"243.512516ms","start":"2026-07-02T17:19:09.87909Z","end":"2026-07-02T17:19:10.122602Z","steps":["trace[1633526939] 'agreement among raft nodes before linearized reading' (duration: 243.364073ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:10.122869Z","caller":"traceutil/trace.go:171","msg":"trace[1385283420] transaction","detail":"{read_only:false; response_revision:25762; number_of_response:1; }","duration":"205.578402ms","start":"2026-07-02T17:19:09.917257Z","end":"2026-07-02T17:19:10.122836Z","steps":["trace[1385283420] 'process raft request' (duration: 205.077112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:19:10.123001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.314666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-02T17:19:10.123052Z","caller":"traceutil/trace.go:171","msg":"trace[2015554693] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:25762; }","duration":"234.383538ms","start":"2026-07-02T17:19:09.888655Z","end":"2026-07-02T17:19:10.123039Z","steps":["trace[2015554693] 'agreement among raft nodes before linearized reading' (duration: 234.249385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T17:19:10.123438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.079366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-02T17:19:10.1237Z","caller":"traceutil/trace.go:171","msg":"trace[1365560323] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25762; }","duration":"120.335433ms","start":"2026-07-02T17:19:10.003289Z","end":"2026-07-02T17:19:10.123624Z","steps":["trace[1365560323] 'agreement among raft nodes before linearized reading' (duration: 119.572906ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:19:17.064689Z","caller":"traceutil/trace.go:171","msg":"trace[1528711324] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"153.278206ms","start":"2026-07-02T17:19:16.911379Z","end":"2026-07-02T17:19:17.064657Z","steps":["trace[1528711324] 'process raft request' (duration: 134.205282ms)","trace[1528711324] 'compare' (duration: 18.88548ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T17:19:17.302125Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22874} {"level":"info","ts":"2026-07-02T17:19:17.441776Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22874,"took":"136.658627ms","hash":1853441555,"current-db-size-bytes":58658816,"current-db-size":"59 MB","current-db-size-in-use-bytes":37638144,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-07-02T17:19:17.441836Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1853441555,"revision":22874,"compact-revision":20113} {"level":"info","ts":"2026-07-02T17:24:17.307886Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25827} {"level":"info","ts":"2026-07-02T17:24:17.453417Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25827,"took":"140.954389ms","hash":327215913,"current-db-size-bytes":59080704,"current-db-size":"59 MB","current-db-size-in-use-bytes":35946496,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-07-02T17:24:17.453497Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":327215913,"revision":25827,"compact-revision":22874} {"level":"info","ts":"2026-07-02T17:25:05.048197Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b5a1dacc8ba86a4f","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T17:25:05.052984Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-07-02T17:25:05.053052Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-07-02T17:29:17.314079Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28705} {"level":"info","ts":"2026-07-02T17:29:17.470576Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28705,"took":"152.100391ms","hash":481018873,"current-db-size-bytes":59080704,"current-db-size":"59 MB","current-db-size-in-use-bytes":31043584,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T17:29:17.470646Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":481018873,"revision":28705,"compact-revision":25827} {"level":"info","ts":"2026-07-02T17:34:17.322433Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30931} {"level":"info","ts":"2026-07-02T17:34:17.445201Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30931,"took":"118.983633ms","hash":1931976842,"current-db-size-bytes":59080704,"current-db-size":"59 MB","current-db-size-in-use-bytes":31453184,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T17:34:17.445258Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1931976842,"revision":30931,"compact-revision":28705} {"level":"info","ts":"2026-07-02T17:37:20.449691Z","caller":"traceutil/trace.go:171","msg":"trace[1337895053] transaction","detail":"{read_only:false; response_revision:34629; number_of_response:1; }","duration":"105.05533ms","start":"2026-07-02T17:37:20.344541Z","end":"2026-07-02T17:37:20.449597Z","steps":["trace[1337895053] 'process raft request' (duration: 104.864206ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T17:39:17.328378Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33197} {"level":"info","ts":"2026-07-02T17:39:17.487791Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33197,"took":"155.209749ms","hash":2280545360,"current-db-size-bytes":60125184,"current-db-size":"60 MB","current-db-size-in-use-bytes":32051200,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T17:39:17.487868Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2280545360,"revision":33197,"compact-revision":30931} {"level":"info","ts":"2026-07-02T17:44:17.337173Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35491} {"level":"info","ts":"2026-07-02T17:44:17.48696Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35491,"took":"145.350815ms","hash":1402478570,"current-db-size-bytes":61448192,"current-db-size":"61 MB","current-db-size-in-use-bytes":31850496,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-02T17:44:17.487034Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1402478570,"revision":35491,"compact-revision":33197} {"level":"info","ts":"2026-07-02T17:46:31.640216Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"b5a1dacc8ba86a4f","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-02T17:46:31.645161Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-07-02T17:46:31.645228Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-07-02T17:49:17.343748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37725} {"level":"info","ts":"2026-07-02T17:49:17.461997Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37725,"took":"113.931063ms","hash":2850778192,"current-db-size-bytes":61448192,"current-db-size":"61 MB","current-db-size-in-use-bytes":31354880,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-02T17:49:17.462051Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2850778192,"revision":37725,"compact-revision":35491}