{"level":"warn","ts":"2026-05-08T18:16:37.40302Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T18:16:37.403131Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-08T18:16:37.403219Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T18:16:37.403233Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-05-08T18:16:37.403266Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T18:16:37.403965Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-05-08T18:16:37.404045Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-08T18:16:37.408099Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.801304ms"} {"level":"info","ts":"2026-05-08T18:16:37.415828Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-05-08T18:16:37.415902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T18:16:37.415926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-05-08T18:16:37.415947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-08T18:16:37.415959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-05-08T18:16:37.416015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-05-08T18:16:37.42046Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T18:16:37.421838Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T18:16:37.422903Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-08T18:16:37.42495Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-08T18:16:37.425347Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-08T18:16:37.42544Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T18:16:37.425566Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T18:16:37.425594Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T18:16:37.425882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-05-08T18:16:37.425988Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-05-08T18:16:37.429547Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T18:16:37.429768Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-08T18:16:37.429817Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T18:16:37.429903Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-05-08T18:16:37.429975Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-05-08T18:16:37.817157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T18:16:37.817279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T18:16:37.817308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-05-08T18:16:37.81733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-05-08T18:16:37.817343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-05-08T18:16:37.817359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-05-08T18:16:37.817375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-05-08T18:16:37.818819Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:16:37.819609Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-05-08T18:16:37.81962Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T18:16:37.819721Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T18:16:37.819981Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:16:37.820005Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T18:16:37.820059Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T18:16:37.820111Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:16:37.820172Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T18:16:37.82332Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-05-08T18:16:37.823422Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-08T18:16:53.73447Z","caller":"traceutil/trace.go:171","msg":"trace[427835868] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:426; }","duration":"182.938223ms","start":"2026-05-08T18:16:53.551505Z","end":"2026-05-08T18:16:53.734444Z","steps":["trace[427835868] 'read index received' (duration: 182.755548ms)","trace[427835868] 'applied index is now lower than readState.Index' (duration: 181.725µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:16:53.734607Z","caller":"traceutil/trace.go:171","msg":"trace[1043677684] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"388.780464ms","start":"2026-05-08T18:16:53.345788Z","end":"2026-05-08T18:16:53.734569Z","steps":["trace[1043677684] 'process raft request' (duration: 388.499846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:16:53.73477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.155939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T18:16:53.734839Z","caller":"traceutil/trace.go:171","msg":"trace[331314034] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"183.374096ms","start":"2026-05-08T18:16:53.551454Z","end":"2026-05-08T18:16:53.734828Z","steps":["trace[331314034] 'agreement among raft nodes before linearized reading' (duration: 183.158429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:16:53.734815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.748633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4378"} {"level":"info","ts":"2026-05-08T18:16:53.734913Z","caller":"traceutil/trace.go:171","msg":"trace[36781498] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:414; }","duration":"149.868487ms","start":"2026-05-08T18:16:53.585021Z","end":"2026-05-08T18:16:53.73489Z","steps":["trace[36781498] 'agreement among raft nodes before linearized reading' (duration: 149.596469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:16:53.735434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:16:53.345765Z","time spent":"388.982979ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:17:09.631458Z","caller":"traceutil/trace.go:171","msg":"trace[2092823336] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"234.173177ms","start":"2026-05-08T18:17:09.397253Z","end":"2026-05-08T18:17:09.631426Z","steps":["trace[2092823336] 'read index received' (duration: 233.438717ms)","trace[2092823336] 'applied index is now lower than readState.Index' (duration: 733.55µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:17:09.631571Z","caller":"traceutil/trace.go:171","msg":"trace[48547346] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"238.527227ms","start":"2026-05-08T18:17:09.392979Z","end":"2026-05-08T18:17:09.631507Z","steps":["trace[48547346] 'process raft request' (duration: 237.965292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:17:09.631817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.513577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31145"} {"level":"info","ts":"2026-05-08T18:17:09.631936Z","caller":"traceutil/trace.go:171","msg":"trace[1647921102] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:584; }","duration":"234.678101ms","start":"2026-05-08T18:17:09.397231Z","end":"2026-05-08T18:17:09.631909Z","steps":["trace[1647921102] 'agreement among raft nodes before linearized reading' (duration: 234.308681ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:17:14.308041Z","caller":"traceutil/trace.go:171","msg":"trace[1065773227] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"393.20151ms","start":"2026-05-08T18:17:13.914812Z","end":"2026-05-08T18:17:14.308013Z","steps":["trace[1065773227] 'process raft request' (duration: 392.992074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:17:14.308317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:17:13.914775Z","time spent":"393.431007ms","remote":"127.0.0.1:34712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:17:14.329142Z","caller":"traceutil/trace.go:171","msg":"trace[1957630716] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"375.573064ms","start":"2026-05-08T18:17:13.953531Z","end":"2026-05-08T18:17:14.329104Z","steps":["trace[1957630716] 'process raft request' (duration: 375.003819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:17:14.329464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:17:13.953507Z","time spent":"375.746019ms","remote":"127.0.0.1:34618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:17:56.034516Z","caller":"traceutil/trace.go:171","msg":"trace[1392000202] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"245.618063ms","start":"2026-05-08T18:17:55.78887Z","end":"2026-05-08T18:17:56.034488Z","steps":["trace[1392000202] 'process raft request' (duration: 245.418527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:18:43.942536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.392138ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:18:43.942626Z","caller":"traceutil/trace.go:171","msg":"trace[1382422201] linearizableReadLoop","detail":"{readStateIndex:1920; appliedIndex:1919; }","duration":"299.921725ms","start":"2026-05-08T18:18:43.64269Z","end":"2026-05-08T18:18:43.942612Z","steps":["trace[1382422201] 'read index received' (duration: 179.27334ms)","trace[1382422201] 'applied index is now lower than readState.Index' (duration: 120.647235ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:18:43.942758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.07495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-08T18:18:43.942786Z","caller":"traceutil/trace.go:171","msg":"trace[1870626020] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1851; }","duration":"300.138001ms","start":"2026-05-08T18:18:43.642639Z","end":"2026-05-08T18:18:43.942777Z","steps":["trace[1870626020] 'agreement among raft nodes before linearized reading' (duration: 300.024128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:18:43.942806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:18:43.642618Z","time spent":"300.182872ms","remote":"127.0.0.1:47418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-08T18:18:43.942631Z","caller":"traceutil/trace.go:171","msg":"trace[1002148567] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"349.637757ms","start":"2026-05-08T18:18:43.592981Z","end":"2026-05-08T18:18:43.942618Z","steps":["trace[1002148567] 'process raft request' (duration: 228.963861ms)","trace[1002148567] 'compare' (duration: 120.295966ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:18:43.943091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:18:43.592951Z","time spent":"350.12178ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:18:43.957964Z","caller":"traceutil/trace.go:171","msg":"trace[1907478494] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"279.560027ms","start":"2026-05-08T18:18:43.678378Z","end":"2026-05-08T18:18:43.957938Z","steps":["trace[1907478494] 'process raft request' (duration: 279.323501ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:18:43.958149Z","caller":"traceutil/trace.go:171","msg":"trace[1690757144] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"157.427003ms","start":"2026-05-08T18:18:43.8007Z","end":"2026-05-08T18:18:43.958127Z","steps":["trace[1690757144] 'process raft request' (duration: 157.191866ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:18:43.990022Z","caller":"traceutil/trace.go:171","msg":"trace[1827834257] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"105.806509ms","start":"2026-05-08T18:18:43.884189Z","end":"2026-05-08T18:18:43.989995Z","steps":["trace[1827834257] 'process raft request' (duration: 105.652984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:18:43.990179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.681284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T18:18:43.990224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.34583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:18:43.990289Z","caller":"traceutil/trace.go:171","msg":"trace[840946053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1854; }","duration":"270.8668ms","start":"2026-05-08T18:18:43.719395Z","end":"2026-05-08T18:18:43.990262Z","steps":["trace[840946053] 'agreement among raft nodes before linearized reading' (duration: 270.693955ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:18:43.990359Z","caller":"traceutil/trace.go:171","msg":"trace[681450466] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1854; }","duration":"222.485503ms","start":"2026-05-08T18:18:43.767833Z","end":"2026-05-08T18:18:43.990319Z","steps":["trace[681450466] 'agreement among raft nodes before linearized reading' (duration: 222.331529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:18:43.99055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.622597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-08T18:18:43.990602Z","caller":"traceutil/trace.go:171","msg":"trace[1601880136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1854; }","duration":"230.72336ms","start":"2026-05-08T18:18:43.759864Z","end":"2026-05-08T18:18:43.990588Z","steps":["trace[1601880136] 'agreement among raft nodes before linearized reading' (duration: 230.571636ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:32.296351Z","caller":"traceutil/trace.go:171","msg":"trace[1970040957] linearizableReadLoop","detail":"{readStateIndex:2246; appliedIndex:2243; }","duration":"101.17285ms","start":"2026-05-08T18:19:32.195108Z","end":"2026-05-08T18:19:32.296281Z","steps":["trace[1970040957] 'read index received' (duration: 3.583958ms)","trace[1970040957] 'applied index is now lower than readState.Index' (duration: 97.587282ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:19:32.296607Z","caller":"traceutil/trace.go:171","msg":"trace[1024104167] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"108.140459ms","start":"2026-05-08T18:19:32.188432Z","end":"2026-05-08T18:19:32.296573Z","steps":["trace[1024104167] 'process raft request' (duration: 10.518666ms)","trace[1024104167] 'compare' (duration: 97.154561ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:19:32.296816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.679264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-08T18:19:32.296919Z","caller":"traceutil/trace.go:171","msg":"trace[306059069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2166; }","duration":"101.800777ms","start":"2026-05-08T18:19:32.195094Z","end":"2026-05-08T18:19:32.296894Z","steps":["trace[306059069] 'agreement among raft nodes before linearized reading' (duration: 101.483908ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:32.831562Z","caller":"traceutil/trace.go:171","msg":"trace[648623655] linearizableReadLoop","detail":"{readStateIndex:2252; appliedIndex:2249; }","duration":"211.011025ms","start":"2026-05-08T18:19:32.620524Z","end":"2026-05-08T18:19:32.831535Z","steps":["trace[648623655] 'read index received' (duration: 56.994449ms)","trace[648623655] 'applied index is now lower than readState.Index' (duration: 154.015546ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:19:32.831594Z","caller":"traceutil/trace.go:171","msg":"trace[698271465] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"228.655734ms","start":"2026-05-08T18:19:32.602913Z","end":"2026-05-08T18:19:32.831569Z","steps":["trace[698271465] 'process raft request' (duration: 210.115539ms)","trace[698271465] 'compare' (duration: 18.307368ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:19:32.831706Z","caller":"traceutil/trace.go:171","msg":"trace[118358853] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"228.090129ms","start":"2026-05-08T18:19:32.603572Z","end":"2026-05-08T18:19:32.831662Z","steps":["trace[118358853] 'process raft request' (duration: 227.897154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:32.831943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.036678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-05-08T18:19:32.83176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.19412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:19:32.832023Z","caller":"traceutil/trace.go:171","msg":"trace[1178578123] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2172; }","duration":"146.152951ms","start":"2026-05-08T18:19:32.685848Z","end":"2026-05-08T18:19:32.832001Z","steps":["trace[1178578123] 'agreement among raft nodes before linearized reading' (duration: 145.904935ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:32.832059Z","caller":"traceutil/trace.go:171","msg":"trace[1461540545] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:2172; }","duration":"211.556689ms","start":"2026-05-08T18:19:32.620486Z","end":"2026-05-08T18:19:32.832043Z","steps":["trace[1461540545] 'agreement among raft nodes before linearized reading' (duration: 211.19706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:32.831784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.472826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:19:32.832365Z","caller":"traceutil/trace.go:171","msg":"trace[1650558486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2172; }","duration":"113.037122ms","start":"2026-05-08T18:19:32.719277Z","end":"2026-05-08T18:19:32.832314Z","steps":["trace[1650558486] 'agreement among raft nodes before linearized reading' (duration: 112.474207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:32.831755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.982602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T18:19:32.832435Z","caller":"traceutil/trace.go:171","msg":"trace[1237141128] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2172; }","duration":"180.697951ms","start":"2026-05-08T18:19:32.651724Z","end":"2026-05-08T18:19:32.832422Z","steps":["trace[1237141128] 'agreement among raft nodes before linearized reading' (duration: 179.983811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:38.541033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.006086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:19:38.541226Z","caller":"traceutil/trace.go:171","msg":"trace[1559334945] linearizableReadLoop","detail":"{readStateIndex:2279; appliedIndex:2278; }","duration":"198.234587ms","start":"2026-05-08T18:19:38.342972Z","end":"2026-05-08T18:19:38.541207Z","steps":["trace[1559334945] 'read index received' (duration: 68.837101ms)","trace[1559334945] 'applied index is now lower than readState.Index' (duration: 129.395886ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:19:38.541281Z","caller":"traceutil/trace.go:171","msg":"trace[1548409326] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"226.248429ms","start":"2026-05-08T18:19:38.314986Z","end":"2026-05-08T18:19:38.541234Z","steps":["trace[1548409326] 'process raft request' (duration: 96.947975ms)","trace[1548409326] 'compare' (duration: 128.893213ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:19:38.541513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.415515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-08T18:19:38.54153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.516745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-05-08T18:19:38.541549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.276096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T18:19:38.541706Z","caller":"traceutil/trace.go:171","msg":"trace[1691658302] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2198; }","duration":"198.748952ms","start":"2026-05-08T18:19:38.342932Z","end":"2026-05-08T18:19:38.541681Z","steps":["trace[1691658302] 'agreement among raft nodes before linearized reading' (duration: 198.371632ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:38.541766Z","caller":"traceutil/trace.go:171","msg":"trace[1484194983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2198; }","duration":"167.513132ms","start":"2026-05-08T18:19:38.374231Z","end":"2026-05-08T18:19:38.541744Z","steps":["trace[1484194983] 'agreement among raft nodes before linearized reading' (duration: 167.208654ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:38.541784Z","caller":"traceutil/trace.go:171","msg":"trace[2094362043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2198; }","duration":"176.572948ms","start":"2026-05-08T18:19:38.365058Z","end":"2026-05-08T18:19:38.541631Z","steps":["trace[2094362043] 'agreement among raft nodes before linearized reading' (duration: 176.281451ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:38.771326Z","caller":"traceutil/trace.go:171","msg":"trace[1493801359] transaction","detail":"{read_only:false; response_revision:2199; number_of_response:1; }","duration":"225.141927ms","start":"2026-05-08T18:19:38.546158Z","end":"2026-05-08T18:19:38.7713Z","steps":["trace[1493801359] 'process raft request' (duration: 224.990823ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:38.873959Z","caller":"traceutil/trace.go:171","msg":"trace[2122796548] transaction","detail":"{read_only:false; response_revision:2200; number_of_response:1; }","duration":"327.555091ms","start":"2026-05-08T18:19:38.546374Z","end":"2026-05-08T18:19:38.873929Z","steps":["trace[2122796548] 'process raft request' (duration: 326.667436ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:19:38.874052Z","caller":"traceutil/trace.go:171","msg":"trace[1994939909] linearizableReadLoop","detail":"{readStateIndex:2282; appliedIndex:2279; }","duration":"173.706801ms","start":"2026-05-08T18:19:38.700311Z","end":"2026-05-08T18:19:38.874018Z","steps":["trace[1994939909] 'read index received' (duration: 70.857116ms)","trace[1994939909] 'applied index is now lower than readState.Index' (duration: 102.846605ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:19:38.874097Z","caller":"traceutil/trace.go:171","msg":"trace[1287761758] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"316.329336ms","start":"2026-05-08T18:19:38.557737Z","end":"2026-05-08T18:19:38.874067Z","steps":["trace[1287761758] 'process raft request' (duration: 316.125981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:38.874255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:19:38.557699Z","time spent":"316.46515ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:19:38.874297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.991978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T18:19:38.874361Z","caller":"traceutil/trace.go:171","msg":"trace[2016610616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2201; }","duration":"174.102731ms","start":"2026-05-08T18:19:38.700241Z","end":"2026-05-08T18:19:38.874344Z","steps":["trace[2016610616] 'agreement among raft nodes before linearized reading' (duration: 173.891425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:19:38.874275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:19:38.546353Z","time spent":"327.752796ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:20:13.101709Z","caller":"traceutil/trace.go:171","msg":"trace[8673451] transaction","detail":"{read_only:false; response_revision:2559; number_of_response:1; }","duration":"166.143316ms","start":"2026-05-08T18:20:12.935553Z","end":"2026-05-08T18:20:13.101696Z","steps":["trace[8673451] 'process raft request' (duration: 166.057974ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:13.110625Z","caller":"traceutil/trace.go:171","msg":"trace[1853053678] linearizableReadLoop","detail":"{readStateIndex:2657; appliedIndex:2655; }","duration":"100.252494ms","start":"2026-05-08T18:20:13.010363Z","end":"2026-05-08T18:20:13.110616Z","steps":["trace[1853053678] 'read index received' (duration: 91.330372ms)","trace[1853053678] 'applied index is now lower than readState.Index' (duration: 8.921532ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:20:13.110684Z","caller":"traceutil/trace.go:171","msg":"trace[551398659] transaction","detail":"{read_only:false; response_revision:2560; number_of_response:1; }","duration":"147.280693ms","start":"2026-05-08T18:20:12.963397Z","end":"2026-05-08T18:20:13.110678Z","steps":["trace[551398659] 'process raft request' (duration: 147.15735ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:20:13.110822Z","caller":"traceutil/trace.go:171","msg":"trace[306821091] transaction","detail":"{read_only:false; response_revision:2561; number_of_response:1; }","duration":"132.553092ms","start":"2026-05-08T18:20:12.97825Z","end":"2026-05-08T18:20:13.110803Z","steps":["trace[306821091] 'process raft request' (duration: 132.342247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:20:13.110834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.451819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-08T18:20:13.110898Z","caller":"traceutil/trace.go:171","msg":"trace[2070083772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2561; }","duration":"100.535982ms","start":"2026-05-08T18:20:13.010346Z","end":"2026-05-08T18:20:13.110882Z","steps":["trace[2070083772] 'agreement among raft nodes before linearized reading' (duration: 100.355227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:20:46.360929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.26816ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-05-08T18:20:46.756995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.951313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T18:20:46.757358Z","caller":"traceutil/trace.go:171","msg":"trace[428749788] transaction","detail":"{read_only:false; response_revision:2763; number_of_response:1; }","duration":"236.164237ms","start":"2026-05-08T18:20:46.521162Z","end":"2026-05-08T18:20:46.757326Z","steps":["trace[428749788] 'process raft request' (duration: 92.793103ms)","trace[428749788] 'compare' (duration: 142.772117ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:20:46.946048Z","caller":"traceutil/trace.go:171","msg":"trace[1555108934] transaction","detail":"{read_only:false; response_revision:2764; number_of_response:1; }","duration":"172.330594ms","start":"2026-05-08T18:20:46.77369Z","end":"2026-05-08T18:20:46.946021Z","steps":["trace[1555108934] 'process raft request' (duration: 172.163669ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:23:12.203301Z","caller":"traceutil/trace.go:171","msg":"trace[1822636768] transaction","detail":"{read_only:false; response_revision:3732; number_of_response:1; }","duration":"177.187184ms","start":"2026-05-08T18:23:12.026079Z","end":"2026-05-08T18:23:12.203267Z","steps":["trace[1822636768] 'process raft request' (duration: 106.361688ms)","trace[1822636768] 'compare' (duration: 70.680852ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:23:12.204795Z","caller":"traceutil/trace.go:171","msg":"trace[1983898178] transaction","detail":"{read_only:false; response_revision:3735; number_of_response:1; }","duration":"178.506129ms","start":"2026-05-08T18:23:12.026267Z","end":"2026-05-08T18:23:12.204773Z","steps":["trace[1983898178] 'process raft request' (duration: 178.462418ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:23:12.205032Z","caller":"traceutil/trace.go:171","msg":"trace[936650015] transaction","detail":"{read_only:false; response_revision:3733; number_of_response:1; }","duration":"178.8887ms","start":"2026-05-08T18:23:12.026118Z","end":"2026-05-08T18:23:12.205007Z","steps":["trace[936650015] 'process raft request' (duration: 178.460948ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:23:12.205135Z","caller":"traceutil/trace.go:171","msg":"trace[1414003820] transaction","detail":"{read_only:false; response_revision:3734; number_of_response:1; }","duration":"178.923291ms","start":"2026-05-08T18:23:12.026142Z","end":"2026-05-08T18:23:12.205065Z","steps":["trace[1414003820] 'process raft request' (duration: 178.54129ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:45.829722Z","caller":"traceutil/trace.go:171","msg":"trace[895229061] transaction","detail":"{read_only:false; response_revision:5109; number_of_response:1; }","duration":"115.177212ms","start":"2026-05-08T18:24:45.714512Z","end":"2026-05-08T18:24:45.829689Z","steps":["trace[895229061] 'process raft request' (duration: 54.128533ms)","trace[895229061] 'compare' (duration: 60.861794ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:24:45.829846Z","caller":"traceutil/trace.go:171","msg":"trace[160025841] transaction","detail":"{read_only:false; response_revision:5110; number_of_response:1; }","duration":"114.997557ms","start":"2026-05-08T18:24:45.714794Z","end":"2026-05-08T18:24:45.829792Z","steps":["trace[160025841] 'process raft request' (duration: 114.842463ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:45.829759Z","caller":"traceutil/trace.go:171","msg":"trace[1669616135] linearizableReadLoop","detail":"{readStateIndex:5297; appliedIndex:5295; }","duration":"110.135495ms","start":"2026-05-08T18:24:45.719592Z","end":"2026-05-08T18:24:45.829728Z","steps":["trace[1669616135] 'read index received' (duration: 49.060525ms)","trace[1669616135] 'applied index is now lower than readState.Index' (duration: 61.07404ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:24:45.829892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.276431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:24:45.830131Z","caller":"traceutil/trace.go:171","msg":"trace[1224546420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5111; }","duration":"110.573808ms","start":"2026-05-08T18:24:45.719542Z","end":"2026-05-08T18:24:45.830116Z","steps":["trace[1224546420] 'agreement among raft nodes before linearized reading' (duration: 110.275819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:24:54.526722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.455029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-08T18:24:54.526784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.796274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-79d8d23e-65b7-4206-81ed-d0bfba9b0fb3\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-08T18:24:54.526844Z","caller":"traceutil/trace.go:171","msg":"trace[1019605321] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:5286; }","duration":"142.672144ms","start":"2026-05-08T18:24:54.384148Z","end":"2026-05-08T18:24:54.52682Z","steps":["trace[1019605321] 'count revisions from in-memory index tree' (duration: 142.318685ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:24:54.526923Z","caller":"traceutil/trace.go:171","msg":"trace[331818149] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-79d8d23e-65b7-4206-81ed-d0bfba9b0fb3; range_end:; response_count:1; response_revision:5286; }","duration":"123.990379ms","start":"2026-05-08T18:24:54.402899Z","end":"2026-05-08T18:24:54.526889Z","steps":["trace[331818149] 'range keys from in-memory index tree' (duration: 123.562608ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:45.164664Z","caller":"traceutil/trace.go:171","msg":"trace[274526236] transaction","detail":"{read_only:false; response_revision:5787; number_of_response:1; }","duration":"104.811639ms","start":"2026-05-08T18:25:45.059827Z","end":"2026-05-08T18:25:45.164638Z","steps":["trace[274526236] 'process raft request' (duration: 97.783249ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:25:45.164859Z","caller":"traceutil/trace.go:171","msg":"trace[86669676] linearizableReadLoop","detail":"{readStateIndex:5993; appliedIndex:5990; }","duration":"103.7331ms","start":"2026-05-08T18:25:45.061095Z","end":"2026-05-08T18:25:45.164828Z","steps":["trace[86669676] 'read index received' (duration: 6.876417ms)","trace[86669676] 'applied index is now lower than readState.Index' (duration: 96.817742ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:25:45.164965Z","caller":"traceutil/trace.go:171","msg":"trace[1737297357] transaction","detail":"{read_only:false; response_revision:5788; number_of_response:1; }","duration":"104.536201ms","start":"2026-05-08T18:25:45.060385Z","end":"2026-05-08T18:25:45.164921Z","steps":["trace[1737297357] 'process raft request' (duration: 104.199652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:25:45.165146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.028238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T18:25:45.16519Z","caller":"traceutil/trace.go:171","msg":"trace[41382191] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5788; }","duration":"104.13096ms","start":"2026-05-08T18:25:45.061045Z","end":"2026-05-08T18:25:45.165176Z","steps":["trace[41382191] 'agreement among raft nodes before linearized reading' (duration: 103.968726ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:26:37.847549Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3047} {"level":"info","ts":"2026-05-08T18:26:37.931207Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3047,"took":"81.9745ms","hash":3889241661,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":32272384,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-08T18:26:37.931278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3889241661,"revision":3047,"compact-revision":-1} {"level":"info","ts":"2026-05-08T18:31:14.782391Z","caller":"traceutil/trace.go:171","msg":"trace[1088857404] transaction","detail":"{read_only:false; response_revision:8191; number_of_response:1; }","duration":"213.400516ms","start":"2026-05-08T18:31:14.56894Z","end":"2026-05-08T18:31:14.78234Z","steps":["trace[1088857404] 'process raft request' (duration: 213.120958ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:31:27.173151Z","caller":"traceutil/trace.go:171","msg":"trace[927627035] linearizableReadLoop","detail":"{readStateIndex:8542; appliedIndex:8541; }","duration":"315.682325ms","start":"2026-05-08T18:31:26.857438Z","end":"2026-05-08T18:31:27.17312Z","steps":["trace[927627035] 'read index received' (duration: 315.493109ms)","trace[927627035] 'applied index is now lower than readState.Index' (duration: 187.666µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:31:27.17332Z","caller":"traceutil/trace.go:171","msg":"trace[2124568722] transaction","detail":"{read_only:false; response_revision:8255; number_of_response:1; }","duration":"324.784612ms","start":"2026-05-08T18:31:26.848478Z","end":"2026-05-08T18:31:27.173262Z","steps":["trace[2124568722] 'process raft request' (duration: 324.434701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:31:27.173407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.931691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T18:31:27.173594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:31:26.848436Z","time spent":"325.008498ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:31:27.173638Z","caller":"traceutil/trace.go:171","msg":"trace[629067850] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:8255; }","duration":"316.22916ms","start":"2026-05-08T18:31:26.857368Z","end":"2026-05-08T18:31:27.173597Z","steps":["trace[629067850] 'agreement among raft nodes before linearized reading' (duration: 315.908281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:31:27.173722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:31:26.85734Z","time spent":"316.361063ms","remote":"127.0.0.1:47270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"info","ts":"2026-05-08T18:31:27.218051Z","caller":"traceutil/trace.go:171","msg":"trace[1800027868] transaction","detail":"{read_only:false; response_revision:8256; number_of_response:1; }","duration":"319.381855ms","start":"2026-05-08T18:31:26.898627Z","end":"2026-05-08T18:31:27.218009Z","steps":["trace[1800027868] 'process raft request' (duration: 290.178492ms)","trace[1800027868] 'compare' (duration: 28.934525ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:31:27.218172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.230228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-08T18:31:27.218199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.326848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-08T18:31:27.218262Z","caller":"traceutil/trace.go:171","msg":"trace[1602972841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8257; }","duration":"285.360322ms","start":"2026-05-08T18:31:26.932879Z","end":"2026-05-08T18:31:27.21824Z","steps":["trace[1602972841] 'agreement among raft nodes before linearized reading' (duration: 285.123015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:31:27.218259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:31:26.898598Z","time spent":"319.538989ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:31:27.218296Z","caller":"traceutil/trace.go:171","msg":"trace[1668300206] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8257; }","duration":"265.4581ms","start":"2026-05-08T18:31:26.952817Z","end":"2026-05-08T18:31:27.218275Z","steps":["trace[1668300206] 'agreement among raft nodes before linearized reading' (duration: 265.202694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:31:27.218371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.942523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-08T18:31:27.218462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.886353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:31:27.218497Z","caller":"traceutil/trace.go:171","msg":"trace[989999713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8257; }","duration":"277.107257ms","start":"2026-05-08T18:31:26.94136Z","end":"2026-05-08T18:31:27.218468Z","steps":["trace[989999713] 'agreement among raft nodes before linearized reading' (duration: 276.696996ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:31:27.218511Z","caller":"traceutil/trace.go:171","msg":"trace[649602791] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:8257; }","duration":"226.938414ms","start":"2026-05-08T18:31:26.991557Z","end":"2026-05-08T18:31:27.218495Z","steps":["trace[649602791] 'agreement among raft nodes before linearized reading' (duration: 226.854792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:31:27.218755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.633388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-08T18:31:27.218847Z","caller":"traceutil/trace.go:171","msg":"trace[88869038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8257; }","duration":"285.793833ms","start":"2026-05-08T18:31:26.93303Z","end":"2026-05-08T18:31:27.218824Z","steps":["trace[88869038] 'agreement among raft nodes before linearized reading' (duration: 285.372642ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:31:37.85424Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6201} {"level":"info","ts":"2026-05-08T18:31:37.956642Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6201,"took":"99.76068ms","hash":2862236006,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":22978560,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-08T18:31:37.956725Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2862236006,"revision":6201,"compact-revision":3047} {"level":"info","ts":"2026-05-08T18:31:55.737291Z","caller":"traceutil/trace.go:171","msg":"trace[453546974] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"261.02868ms","start":"2026-05-08T18:31:55.476227Z","end":"2026-05-08T18:31:55.737256Z","steps":["trace[453546974] 'process raft request' (duration: 260.826735ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:31:55.920443Z","caller":"traceutil/trace.go:171","msg":"trace[188422959] linearizableReadLoop","detail":"{readStateIndex:8765; appliedIndex:8761; }","duration":"238.93597ms","start":"2026-05-08T18:31:55.681471Z","end":"2026-05-08T18:31:55.920407Z","steps":["trace[188422959] 'read index received' (duration: 55.640211ms)","trace[188422959] 'applied index is now lower than readState.Index' (duration: 183.294939ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:31:55.920511Z","caller":"traceutil/trace.go:171","msg":"trace[2062411688] transaction","detail":"{read_only:false; response_revision:8468; number_of_response:1; }","duration":"444.072852ms","start":"2026-05-08T18:31:55.476373Z","end":"2026-05-08T18:31:55.920446Z","steps":["trace[2062411688] 'process raft request' (duration: 443.974729ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:31:55.920609Z","caller":"traceutil/trace.go:171","msg":"trace[598422871] transaction","detail":"{read_only:false; response_revision:8469; number_of_response:1; }","duration":"343.958462ms","start":"2026-05-08T18:31:55.576616Z","end":"2026-05-08T18:31:55.920574Z","steps":["trace[598422871] 'process raft request' (duration: 343.760647ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:31:55.920599Z","caller":"traceutil/trace.go:171","msg":"trace[146222214] transaction","detail":"{read_only:false; response_revision:8467; number_of_response:1; }","duration":"444.211286ms","start":"2026-05-08T18:31:55.47635Z","end":"2026-05-08T18:31:55.920561Z","steps":["trace[146222214] 'process raft request' (duration: 443.823986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:31:55.920695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.201947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ts9hn\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-05-08T18:31:55.920692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:31:55.47635Z","time spent":"444.242377ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:31:55.920736Z","caller":"traceutil/trace.go:171","msg":"trace[1008257536] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ts9hn; range_end:; response_count:1; response_revision:8469; }","duration":"239.305869ms","start":"2026-05-08T18:31:55.681416Z","end":"2026-05-08T18:31:55.920722Z","steps":["trace[1008257536] 'agreement among raft nodes before linearized reading' (duration: 239.147935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:31:55.92079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:31:55.576593Z","time spent":"344.090975ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:31:55.92087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.611855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T18:31:55.920896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:31:55.476331Z","time spent":"444.37872ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:31:55.920938Z","caller":"traceutil/trace.go:171","msg":"trace[530229623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8469; }","duration":"191.728299ms","start":"2026-05-08T18:31:55.729194Z","end":"2026-05-08T18:31:55.920923Z","steps":["trace[530229623] 'agreement among raft nodes before linearized reading' (duration: 191.609434ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:31:59.454593Z","caller":"traceutil/trace.go:171","msg":"trace[746156923] transaction","detail":"{read_only:false; response_revision:8481; number_of_response:1; }","duration":"121.871491ms","start":"2026-05-08T18:31:59.332442Z","end":"2026-05-08T18:31:59.454314Z","steps":["trace[746156923] 'process raft request' (duration: 121.663695ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:31:59.626836Z","caller":"traceutil/trace.go:171","msg":"trace[756750304] linearizableReadLoop","detail":"{readStateIndex:8779; appliedIndex:8777; }","duration":"284.937638ms","start":"2026-05-08T18:31:59.341862Z","end":"2026-05-08T18:31:59.6268Z","steps":["trace[756750304] 'read index received' (duration: 107.506079ms)","trace[756750304] 'applied index is now lower than readState.Index' (duration: 177.430239ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:31:59.627323Z","caller":"traceutil/trace.go:171","msg":"trace[537808057] transaction","detail":"{read_only:false; response_revision:8482; number_of_response:1; }","duration":"290.671545ms","start":"2026-05-08T18:31:59.336621Z","end":"2026-05-08T18:31:59.627293Z","steps":["trace[537808057] 'process raft request' (duration: 227.123419ms)","trace[537808057] 'compare' (duration: 62.894378ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:31:59.627389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.496555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-08T18:31:59.627474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.379892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-08T18:31:59.62754Z","caller":"traceutil/trace.go:171","msg":"trace[462716978] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8482; }","duration":"239.510316ms","start":"2026-05-08T18:31:59.388015Z","end":"2026-05-08T18:31:59.627526Z","steps":["trace[462716978] 'agreement among raft nodes before linearized reading' (duration: 239.28064ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:31:59.627495Z","caller":"traceutil/trace.go:171","msg":"trace[568749560] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8482; }","duration":"285.70707ms","start":"2026-05-08T18:31:59.341766Z","end":"2026-05-08T18:31:59.627473Z","steps":["trace[568749560] 'agreement among raft nodes before linearized reading' (duration: 285.36295ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:31:59.764415Z","caller":"traceutil/trace.go:171","msg":"trace[138173208] transaction","detail":"{read_only:false; response_revision:8483; number_of_response:1; }","duration":"131.4593ms","start":"2026-05-08T18:31:59.632922Z","end":"2026-05-08T18:31:59.764382Z","steps":["trace[138173208] 'process raft request' (duration: 131.255565ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:31:59.77318Z","caller":"traceutil/trace.go:171","msg":"trace[57821562] transaction","detail":"{read_only:false; response_revision:8484; number_of_response:1; }","duration":"139.816077ms","start":"2026-05-08T18:31:59.633341Z","end":"2026-05-08T18:31:59.773157Z","steps":["trace[57821562] 'process raft request' (duration: 139.438466ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:32:08.266525Z","caller":"traceutil/trace.go:171","msg":"trace[1767830210] transaction","detail":"{read_only:false; response_revision:8575; number_of_response:1; }","duration":"129.627891ms","start":"2026-05-08T18:32:08.136862Z","end":"2026-05-08T18:32:08.26649Z","steps":["trace[1767830210] 'process raft request' (duration: 129.448216ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:32:08.444787Z","caller":"traceutil/trace.go:171","msg":"trace[766709113] transaction","detail":"{read_only:false; response_revision:8576; number_of_response:1; }","duration":"300.739779ms","start":"2026-05-08T18:32:08.14401Z","end":"2026-05-08T18:32:08.44475Z","steps":["trace[766709113] 'process raft request' (duration: 300.548103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:32:08.444987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:32:08.143993Z","time spent":"300.887122ms","remote":"127.0.0.1:34712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4637,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:32:50.028144Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002430.wal"} {"level":"info","ts":"2026-05-08T18:33:28.109473Z","caller":"traceutil/trace.go:171","msg":"trace[1292350991] transaction","detail":"{read_only:false; response_revision:9341; number_of_response:1; }","duration":"146.215318ms","start":"2026-05-08T18:33:27.963213Z","end":"2026-05-08T18:33:28.109429Z","steps":["trace[1292350991] 'process raft request' (duration: 122.240336ms)","trace[1292350991] 'compare' (duration: 23.535741ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:34:12.49001Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-08T18:34:12.496254Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-08T18:34:12.496367Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-08T18:36:37.862076Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8323} {"level":"info","ts":"2026-05-08T18:36:37.94609Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8323,"took":"80.82844ms","hash":3556292744,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":26329088,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-08T18:36:37.946199Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3556292744,"revision":8323,"compact-revision":6201} {"level":"info","ts":"2026-05-08T18:36:51.619008Z","caller":"traceutil/trace.go:171","msg":"trace[294123657] transaction","detail":"{read_only:false; response_revision:11085; number_of_response:1; }","duration":"113.694795ms","start":"2026-05-08T18:36:51.505282Z","end":"2026-05-08T18:36:51.618976Z","steps":["trace[294123657] 'process raft request' (duration: 113.49148ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:41:37.871525Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10964} {"level":"info","ts":"2026-05-08T18:41:37.968203Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10964,"took":"91.931202ms","hash":3335145527,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":27402240,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-08T18:41:37.968289Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3335145527,"revision":10964,"compact-revision":8323} {"level":"warn","ts":"2026-05-08T18:43:51.645108Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757020274814988483,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T18:43:51.68538Z","caller":"traceutil/trace.go:171","msg":"trace[1219369745] transaction","detail":"{read_only:false; response_revision:13855; number_of_response:1; }","duration":"897.28108ms","start":"2026-05-08T18:43:50.788068Z","end":"2026-05-08T18:43:51.685349Z","steps":["trace[1219369745] 'process raft request' (duration: 897.095565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:43:51.685639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:43:50.788032Z","time spent":"897.494916ms","remote":"127.0.0.1:34712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12479,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:43:51.695211Z","caller":"traceutil/trace.go:171","msg":"trace[607647139] linearizableReadLoop","detail":"{readStateIndex:14330; appliedIndex:14326; }","duration":"551.036197ms","start":"2026-05-08T18:43:51.144139Z","end":"2026-05-08T18:43:51.695176Z","steps":["trace[607647139] 'read index received' (duration: 541.117677ms)","trace[607647139] 'applied index is now lower than readState.Index' (duration: 9.91686ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:43:51.695545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.375127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-08T18:43:51.695588Z","caller":"traceutil/trace.go:171","msg":"trace[274514330] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:13858; }","duration":"551.49954ms","start":"2026-05-08T18:43:51.144074Z","end":"2026-05-08T18:43:51.695574Z","steps":["trace[274514330] 'agreement among raft nodes before linearized reading' (duration: 551.244383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:43:51.695627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:43:51.144058Z","time spent":"551.560081ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"info","ts":"2026-05-08T18:43:51.695886Z","caller":"traceutil/trace.go:171","msg":"trace[1867108147] transaction","detail":"{read_only:false; response_revision:13858; number_of_response:1; }","duration":"732.605399ms","start":"2026-05-08T18:43:50.963238Z","end":"2026-05-08T18:43:51.695844Z","steps":["trace[1867108147] 'process raft request' (duration: 731.8964ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:43:51.696005Z","caller":"traceutil/trace.go:171","msg":"trace[1413697836] transaction","detail":"{read_only:false; response_revision:13857; number_of_response:1; }","duration":"733.921164ms","start":"2026-05-08T18:43:50.962068Z","end":"2026-05-08T18:43:51.695989Z","steps":["trace[1413697836] 'process raft request' (duration: 733.01479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:43:51.696088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:43:50.963219Z","time spent":"732.771593ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T18:43:51.69613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:43:50.96204Z","time spent":"734.006177ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:43:51.696299Z","caller":"traceutil/trace.go:171","msg":"trace[1160510400] transaction","detail":"{read_only:false; response_revision:13856; number_of_response:1; }","duration":"796.6104ms","start":"2026-05-08T18:43:50.89966Z","end":"2026-05-08T18:43:51.69627Z","steps":["trace[1160510400] 'process raft request' (duration: 795.232553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:43:51.696482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:43:50.899569Z","time spent":"796.860207ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:44:14.619733Z","caller":"traceutil/trace.go:171","msg":"trace[977393636] transaction","detail":"{read_only:false; response_revision:14172; number_of_response:1; }","duration":"141.999905ms","start":"2026-05-08T18:44:14.477698Z","end":"2026-05-08T18:44:14.619697Z","steps":["trace[977393636] 'process raft request' (duration: 141.751838ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:44:14.784866Z","caller":"traceutil/trace.go:171","msg":"trace[684464794] transaction","detail":"{read_only:false; response_revision:14173; number_of_response:1; }","duration":"139.201028ms","start":"2026-05-08T18:44:14.645617Z","end":"2026-05-08T18:44:14.784818Z","steps":["trace[684464794] 'process raft request' (duration: 120.507309ms)","trace[684464794] 'compare' (duration: 18.466653ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:44:14.785016Z","caller":"traceutil/trace.go:171","msg":"trace[911980525] linearizableReadLoop","detail":"{readStateIndex:14659; appliedIndex:14658; }","duration":"133.655207ms","start":"2026-05-08T18:44:14.651073Z","end":"2026-05-08T18:44:14.784728Z","steps":["trace[911980525] 'read index received' (duration: 115.061651ms)","trace[911980525] 'applied index is now lower than readState.Index' (duration: 18.592046ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:44:14.785037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.940474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T18:44:14.785135Z","caller":"traceutil/trace.go:171","msg":"trace[2142930417] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:14173; }","duration":"134.113919ms","start":"2026-05-08T18:44:14.650993Z","end":"2026-05-08T18:44:14.785107Z","steps":["trace[2142930417] 'agreement among raft nodes before linearized reading' (duration: 133.927924ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:45:33.120115Z","caller":"traceutil/trace.go:171","msg":"trace[1556840909] transaction","detail":"{read_only:false; response_revision:14944; number_of_response:1; }","duration":"121.104515ms","start":"2026-05-08T18:45:32.998981Z","end":"2026-05-08T18:45:33.120085Z","steps":["trace[1556840909] 'process raft request' (duration: 120.872369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:45:33.367165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.559416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T18:45:33.367293Z","caller":"traceutil/trace.go:171","msg":"trace[1220582063] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14944; }","duration":"100.792742ms","start":"2026-05-08T18:45:33.266475Z","end":"2026-05-08T18:45:33.367268Z","steps":["trace[1220582063] 'count revisions from in-memory index tree' (duration: 100.463754ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:46:37.878435Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12993} {"level":"info","ts":"2026-05-08T18:46:37.962134Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12993,"took":"79.713308ms","hash":4055098774,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":29687808,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-08T18:46:37.962219Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4055098774,"revision":12993,"compact-revision":10964} {"level":"info","ts":"2026-05-08T18:51:35.30156Z","caller":"traceutil/trace.go:171","msg":"trace[739282295] linearizableReadLoop","detail":"{readStateIndex:18194; appliedIndex:18193; }","duration":"293.568508ms","start":"2026-05-08T18:51:35.007956Z","end":"2026-05-08T18:51:35.301524Z","steps":["trace[739282295] 'read index received' (duration: 293.226578ms)","trace[739282295] 'applied index is now lower than readState.Index' (duration: 340.33µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:51:35.301673Z","caller":"traceutil/trace.go:171","msg":"trace[1354664406] transaction","detail":"{read_only:false; response_revision:17600; number_of_response:1; }","duration":"376.839744ms","start":"2026-05-08T18:51:34.924789Z","end":"2026-05-08T18:51:35.301629Z","steps":["trace[1354664406] 'process raft request' (duration: 376.502784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:51:35.301903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.912557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-05-08T18:51:35.301954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:51:34.924754Z","time spent":"377.010578ms","remote":"127.0.0.1:34712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20970,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:51:35.301989Z","caller":"traceutil/trace.go:171","msg":"trace[735430313] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:17600; }","duration":"294.03479ms","start":"2026-05-08T18:51:35.007905Z","end":"2026-05-08T18:51:35.30194Z","steps":["trace[735430313] 'agreement among raft nodes before linearized reading' (duration: 293.801324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:51:35.322354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.268482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-wwtt5\" ","response":"range_response_count:1 size:22096"} {"level":"info","ts":"2026-05-08T18:51:35.322441Z","caller":"traceutil/trace.go:171","msg":"trace[1158101977] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-wwtt5; range_end:; response_count:1; response_revision:17601; }","duration":"127.399686ms","start":"2026-05-08T18:51:35.195022Z","end":"2026-05-08T18:51:35.322422Z","steps":["trace[1158101977] 'agreement among raft nodes before linearized reading' (duration: 127.153469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:51:35.32242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.33551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T18:51:35.322541Z","caller":"traceutil/trace.go:171","msg":"trace[766789850] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:17601; }","duration":"107.500084ms","start":"2026-05-08T18:51:35.21501Z","end":"2026-05-08T18:51:35.32251Z","steps":["trace[766789850] 'agreement among raft nodes before linearized reading' (duration: 107.283359ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:51:37.884875Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15341} {"level":"info","ts":"2026-05-08T18:51:37.998319Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15341,"took":"109.146332ms","hash":3236465673,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":31805440,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-08T18:51:37.998404Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3236465673,"revision":15341,"compact-revision":12993} {"level":"info","ts":"2026-05-08T18:51:42.60777Z","caller":"traceutil/trace.go:171","msg":"trace[563081023] transaction","detail":"{read_only:false; response_revision:17717; number_of_response:1; }","duration":"116.207703ms","start":"2026-05-08T18:51:42.491525Z","end":"2026-05-08T18:51:42.607733Z","steps":["trace[563081023] 'process raft request' (duration: 112.316686ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:51:42.607787Z","caller":"traceutil/trace.go:171","msg":"trace[1244700530] transaction","detail":"{read_only:false; response_revision:17718; number_of_response:1; }","duration":"115.36251ms","start":"2026-05-08T18:51:42.492393Z","end":"2026-05-08T18:51:42.607755Z","steps":["trace[1244700530] 'process raft request' (duration: 115.208065ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:52:18.933393Z","caller":"traceutil/trace.go:171","msg":"trace[1758806361] transaction","detail":"{read_only:false; response_revision:18190; number_of_response:1; }","duration":"126.439171ms","start":"2026-05-08T18:52:18.806903Z","end":"2026-05-08T18:52:18.933342Z","steps":["trace[1758806361] 'process raft request' (duration: 111.870104ms)","trace[1758806361] 'compare' (duration: 13.96135ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T18:52:18.934305Z","caller":"traceutil/trace.go:171","msg":"trace[1173726124] transaction","detail":"{read_only:false; response_revision:18191; number_of_response:1; }","duration":"125.997838ms","start":"2026-05-08T18:52:18.808264Z","end":"2026-05-08T18:52:18.934262Z","steps":["trace[1173726124] 'process raft request' (duration: 125.362571ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:55:07.875214Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-08T18:55:07.88187Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-08T18:55:07.88204Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-08T18:56:37.891061Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17628} {"level":"info","ts":"2026-05-08T18:56:37.999819Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17628,"took":"103.664989ms","hash":2850743337,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":33734656,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-08T18:56:37.999915Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2850743337,"revision":17628,"compact-revision":15341} {"level":"warn","ts":"2026-05-08T18:57:54.880769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.319376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T18:57:54.880921Z","caller":"traceutil/trace.go:171","msg":"trace[1105841526] linearizableReadLoop","detail":"{readStateIndex:21151; appliedIndex:21150; }","duration":"205.297434ms","start":"2026-05-08T18:57:54.6756Z","end":"2026-05-08T18:57:54.880898Z","steps":["trace[1105841526] 'read index received' (duration: 82.69325ms)","trace[1105841526] 'applied index is now lower than readState.Index' (duration: 122.601944ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:57:54.881077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.0388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T18:57:54.881136Z","caller":"traceutil/trace.go:171","msg":"trace[1263698527] transaction","detail":"{read_only:false; response_revision:20458; number_of_response:1; }","duration":"337.759817ms","start":"2026-05-08T18:57:54.543337Z","end":"2026-05-08T18:57:54.881097Z","steps":["trace[1263698527] 'process raft request' (duration: 214.977677ms)","trace[1263698527] 'compare' (duration: 121.729241ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T18:57:54.881243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.620393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-08T18:57:54.881331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T18:57:54.543329Z","time spent":"337.88684ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T18:57:54.88135Z","caller":"traceutil/trace.go:171","msg":"trace[221248120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20458; }","duration":"205.796768ms","start":"2026-05-08T18:57:54.675531Z","end":"2026-05-08T18:57:54.881328Z","steps":["trace[221248120] 'agreement among raft nodes before linearized reading' (duration: 205.48276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T18:57:54.881559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.205318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-08T18:57:54.881548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.63454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-08T18:57:54.881597Z","caller":"traceutil/trace.go:171","msg":"trace[1439916346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20458; }","duration":"117.27126ms","start":"2026-05-08T18:57:54.764317Z","end":"2026-05-08T18:57:54.881588Z","steps":["trace[1439916346] 'agreement among raft nodes before linearized reading' (duration: 117.177977ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:57:54.881626Z","caller":"traceutil/trace.go:171","msg":"trace[397561436] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20458; }","duration":"110.726321ms","start":"2026-05-08T18:57:54.77088Z","end":"2026-05-08T18:57:54.881606Z","steps":["trace[397561436] 'agreement among raft nodes before linearized reading' (duration: 110.522326ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:57:54.882983Z","caller":"traceutil/trace.go:171","msg":"trace[373515692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20458; }","duration":"161.128173ms","start":"2026-05-08T18:57:54.719966Z","end":"2026-05-08T18:57:54.881094Z","steps":["trace[373515692] 'agreement among raft nodes before linearized reading' (duration: 161.063981ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:57:55.049002Z","caller":"traceutil/trace.go:171","msg":"trace[237903114] transaction","detail":"{read_only:false; response_revision:20461; number_of_response:1; }","duration":"161.670428ms","start":"2026-05-08T18:57:54.887305Z","end":"2026-05-08T18:57:55.048976Z","steps":["trace[237903114] 'process raft request' (duration: 161.619757ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:57:55.049081Z","caller":"traceutil/trace.go:171","msg":"trace[273691126] transaction","detail":"{read_only:false; response_revision:20460; number_of_response:1; }","duration":"162.106889ms","start":"2026-05-08T18:57:54.886925Z","end":"2026-05-08T18:57:55.049032Z","steps":["trace[273691126] 'process raft request' (duration: 161.845051ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:57:55.322706Z","caller":"traceutil/trace.go:171","msg":"trace[725313432] transaction","detail":"{read_only:false; response_revision:20462; number_of_response:1; }","duration":"128.290679ms","start":"2026-05-08T18:57:55.194384Z","end":"2026-05-08T18:57:55.322674Z","steps":["trace[725313432] 'process raft request' (duration: 122.533263ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:57:55.367306Z","caller":"traceutil/trace.go:171","msg":"trace[959379464] transaction","detail":"{read_only:false; response_revision:20463; number_of_response:1; }","duration":"171.488204ms","start":"2026-05-08T18:57:55.195773Z","end":"2026-05-08T18:57:55.367261Z","steps":["trace[959379464] 'process raft request' (duration: 171.142475ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T18:57:55.367339Z","caller":"traceutil/trace.go:171","msg":"trace[1402075261] transaction","detail":"{read_only:false; response_revision:20464; number_of_response:1; }","duration":"169.756557ms","start":"2026-05-08T18:57:55.197541Z","end":"2026-05-08T18:57:55.367298Z","steps":["trace[1402075261] 'process raft request' (duration: 169.594463ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:01:37.897302Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20061} {"level":"info","ts":"2026-05-08T19:01:38.014317Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20061,"took":"112.392416ms","hash":2139481556,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":31539200,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-08T19:01:38.014398Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2139481556,"revision":20061,"compact-revision":17628} {"level":"warn","ts":"2026-05-08T19:05:26.993039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.253434ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-08T19:05:26.993859Z","caller":"traceutil/trace.go:171","msg":"trace[547867704] transaction","detail":"{read_only:false; response_revision:25191; number_of_response:1; }","duration":"323.255271ms","start":"2026-05-08T19:05:26.670535Z","end":"2026-05-08T19:05:26.99379Z","steps":["trace[547867704] 'process raft request' (duration: 322.738086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:26.994076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:26.670505Z","time spent":"323.459026ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:27.18262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.766553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:27.182803Z","caller":"traceutil/trace.go:171","msg":"trace[747185479] linearizableReadLoop","detail":"{readStateIndex:26017; appliedIndex:26015; }","duration":"121.818123ms","start":"2026-05-08T19:05:27.06097Z","end":"2026-05-08T19:05:27.182788Z","steps":["trace[747185479] 'read index received' (duration: 121.524605ms)","trace[747185479] 'applied index is now lower than readState.Index' (duration: 292.678µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:27.182927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.968486ms","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-05-08T19:05:27.182908Z","caller":"traceutil/trace.go:171","msg":"trace[944029594] transaction","detail":"{read_only:false; response_revision:25192; number_of_response:1; }","duration":"458.996422ms","start":"2026-05-08T19:05:26.72388Z","end":"2026-05-08T19:05:27.182877Z","steps":["trace[944029594] 'process raft request' (duration: 269.88853ms)","trace[944029594] 'compare' (duration: 188.624679ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:27.182947Z","caller":"traceutil/trace.go:171","msg":"trace[837286068] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25193; }","duration":"122.019488ms","start":"2026-05-08T19:05:27.060921Z","end":"2026-05-08T19:05:27.182941Z","steps":["trace[837286068] 'agreement among raft nodes before linearized reading' (duration: 121.907455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:27.183032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:26.723848Z","time spent":"459.119035ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:27.183082Z","caller":"traceutil/trace.go:171","msg":"trace[122361530] transaction","detail":"{read_only:false; response_revision:25193; number_of_response:1; }","duration":"125.05582ms","start":"2026-05-08T19:05:27.05802Z","end":"2026-05-08T19:05:27.183075Z","steps":["trace[122361530] 'process raft request' (duration: 124.68697ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:31.270716Z","caller":"traceutil/trace.go:171","msg":"trace[944951901] transaction","detail":"{read_only:false; response_revision:25226; number_of_response:1; }","duration":"114.753061ms","start":"2026-05-08T19:05:31.155938Z","end":"2026-05-08T19:05:31.270691Z","steps":["trace[944951901] 'process raft request' (duration: 114.580466ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:31.417628Z","caller":"traceutil/trace.go:171","msg":"trace[436422737] linearizableReadLoop","detail":"{readStateIndex:26052; appliedIndex:26049; }","duration":"196.052421ms","start":"2026-05-08T19:05:31.221555Z","end":"2026-05-08T19:05:31.417607Z","steps":["trace[436422737] 'read index received' (duration: 48.980341ms)","trace[436422737] 'applied index is now lower than readState.Index' (duration: 147.0711ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:31.417931Z","caller":"traceutil/trace.go:171","msg":"trace[115659547] transaction","detail":"{read_only:false; response_revision:25228; number_of_response:1; }","duration":"222.000776ms","start":"2026-05-08T19:05:31.195882Z","end":"2026-05-08T19:05:31.417883Z","steps":["trace[115659547] 'process raft request' (duration: 221.578445ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:31.418009Z","caller":"traceutil/trace.go:171","msg":"trace[386624575] transaction","detail":"{read_only:false; response_revision:25229; number_of_response:1; }","duration":"168.124122ms","start":"2026-05-08T19:05:31.249838Z","end":"2026-05-08T19:05:31.417963Z","steps":["trace[386624575] 'process raft request' (duration: 167.653899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:31.418089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.524034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-08T19:05:31.417941Z","caller":"traceutil/trace.go:171","msg":"trace[223629522] transaction","detail":"{read_only:false; response_revision:25227; number_of_response:1; }","duration":"222.247213ms","start":"2026-05-08T19:05:31.195666Z","end":"2026-05-08T19:05:31.417913Z","steps":["trace[223629522] 'process raft request' (duration: 221.639237ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:31.418131Z","caller":"traceutil/trace.go:171","msg":"trace[216653536] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:25231; }","duration":"196.613176ms","start":"2026-05-08T19:05:31.221509Z","end":"2026-05-08T19:05:31.418122Z","steps":["trace[216653536] 'agreement among raft nodes before linearized reading' (duration: 196.451362ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:31.418041Z","caller":"traceutil/trace.go:171","msg":"trace[1018144797] transaction","detail":"{read_only:false; response_revision:25231; number_of_response:1; }","duration":"153.68582ms","start":"2026-05-08T19:05:31.264313Z","end":"2026-05-08T19:05:31.417999Z","steps":["trace[1018144797] 'process raft request' (duration: 153.231917ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:31.418179Z","caller":"traceutil/trace.go:171","msg":"trace[289170782] transaction","detail":"{read_only:false; response_revision:25230; number_of_response:1; }","duration":"167.657339ms","start":"2026-05-08T19:05:31.250206Z","end":"2026-05-08T19:05:31.417863Z","steps":["trace[289170782] 'process raft request' (duration: 167.31654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:31.418079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.924266ms","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-05-08T19:05:31.418433Z","caller":"traceutil/trace.go:171","msg":"trace[1213790006] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25231; }","duration":"154.266525ms","start":"2026-05-08T19:05:31.264105Z","end":"2026-05-08T19:05:31.418372Z","steps":["trace[1213790006] 'agreement among raft nodes before linearized reading' (duration: 153.805612ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:31.743178Z","caller":"traceutil/trace.go:171","msg":"trace[1705183123] transaction","detail":"{read_only:false; response_revision:25234; number_of_response:1; }","duration":"319.679193ms","start":"2026-05-08T19:05:31.423465Z","end":"2026-05-08T19:05:31.743145Z","steps":["trace[1705183123] 'process raft request' (duration: 319.622791ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:31.743199Z","caller":"traceutil/trace.go:171","msg":"trace[1856070286] transaction","detail":"{read_only:false; response_revision:25232; number_of_response:1; }","duration":"320.186346ms","start":"2026-05-08T19:05:31.422979Z","end":"2026-05-08T19:05:31.743165Z","steps":["trace[1856070286] 'process raft request' (duration: 261.989653ms)","trace[1856070286] 'compare' (duration: 57.819883ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:31.743241Z","caller":"traceutil/trace.go:171","msg":"trace[150900407] transaction","detail":"{read_only:false; response_revision:25233; number_of_response:1; }","duration":"320.112365ms","start":"2026-05-08T19:05:31.423065Z","end":"2026-05-08T19:05:31.743177Z","steps":["trace[150900407] 'process raft request' (duration: 319.975101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:31.743364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:31.422956Z","time spent":"320.31513ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:31.743367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:31.423446Z","time spent":"319.830357ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:31.74349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:31.423057Z","time spent":"320.295819ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:31.748974Z","caller":"traceutil/trace.go:171","msg":"trace[1266837043] linearizableReadLoop","detail":"{readStateIndex:26060; appliedIndex:26056; }","duration":"194.342694ms","start":"2026-05-08T19:05:31.554598Z","end":"2026-05-08T19:05:31.748941Z","steps":["trace[1266837043] 'read index received' (duration: 130.531979ms)","trace[1266837043] 'applied index is now lower than readState.Index' (duration: 63.809165ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:31.749013Z","caller":"traceutil/trace.go:171","msg":"trace[1171698789] transaction","detail":"{read_only:false; response_revision:25235; number_of_response:1; }","duration":"308.299054ms","start":"2026-05-08T19:05:31.440682Z","end":"2026-05-08T19:05:31.748981Z","steps":["trace[1171698789] 'process raft request' (duration: 308.040917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:31.749165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:31.440656Z","time spent":"308.436927ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:31.749347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.719714ms","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-05-08T19:05:31.74944Z","caller":"traceutil/trace.go:171","msg":"trace[74735617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25235; }","duration":"194.868459ms","start":"2026-05-08T19:05:31.55455Z","end":"2026-05-08T19:05:31.749418Z","steps":["trace[74735617] 'agreement among raft nodes before linearized reading' (duration: 194.53589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:31.749584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.819407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-08T19:05:31.749765Z","caller":"traceutil/trace.go:171","msg":"trace[392982469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25235; }","duration":"195.008732ms","start":"2026-05-08T19:05:31.554643Z","end":"2026-05-08T19:05:31.749651Z","steps":["trace[392982469] 'agreement among raft nodes before linearized reading' (duration: 194.694214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:32.094946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.80489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:32.095096Z","caller":"traceutil/trace.go:171","msg":"trace[2044516452] linearizableReadLoop","detail":"{readStateIndex:26062; appliedIndex:26060; }","duration":"339.929995ms","start":"2026-05-08T19:05:31.755131Z","end":"2026-05-08T19:05:32.095061Z","steps":["trace[2044516452] 'read index received' (duration: 26.871571ms)","trace[2044516452] 'applied index is now lower than readState.Index' (duration: 313.057234ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:32.095234Z","caller":"traceutil/trace.go:171","msg":"trace[37332553] transaction","detail":"{read_only:false; response_revision:25237; number_of_response:1; }","duration":"340.100989ms","start":"2026-05-08T19:05:31.75508Z","end":"2026-05-08T19:05:32.095181Z","steps":["trace[37332553] 'process raft request' (duration: 184.947129ms)","trace[37332553] 'compare' (duration: 154.627545ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:32.095502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:31.755061Z","time spent":"340.306195ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:32.095424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.258463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-08T19:05:32.096171Z","caller":"traceutil/trace.go:171","msg":"trace[1273743106] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:25237; }","duration":"341.056965ms","start":"2026-05-08T19:05:31.75508Z","end":"2026-05-08T19:05:32.096137Z","steps":["trace[1273743106] 'agreement among raft nodes before linearized reading' (duration: 340.088199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:32.096265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:31.755059Z","time spent":"341.169008ms","remote":"127.0.0.1:34808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-05-08T19:05:32.098533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.400386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-08T19:05:32.098628Z","caller":"traceutil/trace.go:171","msg":"trace[2042060958] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25238; }","duration":"251.53563ms","start":"2026-05-08T19:05:31.847074Z","end":"2026-05-08T19:05:32.098609Z","steps":["trace[2042060958] 'agreement among raft nodes before linearized reading' (duration: 251.204871ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:32.756018Z","caller":"traceutil/trace.go:171","msg":"trace[1476152782] transaction","detail":"{read_only:false; response_revision:25239; number_of_response:1; }","duration":"271.526744ms","start":"2026-05-08T19:05:32.484447Z","end":"2026-05-08T19:05:32.755974Z","steps":["trace[1476152782] 'process raft request' (duration: 271.256146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:33.279334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.133463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:33.279448Z","caller":"traceutil/trace.go:171","msg":"trace[1980284458] linearizableReadLoop","detail":"{readStateIndex:26066; appliedIndex:26065; }","duration":"224.759742ms","start":"2026-05-08T19:05:33.054671Z","end":"2026-05-08T19:05:33.279431Z","steps":["trace[1980284458] 'read index received' (duration: 85.371922ms)","trace[1980284458] 'applied index is now lower than readState.Index' (duration: 139.38659ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:33.279716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.04262ms","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-05-08T19:05:33.279764Z","caller":"traceutil/trace.go:171","msg":"trace[1225765650] 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:25241; }","duration":"225.095661ms","start":"2026-05-08T19:05:33.054656Z","end":"2026-05-08T19:05:33.279751Z","steps":["trace[1225765650] 'agreement among raft nodes before linearized reading' (duration: 224.822804ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:33.279987Z","caller":"traceutil/trace.go:171","msg":"trace[1740502593] transaction","detail":"{read_only:false; response_revision:25241; number_of_response:1; }","duration":"255.872007ms","start":"2026-05-08T19:05:33.023859Z","end":"2026-05-08T19:05:33.279731Z","steps":["trace[1740502593] 'process raft request' (duration: 116.241611ms)","trace[1740502593] 'compare' (duration: 139.01114ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:33.685118Z","caller":"traceutil/trace.go:171","msg":"trace[1622837734] transaction","detail":"{read_only:false; response_revision:25243; number_of_response:1; }","duration":"260.557975ms","start":"2026-05-08T19:05:33.424513Z","end":"2026-05-08T19:05:33.685071Z","steps":["trace[1622837734] 'process raft request' (duration: 199.780542ms)","trace[1622837734] 'compare' (duration: 60.480815ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:33.685411Z","caller":"traceutil/trace.go:171","msg":"trace[844481012] transaction","detail":"{read_only:false; response_revision:25246; number_of_response:1; }","duration":"260.817213ms","start":"2026-05-08T19:05:33.424568Z","end":"2026-05-08T19:05:33.685385Z","steps":["trace[844481012] 'process raft request' (duration: 260.452213ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:33.685482Z","caller":"traceutil/trace.go:171","msg":"trace[786612149] transaction","detail":"{read_only:false; response_revision:25244; number_of_response:1; }","duration":"260.904025ms","start":"2026-05-08T19:05:33.424543Z","end":"2026-05-08T19:05:33.685447Z","steps":["trace[786612149] 'process raft request' (duration: 260.380281ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:33.686608Z","caller":"traceutil/trace.go:171","msg":"trace[1220440854] transaction","detail":"{read_only:false; response_revision:25245; number_of_response:1; }","duration":"260.665689ms","start":"2026-05-08T19:05:33.424563Z","end":"2026-05-08T19:05:33.685229Z","steps":["trace[1220440854] 'process raft request' (duration: 260.404081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:34.014076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.47995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:34.014281Z","caller":"traceutil/trace.go:171","msg":"trace[545218362] linearizableReadLoop","detail":"{readStateIndex:26074; appliedIndex:26071; }","duration":"228.733081ms","start":"2026-05-08T19:05:33.78553Z","end":"2026-05-08T19:05:34.014263Z","steps":["trace[545218362] 'read index received' (duration: 108.936253ms)","trace[545218362] 'applied index is now lower than readState.Index' (duration: 119.795768ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:34.014433Z","caller":"traceutil/trace.go:171","msg":"trace[1927116122] transaction","detail":"{read_only:false; response_revision:25247; number_of_response:1; }","duration":"261.774799ms","start":"2026-05-08T19:05:33.752644Z","end":"2026-05-08T19:05:34.014419Z","steps":["trace[1927116122] 'process raft request' (duration: 141.848927ms)","trace[1927116122] 'compare' (duration: 119.338776ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:34.014466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.931806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-08T19:05:34.014443Z","caller":"traceutil/trace.go:171","msg":"trace[949309807] transaction","detail":"{read_only:false; response_revision:25248; number_of_response:1; }","duration":"261.724478ms","start":"2026-05-08T19:05:33.752667Z","end":"2026-05-08T19:05:34.014391Z","steps":["trace[949309807] 'process raft request' (duration: 261.508562ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:34.0145Z","caller":"traceutil/trace.go:171","msg":"trace[137030861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25249; }","duration":"229.007938ms","start":"2026-05-08T19:05:33.78548Z","end":"2026-05-08T19:05:34.014488Z","steps":["trace[137030861] 'agreement among raft nodes before linearized reading' (duration: 228.832953ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:34.014607Z","caller":"traceutil/trace.go:171","msg":"trace[1056966299] transaction","detail":"{read_only:false; response_revision:25249; number_of_response:1; }","duration":"261.897442ms","start":"2026-05-08T19:05:33.752699Z","end":"2026-05-08T19:05:34.014597Z","steps":["trace[1056966299] 'process raft request' (duration: 261.527273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:34.420796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.753477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:34.42099Z","caller":"traceutil/trace.go:171","msg":"trace[968337621] transaction","detail":"{read_only:false; response_revision:25251; number_of_response:1; }","duration":"316.900847ms","start":"2026-05-08T19:05:34.104069Z","end":"2026-05-08T19:05:34.42097Z","steps":["trace[968337621] 'process raft request' (duration: 316.845446ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:34.420995Z","caller":"traceutil/trace.go:171","msg":"trace[2107428189] transaction","detail":"{read_only:false; response_revision:25250; number_of_response:1; }","duration":"401.992143ms","start":"2026-05-08T19:05:34.018957Z","end":"2026-05-08T19:05:34.420949Z","steps":["trace[2107428189] 'process raft request' (duration: 206.913277ms)","trace[2107428189] 'compare' (duration: 194.608353ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:34.421134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:34.104053Z","time spent":"316.97337ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:34.421149Z","caller":"traceutil/trace.go:171","msg":"trace[1083659140] linearizableReadLoop","detail":"{readStateIndex:26075; appliedIndex:26074; }","duration":"320.525577ms","start":"2026-05-08T19:05:34.100588Z","end":"2026-05-08T19:05:34.421113Z","steps":["trace[1083659140] 'read index received' (duration: 125.283887ms)","trace[1083659140] 'applied index is now lower than readState.Index' (duration: 195.23795ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:34.421233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:34.018938Z","time spent":"402.177827ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:34.421421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.812575ms","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-05-08T19:05:34.421467Z","caller":"traceutil/trace.go:171","msg":"trace[997348554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25251; }","duration":"320.913077ms","start":"2026-05-08T19:05:34.100543Z","end":"2026-05-08T19:05:34.421456Z","steps":["trace[997348554] 'agreement among raft nodes before linearized reading' (duration: 320.683541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:34.42151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:34.100525Z","time spent":"320.968818ms","remote":"127.0.0.1:34806","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-05-08T19:05:34.528157Z","caller":"traceutil/trace.go:171","msg":"trace[1386527264] transaction","detail":"{read_only:false; response_revision:25252; number_of_response:1; }","duration":"102.153897ms","start":"2026-05-08T19:05:34.425963Z","end":"2026-05-08T19:05:34.528117Z","steps":["trace[1386527264] 'process raft request' (duration: 98.908699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:35.386941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.045032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:35.387046Z","caller":"traceutil/trace.go:171","msg":"trace[1218555237] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25254; }","duration":"196.216096ms","start":"2026-05-08T19:05:35.190807Z","end":"2026-05-08T19:05:35.387023Z","steps":["trace[1218555237] 'range keys from in-memory index tree' (duration: 195.913408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:35.387039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.147744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-dhcp-agent-default-qvt4l.18adabdc75668e6e\" ","response":"range_response_count:1 size:1395"} {"level":"info","ts":"2026-05-08T19:05:35.387122Z","caller":"traceutil/trace.go:171","msg":"trace[1892652240] range","detail":"{range_begin:/registry/events/openstack/neutron-dhcp-agent-default-qvt4l.18adabdc75668e6e; range_end:; response_count:1; response_revision:25254; }","duration":"196.301209ms","start":"2026-05-08T19:05:35.1908Z","end":"2026-05-08T19:05:35.387101Z","steps":["trace[1892652240] 'range keys from in-memory index tree' (duration: 195.926528ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:35.399844Z","caller":"traceutil/trace.go:171","msg":"trace[1832816384] transaction","detail":"{read_only:false; response_revision:25255; number_of_response:1; }","duration":"155.55144ms","start":"2026-05-08T19:05:35.244249Z","end":"2026-05-08T19:05:35.399801Z","steps":["trace[1832816384] 'process raft request' (duration: 155.271702ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:35.448703Z","caller":"traceutil/trace.go:171","msg":"trace[169150841] transaction","detail":"{read_only:false; response_revision:25256; number_of_response:1; }","duration":"163.036354ms","start":"2026-05-08T19:05:35.285617Z","end":"2026-05-08T19:05:35.448654Z","steps":["trace[169150841] 'process raft request' (duration: 162.719135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:35.72213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.392397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:35.722244Z","caller":"traceutil/trace.go:171","msg":"trace[400463148] linearizableReadLoop","detail":"{readStateIndex:26083; appliedIndex:26082; }","duration":"258.953021ms","start":"2026-05-08T19:05:35.463274Z","end":"2026-05-08T19:05:35.722227Z","steps":["trace[400463148] 'read index received' (duration: 111.221905ms)","trace[400463148] 'applied index is now lower than readState.Index' (duration: 147.729766ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:35.722326Z","caller":"traceutil/trace.go:171","msg":"trace[1115286534] transaction","detail":"{read_only:false; response_revision:25258; number_of_response:1; }","duration":"259.980999ms","start":"2026-05-08T19:05:35.462315Z","end":"2026-05-08T19:05:35.722296Z","steps":["trace[1115286534] 'process raft request' (duration: 112.332855ms)","trace[1115286534] 'compare' (duration: 147.248213ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:35.72241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.127676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18416"} {"level":"info","ts":"2026-05-08T19:05:35.722445Z","caller":"traceutil/trace.go:171","msg":"trace[428763256] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25258; }","duration":"259.169937ms","start":"2026-05-08T19:05:35.463263Z","end":"2026-05-08T19:05:35.722433Z","steps":["trace[428763256] 'agreement among raft nodes before linearized reading' (duration: 259.026563ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:36.026587Z","caller":"traceutil/trace.go:171","msg":"trace[133206158] transaction","detail":"{read_only:false; response_revision:25260; number_of_response:1; }","duration":"335.397281ms","start":"2026-05-08T19:05:35.691149Z","end":"2026-05-08T19:05:36.026546Z","steps":["trace[133206158] 'process raft request' (duration: 315.567112ms)","trace[133206158] 'compare' (duration: 19.47248ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:36.026634Z","caller":"traceutil/trace.go:171","msg":"trace[786341105] linearizableReadLoop","detail":"{readStateIndex:26087; appliedIndex:26083; }","duration":"304.337397ms","start":"2026-05-08T19:05:35.722269Z","end":"2026-05-08T19:05:36.026606Z","steps":["trace[786341105] 'read index received' (duration: 562.986µs)","trace[786341105] 'applied index is now lower than readState.Index' (duration: 303.773121ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:36.026668Z","caller":"traceutil/trace.go:171","msg":"trace[263505248] transaction","detail":"{read_only:false; response_revision:25261; number_of_response:1; }","duration":"335.311478ms","start":"2026-05-08T19:05:35.691318Z","end":"2026-05-08T19:05:36.02663Z","steps":["trace[263505248] 'process raft request' (duration: 335.122443ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:36.026764Z","caller":"traceutil/trace.go:171","msg":"trace[1208870215] transaction","detail":"{read_only:false; response_revision:25262; number_of_response:1; }","duration":"334.560428ms","start":"2026-05-08T19:05:35.692192Z","end":"2026-05-08T19:05:36.026753Z","steps":["trace[1208870215] 'process raft request' (duration: 334.359132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:36.026801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:35.691116Z","time spent":"335.571976ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:36.026846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:35.69131Z","time spent":"335.438493ms","remote":"127.0.0.1:34806","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-05-08T19:05:36.026886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:35.692165Z","time spent":"334.63961ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:36.027194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.991299ms","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-05-08T19:05:36.027283Z","caller":"traceutil/trace.go:171","msg":"trace[1607044445] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25262; }","duration":"289.106861ms","start":"2026-05-08T19:05:35.738153Z","end":"2026-05-08T19:05:36.02726Z","steps":["trace[1607044445] 'agreement among raft nodes before linearized reading' (duration: 288.813184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:36.027281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.604156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:36.027361Z","caller":"traceutil/trace.go:171","msg":"trace[2129646985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25262; }","duration":"307.714869ms","start":"2026-05-08T19:05:35.719628Z","end":"2026-05-08T19:05:36.027343Z","steps":["trace[2129646985] 'agreement among raft nodes before linearized reading' (duration: 307.587855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:36.027413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:35.719615Z","time spent":"307.78819ms","remote":"127.0.0.1:34534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T19:05:36.383856Z","caller":"traceutil/trace.go:171","msg":"trace[1057652275] transaction","detail":"{read_only:false; response_revision:25265; number_of_response:1; }","duration":"351.053377ms","start":"2026-05-08T19:05:36.032774Z","end":"2026-05-08T19:05:36.383827Z","steps":["trace[1057652275] 'process raft request' (duration: 350.964844ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:36.383877Z","caller":"traceutil/trace.go:171","msg":"trace[263617433] transaction","detail":"{read_only:false; response_revision:25264; number_of_response:1; }","duration":"351.666194ms","start":"2026-05-08T19:05:36.032174Z","end":"2026-05-08T19:05:36.38384Z","steps":["trace[263617433] 'process raft request' (duration: 351.370745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:36.38406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:36.03211Z","time spent":"351.857289ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:36.384083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:36.032757Z","time spent":"351.18594ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:36.384085Z","caller":"traceutil/trace.go:171","msg":"trace[713330748] transaction","detail":"{read_only:false; response_revision:25266; number_of_response:1; }","duration":"350.682287ms","start":"2026-05-08T19:05:36.033372Z","end":"2026-05-08T19:05:36.384054Z","steps":["trace[713330748] 'process raft request' (duration: 350.41294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:36.384224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:36.033356Z","time spent":"350.79102ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:36.581578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.489355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-08T19:05:36.581717Z","caller":"traceutil/trace.go:171","msg":"trace[1813488390] linearizableReadLoop","detail":"{readStateIndex:26092; appliedIndex:26091; }","duration":"141.965511ms","start":"2026-05-08T19:05:36.439715Z","end":"2026-05-08T19:05:36.58168Z","steps":["trace[1813488390] 'read index received' (duration: 141.266432ms)","trace[1813488390] 'applied index is now lower than readState.Index' (duration: 697.209µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:36.581764Z","caller":"traceutil/trace.go:171","msg":"trace[2105596404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25266; }","duration":"156.744163ms","start":"2026-05-08T19:05:36.424997Z","end":"2026-05-08T19:05:36.581741Z","steps":["trace[2105596404] 'range keys from in-memory index tree' (duration: 156.218348ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:36.581918Z","caller":"traceutil/trace.go:171","msg":"trace[300527285] transaction","detail":"{read_only:false; response_revision:25267; number_of_response:1; }","duration":"156.039622ms","start":"2026-05-08T19:05:36.42586Z","end":"2026-05-08T19:05:36.5819Z","steps":["trace[300527285] 'process raft request' (duration: 155.033055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:36.582131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.381652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:36.582223Z","caller":"traceutil/trace.go:171","msg":"trace[1174159122] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:25267; }","duration":"142.524586ms","start":"2026-05-08T19:05:36.439675Z","end":"2026-05-08T19:05:36.5822Z","steps":["trace[1174159122] 'agreement among raft nodes before linearized reading' (duration: 142.360742ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:36.760087Z","caller":"traceutil/trace.go:171","msg":"trace[1512352930] transaction","detail":"{read_only:false; response_revision:25269; number_of_response:1; }","duration":"151.710455ms","start":"2026-05-08T19:05:36.608339Z","end":"2026-05-08T19:05:36.760049Z","steps":["trace[1512352930] 'process raft request' (duration: 87.432677ms)","trace[1512352930] 'compare' (duration: 64.135234ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:37.002384Z","caller":"traceutil/trace.go:171","msg":"trace[1305930494] transaction","detail":"{read_only:false; response_revision:25270; number_of_response:1; }","duration":"207.690228ms","start":"2026-05-08T19:05:36.794655Z","end":"2026-05-08T19:05:37.002345Z","steps":["trace[1305930494] 'process raft request' (duration: 119.705006ms)","trace[1305930494] 'compare' (duration: 87.787217ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:44.239263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.711638ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:44.239377Z","caller":"traceutil/trace.go:171","msg":"trace[529068683] transaction","detail":"{read_only:false; response_revision:25318; number_of_response:1; }","duration":"482.10638ms","start":"2026-05-08T19:05:43.757246Z","end":"2026-05-08T19:05:44.239352Z","steps":["trace[529068683] 'process raft request' (duration: 210.217127ms)","trace[529068683] 'compare' (duration: 271.120222ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:44.239425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:43.757223Z","time spent":"482.182082ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:44.470416Z","caller":"traceutil/trace.go:171","msg":"trace[1883796494] transaction","detail":"{read_only:false; response_revision:25319; number_of_response:1; }","duration":"418.163231ms","start":"2026-05-08T19:05:44.052211Z","end":"2026-05-08T19:05:44.470374Z","steps":["trace[1883796494] 'process raft request' (duration: 366.822936ms)","trace[1883796494] 'compare' (duration: 51.086618ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:44.471012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:44.052181Z","time spent":"418.692686ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:44.471058Z","caller":"traceutil/trace.go:171","msg":"trace[1715233695] transaction","detail":"{read_only:false; response_revision:25321; number_of_response:1; }","duration":"414.594185ms","start":"2026-05-08T19:05:44.056428Z","end":"2026-05-08T19:05:44.471022Z","steps":["trace[1715233695] 'process raft request' (duration: 414.152373ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:44.471031Z","caller":"traceutil/trace.go:171","msg":"trace[855683203] transaction","detail":"{read_only:false; response_revision:25320; number_of_response:1; }","duration":"416.109685ms","start":"2026-05-08T19:05:44.054901Z","end":"2026-05-08T19:05:44.47101Z","steps":["trace[855683203] 'process raft request' (duration: 415.429057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:44.471268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:44.0564Z","time spent":"414.761259ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:44.471398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:44.054892Z","time spent":"416.384152ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:44.471798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.306153ms","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-05-08T19:05:44.471846Z","caller":"traceutil/trace.go:171","msg":"trace[173165569] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25321; }","duration":"387.386625ms","start":"2026-05-08T19:05:44.084446Z","end":"2026-05-08T19:05:44.471832Z","steps":["trace[173165569] 'agreement among raft nodes before linearized reading' (duration: 387.257592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:44.471876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:44.084426Z","time spent":"387.443666ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T19:05:44.470743Z","caller":"traceutil/trace.go:171","msg":"trace[2076593705] linearizableReadLoop","detail":"{readStateIndex:26148; appliedIndex:26145; }","duration":"386.184902ms","start":"2026-05-08T19:05:44.08449Z","end":"2026-05-08T19:05:44.470675Z","steps":["trace[2076593705] 'read index received' (duration: 334.565689ms)","trace[2076593705] 'applied index is now lower than readState.Index' (duration: 51.618083ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:44.851563Z","caller":"traceutil/trace.go:171","msg":"trace[1913747203] transaction","detail":"{read_only:false; response_revision:25324; number_of_response:1; }","duration":"372.17311ms","start":"2026-05-08T19:05:44.479361Z","end":"2026-05-08T19:05:44.851534Z","steps":["trace[1913747203] 'process raft request' (duration: 372.109438ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:44.851774Z","caller":"traceutil/trace.go:171","msg":"trace[867380264] transaction","detail":"{read_only:false; response_revision:25323; number_of_response:1; }","duration":"373.27018ms","start":"2026-05-08T19:05:44.478474Z","end":"2026-05-08T19:05:44.851745Z","steps":["trace[867380264] 'process raft request' (duration: 344.851127ms)","trace[867380264] 'compare' (duration: 27.990881ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:44.851922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:44.47844Z","time spent":"373.393353ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:44.852124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:44.479339Z","time spent":"372.675914ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:45.158172Z","caller":"traceutil/trace.go:171","msg":"trace[1839588122] linearizableReadLoop","detail":"{readStateIndex:26152; appliedIndex:26149; }","duration":"520.212506ms","start":"2026-05-08T19:05:44.63793Z","end":"2026-05-08T19:05:45.158142Z","steps":["trace[1839588122] 'read index received' (duration: 185.33902ms)","trace[1839588122] 'applied index is now lower than readState.Index' (duration: 334.872176ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:45.158264Z","caller":"traceutil/trace.go:171","msg":"trace[1036887840] transaction","detail":"{read_only:false; response_revision:25325; number_of_response:1; }","duration":"547.91157ms","start":"2026-05-08T19:05:44.610317Z","end":"2026-05-08T19:05:45.158228Z","steps":["trace[1036887840] 'process raft request' (duration: 547.632362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:45.158425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.478963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-08T19:05:45.158442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:44.610302Z","time spent":"548.029043ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:45.158471Z","caller":"traceutil/trace.go:171","msg":"trace[749400780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25325; }","duration":"520.563456ms","start":"2026-05-08T19:05:44.637894Z","end":"2026-05-08T19:05:45.158457Z","steps":["trace[749400780] 'agreement among raft nodes before linearized reading' (duration: 520.380851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:45.158507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:44.637884Z","time spent":"520.615217ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-08T19:05:45.463148Z","caller":"traceutil/trace.go:171","msg":"trace[1977551040] linearizableReadLoop","detail":"{readStateIndex:26153; appliedIndex:26152; }","duration":"304.873231ms","start":"2026-05-08T19:05:45.158247Z","end":"2026-05-08T19:05:45.46312Z","steps":["trace[1977551040] 'read index received' (duration: 304.613923ms)","trace[1977551040] 'applied index is now lower than readState.Index' (duration: 258.138µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:45.463314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.727874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:45.46336Z","caller":"traceutil/trace.go:171","msg":"trace[1085872869] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:25326; }","duration":"811.857727ms","start":"2026-05-08T19:05:44.651489Z","end":"2026-05-08T19:05:45.463347Z","steps":["trace[1085872869] 'agreement among raft nodes before linearized reading' (duration: 811.743094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:45.463415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:44.651465Z","time spent":"811.93244ms","remote":"127.0.0.1:57538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-08T19:05:45.46329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.612405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-08T19:05:45.463627Z","caller":"traceutil/trace.go:171","msg":"trace[1958144127] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:25326; }","duration":"336.931173ms","start":"2026-05-08T19:05:45.126604Z","end":"2026-05-08T19:05:45.463535Z","steps":["trace[1958144127] 'agreement among raft nodes before linearized reading' (duration: 336.578174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:45.463691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.346717ms","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-05-08T19:05:45.463819Z","caller":"traceutil/trace.go:171","msg":"trace[1798886830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25326; }","duration":"671.535061ms","start":"2026-05-08T19:05:44.79226Z","end":"2026-05-08T19:05:45.463795Z","steps":["trace[1798886830] 'agreement among raft nodes before linearized reading' (duration: 671.077369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:45.46387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:44.792236Z","time spent":"671.622974ms","remote":"127.0.0.1:34806","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-05-08T19:05:45.463917Z","caller":"traceutil/trace.go:171","msg":"trace[484704766] transaction","detail":"{read_only:false; response_revision:25326; number_of_response:1; }","duration":"429.370616ms","start":"2026-05-08T19:05:45.034539Z","end":"2026-05-08T19:05:45.46391Z","steps":["trace[484704766] 'process raft request' (duration: 428.354918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:45.463986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:45.034517Z","time spent":"429.418537ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:45.463766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.78935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:45.464049Z","caller":"traceutil/trace.go:171","msg":"trace[574490474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25326; }","duration":"746.153991ms","start":"2026-05-08T19:05:44.71788Z","end":"2026-05-08T19:05:45.464034Z","steps":["trace[574490474] 'agreement among raft nodes before linearized reading' (duration: 745.871133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:45.463754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:45.126587Z","time spent":"337.088907ms","remote":"127.0.0.1:34790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-05-08T19:05:45.464158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:44.717849Z","time spent":"746.234254ms","remote":"127.0.0.1:34534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T19:05:46.016069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.025204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:05:46.016265Z","caller":"traceutil/trace.go:171","msg":"trace[982410625] transaction","detail":"{read_only:false; response_revision:25330; number_of_response:1; }","duration":"519.325392ms","start":"2026-05-08T19:05:45.496928Z","end":"2026-05-08T19:05:46.016254Z","steps":["trace[982410625] 'process raft request' (duration: 519.302682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:46.016313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:45.4969Z","time spent":"519.392533ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:46.016312Z","caller":"traceutil/trace.go:171","msg":"trace[1882644228] transaction","detail":"{read_only:false; response_revision:25328; number_of_response:1; }","duration":"605.27855ms","start":"2026-05-08T19:05:45.411005Z","end":"2026-05-08T19:05:46.016283Z","steps":["trace[1882644228] 'process raft request' (duration: 605.149067ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:46.016339Z","caller":"traceutil/trace.go:171","msg":"trace[1431685075] linearizableReadLoop","detail":"{readStateIndex:26155; appliedIndex:26153; }","duration":"551.245411ms","start":"2026-05-08T19:05:45.46503Z","end":"2026-05-08T19:05:46.016276Z","steps":["trace[1431685075] 'read index received' (duration: 121.080863ms)","trace[1431685075] 'applied index is now lower than readState.Index' (duration: 430.159328ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:46.016436Z","caller":"traceutil/trace.go:171","msg":"trace[2071028352] transaction","detail":"{read_only:false; response_revision:25329; number_of_response:1; }","duration":"548.372852ms","start":"2026-05-08T19:05:45.468056Z","end":"2026-05-08T19:05:46.016429Z","steps":["trace[2071028352] 'process raft request' (duration: 548.152786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:46.016473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:45.468035Z","time spent":"548.421784ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:46.016469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:45.410969Z","time spent":"605.415084ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:46.016532Z","caller":"traceutil/trace.go:171","msg":"trace[1643147413] transaction","detail":"{read_only:false; response_revision:25327; number_of_response:1; }","duration":"854.218919ms","start":"2026-05-08T19:05:45.162298Z","end":"2026-05-08T19:05:46.016517Z","steps":["trace[1643147413] 'process raft request' (duration: 423.680121ms)","trace[1643147413] 'compare' (duration: 429.300245ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:46.016592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:45.162277Z","time spent":"854.286962ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:05:46.016605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.554709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:46.016645Z","caller":"traceutil/trace.go:171","msg":"trace[530207236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25330; }","duration":"551.640751ms","start":"2026-05-08T19:05:45.46499Z","end":"2026-05-08T19:05:46.016631Z","steps":["trace[530207236] 'agreement among raft nodes before linearized reading' (duration: 551.472357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:46.016685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:45.464974Z","time spent":"551.703873ms","remote":"127.0.0.1:34538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T19:05:46.130484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.148251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:46.130558Z","caller":"traceutil/trace.go:171","msg":"trace[1419517720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25331; }","duration":"304.278984ms","start":"2026-05-08T19:05:45.826266Z","end":"2026-05-08T19:05:46.130545Z","steps":["trace[1419517720] 'agreement among raft nodes before linearized reading' (duration: 304.139601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:46.130591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:45.826246Z","time spent":"304.340696ms","remote":"127.0.0.1:34534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T19:05:46.130575Z","caller":"traceutil/trace.go:171","msg":"trace[239348683] transaction","detail":"{read_only:false; response_revision:25331; number_of_response:1; }","duration":"331.334759ms","start":"2026-05-08T19:05:45.799202Z","end":"2026-05-08T19:05:46.130536Z","steps":["trace[239348683] 'process raft request' (duration: 321.922413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:46.13077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:45.79917Z","time spent":"331.478224ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:05:46.130386Z","caller":"traceutil/trace.go:171","msg":"trace[59515322] linearizableReadLoop","detail":"{readStateIndex:26158; appliedIndex:26157; }","duration":"113.931928ms","start":"2026-05-08T19:05:46.016426Z","end":"2026-05-08T19:05:46.130358Z","steps":["trace[59515322] 'read index received' (duration: 104.590474ms)","trace[59515322] 'applied index is now lower than readState.Index' (duration: 9.339304ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:46.130824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.61878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-08T19:05:46.130825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.016801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-08T19:05:46.130902Z","caller":"traceutil/trace.go:171","msg":"trace[1786484349] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25331; }","duration":"331.152905ms","start":"2026-05-08T19:05:45.799734Z","end":"2026-05-08T19:05:46.130886Z","steps":["trace[1786484349] 'agreement among raft nodes before linearized reading' (duration: 330.868267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:46.130955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:45.799721Z","time spent":"331.225567ms","remote":"127.0.0.1:34626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":379,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"info","ts":"2026-05-08T19:05:46.130988Z","caller":"traceutil/trace.go:171","msg":"trace[1245413232] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25331; }","duration":"544.736413ms","start":"2026-05-08T19:05:45.586143Z","end":"2026-05-08T19:05:46.130879Z","steps":["trace[1245413232] 'agreement among raft nodes before linearized reading' (duration: 544.62585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:46.131018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:05:45.586121Z","time spent":"544.888208ms","remote":"127.0.0.1:47190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-05-08T19:05:47.576019Z","caller":"traceutil/trace.go:171","msg":"trace[1540324710] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"104.909003ms","start":"2026-05-08T19:05:47.471083Z","end":"2026-05-08T19:05:47.575992Z","steps":["trace[1540324710] 'process raft request' (duration: 104.753049ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:53.880551Z","caller":"traceutil/trace.go:171","msg":"trace[1368094705] linearizableReadLoop","detail":"{readStateIndex:26302; appliedIndex:26301; }","duration":"269.988991ms","start":"2026-05-08T19:05:53.610534Z","end":"2026-05-08T19:05:53.880523Z","steps":["trace[1368094705] 'read index received' (duration: 269.862598ms)","trace[1368094705] 'applied index is now lower than readState.Index' (duration: 125.423µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:53.880598Z","caller":"traceutil/trace.go:171","msg":"trace[1538389811] transaction","detail":"{read_only:false; response_revision:25471; number_of_response:1; }","duration":"284.283451ms","start":"2026-05-08T19:05:53.596292Z","end":"2026-05-08T19:05:53.880576Z","steps":["trace[1538389811] 'process raft request' (duration: 284.019684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:53.880757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.169317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:53.880804Z","caller":"traceutil/trace.go:171","msg":"trace[1086246862] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:25471; }","duration":"270.30372ms","start":"2026-05-08T19:05:53.610487Z","end":"2026-05-08T19:05:53.880791Z","steps":["trace[1086246862] 'agreement among raft nodes before linearized reading' (duration: 270.178047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:53.936236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.655317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:53.936321Z","caller":"traceutil/trace.go:171","msg":"trace[345463110] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:25472; }","duration":"246.799681ms","start":"2026-05-08T19:05:53.689498Z","end":"2026-05-08T19:05:53.936298Z","steps":["trace[345463110] 'agreement among raft nodes before linearized reading' (duration: 246.645347ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:53.936692Z","caller":"traceutil/trace.go:171","msg":"trace[159426933] transaction","detail":"{read_only:false; response_revision:25472; number_of_response:1; }","duration":"143.621905ms","start":"2026-05-08T19:05:53.793057Z","end":"2026-05-08T19:05:53.936679Z","steps":["trace[159426933] 'process raft request' (duration: 142.885915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:53.936911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.381267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:53.936943Z","caller":"traceutil/trace.go:171","msg":"trace[1799184971] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:25472; }","duration":"140.49291ms","start":"2026-05-08T19:05:53.796441Z","end":"2026-05-08T19:05:53.936934Z","steps":["trace[1799184971] 'agreement among raft nodes before linearized reading' (duration: 140.390977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:53.93711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.429559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:05:53.937151Z","caller":"traceutil/trace.go:171","msg":"trace[138407165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25472; }","duration":"218.512342ms","start":"2026-05-08T19:05:53.718631Z","end":"2026-05-08T19:05:53.937143Z","steps":["trace[138407165] 'agreement among raft nodes before linearized reading' (duration: 218.452121ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:54.247041Z","caller":"traceutil/trace.go:171","msg":"trace[976520303] linearizableReadLoop","detail":"{readStateIndex:26304; appliedIndex:26303; }","duration":"187.857818ms","start":"2026-05-08T19:05:54.059153Z","end":"2026-05-08T19:05:54.247011Z","steps":["trace[976520303] 'read index received' (duration: 144.476899ms)","trace[976520303] 'applied index is now lower than readState.Index' (duration: 43.379799ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:05:54.247339Z","caller":"traceutil/trace.go:171","msg":"trace[370864038] transaction","detail":"{read_only:false; response_revision:25473; number_of_response:1; }","duration":"204.07376ms","start":"2026-05-08T19:05:54.043251Z","end":"2026-05-08T19:05:54.247325Z","steps":["trace[370864038] 'process raft request' (duration: 160.407913ms)","trace[370864038] 'compare' (duration: 42.899996ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:05:54.247507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.329711ms","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-05-08T19:05:54.247574Z","caller":"traceutil/trace.go:171","msg":"trace[201528896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25473; }","duration":"188.449754ms","start":"2026-05-08T19:05:54.05911Z","end":"2026-05-08T19:05:54.24756Z","steps":["trace[201528896] 'agreement among raft nodes before linearized reading' (duration: 188.214468ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:54.30248Z","caller":"traceutil/trace.go:171","msg":"trace[1562248187] transaction","detail":"{read_only:false; response_revision:25474; number_of_response:1; }","duration":"142.68475ms","start":"2026-05-08T19:05:54.159759Z","end":"2026-05-08T19:05:54.302444Z","steps":["trace[1562248187] 'process raft request' (duration: 142.501875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:05:54.302547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.352218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-08T19:05:54.302726Z","caller":"traceutil/trace.go:171","msg":"trace[745957976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25474; }","duration":"243.535393ms","start":"2026-05-08T19:05:54.059168Z","end":"2026-05-08T19:05:54.302703Z","steps":["trace[745957976] 'agreement among raft nodes before linearized reading' (duration: 243.236835ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:05:54.408222Z","caller":"traceutil/trace.go:171","msg":"trace[884675283] transaction","detail":"{read_only:false; response_revision:25477; number_of_response:1; }","duration":"100.155284ms","start":"2026-05-08T19:05:54.308045Z","end":"2026-05-08T19:05:54.408201Z","steps":["trace[884675283] 'process raft request' (duration: 100.033621ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:10.567457Z","caller":"traceutil/trace.go:171","msg":"trace[389789900] transaction","detail":"{read_only:false; response_revision:25598; number_of_response:1; }","duration":"263.872026ms","start":"2026-05-08T19:06:10.303554Z","end":"2026-05-08T19:06:10.567426Z","steps":["trace[389789900] 'process raft request' (duration: 263.67482ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:10.567789Z","caller":"traceutil/trace.go:171","msg":"trace[1074544600] linearizableReadLoop","detail":"{readStateIndex:26432; appliedIndex:26432; }","duration":"231.65422ms","start":"2026-05-08T19:06:10.336123Z","end":"2026-05-08T19:06:10.567777Z","steps":["trace[1074544600] 'read index received' (duration: 231.64179ms)","trace[1074544600] 'applied index is now lower than readState.Index' (duration: 11.58µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:06:10.568077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.925287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-08T19:06:10.568157Z","caller":"traceutil/trace.go:171","msg":"trace[1511741116] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:25598; }","duration":"232.067321ms","start":"2026-05-08T19:06:10.336079Z","end":"2026-05-08T19:06:10.568146Z","steps":["trace[1511741116] 'agreement among raft nodes before linearized reading' (duration: 231.758993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:10.805558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.158727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:06:10.805783Z","caller":"traceutil/trace.go:171","msg":"trace[2118341456] linearizableReadLoop","detail":"{readStateIndex:26434; appliedIndex:26432; }","duration":"237.950331ms","start":"2026-05-08T19:06:10.567815Z","end":"2026-05-08T19:06:10.805765Z","steps":["trace[2118341456] 'read index received' (duration: 99.420754ms)","trace[2118341456] 'applied index is now lower than readState.Index' (duration: 138.528667ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:06:10.805908Z","caller":"traceutil/trace.go:171","msg":"trace[438028190] transaction","detail":"{read_only:false; response_revision:25599; number_of_response:1; }","duration":"449.814892ms","start":"2026-05-08T19:06:10.356081Z","end":"2026-05-08T19:06:10.805896Z","steps":["trace[438028190] 'process raft request' (duration: 311.223644ms)","trace[438028190] 'compare' (duration: 137.52029ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:06:10.805995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:10.356061Z","time spent":"449.880075ms","remote":"127.0.0.1:34806","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-05-08T19:06:10.806047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.814633ms","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-05-08T19:06:10.806097Z","caller":"traceutil/trace.go:171","msg":"trace[71026029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25600; }","duration":"331.864904ms","start":"2026-05-08T19:06:10.474218Z","end":"2026-05-08T19:06:10.806083Z","steps":["trace[71026029] 'agreement among raft nodes before linearized reading' (duration: 331.719131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:10.806129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:10.474164Z","time spent":"331.958807ms","remote":"127.0.0.1:34806","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-05-08T19:06:10.806244Z","caller":"traceutil/trace.go:171","msg":"trace[746833937] transaction","detail":"{read_only:false; response_revision:25600; number_of_response:1; }","duration":"404.966782ms","start":"2026-05-08T19:06:10.401226Z","end":"2026-05-08T19:06:10.806192Z","steps":["trace[746833937] 'process raft request' (duration: 404.461549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:10.806315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.407419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-08T19:06:10.806382Z","caller":"traceutil/trace.go:171","msg":"trace[1262963232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25600; }","duration":"396.541753ms","start":"2026-05-08T19:06:10.40982Z","end":"2026-05-08T19:06:10.806362Z","steps":["trace[1262963232] 'agreement among raft nodes before linearized reading' (duration: 396.349648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:10.806422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:10.409776Z","time spent":"396.635355ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T19:06:10.806424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:10.4012Z","time spent":"405.124926ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:06:11.082367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.328746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:06:11.082592Z","caller":"traceutil/trace.go:171","msg":"trace[1982630837] transaction","detail":"{read_only:false; response_revision:25601; number_of_response:1; }","duration":"508.325224ms","start":"2026-05-08T19:06:10.574227Z","end":"2026-05-08T19:06:11.082552Z","steps":["trace[1982630837] 'process raft request' (duration: 342.743051ms)","trace[1982630837] 'compare' (duration: 165.230803ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:06:11.082702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:10.574201Z","time spent":"508.441147ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:06:11.082735Z","caller":"traceutil/trace.go:171","msg":"trace[1549031154] transaction","detail":"{read_only:false; response_revision:25602; number_of_response:1; }","duration":"506.893214ms","start":"2026-05-08T19:06:10.575826Z","end":"2026-05-08T19:06:11.082719Z","steps":["trace[1549031154] 'process raft request' (duration: 506.626537ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:11.082745Z","caller":"traceutil/trace.go:171","msg":"trace[757302047] transaction","detail":"{read_only:false; response_revision:25604; number_of_response:1; }","duration":"271.495643ms","start":"2026-05-08T19:06:10.811238Z","end":"2026-05-08T19:06:11.082733Z","steps":["trace[757302047] 'process raft request' (duration: 271.282817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:11.082785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:10.575814Z","time spent":"506.944065ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:06:11.082761Z","caller":"traceutil/trace.go:171","msg":"trace[682134381] linearizableReadLoop","detail":"{readStateIndex:26437; appliedIndex:26434; }","duration":"276.861749ms","start":"2026-05-08T19:06:10.805858Z","end":"2026-05-08T19:06:11.082719Z","steps":["trace[682134381] 'read index received' (duration: 111.128872ms)","trace[682134381] 'applied index is now lower than readState.Index' (duration: 165.727517ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:06:11.082786Z","caller":"traceutil/trace.go:171","msg":"trace[1219940415] transaction","detail":"{read_only:false; response_revision:25605; number_of_response:1; }","duration":"271.412711ms","start":"2026-05-08T19:06:10.811335Z","end":"2026-05-08T19:06:11.082748Z","steps":["trace[1219940415] 'process raft request' (duration: 271.207265ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:11.082842Z","caller":"traceutil/trace.go:171","msg":"trace[1118299014] transaction","detail":"{read_only:false; response_revision:25603; number_of_response:1; }","duration":"457.767538ms","start":"2026-05-08T19:06:10.625068Z","end":"2026-05-08T19:06:11.082836Z","steps":["trace[1118299014] 'process raft request' (duration: 457.421279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:11.082838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.512726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-08T19:06:11.082874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:10.625043Z","time spent":"457.81645ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:06:11.082887Z","caller":"traceutil/trace.go:171","msg":"trace[769998550] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:25606; }","duration":"306.597998ms","start":"2026-05-08T19:06:10.776274Z","end":"2026-05-08T19:06:11.082872Z","steps":["trace[769998550] 'agreement among raft nodes before linearized reading' (duration: 306.475095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:11.082877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.330993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-08T19:06:11.082927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:10.776255Z","time spent":"306.660559ms","remote":"127.0.0.1:58576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true "} {"level":"info","ts":"2026-05-08T19:06:11.082938Z","caller":"traceutil/trace.go:171","msg":"trace[775904340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25606; }","duration":"362.442626ms","start":"2026-05-08T19:06:10.720477Z","end":"2026-05-08T19:06:11.082919Z","steps":["trace[775904340] 'agreement among raft nodes before linearized reading' (duration: 362.283651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:11.08296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.480548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-08T19:06:11.082986Z","caller":"traceutil/trace.go:171","msg":"trace[187331668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25606; }","duration":"419.554949ms","start":"2026-05-08T19:06:10.663424Z","end":"2026-05-08T19:06:11.082979Z","steps":["trace[187331668] 'agreement among raft nodes before linearized reading' (duration: 419.452996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:11.082976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:10.720452Z","time spent":"362.515628ms","remote":"127.0.0.1:34534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T19:06:11.083003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:10.663387Z","time spent":"419.612861ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T19:06:11.248393Z","caller":"traceutil/trace.go:171","msg":"trace[1077734781] transaction","detail":"{read_only:false; response_revision:25607; number_of_response:1; }","duration":"160.732371ms","start":"2026-05-08T19:06:11.087639Z","end":"2026-05-08T19:06:11.248372Z","steps":["trace[1077734781] 'process raft request' (duration: 160.576017ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:11.521402Z","caller":"traceutil/trace.go:171","msg":"trace[946114653] transaction","detail":"{read_only:false; response_revision:25608; number_of_response:1; }","duration":"432.446989ms","start":"2026-05-08T19:06:11.088921Z","end":"2026-05-08T19:06:11.521368Z","steps":["trace[946114653] 'process raft request' (duration: 432.187192ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:11.52159Z","caller":"traceutil/trace.go:171","msg":"trace[1930490920] linearizableReadLoop","detail":"{readStateIndex:26443; appliedIndex:26440; }","duration":"327.195327ms","start":"2026-05-08T19:06:11.194368Z","end":"2026-05-08T19:06:11.521563Z","steps":["trace[1930490920] 'read index received' (duration: 53.830003ms)","trace[1930490920] 'applied index is now lower than readState.Index' (duration: 273.363124ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:06:11.521717Z","caller":"traceutil/trace.go:171","msg":"trace[1302756904] transaction","detail":"{read_only:false; response_revision:25609; number_of_response:1; }","duration":"430.768814ms","start":"2026-05-08T19:06:11.090912Z","end":"2026-05-08T19:06:11.521681Z","steps":["trace[1302756904] 'process raft request' (duration: 430.404954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:11.521733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:11.088898Z","time spent":"432.680457ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:06:11.521858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.473405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-05-08T19:06:11.521932Z","caller":"traceutil/trace.go:171","msg":"trace[1447655037] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25609; }","duration":"327.565987ms","start":"2026-05-08T19:06:11.19433Z","end":"2026-05-08T19:06:11.521896Z","steps":["trace[1447655037] 'agreement among raft nodes before linearized reading' (duration: 327.355011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:11.521941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:11.090905Z","time spent":"430.887627ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:06:11.521973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:11.194321Z","time spent":"327.64226ms","remote":"127.0.0.1:47190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3331,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-08T19:06:11.564031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.83657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-08T19:06:11.56413Z","caller":"traceutil/trace.go:171","msg":"trace[578950929] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:25610; }","duration":"336.980264ms","start":"2026-05-08T19:06:11.227125Z","end":"2026-05-08T19:06:11.564106Z","steps":["trace[578950929] 'agreement among raft nodes before linearized reading' (duration: 336.717947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:11.564174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:11.227105Z","time spent":"337.060037ms","remote":"127.0.0.1:34568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"info","ts":"2026-05-08T19:06:11.564529Z","caller":"traceutil/trace.go:171","msg":"trace[1072371371] transaction","detail":"{read_only:false; response_revision:25610; number_of_response:1; }","duration":"296.179305ms","start":"2026-05-08T19:06:11.268337Z","end":"2026-05-08T19:06:11.564517Z","steps":["trace[1072371371] 'process raft request' (duration: 295.344933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:11.564715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.392667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:06:11.564753Z","caller":"traceutil/trace.go:171","msg":"trace[1363329296] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25610; }","duration":"176.49563ms","start":"2026-05-08T19:06:11.388246Z","end":"2026-05-08T19:06:11.564742Z","steps":["trace[1363329296] 'agreement among raft nodes before linearized reading' (duration: 176.429238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:11.792396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.363864ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-08T19:06:11.792494Z","caller":"traceutil/trace.go:171","msg":"trace[944418654] linearizableReadLoop","detail":"{readStateIndex:26445; appliedIndex:26444; }","duration":"109.825066ms","start":"2026-05-08T19:06:11.682653Z","end":"2026-05-08T19:06:11.792478Z","steps":["trace[944418654] 'read index received' (duration: 290.367µs)","trace[944418654] 'applied index is now lower than readState.Index' (duration: 109.533169ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:06:11.792691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.031072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-08T19:06:11.792709Z","caller":"traceutil/trace.go:171","msg":"trace[958518159] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25610; }","duration":"110.101974ms","start":"2026-05-08T19:06:11.6826Z","end":"2026-05-08T19:06:11.792702Z","steps":["trace[958518159] 'agreement among raft nodes before linearized reading' (duration: 109.96064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:11.793397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.596265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10850"} {"level":"info","ts":"2026-05-08T19:06:11.793424Z","caller":"traceutil/trace.go:171","msg":"trace[1622930113] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25610; }","duration":"104.666847ms","start":"2026-05-08T19:06:11.68875Z","end":"2026-05-08T19:06:11.793417Z","steps":["trace[1622930113] 'agreement among raft nodes before linearized reading' (duration: 104.120061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:12.045071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.546058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:06:12.045182Z","caller":"traceutil/trace.go:171","msg":"trace[1114111905] linearizableReadLoop","detail":"{readStateIndex:26446; appliedIndex:26445; }","duration":"248.201899ms","start":"2026-05-08T19:06:11.796961Z","end":"2026-05-08T19:06:12.045163Z","steps":["trace[1114111905] 'read index received' (duration: 120.450756ms)","trace[1114111905] 'applied index is now lower than readState.Index' (duration: 127.749713ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:06:12.045297Z","caller":"traceutil/trace.go:171","msg":"trace[1884833568] transaction","detail":"{read_only:false; response_revision:25611; number_of_response:1; }","duration":"250.629135ms","start":"2026-05-08T19:06:11.794629Z","end":"2026-05-08T19:06:12.045258Z","steps":["trace[1884833568] 'process raft request' (duration: 122.7999ms)","trace[1884833568] 'compare' (duration: 127.388794ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:06:12.045414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.446296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-08T19:06:12.045458Z","caller":"traceutil/trace.go:171","msg":"trace[242226297] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25611; }","duration":"248.493778ms","start":"2026-05-08T19:06:11.796953Z","end":"2026-05-08T19:06:12.045447Z","steps":["trace[242226297] 'agreement among raft nodes before linearized reading' (duration: 248.291371ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:12.107333Z","caller":"traceutil/trace.go:171","msg":"trace[1937025285] transaction","detail":"{read_only:false; response_revision:25612; number_of_response:1; }","duration":"292.31139ms","start":"2026-05-08T19:06:11.814993Z","end":"2026-05-08T19:06:12.107304Z","steps":["trace[1937025285] 'process raft request' (duration: 292.125235ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:12.208119Z","caller":"traceutil/trace.go:171","msg":"trace[1386360250] linearizableReadLoop","detail":"{readStateIndex:26448; appliedIndex:26446; }","duration":"162.88307ms","start":"2026-05-08T19:06:12.045211Z","end":"2026-05-08T19:06:12.208094Z","steps":["trace[1386360250] 'read index received' (duration: 61.887674ms)","trace[1386360250] 'applied index is now lower than readState.Index' (duration: 100.994486ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:06:12.208278Z","caller":"traceutil/trace.go:171","msg":"trace[2016072175] transaction","detail":"{read_only:false; response_revision:25613; number_of_response:1; }","duration":"254.822699ms","start":"2026-05-08T19:06:11.953415Z","end":"2026-05-08T19:06:12.208237Z","steps":["trace[2016072175] 'process raft request' (duration: 254.515941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:12.208415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.024496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:06:12.208599Z","caller":"traceutil/trace.go:171","msg":"trace[396505478] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:25613; }","duration":"233.214922ms","start":"2026-05-08T19:06:11.975315Z","end":"2026-05-08T19:06:12.20853Z","steps":["trace[396505478] 'agreement among raft nodes before linearized reading' (duration: 232.889653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:12.20867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.572813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:06:12.208725Z","caller":"traceutil/trace.go:171","msg":"trace[1611789269] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25613; }","duration":"247.689946ms","start":"2026-05-08T19:06:11.96102Z","end":"2026-05-08T19:06:12.20871Z","steps":["trace[1611789269] 'agreement among raft nodes before linearized reading' (duration: 247.200913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:12.208863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.385377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0ef6d0bf-38ce-4bd5-ae6c-518eb0feb392\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-05-08T19:06:12.209456Z","caller":"traceutil/trace.go:171","msg":"trace[531535772] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0ef6d0bf-38ce-4bd5-ae6c-518eb0feb392; range_end:; response_count:1; response_revision:25613; }","duration":"412.015194ms","start":"2026-05-08T19:06:11.797417Z","end":"2026-05-08T19:06:12.209432Z","steps":["trace[531535772] 'agreement among raft nodes before linearized reading' (duration: 410.830072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:12.209587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:11.797401Z","time spent":"412.164628ms","remote":"127.0.0.1:34664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1344,"request content":"key:\"/registry/persistentvolumes/pvc-0ef6d0bf-38ce-4bd5-ae6c-518eb0feb392\" "} {"level":"info","ts":"2026-05-08T19:06:12.394497Z","caller":"traceutil/trace.go:171","msg":"trace[1016361378] transaction","detail":"{read_only:false; response_revision:25614; number_of_response:1; }","duration":"279.84486ms","start":"2026-05-08T19:06:12.114624Z","end":"2026-05-08T19:06:12.394469Z","steps":["trace[1016361378] 'process raft request' (duration: 262.298863ms)","trace[1016361378] 'compare' (duration: 17.252169ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:06:12.394976Z","caller":"traceutil/trace.go:171","msg":"trace[433318518] transaction","detail":"{read_only:false; response_revision:25616; number_of_response:1; }","duration":"278.864523ms","start":"2026-05-08T19:06:12.1161Z","end":"2026-05-08T19:06:12.394964Z","steps":["trace[433318518] 'process raft request' (duration: 278.547545ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:12.395021Z","caller":"traceutil/trace.go:171","msg":"trace[1503247559] linearizableReadLoop","detail":"{readStateIndex:26452; appliedIndex:26448; }","duration":"186.804539ms","start":"2026-05-08T19:06:12.20819Z","end":"2026-05-08T19:06:12.394995Z","steps":["trace[1503247559] 'read index received' (duration: 168.764899ms)","trace[1503247559] 'applied index is now lower than readState.Index' (duration: 18.03766ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:06:12.395102Z","caller":"traceutil/trace.go:171","msg":"trace[530871176] transaction","detail":"{read_only:false; response_revision:25617; number_of_response:1; }","duration":"273.917008ms","start":"2026-05-08T19:06:12.121174Z","end":"2026-05-08T19:06:12.395091Z","steps":["trace[530871176] 'process raft request' (duration: 273.64711ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:12.395023Z","caller":"traceutil/trace.go:171","msg":"trace[1928292514] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"280.283042ms","start":"2026-05-08T19:06:12.114708Z","end":"2026-05-08T19:06:12.394991Z","steps":["trace[1928292514] 'process raft request' (duration: 279.688636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:12.395279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.042554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-05-08T19:06:12.39528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.043564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18416"} {"level":"info","ts":"2026-05-08T19:06:12.395317Z","caller":"traceutil/trace.go:171","msg":"trace[1870119629] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25617; }","duration":"348.123066ms","start":"2026-05-08T19:06:12.047183Z","end":"2026-05-08T19:06:12.395306Z","steps":["trace[1870119629] 'agreement among raft nodes before linearized reading' (duration: 347.927701ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:12.395332Z","caller":"traceutil/trace.go:171","msg":"trace[1521398567] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25617; }","duration":"348.132357ms","start":"2026-05-08T19:06:12.047185Z","end":"2026-05-08T19:06:12.395317Z","steps":["trace[1521398567] 'agreement among raft nodes before linearized reading' (duration: 347.971582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:12.395348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:12.047166Z","time spent":"348.174487ms","remote":"127.0.0.1:34696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-05-08T19:06:12.39537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:12.047171Z","time spent":"348.189598ms","remote":"127.0.0.1:34704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18438,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-08T19:06:12.710881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.448369ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:06:12.711192Z","caller":"traceutil/trace.go:171","msg":"trace[1635899938] transaction","detail":"{read_only:false; response_revision:25619; number_of_response:1; }","duration":"138.190527ms","start":"2026-05-08T19:06:12.572977Z","end":"2026-05-08T19:06:12.711167Z","steps":["trace[1635899938] 'process raft request' (duration: 138.047603ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:12.711434Z","caller":"traceutil/trace.go:171","msg":"trace[46999659] linearizableReadLoop","detail":"{readStateIndex:26453; appliedIndex:26452; }","duration":"239.524983ms","start":"2026-05-08T19:06:12.471887Z","end":"2026-05-08T19:06:12.711412Z","steps":["trace[46999659] 'read index received' (duration: 137.432267ms)","trace[46999659] 'applied index is now lower than readState.Index' (duration: 102.090946ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:06:12.711566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.635675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:06:12.711596Z","caller":"traceutil/trace.go:171","msg":"trace[493586610] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25619; }","duration":"239.707547ms","start":"2026-05-08T19:06:12.471881Z","end":"2026-05-08T19:06:12.711588Z","steps":["trace[493586610] 'agreement among raft nodes before linearized reading' (duration: 239.606785ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:12.711728Z","caller":"traceutil/trace.go:171","msg":"trace[410816841] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"295.754642ms","start":"2026-05-08T19:06:12.415956Z","end":"2026-05-08T19:06:12.711711Z","steps":["trace[410816841] 'process raft request' (duration: 193.347208ms)","trace[410816841] 'compare' (duration: 101.14839ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:06:12.900721Z","caller":"traceutil/trace.go:171","msg":"trace[1129366998] linearizableReadLoop","detail":"{readStateIndex:26455; appliedIndex:26454; }","duration":"172.559363ms","start":"2026-05-08T19:06:12.72813Z","end":"2026-05-08T19:06:12.900689Z","steps":["trace[1129366998] 'read index received' (duration: 172.325797ms)","trace[1129366998] 'applied index is now lower than readState.Index' (duration: 231.966µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:06:12.900941Z","caller":"traceutil/trace.go:171","msg":"trace[245367414] transaction","detail":"{read_only:false; response_revision:25620; number_of_response:1; }","duration":"176.353056ms","start":"2026-05-08T19:06:12.724549Z","end":"2026-05-08T19:06:12.900902Z","steps":["trace[245367414] 'process raft request' (duration: 175.937335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:12.901054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.908542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9650"} {"level":"info","ts":"2026-05-08T19:06:12.901121Z","caller":"traceutil/trace.go:171","msg":"trace[1811294956] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25620; }","duration":"173.009714ms","start":"2026-05-08T19:06:12.728094Z","end":"2026-05-08T19:06:12.901104Z","steps":["trace[1811294956] 'agreement among raft nodes before linearized reading' (duration: 172.752207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:12.901716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.042816ms","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-05-08T19:06:12.90177Z","caller":"traceutil/trace.go:171","msg":"trace[493613345] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25622; }","duration":"173.150229ms","start":"2026-05-08T19:06:12.728607Z","end":"2026-05-08T19:06:12.901757Z","steps":["trace[493613345] 'agreement among raft nodes before linearized reading' (duration: 172.953344ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:15.23516Z","caller":"traceutil/trace.go:171","msg":"trace[164498547] transaction","detail":"{read_only:false; response_revision:25641; number_of_response:1; }","duration":"134.214529ms","start":"2026-05-08T19:06:15.100912Z","end":"2026-05-08T19:06:15.235127Z","steps":["trace[164498547] 'process raft request' (duration: 133.821649ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:15.273557Z","caller":"traceutil/trace.go:171","msg":"trace[160634033] transaction","detail":"{read_only:false; response_revision:25642; number_of_response:1; }","duration":"172.581584ms","start":"2026-05-08T19:06:15.100945Z","end":"2026-05-08T19:06:15.273527Z","steps":["trace[160634033] 'process raft request' (duration: 172.366458ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:15.273956Z","caller":"traceutil/trace.go:171","msg":"trace[259360426] transaction","detail":"{read_only:false; response_revision:25643; number_of_response:1; }","duration":"171.332489ms","start":"2026-05-08T19:06:15.10259Z","end":"2026-05-08T19:06:15.273922Z","steps":["trace[259360426] 'process raft request' (duration: 170.899007ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:15.273982Z","caller":"traceutil/trace.go:171","msg":"trace[630202609] transaction","detail":"{read_only:false; response_revision:25644; number_of_response:1; }","duration":"169.056367ms","start":"2026-05-08T19:06:15.104899Z","end":"2026-05-08T19:06:15.273955Z","steps":["trace[630202609] 'process raft request' (duration: 168.870382ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:15.274161Z","caller":"traceutil/trace.go:171","msg":"trace[804694428] transaction","detail":"{read_only:false; response_revision:25645; number_of_response:1; }","duration":"169.075968ms","start":"2026-05-08T19:06:15.105072Z","end":"2026-05-08T19:06:15.274148Z","steps":["trace[804694428] 'process raft request' (duration: 168.838842ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:15.732551Z","caller":"traceutil/trace.go:171","msg":"trace[1985473364] transaction","detail":"{read_only:false; response_revision:25648; number_of_response:1; }","duration":"144.604033ms","start":"2026-05-08T19:06:15.587915Z","end":"2026-05-08T19:06:15.732519Z","steps":["trace[1985473364] 'process raft request' (duration: 123.311344ms)","trace[1985473364] 'compare' (duration: 21.063713ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:06:15.732781Z","caller":"traceutil/trace.go:171","msg":"trace[299217518] transaction","detail":"{read_only:false; response_revision:25649; number_of_response:1; }","duration":"144.185851ms","start":"2026-05-08T19:06:15.588567Z","end":"2026-05-08T19:06:15.732753Z","steps":["trace[299217518] 'process raft request' (duration: 143.899643ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:21.92467Z","caller":"traceutil/trace.go:171","msg":"trace[1463158594] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"105.640763ms","start":"2026-05-08T19:06:21.818998Z","end":"2026-05-08T19:06:21.924639Z","steps":["trace[1463158594] 'process raft request' (duration: 105.417797ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:21.941513Z","caller":"traceutil/trace.go:171","msg":"trace[1569154078] transaction","detail":"{read_only:false; response_revision:25695; number_of_response:1; }","duration":"120.905738ms","start":"2026-05-08T19:06:21.82056Z","end":"2026-05-08T19:06:21.941466Z","steps":["trace[1569154078] 'process raft request' (duration: 120.225129ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:32.102163Z","caller":"traceutil/trace.go:171","msg":"trace[1611935739] linearizableReadLoop","detail":"{readStateIndex:26605; appliedIndex:26604; }","duration":"115.554862ms","start":"2026-05-08T19:06:31.986579Z","end":"2026-05-08T19:06:32.102134Z","steps":["trace[1611935739] 'read index received' (duration: 115.325176ms)","trace[1611935739] 'applied index is now lower than readState.Index' (duration: 228.296µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:06:32.102406Z","caller":"traceutil/trace.go:171","msg":"trace[64527479] transaction","detail":"{read_only:false; response_revision:25766; number_of_response:1; }","duration":"123.374635ms","start":"2026-05-08T19:06:31.978866Z","end":"2026-05-08T19:06:32.10224Z","steps":["trace[64527479] 'process raft request' (duration: 123.044556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:32.10253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.919832ms","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-05-08T19:06:32.102709Z","caller":"traceutil/trace.go:171","msg":"trace[740318889] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25766; }","duration":"116.173939ms","start":"2026-05-08T19:06:31.9865Z","end":"2026-05-08T19:06:32.102674Z","steps":["trace[740318889] 'agreement among raft nodes before linearized reading' (duration: 115.764988ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:32.301889Z","caller":"traceutil/trace.go:171","msg":"trace[1366921406] transaction","detail":"{read_only:false; response_revision:25767; number_of_response:1; }","duration":"193.948464ms","start":"2026-05-08T19:06:32.107902Z","end":"2026-05-08T19:06:32.301851Z","steps":["trace[1366921406] 'process raft request' (duration: 131.528097ms)","trace[1366921406] 'compare' (duration: 62.208151ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:06:38.10939Z","caller":"traceutil/trace.go:171","msg":"trace[1125836966] linearizableReadLoop","detail":"{readStateIndex:26652; appliedIndex:26651; }","duration":"243.442349ms","start":"2026-05-08T19:06:37.865911Z","end":"2026-05-08T19:06:38.109353Z","steps":["trace[1125836966] 'read index received' (duration: 243.239823ms)","trace[1125836966] 'applied index is now lower than readState.Index' (duration: 200.536µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:06:38.109539Z","caller":"traceutil/trace.go:171","msg":"trace[2126363220] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"300.210955ms","start":"2026-05-08T19:06:37.809281Z","end":"2026-05-08T19:06:38.109492Z","steps":["trace[2126363220] 'process raft request' (duration: 299.856234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:38.109639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.691397ms","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-05-08T19:06:38.109724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:37.809253Z","time spent":"300.366469ms","remote":"127.0.0.1:34712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:06:38.109735Z","caller":"traceutil/trace.go:171","msg":"trace[33082919] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25812; }","duration":"243.822061ms","start":"2026-05-08T19:06:37.865894Z","end":"2026-05-08T19:06:38.109716Z","steps":["trace[33082919] 'agreement among raft nodes before linearized reading' (duration: 243.607415ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:38.228151Z","caller":"traceutil/trace.go:171","msg":"trace[1472875070] linearizableReadLoop","detail":"{readStateIndex:26653; appliedIndex:26652; }","duration":"118.623245ms","start":"2026-05-08T19:06:38.109474Z","end":"2026-05-08T19:06:38.228097Z","steps":["trace[1472875070] 'read index received' (duration: 107.468952ms)","trace[1472875070] 'applied index is now lower than readState.Index' (duration: 11.151223ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:06:38.228199Z","caller":"traceutil/trace.go:171","msg":"trace[132171670] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"327.812535ms","start":"2026-05-08T19:06:37.900333Z","end":"2026-05-08T19:06:38.228146Z","steps":["trace[132171670] 'process raft request' (duration: 316.6171ms)","trace[132171670] 'compare' (duration: 10.870876ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:06:38.228364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:37.900311Z","time spent":"327.965558ms","remote":"127.0.0.1:34552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":26,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:06:38.228574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.199271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-05-08T19:06:38.228666Z","caller":"traceutil/trace.go:171","msg":"trace[907828198] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:25813; }","duration":"347.304404ms","start":"2026-05-08T19:06:37.881342Z","end":"2026-05-08T19:06:38.228646Z","steps":["trace[907828198] 'agreement among raft nodes before linearized reading' (duration: 346.865372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:38.228723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:37.881319Z","time spent":"347.395357ms","remote":"127.0.0.1:34738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":920,"request content":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" "} {"level":"info","ts":"2026-05-08T19:06:38.365908Z","caller":"traceutil/trace.go:171","msg":"trace[2077193737] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"250.425089ms","start":"2026-05-08T19:06:38.115456Z","end":"2026-05-08T19:06:38.365881Z","steps":["trace[2077193737] 'process raft request' (duration: 250.136202ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:38.366571Z","caller":"traceutil/trace.go:171","msg":"trace[1878544192] linearizableReadLoop","detail":"{readStateIndex:26655; appliedIndex:26653; }","duration":"138.302351ms","start":"2026-05-08T19:06:38.228252Z","end":"2026-05-08T19:06:38.366554Z","steps":["trace[1878544192] 'read index received' (duration: 137.443208ms)","trace[1878544192] 'applied index is now lower than readState.Index' (duration: 857.933µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:06:38.366674Z","caller":"traceutil/trace.go:171","msg":"trace[1685214672] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"250.146592ms","start":"2026-05-08T19:06:38.116514Z","end":"2026-05-08T19:06:38.366661Z","steps":["trace[1685214672] 'process raft request' (duration: 249.242268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:38.367166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.640451ms","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-05-08T19:06:38.367259Z","caller":"traceutil/trace.go:171","msg":"trace[1440825040] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25815; }","duration":"242.005792ms","start":"2026-05-08T19:06:38.125232Z","end":"2026-05-08T19:06:38.367238Z","steps":["trace[1440825040] 'agreement among raft nodes before linearized reading' (duration: 241.536618ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:38.383013Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22424} {"level":"info","ts":"2026-05-08T19:06:38.383175Z","caller":"traceutil/trace.go:171","msg":"trace[1786485253] compact","detail":"{revision:22424; response_revision:25816; }","duration":"153.516885ms","start":"2026-05-08T19:06:38.229644Z","end":"2026-05-08T19:06:38.38316Z","steps":["trace[1786485253] 'process raft request' (duration: 150.979726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:38.383533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.525024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-qn4g9\" ","response":"range_response_count:1 size:3718"} {"level":"info","ts":"2026-05-08T19:06:38.38366Z","caller":"traceutil/trace.go:171","msg":"trace[734050649] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-679c578f5-qn4g9; range_end:; response_count:1; response_revision:25816; }","duration":"153.667108ms","start":"2026-05-08T19:06:38.229957Z","end":"2026-05-08T19:06:38.383624Z","steps":["trace[734050649] 'agreement among raft nodes before linearized reading' (duration: 153.178405ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:38.524132Z","caller":"traceutil/trace.go:171","msg":"trace[2002912274] linearizableReadLoop","detail":"{readStateIndex:26659; appliedIndex:26658; }","duration":"133.514631ms","start":"2026-05-08T19:06:38.390579Z","end":"2026-05-08T19:06:38.524094Z","steps":["trace[2002912274] 'read index received' (duration: 62.363096ms)","trace[2002912274] 'applied index is now lower than readState.Index' (duration: 71.149645ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:06:38.524379Z","caller":"traceutil/trace.go:171","msg":"trace[1758203581] transaction","detail":"{read_only:false; response_revision:25818; number_of_response:1; }","duration":"134.965842ms","start":"2026-05-08T19:06:38.389226Z","end":"2026-05-08T19:06:38.524192Z","steps":["trace[1758203581] 'process raft request' (duration: 63.822347ms)","trace[1758203581] 'compare' (duration: 70.859656ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:06:38.524616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.989744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-08T19:06:38.524673Z","caller":"traceutil/trace.go:171","msg":"trace[1519924422] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25818; }","duration":"134.184289ms","start":"2026-05-08T19:06:38.390475Z","end":"2026-05-08T19:06:38.52466Z","steps":["trace[1519924422] 'agreement among raft nodes before linearized reading' (duration: 133.818189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:38.72808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.520739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:06:38.728188Z","caller":"traceutil/trace.go:171","msg":"trace[620080498] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25818; }","duration":"133.707255ms","start":"2026-05-08T19:06:38.594456Z","end":"2026-05-08T19:06:38.728163Z","steps":["trace[620080498] 'range keys from in-memory index tree' (duration: 133.387646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:38.882089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.20636ms","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-05-08T19:06:38.882256Z","caller":"traceutil/trace.go:171","msg":"trace[1524783514] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25818; }","duration":"134.414765ms","start":"2026-05-08T19:06:38.747815Z","end":"2026-05-08T19:06:38.882229Z","steps":["trace[1524783514] 'range keys from in-memory index tree' (duration: 133.994503ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:39.065314Z","caller":"traceutil/trace.go:171","msg":"trace[1578774479] transaction","detail":"{read_only:false; response_revision:25819; number_of_response:1; }","duration":"171.683599ms","start":"2026-05-08T19:06:38.893592Z","end":"2026-05-08T19:06:39.065276Z","steps":["trace[1578774479] 'process raft request' (duration: 112.19291ms)","trace[1578774479] 'compare' (duration: 59.279793ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:06:39.395856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.258449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:06:39.396125Z","caller":"traceutil/trace.go:171","msg":"trace[1466853423] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"445.183196ms","start":"2026-05-08T19:06:38.950876Z","end":"2026-05-08T19:06:39.396059Z","steps":["trace[1466853423] 'process raft request' (duration: 231.612829ms)","trace[1466853423] 'compare' (duration: 79.756458ms)","trace[1466853423] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/capi-system/controller-leader-election-capi; req_size:519; } (duration: 133.216733ms)"],"step_count":3} {"level":"info","ts":"2026-05-08T19:06:39.396175Z","caller":"traceutil/trace.go:171","msg":"trace[1790872871] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"419.020725ms","start":"2026-05-08T19:06:38.977105Z","end":"2026-05-08T19:06:39.396126Z","steps":["trace[1790872871] 'process raft request' (duration: 418.84985ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:39.396249Z","caller":"traceutil/trace.go:171","msg":"trace[1780964153] transaction","detail":"{read_only:false; response_revision:25822; number_of_response:1; }","duration":"322.599273ms","start":"2026-05-08T19:06:39.073604Z","end":"2026-05-08T19:06:39.396203Z","steps":["trace[1780964153] 'process raft request' (duration: 322.453949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:39.396341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:38.950845Z","time spent":"445.383902ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:06:39.396387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:38.977062Z","time spent":"419.21975ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:06:39.39646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:39.073573Z","time spent":"322.781647ms","remote":"127.0.0.1:34696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":674,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:06:39.396236Z","caller":"traceutil/trace.go:171","msg":"trace[284341665] transaction","detail":"{read_only:false; response_revision:25823; number_of_response:1; }","duration":"321.596235ms","start":"2026-05-08T19:06:39.074623Z","end":"2026-05-08T19:06:39.396219Z","steps":["trace[284341665] 'process raft request' (duration: 321.487303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:39.396618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:39.074614Z","time spent":"321.912344ms","remote":"127.0.0.1:34970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2789,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:06:39.396662Z","caller":"traceutil/trace.go:171","msg":"trace[1448539970] linearizableReadLoop","detail":"{readStateIndex:26667; appliedIndex:26660; }","duration":"170.421395ms","start":"2026-05-08T19:06:39.226215Z","end":"2026-05-08T19:06:39.396637Z","steps":["trace[1448539970] 'read index received' (duration: 99.412304ms)","trace[1448539970] 'applied index is now lower than readState.Index' (duration: 71.007171ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:06:39.396871Z","caller":"traceutil/trace.go:171","msg":"trace[759428778] transaction","detail":"{read_only:false; response_revision:25825; number_of_response:1; }","duration":"315.280714ms","start":"2026-05-08T19:06:39.081577Z","end":"2026-05-08T19:06:39.396858Z","steps":["trace[759428778] 'process raft request' (duration: 314.703688ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:39.396929Z","caller":"traceutil/trace.go:171","msg":"trace[462472454] transaction","detail":"{read_only:false; response_revision:25824; number_of_response:1; }","duration":"321.159374ms","start":"2026-05-08T19:06:39.075388Z","end":"2026-05-08T19:06:39.396547Z","steps":["trace[462472454] 'process raft request' (duration: 320.773214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:39.396948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:39.081553Z","time spent":"315.356935ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:06:39.397014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:39.075383Z","time spent":"321.592776ms","remote":"127.0.0.1:34808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1204,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:06:39.397122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.897587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418399"} {"level":"info","ts":"2026-05-08T19:06:39.397186Z","caller":"traceutil/trace.go:171","msg":"trace[1464042899] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25826; }","duration":"171.013271ms","start":"2026-05-08T19:06:39.226156Z","end":"2026-05-08T19:06:39.397169Z","steps":["trace[1464042899] 'agreement among raft nodes before linearized reading' (duration: 170.712613ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:39.398355Z","caller":"traceutil/trace.go:171","msg":"trace[1228399995] transaction","detail":"{read_only:false; response_revision:25826; number_of_response:1; }","duration":"312.628382ms","start":"2026-05-08T19:06:39.084195Z","end":"2026-05-08T19:06:39.396823Z","steps":["trace[1228399995] 'process raft request' (duration: 312.270032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:39.398701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:39.084186Z","time spent":"314.289957ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:06:39.667529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.737154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-08T19:06:39.667775Z","caller":"traceutil/trace.go:171","msg":"trace[1753926508] transaction","detail":"{read_only:false; response_revision:25827; number_of_response:1; }","duration":"302.181157ms","start":"2026-05-08T19:06:39.365576Z","end":"2026-05-08T19:06:39.667757Z","steps":["trace[1753926508] 'process raft request' (duration: 110.139385ms)","trace[1753926508] 'compare' (duration: 76.652025ms)","trace[1753926508] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; req_size:605; } (duration: 114.537254ms)"],"step_count":3} {"level":"warn","ts":"2026-05-08T19:06:39.667839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:39.365554Z","time spent":"302.250789ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:06:39.667849Z","caller":"traceutil/trace.go:171","msg":"trace[68354951] transaction","detail":"{read_only:false; response_revision:25828; number_of_response:1; }","duration":"280.392154ms","start":"2026-05-08T19:06:39.387401Z","end":"2026-05-08T19:06:39.667793Z","steps":["trace[68354951] 'process raft request' (duration: 280.21093ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:39.66809Z","caller":"traceutil/trace.go:171","msg":"trace[656478434] transaction","detail":"{read_only:false; response_revision:25829; number_of_response:1; }","duration":"263.176837ms","start":"2026-05-08T19:06:39.404892Z","end":"2026-05-08T19:06:39.668068Z","steps":["trace[656478434] 'process raft request' (duration: 262.807487ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:39.66824Z","caller":"traceutil/trace.go:171","msg":"trace[694830037] linearizableReadLoop","detail":"{readStateIndex:26669; appliedIndex:26667; }","duration":"271.482063ms","start":"2026-05-08T19:06:39.396736Z","end":"2026-05-08T19:06:39.668218Z","steps":["trace[694830037] 'read index received' (duration: 78.984418ms)","trace[694830037] 'applied index is now lower than readState.Index' (duration: 192.497035ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:06:39.668416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.828737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-08T19:06:39.668405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.879934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-08T19:06:39.668435Z","caller":"traceutil/trace.go:171","msg":"trace[15545466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25830; }","duration":"255.850798ms","start":"2026-05-08T19:06:39.412579Z","end":"2026-05-08T19:06:39.66843Z","steps":["trace[15545466] 'agreement among raft nodes before linearized reading' (duration: 255.734304ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:39.668459Z","caller":"traceutil/trace.go:171","msg":"trace[1674401477] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:25830; }","duration":"325.001158ms","start":"2026-05-08T19:06:39.343446Z","end":"2026-05-08T19:06:39.668447Z","steps":["trace[1674401477] 'agreement among raft nodes before linearized reading' (duration: 324.862214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:39.668546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:39.343419Z","time spent":"325.11952ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":19,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-05-08T19:06:39.668556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.109107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-08T19:06:39.668597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.047197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-08T19:06:39.668618Z","caller":"traceutil/trace.go:171","msg":"trace[1946886842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25830; }","duration":"244.084627ms","start":"2026-05-08T19:06:39.424528Z","end":"2026-05-08T19:06:39.668613Z","steps":["trace[1946886842] 'agreement among raft nodes before linearized reading' (duration: 244.001075ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:39.668636Z","caller":"traceutil/trace.go:171","msg":"trace[1227107903] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:25830; }","duration":"109.24001ms","start":"2026-05-08T19:06:39.559366Z","end":"2026-05-08T19:06:39.668606Z","steps":["trace[1227107903] 'agreement among raft nodes before linearized reading' (duration: 109.140968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:39.668665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.351044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-08T19:06:39.668718Z","caller":"traceutil/trace.go:171","msg":"trace[521287064] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25830; }","duration":"244.443057ms","start":"2026-05-08T19:06:39.424267Z","end":"2026-05-08T19:06:39.66871Z","steps":["trace[521287064] 'agreement among raft nodes before linearized reading' (duration: 244.274132ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:39.668671Z","caller":"traceutil/trace.go:171","msg":"trace[1305691885] transaction","detail":"{read_only:false; response_revision:25830; number_of_response:1; }","duration":"263.613868ms","start":"2026-05-08T19:06:39.405032Z","end":"2026-05-08T19:06:39.668646Z","steps":["trace[1305691885] 'process raft request' (duration: 262.968401ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:39.69424Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22424,"took":"1.30507371s","hash":3536357200,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":43937792,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-05-08T19:06:39.694299Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3536357200,"revision":22424,"compact-revision":20061} {"level":"info","ts":"2026-05-08T19:06:57.063799Z","caller":"traceutil/trace.go:171","msg":"trace[670427437] linearizableReadLoop","detail":"{readStateIndex:26947; appliedIndex:26946; }","duration":"345.053215ms","start":"2026-05-08T19:06:56.718698Z","end":"2026-05-08T19:06:57.063752Z","steps":["trace[670427437] 'read index received' (duration: 246.73267ms)","trace[670427437] 'applied index is now lower than readState.Index' (duration: 98.318834ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:06:57.064002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.26559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:06:57.064048Z","caller":"traceutil/trace.go:171","msg":"trace[1226880484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26100; }","duration":"345.386603ms","start":"2026-05-08T19:06:56.718649Z","end":"2026-05-08T19:06:57.064035Z","steps":["trace[1226880484] 'agreement among raft nodes before linearized reading' (duration: 345.26041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:06:57.064096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:06:56.718621Z","time spent":"345.467426ms","remote":"127.0.0.1:34534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T19:06:57.183535Z","caller":"traceutil/trace.go:171","msg":"trace[581503715] linearizableReadLoop","detail":"{readStateIndex:26948; appliedIndex:26947; }","duration":"117.752573ms","start":"2026-05-08T19:06:57.065757Z","end":"2026-05-08T19:06:57.183509Z","steps":["trace[581503715] 'read index received' (duration: 117.523636ms)","trace[581503715] 'applied index is now lower than readState.Index' (duration: 228.136µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T19:06:57.18366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.895776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:06:57.183687Z","caller":"traceutil/trace.go:171","msg":"trace[2142919197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26101; }","duration":"117.999119ms","start":"2026-05-08T19:06:57.065678Z","end":"2026-05-08T19:06:57.183677Z","steps":["trace[2142919197] 'agreement among raft nodes before linearized reading' (duration: 117.926567ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:06:57.183879Z","caller":"traceutil/trace.go:171","msg":"trace[1945746222] transaction","detail":"{read_only:false; response_revision:26101; number_of_response:1; }","duration":"297.161421ms","start":"2026-05-08T19:06:56.886691Z","end":"2026-05-08T19:06:57.183853Z","steps":["trace[1945746222] 'process raft request' (duration: 296.607486ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:07:03.478104Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006989.wal"} {"level":"info","ts":"2026-05-08T19:08:16.988193Z","caller":"traceutil/trace.go:171","msg":"trace[187501100] transaction","detail":"{read_only:false; response_revision:26825; number_of_response:1; }","duration":"109.303482ms","start":"2026-05-08T19:08:16.878807Z","end":"2026-05-08T19:08:16.98811Z","steps":["trace[187501100] 'process raft request' (duration: 90.371367ms)","trace[187501100] 'compare' (duration: 17.635839ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T19:08:57.02267Z","caller":"traceutil/trace.go:171","msg":"trace[325501030] transaction","detail":"{read_only:false; response_revision:27163; number_of_response:1; }","duration":"104.495962ms","start":"2026-05-08T19:08:56.918134Z","end":"2026-05-08T19:08:57.02263Z","steps":["trace[325501030] 'process raft request' (duration: 104.212684ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:08:58.080422Z","caller":"traceutil/trace.go:171","msg":"trace[957652756] transaction","detail":"{read_only:false; response_revision:27171; number_of_response:1; }","duration":"104.287496ms","start":"2026-05-08T19:08:57.976104Z","end":"2026-05-08T19:08:58.080392Z","steps":["trace[957652756] 'process raft request' (duration: 104.234975ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:08:58.080482Z","caller":"traceutil/trace.go:171","msg":"trace[900855624] transaction","detail":"{read_only:false; response_revision:27170; number_of_response:1; }","duration":"105.657963ms","start":"2026-05-08T19:08:57.97479Z","end":"2026-05-08T19:08:58.080448Z","steps":["trace[900855624] 'process raft request' (duration: 105.388916ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:08:58.724351Z","caller":"traceutil/trace.go:171","msg":"trace[1546266190] transaction","detail":"{read_only:false; response_revision:27182; number_of_response:1; }","duration":"119.14937ms","start":"2026-05-08T19:08:58.60516Z","end":"2026-05-08T19:08:58.724309Z","steps":["trace[1546266190] 'process raft request' (duration: 118.889353ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:11:38.39059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25813} {"level":"info","ts":"2026-05-08T19:11:38.547175Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25813,"took":"151.648749ms","hash":18296689,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":35745792,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-08T19:11:38.547246Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":18296689,"revision":25813,"compact-revision":22424} {"level":"info","ts":"2026-05-08T19:12:29.631401Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-08T19:12:29.636633Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-08T19:12:29.636708Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-08T19:16:38.398593Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28697} {"level":"info","ts":"2026-05-08T19:16:38.545049Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28697,"took":"140.459134ms","hash":3510505764,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":30638080,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-08T19:16:38.545126Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3510505764,"revision":28697,"compact-revision":25813} {"level":"info","ts":"2026-05-08T19:21:38.403991Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30924} {"level":"info","ts":"2026-05-08T19:21:38.518929Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30924,"took":"110.317256ms","hash":1320655872,"current-db-size-bytes":58204160,"current-db-size":"58 MB","current-db-size-in-use-bytes":31166464,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-08T19:21:38.518989Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1320655872,"revision":30924,"compact-revision":28697} {"level":"info","ts":"2026-05-08T19:26:38.411018Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33191} {"level":"info","ts":"2026-05-08T19:26:38.573266Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33191,"took":"152.78525ms","hash":740303177,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":31584256,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-08T19:26:38.573341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":740303177,"revision":33191,"compact-revision":30924} {"level":"info","ts":"2026-05-08T19:27:18.498142Z","caller":"traceutil/trace.go:171","msg":"trace[1834638308] transaction","detail":"{read_only:false; response_revision:35773; number_of_response:1; }","duration":"446.389744ms","start":"2026-05-08T19:27:18.051725Z","end":"2026-05-08T19:27:18.498114Z","steps":["trace[1834638308] 'process raft request' (duration: 446.197949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:27:18.49832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:27:18.051711Z","time spent":"446.527037ms","remote":"127.0.0.1:34806","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-05-08T19:27:18.50099Z","caller":"traceutil/trace.go:171","msg":"trace[929729435] transaction","detail":"{read_only:false; response_revision:35776; number_of_response:1; }","duration":"375.322613ms","start":"2026-05-08T19:27:18.125648Z","end":"2026-05-08T19:27:18.500971Z","steps":["trace[929729435] 'process raft request' (duration: 375.264272ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:27:18.50105Z","caller":"traceutil/trace.go:171","msg":"trace[1307739678] transaction","detail":"{read_only:false; response_revision:35775; number_of_response:1; }","duration":"378.982853ms","start":"2026-05-08T19:27:18.122034Z","end":"2026-05-08T19:27:18.501017Z","steps":["trace[1307739678] 'process raft request' (duration: 378.846069ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:27:18.501031Z","caller":"traceutil/trace.go:171","msg":"trace[1693323782] transaction","detail":"{read_only:false; response_revision:35774; number_of_response:1; }","duration":"379.988919ms","start":"2026-05-08T19:27:18.12101Z","end":"2026-05-08T19:27:18.500999Z","steps":["trace[1693323782] 'process raft request' (duration: 379.736872ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:27:18.501215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:27:18.122023Z","time spent":"379.106396ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:27:18.501125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:27:18.125639Z","time spent":"375.414506ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T19:27:18.501284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:27:18.120982Z","time spent":"380.160074ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:27:18.501987Z","caller":"traceutil/trace.go:171","msg":"trace[217218097] transaction","detail":"{read_only:false; response_revision:35777; number_of_response:1; }","duration":"321.381889ms","start":"2026-05-08T19:27:18.18058Z","end":"2026-05-08T19:27:18.501962Z","steps":["trace[217218097] 'process raft request' (duration: 320.359311ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:27:18.502149Z","caller":"traceutil/trace.go:171","msg":"trace[312374747] transaction","detail":"{read_only:false; response_revision:35778; number_of_response:1; }","duration":"156.651485ms","start":"2026-05-08T19:27:18.34549Z","end":"2026-05-08T19:27:18.502141Z","steps":["trace[312374747] 'process raft request' (duration: 156.388638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:27:18.502171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T19:27:18.180555Z","time spent":"321.500283ms","remote":"127.0.0.1:34806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T19:27:18.50231Z","caller":"traceutil/trace.go:171","msg":"trace[2007452591] transaction","detail":"{read_only:false; response_revision:35779; number_of_response:1; }","duration":"155.828833ms","start":"2026-05-08T19:27:18.346465Z","end":"2026-05-08T19:27:18.502294Z","steps":["trace[2007452591] 'process raft request' (duration: 155.639848ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:27:18.502347Z","caller":"traceutil/trace.go:171","msg":"trace[1808775316] transaction","detail":"{read_only:false; response_revision:35780; number_of_response:1; }","duration":"155.265947ms","start":"2026-05-08T19:27:18.347072Z","end":"2026-05-08T19:27:18.502338Z","steps":["trace[1808775316] 'process raft request' (duration: 155.138644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:27:18.783198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.331405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-08T19:27:18.78331Z","caller":"traceutil/trace.go:171","msg":"trace[39237006] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:35784; }","duration":"202.462909ms","start":"2026-05-08T19:27:18.580825Z","end":"2026-05-08T19:27:18.783288Z","steps":["trace[39237006] 'count revisions from in-memory index tree' (duration: 202.204212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:27:18.783368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.055841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-08T19:27:18.783444Z","caller":"traceutil/trace.go:171","msg":"trace[1307127270] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:35784; }","duration":"130.202496ms","start":"2026-05-08T19:27:18.653221Z","end":"2026-05-08T19:27:18.783424Z","steps":["trace[1307127270] 'range keys from in-memory index tree' (duration: 129.842896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T19:27:18.785239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.19405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-08T19:27:18.785314Z","caller":"traceutil/trace.go:171","msg":"trace[306023200] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:35784; }","duration":"107.319114ms","start":"2026-05-08T19:27:18.67798Z","end":"2026-05-08T19:27:18.7853Z","steps":["trace[306023200] 'count revisions from in-memory index tree' (duration: 107.107648ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T19:31:38.419537Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35490} {"level":"info","ts":"2026-05-08T19:31:38.564313Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35490,"took":"139.739536ms","hash":2466826956,"current-db-size-bytes":59715584,"current-db-size":"60 MB","current-db-size-in-use-bytes":31653888,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-08T19:31:38.56437Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2466826956,"revision":35490,"compact-revision":33191} {"level":"info","ts":"2026-05-08T19:33:58.494718Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-08T19:33:58.499872Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-08T19:33:58.499956Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-08T19:36:38.427283Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37719} {"level":"info","ts":"2026-05-08T19:36:38.546857Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37719,"took":"115.962079ms","hash":2074203894,"current-db-size-bytes":59715584,"current-db-size":"60 MB","current-db-size-in-use-bytes":30732288,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-08T19:36:38.546918Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2074203894,"revision":37719,"compact-revision":35490}