{"level":"warn","ts":"2026-10-02T15:20:36.536856Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T15:20:36.537038Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.20: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.20:2380","--initial-cluster=instance=https://199.204.45.20:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.20:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.20:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-02T15:20:36.537179Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T15:20:36.537224Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.20:2380"]} {"level":"info","ts":"2026-10-02T15:20:36.537271Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-02T15:20:36.538096Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.20:2379"]} {"level":"info","ts":"2026-10-02T15:20:36.538248Z","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.20:2380"],"listen-peer-urls":["https://199.204.45.20:2380"],"advertise-client-urls":["https://199.204.45.20:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.20:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.20:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-02T15:20:36.570065Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.416555ms"} {"level":"info","ts":"2026-10-02T15:20:36.579485Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e2d037ebe7b91690","cluster-id":"7224e0058ac64c16"} {"level":"info","ts":"2026-10-02T15:20:36.579594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 switched to configuration voters=()"} {"level":"info","ts":"2026-10-02T15:20:36.57963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 became follower at term 0"} {"level":"info","ts":"2026-10-02T15:20:36.579647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e2d037ebe7b91690 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-02T15:20:36.57966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 became follower at term 1"} {"level":"info","ts":"2026-10-02T15:20:36.579731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 switched to configuration voters=(16343624534072039056)"} {"level":"warn","ts":"2026-10-02T15:20:36.587842Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-02T15:20:36.589808Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-02T15:20:36.591511Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-02T15:20:36.594687Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e2d037ebe7b91690","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-02T15:20:36.594887Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e2d037ebe7b91690","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-02T15:20:36.595071Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T15:20:36.59522Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T15:20:36.595261Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T15:20:36.596143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 switched to configuration voters=(16343624534072039056)"} {"level":"info","ts":"2026-10-02T15:20:36.59631Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7224e0058ac64c16","local-member-id":"e2d037ebe7b91690","added-peer-id":"e2d037ebe7b91690","added-peer-peer-urls":["https://199.204.45.20:2380"]} {"level":"info","ts":"2026-10-02T15:20:36.602767Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-02T15:20:36.602951Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.20:2380"} {"level":"info","ts":"2026-10-02T15:20:36.603021Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.20:2380"} {"level":"info","ts":"2026-10-02T15:20:36.603183Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e2d037ebe7b91690","initial-advertise-peer-urls":["https://199.204.45.20:2380"],"listen-peer-urls":["https://199.204.45.20:2380"],"advertise-client-urls":["https://199.204.45.20:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.20:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-02T15:20:36.603263Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-02T15:20:37.28211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 is starting a new election at term 1"} {"level":"info","ts":"2026-10-02T15:20:37.282232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-02T15:20:37.282265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 received MsgPreVoteResp from e2d037ebe7b91690 at term 1"} {"level":"info","ts":"2026-10-02T15:20:37.282293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 became candidate at term 2"} {"level":"info","ts":"2026-10-02T15:20:37.282304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 received MsgVoteResp from e2d037ebe7b91690 at term 2"} {"level":"info","ts":"2026-10-02T15:20:37.282322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2d037ebe7b91690 became leader at term 2"} {"level":"info","ts":"2026-10-02T15:20:37.282343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e2d037ebe7b91690 elected leader e2d037ebe7b91690 at term 2"} {"level":"info","ts":"2026-10-02T15:20:37.283774Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:20:37.28525Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T15:20:37.285248Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e2d037ebe7b91690","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.20:2379]}","request-path":"/0/members/e2d037ebe7b91690/attributes","cluster-id":"7224e0058ac64c16","publish-timeout":"7s"} {"level":"info","ts":"2026-10-02T15:20:37.285408Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T15:20:37.286062Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7224e0058ac64c16","local-member-id":"e2d037ebe7b91690","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:20:37.286246Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:20:37.286305Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T15:20:37.286312Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-02T15:20:37.286395Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-02T15:20:37.288434Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.20:2379"} {"level":"info","ts":"2026-10-02T15:20:37.289902Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-10-02T15:21:24.284614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.004387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:21:24.284707Z","caller":"traceutil/trace.go:171","msg":"trace[1029340081] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"118.115451ms","start":"2026-10-02T15:21:24.166566Z","end":"2026-10-02T15:21:24.284681Z","steps":["trace[1029340081] 'process raft request' (duration: 109.964065ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:21:24.284796Z","caller":"traceutil/trace.go:171","msg":"trace[216901507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"161.246803ms","start":"2026-10-02T15:21:24.123524Z","end":"2026-10-02T15:21:24.284771Z","steps":["trace[216901507] 'range keys from in-memory index tree' (duration: 160.853112ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:21:24.907351Z","caller":"traceutil/trace.go:171","msg":"trace[172846889] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"200.459468ms","start":"2026-10-02T15:21:24.70685Z","end":"2026-10-02T15:21:24.907309Z","steps":["trace[172846889] 'process raft request' (duration: 198.965032ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:23:01.986326Z","caller":"traceutil/trace.go:171","msg":"trace[862966731] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"208.751557ms","start":"2026-10-02T15:23:01.777552Z","end":"2026-10-02T15:23:01.986304Z","steps":["trace[862966731] 'process raft request' (duration: 208.604943ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:23:02.02589Z","caller":"traceutil/trace.go:171","msg":"trace[1226549090] linearizableReadLoop","detail":"{readStateIndex:1965; appliedIndex:1963; }","duration":"219.22801ms","start":"2026-10-02T15:23:01.80663Z","end":"2026-10-02T15:23:02.025858Z","steps":["trace[1226549090] 'read index received' (duration: 179.582067ms)","trace[1226549090] 'applied index is now lower than readState.Index' (duration: 39.644533ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:23:02.02599Z","caller":"traceutil/trace.go:171","msg":"trace[1599822850] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"242.423837ms","start":"2026-10-02T15:23:01.783523Z","end":"2026-10-02T15:23:02.025946Z","steps":["trace[1599822850] 'process raft request' (duration: 242.076228ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:23:02.026039Z","caller":"traceutil/trace.go:171","msg":"trace[1550604643] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"199.084766ms","start":"2026-10-02T15:23:01.826931Z","end":"2026-10-02T15:23:02.026016Z","steps":["trace[1550604643] 'process raft request' (duration: 198.818359ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:23:02.026208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.560417ms","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-10-02T15:23:02.026227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.960687ms","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-10-02T15:23:02.026235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.352281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-02T15:23:02.026268Z","caller":"traceutil/trace.go:171","msg":"trace[897526112] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1891; }","duration":"219.67395ms","start":"2026-10-02T15:23:01.806578Z","end":"2026-10-02T15:23:02.026252Z","steps":["trace[897526112] 'agreement among raft nodes before linearized reading' (duration: 219.464035ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:23:02.026272Z","caller":"traceutil/trace.go:171","msg":"trace[585820792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1891; }","duration":"215.068909ms","start":"2026-10-02T15:23:01.811192Z","end":"2026-10-02T15:23:02.02626Z","steps":["trace[585820792] 'agreement among raft nodes before linearized reading' (duration: 214.856854ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:23:02.026266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.17109ms","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-10-02T15:23:02.026328Z","caller":"traceutil/trace.go:171","msg":"trace[1875702897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1891; }","duration":"180.233442ms","start":"2026-10-02T15:23:01.846078Z","end":"2026-10-02T15:23:02.026312Z","steps":["trace[1875702897] 'agreement among raft nodes before linearized reading' (duration: 180.069128ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:23:02.026306Z","caller":"traceutil/trace.go:171","msg":"trace[209526272] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1891; }","duration":"122.464004ms","start":"2026-10-02T15:23:01.903822Z","end":"2026-10-02T15:23:02.026286Z","steps":["trace[209526272] 'agreement among raft nodes before linearized reading' (duration: 122.253289ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:23:02.309232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.786975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T15:23:02.309357Z","caller":"traceutil/trace.go:171","msg":"trace[729703855] linearizableReadLoop","detail":"{readStateIndex:1969; appliedIndex:1968; }","duration":"211.613996ms","start":"2026-10-02T15:23:02.097723Z","end":"2026-10-02T15:23:02.309337Z","steps":["trace[729703855] 'read index received' (duration: 89.592µs)","trace[729703855] 'applied index is now lower than readState.Index' (duration: 211.522864ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:23:02.309383Z","caller":"traceutil/trace.go:171","msg":"trace[990977676] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"276.938826ms","start":"2026-10-02T15:23:02.03242Z","end":"2026-10-02T15:23:02.309359Z","steps":["trace[990977676] 'process raft request' (duration: 38.398483ms)","trace[990977676] 'compare' (duration: 237.685223ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:23:02.309513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.79252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-02T15:23:02.309551Z","caller":"traceutil/trace.go:171","msg":"trace[1383127028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1894; }","duration":"211.873453ms","start":"2026-10-02T15:23:02.097664Z","end":"2026-10-02T15:23:02.309538Z","steps":["trace[1383127028] 'agreement among raft nodes before linearized reading' (duration: 211.727759ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:23:15.146524Z","caller":"traceutil/trace.go:171","msg":"trace[302665984] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"262.779886ms","start":"2026-10-02T15:23:14.883708Z","end":"2026-10-02T15:23:15.146488Z","steps":["trace[302665984] 'process raft request' (duration: 217.60316ms)","trace[302665984] 'compare' (duration: 44.91649ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:25:15.764335Z","caller":"traceutil/trace.go:171","msg":"trace[1777990597] transaction","detail":"{read_only:false; response_revision:2855; number_of_response:1; }","duration":"108.209609ms","start":"2026-10-02T15:25:15.656096Z","end":"2026-10-02T15:25:15.764306Z","steps":["trace[1777990597] 'process raft request' (duration: 108.015304ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:29:56.834215Z","caller":"traceutil/trace.go:171","msg":"trace[1708878095] transaction","detail":"{read_only:false; response_revision:5054; number_of_response:1; }","duration":"110.633026ms","start":"2026-10-02T15:29:56.723539Z","end":"2026-10-02T15:29:56.834172Z","steps":["trace[1708878095] 'process raft request' (duration: 96.829982ms)","trace[1708878095] 'compare' (duration: 13.663561ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:30:05.031288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.969963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T15:30:05.031413Z","caller":"traceutil/trace.go:171","msg":"trace[981362765] linearizableReadLoop","detail":"{readStateIndex:5516; appliedIndex:5515; }","duration":"236.854509ms","start":"2026-10-02T15:30:04.794537Z","end":"2026-10-02T15:30:05.031391Z","steps":["trace[981362765] 'read index received' (duration: 84.62361ms)","trace[981362765] 'applied index is now lower than readState.Index' (duration: 152.229569ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:30:05.031511Z","caller":"traceutil/trace.go:171","msg":"trace[919216418] transaction","detail":"{read_only:false; response_revision:5312; number_of_response:1; }","duration":"278.688754ms","start":"2026-10-02T15:30:04.752796Z","end":"2026-10-02T15:30:05.031485Z","steps":["trace[919216418] 'process raft request' (duration: 126.410844ms)","trace[919216418] 'compare' (duration: 151.834979ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:30:05.031672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.115096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7eb0e3c4-c582-43c3-b181-ad9ab9b35631\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-10-02T15:30:05.031722Z","caller":"traceutil/trace.go:171","msg":"trace[1837737042] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7eb0e3c4-c582-43c3-b181-ad9ab9b35631; range_end:; response_count:1; response_revision:5312; }","duration":"237.215318ms","start":"2026-10-02T15:30:04.79449Z","end":"2026-10-02T15:30:05.031705Z","steps":["trace[1837737042] 'agreement among raft nodes before linearized reading' (duration: 237.016193ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:30:05.032114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.687734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-10-02T15:30:05.032126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.13282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7eb0e3c4-c582-43c3-b181-ad9ab9b35631\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-10-02T15:30:05.032177Z","caller":"traceutil/trace.go:171","msg":"trace[2106854342] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5312; }","duration":"212.752945ms","start":"2026-10-02T15:30:04.819409Z","end":"2026-10-02T15:30:05.032162Z","steps":["trace[2106854342] 'agreement among raft nodes before linearized reading' (duration: 212.376736ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:30:05.032162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.143873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"info","ts":"2026-10-02T15:30:05.032226Z","caller":"traceutil/trace.go:171","msg":"trace[1490467102] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7eb0e3c4-c582-43c3-b181-ad9ab9b35631; range_end:; response_count:1; response_revision:5312; }","duration":"174.269323ms","start":"2026-10-02T15:30:04.85794Z","end":"2026-10-02T15:30:05.032209Z","steps":["trace[1490467102] 'agreement among raft nodes before linearized reading' (duration: 174.058418ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:30:05.03218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.43418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-02T15:30:05.032263Z","caller":"traceutil/trace.go:171","msg":"trace[1703525016] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:5312; }","duration":"196.273556ms","start":"2026-10-02T15:30:04.835971Z","end":"2026-10-02T15:30:05.032244Z","steps":["trace[1703525016] 'agreement among raft nodes before linearized reading' (duration: 196.03789ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:30:05.032286Z","caller":"traceutil/trace.go:171","msg":"trace[321875560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5312; }","duration":"144.593264ms","start":"2026-10-02T15:30:04.88767Z","end":"2026-10-02T15:30:05.032264Z","steps":["trace[321875560] 'agreement among raft nodes before linearized reading' (duration: 144.330937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:30:05.032268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.733296ms","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-10-02T15:30:05.032352Z","caller":"traceutil/trace.go:171","msg":"trace[571953503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5312; }","duration":"153.868738ms","start":"2026-10-02T15:30:04.878462Z","end":"2026-10-02T15:30:05.032331Z","steps":["trace[571953503] 'agreement among raft nodes before linearized reading' (duration: 153.5445ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:30:05.032481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.246425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786897"} {"level":"info","ts":"2026-10-02T15:30:05.032575Z","caller":"traceutil/trace.go:171","msg":"trace[1674218315] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5312; }","duration":"137.332118ms","start":"2026-10-02T15:30:04.895189Z","end":"2026-10-02T15:30:05.032521Z","steps":["trace[1674218315] 'agreement among raft nodes before linearized reading' (duration: 136.812835ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:30:07.161974Z","caller":"traceutil/trace.go:171","msg":"trace[1994275311] linearizableReadLoop","detail":"{readStateIndex:5534; appliedIndex:5533; }","duration":"125.230425ms","start":"2026-10-02T15:30:07.036717Z","end":"2026-10-02T15:30:07.161947Z","steps":["trace[1994275311] 'read index received' (duration: 125.086591ms)","trace[1994275311] 'applied index is now lower than readState.Index' (duration: 142.714µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:30:07.162074Z","caller":"traceutil/trace.go:171","msg":"trace[932201449] transaction","detail":"{read_only:false; response_revision:5328; number_of_response:1; }","duration":"126.086496ms","start":"2026-10-02T15:30:07.035947Z","end":"2026-10-02T15:30:07.162033Z","steps":["trace[932201449] 'process raft request' (duration: 125.85287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:30:07.162243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.502741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-02T15:30:07.16252Z","caller":"traceutil/trace.go:171","msg":"trace[268284964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5328; }","duration":"125.803459ms","start":"2026-10-02T15:30:07.036682Z","end":"2026-10-02T15:30:07.162485Z","steps":["trace[268284964] 'agreement among raft nodes before linearized reading' (duration: 125.400969ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:30:07.175015Z","caller":"traceutil/trace.go:171","msg":"trace[1796816198] transaction","detail":"{read_only:false; response_revision:5329; number_of_response:1; }","duration":"126.2913ms","start":"2026-10-02T15:30:07.048679Z","end":"2026-10-02T15:30:07.174971Z","steps":["trace[1796816198] 'process raft request' (duration: 126.175097ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:30:07.175435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.810696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-02T15:30:07.175515Z","caller":"traceutil/trace.go:171","msg":"trace[1785349747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5329; }","duration":"111.00437ms","start":"2026-10-02T15:30:07.064492Z","end":"2026-10-02T15:30:07.175496Z","steps":["trace[1785349747] 'agreement among raft nodes before linearized reading' (duration: 110.754494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:30:07.175433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.946206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7eb0e3c4-c582-43c3-b181-ad9ab9b35631\" ","response":"range_response_count:1 size:3906"} {"level":"info","ts":"2026-10-02T15:30:07.175658Z","caller":"traceutil/trace.go:171","msg":"trace[803353377] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7eb0e3c4-c582-43c3-b181-ad9ab9b35631; range_end:; response_count:1; response_revision:5329; }","duration":"134.204832ms","start":"2026-10-02T15:30:07.04143Z","end":"2026-10-02T15:30:07.175635Z","steps":["trace[803353377] 'agreement among raft nodes before linearized reading' (duration: 133.779522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:30:26.722352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.212933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-10-02T15:30:26.722592Z","caller":"traceutil/trace.go:171","msg":"trace[417570643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5719; }","duration":"113.513011ms","start":"2026-10-02T15:30:26.609049Z","end":"2026-10-02T15:30:26.722562Z","steps":["trace[417570643] 'range keys from in-memory index tree' (duration: 112.984438ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:30:37.347233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2976} {"level":"info","ts":"2026-10-02T15:30:37.423465Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2976,"took":"74.737861ms","hash":3460066050,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":32092160,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-02T15:30:37.423556Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3460066050,"revision":2976,"compact-revision":-1} {"level":"info","ts":"2026-10-02T15:30:38.091982Z","caller":"traceutil/trace.go:171","msg":"trace[1744694028] transaction","detail":"{read_only:false; response_revision:5873; number_of_response:1; }","duration":"112.251551ms","start":"2026-10-02T15:30:37.979699Z","end":"2026-10-02T15:30:38.091951Z","steps":["trace[1744694028] 'process raft request' (duration: 112.038185ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:30:38.092036Z","caller":"traceutil/trace.go:171","msg":"trace[583976059] transaction","detail":"{read_only:false; response_revision:5874; number_of_response:1; }","duration":"111.980084ms","start":"2026-10-02T15:30:37.980029Z","end":"2026-10-02T15:30:38.092009Z","steps":["trace[583976059] 'process raft request' (duration: 111.847901ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:35:37.354426Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5865} {"level":"info","ts":"2026-10-02T15:35:37.450996Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5865,"took":"94.556427ms","hash":1777752066,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":26914816,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-02T15:35:37.451075Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1777752066,"revision":5865,"compact-revision":2976} {"level":"warn","ts":"2026-10-02T15:36:47.460442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.677751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:36:47.460545Z","caller":"traceutil/trace.go:171","msg":"trace[348783734] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8639; }","duration":"107.817165ms","start":"2026-10-02T15:36:47.352712Z","end":"2026-10-02T15:36:47.460529Z","steps":["trace[348783734] 'count revisions from in-memory index tree' (duration: 107.59712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:36:47.460723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.994619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:36:47.460762Z","caller":"traceutil/trace.go:171","msg":"trace[353689011] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8639; }","duration":"108.068971ms","start":"2026-10-02T15:36:47.352683Z","end":"2026-10-02T15:36:47.460752Z","steps":["trace[353689011] 'range keys from in-memory index tree' (duration: 107.860566ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:36:47.460883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.310602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T15:36:47.460921Z","caller":"traceutil/trace.go:171","msg":"trace[1818537311] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:8639; }","duration":"107.367634ms","start":"2026-10-02T15:36:47.353542Z","end":"2026-10-02T15:36:47.460909Z","steps":["trace[1818537311] 'range keys from in-memory index tree' (duration: 107.249021ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:36:49.37201Z","caller":"traceutil/trace.go:171","msg":"trace[542094035] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"124.313542ms","start":"2026-10-02T15:36:49.247646Z","end":"2026-10-02T15:36:49.37196Z","steps":["trace[542094035] 'process raft request' (duration: 45.982517ms)","trace[542094035] 'compare' (duration: 78.13529ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:36:49.372223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.516389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-02T15:36:49.372325Z","caller":"traceutil/trace.go:171","msg":"trace[1347430552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8647; }","duration":"100.676263ms","start":"2026-10-02T15:36:49.271611Z","end":"2026-10-02T15:36:49.372287Z","steps":["trace[1347430552] 'agreement among raft nodes before linearized reading' (duration: 100.432607ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:36:49.371928Z","caller":"traceutil/trace.go:171","msg":"trace[1883589436] linearizableReadLoop","detail":"{readStateIndex:8955; appliedIndex:8954; }","duration":"100.226192ms","start":"2026-10-02T15:36:49.271655Z","end":"2026-10-02T15:36:49.371882Z","steps":["trace[1883589436] 'read index received' (duration: 21.903797ms)","trace[1883589436] 'applied index is now lower than readState.Index' (duration: 78.321095ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:37:17.476367Z","caller":"traceutil/trace.go:171","msg":"trace[532369956] transaction","detail":"{read_only:false; response_revision:8850; number_of_response:1; }","duration":"354.622084ms","start":"2026-10-02T15:37:17.121725Z","end":"2026-10-02T15:37:17.476347Z","steps":["trace[532369956] 'process raft request' (duration: 354.499351ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:37:17.476534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T15:37:17.121709Z","time spent":"354.729537ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T15:37:17.516176Z","caller":"traceutil/trace.go:171","msg":"trace[1445153124] transaction","detail":"{read_only:false; response_revision:8851; number_of_response:1; }","duration":"151.147228ms","start":"2026-10-02T15:37:17.365004Z","end":"2026-10-02T15:37:17.516151Z","steps":["trace[1445153124] 'process raft request' (duration: 151.016474ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:37:17.594248Z","caller":"traceutil/trace.go:171","msg":"trace[2131688217] transaction","detail":"{read_only:false; response_revision:8852; number_of_response:1; }","duration":"110.153461ms","start":"2026-10-02T15:37:17.484076Z","end":"2026-10-02T15:37:17.594229Z","steps":["trace[2131688217] 'process raft request' (duration: 109.866874ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:37:20.355224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.044543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T15:37:20.355386Z","caller":"traceutil/trace.go:171","msg":"trace[1110688895] transaction","detail":"{read_only:false; response_revision:8869; number_of_response:1; }","duration":"185.199257ms","start":"2026-10-02T15:37:20.170166Z","end":"2026-10-02T15:37:20.355365Z","steps":["trace[1110688895] 'process raft request' (duration: 73.876268ms)","trace[1110688895] 'compare' (duration: 110.795507ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:37:31.134436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.073525ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-10-02T15:37:31.134526Z","caller":"traceutil/trace.go:171","msg":"trace[1373586823] linearizableReadLoop","detail":"{readStateIndex:9252; appliedIndex:9251; }","duration":"241.00798ms","start":"2026-10-02T15:37:30.893506Z","end":"2026-10-02T15:37:31.134514Z","steps":["trace[1373586823] 'read index received' (duration: 94.773511ms)","trace[1373586823] 'applied index is now lower than readState.Index' (duration: 146.233239ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T15:37:31.134786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.272486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-57chc\" ","response":"range_response_count:1 size:2802"} {"level":"info","ts":"2026-10-02T15:37:31.134818Z","caller":"traceutil/trace.go:171","msg":"trace[871494574] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-57chc; range_end:; response_count:1; response_revision:8933; }","duration":"241.375769ms","start":"2026-10-02T15:37:30.893432Z","end":"2026-10-02T15:37:31.134808Z","steps":["trace[871494574] 'agreement among raft nodes before linearized reading' (duration: 241.268016ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:37:31.135057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.797978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-10-02T15:37:31.135013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.310918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-57chc\" ","response":"range_response_count:1 size:4672"} {"level":"info","ts":"2026-10-02T15:37:31.135089Z","caller":"traceutil/trace.go:171","msg":"trace[720113724] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8933; }","duration":"130.906371ms","start":"2026-10-02T15:37:31.004176Z","end":"2026-10-02T15:37:31.135082Z","steps":["trace[720113724] 'agreement among raft nodes before linearized reading' (duration: 130.831519ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:37:31.135115Z","caller":"traceutil/trace.go:171","msg":"trace[2080153901] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-57chc; range_end:; response_count:1; response_revision:8933; }","duration":"241.483382ms","start":"2026-10-02T15:37:30.893612Z","end":"2026-10-02T15:37:31.135096Z","steps":["trace[2080153901] 'agreement among raft nodes before linearized reading' (duration: 241.250816ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:37:46.59464Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024d7.wal"} {"level":"info","ts":"2026-10-02T15:38:39.25576Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2d037ebe7b91690","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T15:38:39.262438Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-02T15:38:39.26258Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-02T15:38:56.36323Z","caller":"traceutil/trace.go:171","msg":"trace[1088318163] transaction","detail":"{read_only:false; response_revision:9763; number_of_response:1; }","duration":"175.561708ms","start":"2026-10-02T15:38:56.187631Z","end":"2026-10-02T15:38:56.363193Z","steps":["trace[1088318163] 'process raft request' (duration: 162.66093ms)","trace[1088318163] 'compare' (duration: 12.672092ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:38:56.380175Z","caller":"traceutil/trace.go:171","msg":"trace[1449752176] linearizableReadLoop","detail":"{readStateIndex:10108; appliedIndex:10106; }","duration":"147.60079ms","start":"2026-10-02T15:38:56.232554Z","end":"2026-10-02T15:38:56.380155Z","steps":["trace[1449752176] 'read index received' (duration: 117.771479ms)","trace[1449752176] 'applied index is now lower than readState.Index' (duration: 29.828401ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:38:56.380277Z","caller":"traceutil/trace.go:171","msg":"trace[24831884] transaction","detail":"{read_only:false; response_revision:9764; number_of_response:1; }","duration":"192.285816ms","start":"2026-10-02T15:38:56.187967Z","end":"2026-10-02T15:38:56.380253Z","steps":["trace[24831884] 'process raft request' (duration: 192.096812ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:38:56.380444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.873017ms","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-10-02T15:38:56.380524Z","caller":"traceutil/trace.go:171","msg":"trace[1741109852] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:9764; }","duration":"148.0093ms","start":"2026-10-02T15:38:56.232499Z","end":"2026-10-02T15:38:56.380508Z","steps":["trace[1741109852] 'agreement among raft nodes before linearized reading' (duration: 147.800105ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:40:37.36211Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8056} {"level":"info","ts":"2026-10-02T15:40:37.426265Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8056,"took":"62.546172ms","hash":2217556234,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":28196864,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-02T15:40:37.426332Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2217556234,"revision":8056,"compact-revision":5865} {"level":"info","ts":"2026-10-02T15:42:45.403709Z","caller":"traceutil/trace.go:171","msg":"trace[538814552] transaction","detail":"{read_only:false; response_revision:11641; number_of_response:1; }","duration":"150.054323ms","start":"2026-10-02T15:42:45.253628Z","end":"2026-10-02T15:42:45.403683Z","steps":["trace[538814552] 'process raft request' (duration: 149.827458ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:42:45.683747Z","caller":"traceutil/trace.go:171","msg":"trace[310759391] transaction","detail":"{read_only:false; response_revision:11645; number_of_response:1; }","duration":"117.249335ms","start":"2026-10-02T15:42:45.566475Z","end":"2026-10-02T15:42:45.683724Z","steps":["trace[310759391] 'process raft request' (duration: 117.148173ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:42:45.683748Z","caller":"traceutil/trace.go:171","msg":"trace[1671353828] transaction","detail":"{read_only:false; response_revision:11644; number_of_response:1; }","duration":"147.325228ms","start":"2026-10-02T15:42:45.536384Z","end":"2026-10-02T15:42:45.683709Z","steps":["trace[1671353828] 'process raft request' (duration: 117.342188ms)","trace[1671353828] 'compare' (duration: 29.55252ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:45:37.368739Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10469} {"level":"info","ts":"2026-10-02T15:45:37.457164Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10469,"took":"86.096439ms","hash":1801226451,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":27774976,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-02T15:45:37.457237Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1801226451,"revision":10469,"compact-revision":8056} {"level":"info","ts":"2026-10-02T15:48:31.300602Z","caller":"traceutil/trace.go:171","msg":"trace[573275969] linearizableReadLoop","detail":"{readStateIndex:14486; appliedIndex:14485; }","duration":"104.945222ms","start":"2026-10-02T15:48:31.195636Z","end":"2026-10-02T15:48:31.300581Z","steps":["trace[573275969] 'read index received' (duration: 104.761468ms)","trace[573275969] 'applied index is now lower than readState.Index' (duration: 182.894µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:48:31.300857Z","caller":"traceutil/trace.go:171","msg":"trace[1079713513] transaction","detail":"{read_only:false; response_revision:14005; number_of_response:1; }","duration":"129.675237ms","start":"2026-10-02T15:48:31.17117Z","end":"2026-10-02T15:48:31.300845Z","steps":["trace[1079713513] 'process raft request' (duration: 129.279837ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:48:31.301084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.441804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-02T15:48:31.301123Z","caller":"traceutil/trace.go:171","msg":"trace[287394713] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14005; }","duration":"105.519666ms","start":"2026-10-02T15:48:31.195592Z","end":"2026-10-02T15:48:31.301112Z","steps":["trace[287394713] 'agreement among raft nodes before linearized reading' (duration: 105.401703ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:49:11.369531Z","caller":"traceutil/trace.go:171","msg":"trace[181351526] transaction","detail":"{read_only:false; response_revision:14234; number_of_response:1; }","duration":"193.690154ms","start":"2026-10-02T15:49:11.175815Z","end":"2026-10-02T15:49:11.369505Z","steps":["trace[181351526] 'process raft request' (duration: 193.499089ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:49:35.144822Z","caller":"traceutil/trace.go:171","msg":"trace[1799960691] transaction","detail":"{read_only:false; response_revision:14552; number_of_response:1; }","duration":"123.813608ms","start":"2026-10-02T15:49:35.020982Z","end":"2026-10-02T15:49:35.144795Z","steps":["trace[1799960691] 'process raft request' (duration: 121.451251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:50:28.769267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.265124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T15:50:28.769487Z","caller":"traceutil/trace.go:171","msg":"trace[425068442] transaction","detail":"{read_only:false; response_revision:15195; number_of_response:1; }","duration":"146.849576ms","start":"2026-10-02T15:50:28.622596Z","end":"2026-10-02T15:50:28.769446Z","steps":["trace[425068442] 'process raft request' (duration: 40.322537ms)","trace[425068442] 'compare' (duration: 106.113299ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T15:50:37.376179Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12784} {"level":"info","ts":"2026-10-02T15:50:37.462746Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12784,"took":"83.732178ms","hash":1033989074,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":30756864,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T15:50:37.462799Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1033989074,"revision":12784,"compact-revision":10469} {"level":"info","ts":"2026-10-02T15:55:37.383184Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15238} {"level":"info","ts":"2026-10-02T15:55:37.485712Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15238,"took":"100.095068ms","hash":1671801087,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":31961088,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-02T15:55:37.48578Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1671801087,"revision":15238,"compact-revision":12784} {"level":"info","ts":"2026-10-02T15:56:53.123125Z","caller":"traceutil/trace.go:171","msg":"trace[1157944307] transaction","detail":"{read_only:false; response_revision:17970; number_of_response:1; }","duration":"122.330153ms","start":"2026-10-02T15:56:53.000777Z","end":"2026-10-02T15:56:53.123107Z","steps":["trace[1157944307] 'process raft request' (duration: 122.2034ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:56:53.478722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.313651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T15:56:53.478805Z","caller":"traceutil/trace.go:171","msg":"trace[1429174884] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:17971; }","duration":"203.442524ms","start":"2026-10-02T15:56:53.275343Z","end":"2026-10-02T15:56:53.478785Z","steps":["trace[1429174884] 'count revisions from in-memory index tree' (duration: 203.212128ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:56:53.479622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.1124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-02T15:56:53.479684Z","caller":"traceutil/trace.go:171","msg":"trace[2063127151] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17971; }","duration":"194.210592ms","start":"2026-10-02T15:56:53.285459Z","end":"2026-10-02T15:56:53.479669Z","steps":["trace[2063127151] 'range keys from in-memory index tree' (duration: 193.33018ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T15:56:53.893216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.961562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T15:56:53.893321Z","caller":"traceutil/trace.go:171","msg":"trace[911687267] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:17972; }","duration":"299.095865ms","start":"2026-10-02T15:56:53.594208Z","end":"2026-10-02T15:56:53.893303Z","steps":["trace[911687267] 'count revisions from in-memory index tree' (duration: 298.876289ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T15:59:29.008543Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2d037ebe7b91690","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T15:59:29.016246Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-02T15:59:29.01637Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-02T16:00:37.392224Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17456} {"level":"info","ts":"2026-10-02T16:00:37.494896Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17456,"took":"99.37829ms","hash":3004963786,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":34545664,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-02T16:00:37.494965Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3004963786,"revision":17456,"compact-revision":15238} {"level":"info","ts":"2026-10-02T16:02:18.288458Z","caller":"traceutil/trace.go:171","msg":"trace[310005104] linearizableReadLoop","detail":"{readStateIndex:21302; appliedIndex:21301; }","duration":"165.561193ms","start":"2026-10-02T16:02:18.122874Z","end":"2026-10-02T16:02:18.288435Z","steps":["trace[310005104] 'read index received' (duration: 165.390549ms)","trace[310005104] 'applied index is now lower than readState.Index' (duration: 169.624µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:02:18.288511Z","caller":"traceutil/trace.go:171","msg":"trace[1685354908] transaction","detail":"{read_only:false; response_revision:20607; number_of_response:1; }","duration":"251.693795ms","start":"2026-10-02T16:02:18.036782Z","end":"2026-10-02T16:02:18.288476Z","steps":["trace[1685354908] 'process raft request' (duration: 251.50967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:02:18.288649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.747917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T16:02:18.288735Z","caller":"traceutil/trace.go:171","msg":"trace[2146779318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20607; }","duration":"165.916392ms","start":"2026-10-02T16:02:18.122796Z","end":"2026-10-02T16:02:18.288712Z","steps":["trace[2146779318] 'agreement among raft nodes before linearized reading' (duration: 165.746017ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:02:18.335323Z","caller":"traceutil/trace.go:171","msg":"trace[244834025] transaction","detail":"{read_only:false; response_revision:20608; number_of_response:1; }","duration":"199.590281ms","start":"2026-10-02T16:02:18.135702Z","end":"2026-10-02T16:02:18.335292Z","steps":["trace[244834025] 'process raft request' (duration: 199.378416ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:02:18.33579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.623768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-02T16:02:18.335883Z","caller":"traceutil/trace.go:171","msg":"trace[1987636617] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20609; }","duration":"194.457128ms","start":"2026-10-02T16:02:18.141401Z","end":"2026-10-02T16:02:18.335858Z","steps":["trace[1987636617] 'agreement among raft nodes before linearized reading' (duration: 194.248993ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:02:28.664706Z","caller":"traceutil/trace.go:171","msg":"trace[2040048106] transaction","detail":"{read_only:false; response_revision:20662; number_of_response:1; }","duration":"132.545019ms","start":"2026-10-02T16:02:28.532092Z","end":"2026-10-02T16:02:28.664637Z","steps":["trace[2040048106] 'process raft request' (duration: 132.302373ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:02:34.167498Z","caller":"traceutil/trace.go:171","msg":"trace[1470182402] transaction","detail":"{read_only:false; response_revision:20689; number_of_response:1; }","duration":"115.336874ms","start":"2026-10-02T16:02:34.052136Z","end":"2026-10-02T16:02:34.167473Z","steps":["trace[1470182402] 'process raft request' (duration: 115.17603ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:02:34.465334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.030275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T16:02:34.465428Z","caller":"traceutil/trace.go:171","msg":"trace[1726957575] linearizableReadLoop","detail":"{readStateIndex:21388; appliedIndex:21386; }","duration":"344.565529ms","start":"2026-10-02T16:02:34.120845Z","end":"2026-10-02T16:02:34.46541Z","steps":["trace[1726957575] 'read index received' (duration: 46.505128ms)","trace[1726957575] 'applied index is now lower than readState.Index' (duration: 298.058521ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:02:34.465889Z","caller":"traceutil/trace.go:171","msg":"trace[1691106231] transaction","detail":"{read_only:false; response_revision:20690; number_of_response:1; }","duration":"407.80512ms","start":"2026-10-02T16:02:34.058051Z","end":"2026-10-02T16:02:34.465856Z","steps":["trace[1691106231] 'process raft request' (duration: 268.161301ms)","trace[1691106231] 'compare' (duration: 138.84605ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:02:34.466011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:02:34.058037Z","time spent":"407.916733ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T16:02:34.466041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.172943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T16:02:34.466131Z","caller":"traceutil/trace.go:171","msg":"trace[655727849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20690; }","duration":"345.312816ms","start":"2026-10-02T16:02:34.120796Z","end":"2026-10-02T16:02:34.466109Z","steps":["trace[655727849] 'agreement among raft nodes before linearized reading' (duration: 344.924897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:02:34.466181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:02:34.120777Z","time spent":"345.394098ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-02T16:02:34.701792Z","caller":"traceutil/trace.go:171","msg":"trace[2135753459] transaction","detail":"{read_only:false; response_revision:20692; number_of_response:1; }","duration":"231.228652ms","start":"2026-10-02T16:02:34.470535Z","end":"2026-10-02T16:02:34.701764Z","steps":["trace[2135753459] 'process raft request' (duration: 134.809093ms)","trace[2135753459] 'compare' (duration: 96.224425ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:02:34.701982Z","caller":"traceutil/trace.go:171","msg":"trace[1805562260] transaction","detail":"{read_only:false; response_revision:20693; number_of_response:1; }","duration":"218.306942ms","start":"2026-10-02T16:02:34.483638Z","end":"2026-10-02T16:02:34.701945Z","steps":["trace[1805562260] 'process raft request' (duration: 218.083066ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:02:35.384513Z","caller":"traceutil/trace.go:171","msg":"trace[1916316832] linearizableReadLoop","detail":"{readStateIndex:21392; appliedIndex:21391; }","duration":"127.112058ms","start":"2026-10-02T16:02:35.257374Z","end":"2026-10-02T16:02:35.384486Z","steps":["trace[1916316832] 'read index received' (duration: 126.898543ms)","trace[1916316832] 'applied index is now lower than readState.Index' (duration: 212.345µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:02:35.38457Z","caller":"traceutil/trace.go:171","msg":"trace[858679271] transaction","detail":"{read_only:false; response_revision:20694; number_of_response:1; }","duration":"129.049324ms","start":"2026-10-02T16:02:35.255486Z","end":"2026-10-02T16:02:35.384535Z","steps":["trace[858679271] 'process raft request' (duration: 128.811698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:02:35.384716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.314722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T16:02:35.384829Z","caller":"traceutil/trace.go:171","msg":"trace[2076918250] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:20694; }","duration":"127.468417ms","start":"2026-10-02T16:02:35.257336Z","end":"2026-10-02T16:02:35.384804Z","steps":["trace[2076918250] 'agreement among raft nodes before linearized reading' (duration: 127.250661ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:05:37.408762Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19891} {"level":"info","ts":"2026-10-02T16:05:37.522836Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19891,"took":"110.17214ms","hash":1081962638,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":30777344,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T16:05:37.52291Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1081962638,"revision":19891,"compact-revision":17456} {"level":"warn","ts":"2026-10-02T16:06:32.520603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.589448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T16:06:32.52071Z","caller":"traceutil/trace.go:171","msg":"trace[1037988431] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:23197; }","duration":"108.757161ms","start":"2026-10-02T16:06:32.411936Z","end":"2026-10-02T16:06:32.520693Z","steps":["trace[1037988431] 'count revisions from in-memory index tree' (duration: 108.488615ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:06:32.534727Z","caller":"traceutil/trace.go:171","msg":"trace[1951708154] transaction","detail":"{read_only:false; response_revision:23198; number_of_response:1; }","duration":"105.939314ms","start":"2026-10-02T16:06:32.42875Z","end":"2026-10-02T16:06:32.53469Z","steps":["trace[1951708154] 'process raft request' (duration: 105.670257ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:06:32.599253Z","caller":"traceutil/trace.go:171","msg":"trace[2085951165] transaction","detail":"{read_only:false; response_revision:23199; number_of_response:1; }","duration":"169.962748ms","start":"2026-10-02T16:06:32.429266Z","end":"2026-10-02T16:06:32.599228Z","steps":["trace[2085951165] 'process raft request' (duration: 169.774633ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:06:32.84882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.259555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T16:06:32.848945Z","caller":"traceutil/trace.go:171","msg":"trace[1292749589] linearizableReadLoop","detail":"{readStateIndex:23973; appliedIndex:23972; }","duration":"219.559313ms","start":"2026-10-02T16:06:32.629367Z","end":"2026-10-02T16:06:32.848926Z","steps":["trace[1292749589] 'read index received' (duration: 32.108184ms)","trace[1292749589] 'applied index is now lower than readState.Index' (duration: 187.449629ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:06:32.849125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.755019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-02T16:06:32.849157Z","caller":"traceutil/trace.go:171","msg":"trace[1707752482] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23200; }","duration":"219.835821ms","start":"2026-10-02T16:06:32.629312Z","end":"2026-10-02T16:06:32.849148Z","steps":["trace[1707752482] 'agreement among raft nodes before linearized reading' (duration: 219.665066ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:06:32.849409Z","caller":"traceutil/trace.go:171","msg":"trace[38800607] transaction","detail":"{read_only:false; response_revision:23200; number_of_response:1; }","duration":"264.412355ms","start":"2026-10-02T16:06:32.584981Z","end":"2026-10-02T16:06:32.849394Z","steps":["trace[38800607] 'process raft request' (duration: 76.485374ms)","trace[38800607] 'compare' (duration: 187.102252ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:06:32.851481Z","caller":"traceutil/trace.go:171","msg":"trace[750556427] transaction","detail":"{read_only:false; response_revision:23201; number_of_response:1; }","duration":"222.110555ms","start":"2026-10-02T16:06:32.62935Z","end":"2026-10-02T16:06:32.85146Z","steps":["trace[750556427] 'process raft request' (duration: 221.988702ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:09:48.684869Z","caller":"traceutil/trace.go:171","msg":"trace[843709659] transaction","detail":"{read_only:false; response_revision:25095; number_of_response:1; }","duration":"146.835091ms","start":"2026-10-02T16:09:48.538006Z","end":"2026-10-02T16:09:48.684841Z","steps":["trace[843709659] 'process raft request' (duration: 104.910579ms)","trace[843709659] 'compare' (duration: 41.636145ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:09:48.692441Z","caller":"traceutil/trace.go:171","msg":"trace[1257546464] linearizableReadLoop","detail":"{readStateIndex:25915; appliedIndex:25913; }","duration":"154.275599ms","start":"2026-10-02T16:09:48.538136Z","end":"2026-10-02T16:09:48.692412Z","steps":["trace[1257546464] 'read index received' (duration: 104.794436ms)","trace[1257546464] 'applied index is now lower than readState.Index' (duration: 49.480503ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:09:48.692503Z","caller":"traceutil/trace.go:171","msg":"trace[659583500] transaction","detail":"{read_only:false; response_revision:25096; number_of_response:1; }","duration":"154.446264ms","start":"2026-10-02T16:09:48.538034Z","end":"2026-10-02T16:09:48.69248Z","steps":["trace[659583500] 'process raft request' (duration: 154.249069ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:09:48.692645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.485884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-02T16:09:48.692694Z","caller":"traceutil/trace.go:171","msg":"trace[1161050298] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25096; }","duration":"154.568287ms","start":"2026-10-02T16:09:48.538116Z","end":"2026-10-02T16:09:48.692684Z","steps":["trace[1161050298] 'agreement among raft nodes before linearized reading' (duration: 154.401253ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:09:48.693608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.429238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3959"} {"level":"info","ts":"2026-10-02T16:09:48.693675Z","caller":"traceutil/trace.go:171","msg":"trace[1016555972] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:25097; }","duration":"155.477539ms","start":"2026-10-02T16:09:48.538165Z","end":"2026-10-02T16:09:48.693643Z","steps":["trace[1016555972] 'agreement among raft nodes before linearized reading' (duration: 155.315975ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:09:48.694086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.714936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/magnum-api-certs\" ","response":"range_response_count:1 size:2344"} {"level":"info","ts":"2026-10-02T16:09:48.694121Z","caller":"traceutil/trace.go:171","msg":"trace[1550178492] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/magnum-api-certs; range_end:; response_count:1; response_revision:25097; }","duration":"153.777457ms","start":"2026-10-02T16:09:48.540333Z","end":"2026-10-02T16:09:48.69411Z","steps":["trace[1550178492] 'agreement among raft nodes before linearized reading' (duration: 153.686995ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:00.235776Z","caller":"traceutil/trace.go:171","msg":"trace[1087361006] transaction","detail":"{read_only:false; response_revision:25273; number_of_response:1; }","duration":"131.182723ms","start":"2026-10-02T16:10:00.104547Z","end":"2026-10-02T16:10:00.23573Z","steps":["trace[1087361006] 'process raft request' (duration: 116.176731ms)","trace[1087361006] 'compare' (duration: 14.651053ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:00.471013Z","caller":"traceutil/trace.go:171","msg":"trace[1264492032] transaction","detail":"{read_only:false; response_revision:25274; number_of_response:1; }","duration":"230.891637ms","start":"2026-10-02T16:10:00.240076Z","end":"2026-10-02T16:10:00.470967Z","steps":["trace[1264492032] 'process raft request' (duration: 214.8222ms)","trace[1264492032] 'compare' (duration: 15.909643ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:00.704404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.64955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-10-02T16:10:00.704742Z","caller":"traceutil/trace.go:171","msg":"trace[884772258] transaction","detail":"{read_only:false; response_revision:25276; number_of_response:1; }","duration":"456.854405ms","start":"2026-10-02T16:10:00.247866Z","end":"2026-10-02T16:10:00.70472Z","steps":["trace[884772258] 'process raft request' (duration: 456.64988ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:00.704849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:00.247851Z","time spent":"456.941246ms","remote":"127.0.0.1:45062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:00.704899Z","caller":"traceutil/trace.go:171","msg":"trace[1859195124] transaction","detail":"{read_only:false; response_revision:25275; number_of_response:1; }","duration":"462.06146ms","start":"2026-10-02T16:10:00.242791Z","end":"2026-10-02T16:10:00.704853Z","steps":["trace[1859195124] 'process raft request' (duration: 359.860526ms)","trace[1859195124] 'compare' (duration: 101.442906ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:00.705006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:00.242767Z","time spent":"462.192103ms","remote":"127.0.0.1:44894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-02T16:10:00.705019Z","caller":"traceutil/trace.go:171","msg":"trace[388841018] transaction","detail":"{read_only:false; response_revision:25277; number_of_response:1; }","duration":"217.206277ms","start":"2026-10-02T16:10:00.487801Z","end":"2026-10-02T16:10:00.705007Z","steps":["trace[388841018] 'process raft request' (duration: 216.812737ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:00.705443Z","caller":"traceutil/trace.go:171","msg":"trace[531717581] linearizableReadLoop","detail":"{readStateIndex:26101; appliedIndex:26099; }","duration":"461.511746ms","start":"2026-10-02T16:10:00.243902Z","end":"2026-10-02T16:10:00.705414Z","steps":["trace[531717581] 'read index received' (duration: 211.009407ms)","trace[531717581] 'applied index is now lower than readState.Index' (duration: 250.499399ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:00.7057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.762432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T16:10:00.705794Z","caller":"traceutil/trace.go:171","msg":"trace[537973605] range","detail":"{range_begin:/registry/limitranges/openstack/; range_end:/registry/limitranges/openstack0; response_count:0; response_revision:25277; }","duration":"461.915066ms","start":"2026-10-02T16:10:00.243856Z","end":"2026-10-02T16:10:00.705771Z","steps":["trace[537973605] 'agreement among raft nodes before linearized reading' (duration: 461.66643ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:00.705835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:00.243844Z","time spent":"461.981357ms","remote":"127.0.0.1:44960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/openstack/\" range_end:\"/registry/limitranges/openstack0\" "} {"level":"warn","ts":"2026-10-02T16:10:00.705907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.781502ms","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-10-02T16:10:00.705977Z","caller":"traceutil/trace.go:171","msg":"trace[866254471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25277; }","duration":"125.890745ms","start":"2026-10-02T16:10:00.58007Z","end":"2026-10-02T16:10:00.705961Z","steps":["trace[866254471] 'agreement among raft nodes before linearized reading' (duration: 125.681199ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:00.706086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.013697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19028"} {"level":"info","ts":"2026-10-02T16:10:00.706185Z","caller":"traceutil/trace.go:171","msg":"trace[1018315395] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25277; }","duration":"267.355646ms","start":"2026-10-02T16:10:00.438771Z","end":"2026-10-02T16:10:00.706126Z","steps":["trace[1018315395] 'agreement among raft nodes before linearized reading' (duration: 266.922855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:00.706512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.503504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-10-02T16:10:00.706183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.594794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T16:10:00.7066Z","caller":"traceutil/trace.go:171","msg":"trace[418033812] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:25277; }","duration":"239.624737ms","start":"2026-10-02T16:10:00.466961Z","end":"2026-10-02T16:10:00.706586Z","steps":["trace[418033812] 'agreement among raft nodes before linearized reading' (duration: 239.383481ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:00.706643Z","caller":"traceutil/trace.go:171","msg":"trace[2124120609] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:25277; }","duration":"108.098615ms","start":"2026-10-02T16:10:00.598522Z","end":"2026-10-02T16:10:00.706621Z","steps":["trace[2124120609] 'agreement among raft nodes before linearized reading' (duration: 107.618684ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:00.859155Z","caller":"traceutil/trace.go:171","msg":"trace[623443670] transaction","detail":"{read_only:false; response_revision:25278; number_of_response:1; }","duration":"150.380336ms","start":"2026-10-02T16:10:00.708737Z","end":"2026-10-02T16:10:00.859117Z","steps":["trace[623443670] 'process raft request' (duration: 115.805133ms)","trace[623443670] 'compare' (duration: 34.340848ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:00.859177Z","caller":"traceutil/trace.go:171","msg":"trace[348387665] linearizableReadLoop","detail":"{readStateIndex:26105; appliedIndex:26103; }","duration":"146.279427ms","start":"2026-10-02T16:10:00.712872Z","end":"2026-10-02T16:10:00.859151Z","steps":["trace[348387665] 'read index received' (duration: 111.627272ms)","trace[348387665] 'applied index is now lower than readState.Index' (duration: 34.648815ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:00.859227Z","caller":"traceutil/trace.go:171","msg":"trace[1678576047] transaction","detail":"{read_only:false; response_revision:25280; number_of_response:1; }","duration":"120.131146ms","start":"2026-10-02T16:10:00.739067Z","end":"2026-10-02T16:10:00.859198Z","steps":["trace[1678576047] 'process raft request' (duration: 120.042374ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:00.859236Z","caller":"traceutil/trace.go:171","msg":"trace[1987859744] transaction","detail":"{read_only:false; response_revision:25279; number_of_response:1; }","duration":"148.967052ms","start":"2026-10-02T16:10:00.710215Z","end":"2026-10-02T16:10:00.859182Z","steps":["trace[1987859744] 'process raft request' (duration: 148.824158ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:00.85941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.516103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19028"} {"level":"info","ts":"2026-10-02T16:10:00.859686Z","caller":"traceutil/trace.go:171","msg":"trace[1921200602] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25280; }","duration":"146.591624ms","start":"2026-10-02T16:10:00.712841Z","end":"2026-10-02T16:10:00.859433Z","steps":["trace[1921200602] 'agreement among raft nodes before linearized reading' (duration: 146.44794ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:01.05081Z","caller":"traceutil/trace.go:171","msg":"trace[1828998756] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"185.540723ms","start":"2026-10-02T16:10:00.865235Z","end":"2026-10-02T16:10:01.050776Z","steps":["trace[1828998756] 'process raft request' (duration: 147.955027ms)","trace[1828998756] 'compare' (duration: 37.424692ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:01.360415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.223854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T16:10:01.360649Z","caller":"traceutil/trace.go:171","msg":"trace[1505252828] transaction","detail":"{read_only:false; response_revision:25282; number_of_response:1; }","duration":"495.378373ms","start":"2026-10-02T16:10:00.865237Z","end":"2026-10-02T16:10:01.360616Z","steps":["trace[1505252828] 'process raft request' (duration: 358.863962ms)","trace[1505252828] 'compare' (duration: 135.868995ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:01.360699Z","caller":"traceutil/trace.go:171","msg":"trace[2035025894] transaction","detail":"{read_only:false; response_revision:25283; number_of_response:1; }","duration":"495.176299ms","start":"2026-10-02T16:10:00.865479Z","end":"2026-10-02T16:10:01.360655Z","steps":["trace[2035025894] 'process raft request' (duration: 495.039866ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:01.360775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:00.865457Z","time spent":"495.285491ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T16:10:01.360819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:00.865212Z","time spent":"495.526137ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5660,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:01.360816Z","caller":"traceutil/trace.go:171","msg":"trace[943726660] linearizableReadLoop","detail":"{readStateIndex:26110; appliedIndex:26106; }","duration":"414.427791ms","start":"2026-10-02T16:10:00.946364Z","end":"2026-10-02T16:10:01.360792Z","steps":["trace[943726660] 'read index received' (duration: 66.853651ms)","trace[943726660] 'applied index is now lower than readState.Index' (duration: 347.57302ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:01.360962Z","caller":"traceutil/trace.go:171","msg":"trace[911459563] transaction","detail":"{read_only:false; response_revision:25284; number_of_response:1; }","duration":"492.30488ms","start":"2026-10-02T16:10:00.868624Z","end":"2026-10-02T16:10:01.360928Z","steps":["trace[911459563] 'process raft request' (duration: 491.979522ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:01.361102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:00.868605Z","time spent":"492.420412ms","remote":"127.0.0.1:45050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5599,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T16:10:01.361159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.77329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.20\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-10-02T16:10:01.361235Z","caller":"traceutil/trace.go:171","msg":"trace[1271059287] range","detail":"{range_begin:/registry/masterleases/199.204.45.20; range_end:; response_count:1; response_revision:25284; }","duration":"414.910633ms","start":"2026-10-02T16:10:00.946306Z","end":"2026-10-02T16:10:01.361216Z","steps":["trace[1271059287] 'agreement among raft nodes before linearized reading' (duration: 414.623967ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:01.361278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:00.946283Z","time spent":"414.985955ms","remote":"127.0.0.1:44864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/199.204.45.20\" "} {"level":"info","ts":"2026-10-02T16:10:01.48061Z","caller":"traceutil/trace.go:171","msg":"trace[1202177273] transaction","detail":"{read_only:false; response_revision:25285; number_of_response:1; }","duration":"357.287605ms","start":"2026-10-02T16:10:01.123295Z","end":"2026-10-02T16:10:01.480583Z","steps":["trace[1202177273] 'process raft request' (duration: 347.290043ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:01.480616Z","caller":"traceutil/trace.go:171","msg":"trace[768351948] transaction","detail":"{read_only:false; response_revision:25286; number_of_response:1; }","duration":"352.734084ms","start":"2026-10-02T16:10:01.127861Z","end":"2026-10-02T16:10:01.480595Z","steps":["trace[768351948] 'process raft request' (duration: 352.635171ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:01.480795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:01.123269Z","time spent":"357.429817ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T16:10:01.480806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:01.127835Z","time spent":"352.871248ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:01.480776Z","caller":"traceutil/trace.go:171","msg":"trace[2135567507] transaction","detail":"{read_only:false; response_revision:25287; number_of_response:1; }","duration":"115.665439ms","start":"2026-10-02T16:10:01.365084Z","end":"2026-10-02T16:10:01.480749Z","steps":["trace[2135567507] 'process raft request' (duration: 115.553446ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:01.480855Z","caller":"traceutil/trace.go:171","msg":"trace[1250014464] linearizableReadLoop","detail":"{readStateIndex:26112; appliedIndex:26110; }","duration":"119.971793ms","start":"2026-10-02T16:10:01.360867Z","end":"2026-10-02T16:10:01.480839Z","steps":["trace[1250014464] 'read index received' (duration: 109.718775ms)","trace[1250014464] 'applied index is now lower than readState.Index' (duration: 10.251888ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:01.481056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.24321ms","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-10-02T16:10:01.481091Z","caller":"traceutil/trace.go:171","msg":"trace[704724980] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25287; }","duration":"293.325323ms","start":"2026-10-02T16:10:01.187754Z","end":"2026-10-02T16:10:01.481079Z","steps":["trace[704724980] 'agreement among raft nodes before linearized reading' (duration: 293.134628ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:01.480978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.395075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T16:10:01.481103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.3538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-02T16:10:01.481507Z","caller":"traceutil/trace.go:171","msg":"trace[1290294191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25287; }","duration":"359.571539ms","start":"2026-10-02T16:10:01.12154Z","end":"2026-10-02T16:10:01.481111Z","steps":["trace[1290294191] 'agreement among raft nodes before linearized reading' (duration: 359.363894ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:01.482141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:01.121524Z","time spent":"360.607554ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-02T16:10:01.48159Z","caller":"traceutil/trace.go:171","msg":"trace[357724960] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25287; }","duration":"504.385091ms","start":"2026-10-02T16:10:00.976743Z","end":"2026-10-02T16:10:01.481128Z","steps":["trace[357724960] 'agreement among raft nodes before linearized reading' (duration: 504.270168ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:01.482227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:00.976689Z","time spent":"505.521798ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-02T16:10:04.699987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.070708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T16:10:04.700096Z","caller":"traceutil/trace.go:171","msg":"trace[1703138534] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:25319; }","duration":"116.203211ms","start":"2026-10-02T16:10:04.583864Z","end":"2026-10-02T16:10:04.700067Z","steps":["trace[1703138534] 'count revisions from in-memory index tree' (duration: 115.917384ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:04.700117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.074766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T16:10:04.700237Z","caller":"traceutil/trace.go:171","msg":"trace[1289340548] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:25319; }","duration":"113.21158ms","start":"2026-10-02T16:10:04.586971Z","end":"2026-10-02T16:10:04.700183Z","steps":["trace[1289340548] 'count revisions from in-memory index tree' (duration: 112.979784ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:04.824126Z","caller":"traceutil/trace.go:171","msg":"trace[102978605] transaction","detail":"{read_only:false; response_revision:25320; number_of_response:1; }","duration":"106.347865ms","start":"2026-10-02T16:10:04.717751Z","end":"2026-10-02T16:10:04.824099Z","steps":["trace[102978605] 'process raft request' (duration: 106.185581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:05.120949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.137292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T16:10:05.121043Z","caller":"traceutil/trace.go:171","msg":"trace[1605830128] linearizableReadLoop","detail":"{readStateIndex:26148; appliedIndex:26147; }","duration":"234.084584ms","start":"2026-10-02T16:10:04.88694Z","end":"2026-10-02T16:10:05.121025Z","steps":["trace[1605830128] 'read index received' (duration: 97.656415ms)","trace[1605830128] 'applied index is now lower than readState.Index' (duration: 136.427069ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:05.122508Z","caller":"traceutil/trace.go:171","msg":"trace[811763649] transaction","detail":"{read_only:false; response_revision:25321; number_of_response:1; }","duration":"250.796166ms","start":"2026-10-02T16:10:04.871689Z","end":"2026-10-02T16:10:05.122485Z","steps":["trace[811763649] 'process raft request' (duration: 113.037095ms)","trace[811763649] 'compare' (duration: 136.026299ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:05.124096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.144718ms","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-10-02T16:10:05.124163Z","caller":"traceutil/trace.go:171","msg":"trace[1315511397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25321; }","duration":"237.24424ms","start":"2026-10-02T16:10:04.886896Z","end":"2026-10-02T16:10:05.12414Z","steps":["trace[1315511397] 'agreement among raft nodes before linearized reading' (duration: 234.169256ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:05.3149Z","caller":"traceutil/trace.go:171","msg":"trace[882174433] linearizableReadLoop","detail":"{readStateIndex:26149; appliedIndex:26148; }","duration":"112.283317ms","start":"2026-10-02T16:10:05.202594Z","end":"2026-10-02T16:10:05.314877Z","steps":["trace[882174433] 'read index received' (duration: 112.00727ms)","trace[882174433] 'applied index is now lower than readState.Index' (duration: 275.027µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:05.315033Z","caller":"traceutil/trace.go:171","msg":"trace[199231316] transaction","detail":"{read_only:false; response_revision:25322; number_of_response:1; }","duration":"182.842618ms","start":"2026-10-02T16:10:05.132176Z","end":"2026-10-02T16:10:05.315019Z","steps":["trace[199231316] 'process raft request' (duration: 182.51934ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:05.315234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.604865ms","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-10-02T16:10:05.315316Z","caller":"traceutil/trace.go:171","msg":"trace[1932861508] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25322; }","duration":"112.739818ms","start":"2026-10-02T16:10:05.202554Z","end":"2026-10-02T16:10:05.315294Z","steps":["trace[1932861508] 'agreement among raft nodes before linearized reading' (duration: 112.481571ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:05.777737Z","caller":"traceutil/trace.go:171","msg":"trace[292835318] transaction","detail":"{read_only:false; response_revision:25327; number_of_response:1; }","duration":"220.121547ms","start":"2026-10-02T16:10:05.557287Z","end":"2026-10-02T16:10:05.777408Z","steps":["trace[292835318] 'process raft request' (duration: 219.925552ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:06.038967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.770692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T16:10:06.039072Z","caller":"traceutil/trace.go:171","msg":"trace[936380348] transaction","detail":"{read_only:false; response_revision:25328; number_of_response:1; }","duration":"451.612298ms","start":"2026-10-02T16:10:05.587447Z","end":"2026-10-02T16:10:06.039059Z","steps":["trace[936380348] 'process raft request' (duration: 291.694573ms)","trace[936380348] 'compare' (duration: 159.669499ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:06.039111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:05.587423Z","time spent":"451.669569ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:06.039306Z","caller":"traceutil/trace.go:171","msg":"trace[1365562669] transaction","detail":"{read_only:false; response_revision:25329; number_of_response:1; }","duration":"351.863954ms","start":"2026-10-02T16:10:05.687421Z","end":"2026-10-02T16:10:06.039285Z","steps":["trace[1365562669] 'process raft request' (duration: 351.608317ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:06.03943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:05.687398Z","time spent":"351.954085ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:06.039496Z","caller":"traceutil/trace.go:171","msg":"trace[1933158893] transaction","detail":"{read_only:false; response_revision:25330; number_of_response:1; }","duration":"310.697651ms","start":"2026-10-02T16:10:05.728765Z","end":"2026-10-02T16:10:06.039463Z","steps":["trace[1933158893] 'process raft request' (duration: 310.445895ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:06.039678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:05.728744Z","time spent":"310.826923ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T16:10:06.424285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.554559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T16:10:06.424462Z","caller":"traceutil/trace.go:171","msg":"trace[216410929] transaction","detail":"{read_only:false; response_revision:25331; number_of_response:1; }","duration":"369.806576ms","start":"2026-10-02T16:10:06.054602Z","end":"2026-10-02T16:10:06.424408Z","steps":["trace[216410929] 'process raft request' (duration: 139.008021ms)","trace[216410929] 'compare' (duration: 230.264792ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:06.424605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:06.054581Z","time spent":"369.95151ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:06.424881Z","caller":"traceutil/trace.go:171","msg":"trace[1414897738] linearizableReadLoop","detail":"{readStateIndex:26158; appliedIndex:26157; }","duration":"359.281542ms","start":"2026-10-02T16:10:06.065588Z","end":"2026-10-02T16:10:06.42487Z","steps":["trace[1414897738] 'read index received' (duration: 128.033697ms)","trace[1414897738] 'applied index is now lower than readState.Index' (duration: 231.246675ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:06.53477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.159391ms","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-10-02T16:10:06.534863Z","caller":"traceutil/trace.go:171","msg":"trace[2142381099] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25331; }","duration":"469.285134ms","start":"2026-10-02T16:10:06.065553Z","end":"2026-10-02T16:10:06.534838Z","steps":["trace[2142381099] 'agreement among raft nodes before linearized reading' (duration: 359.357894ms)","trace[2142381099] 'range keys from in-memory index tree' (duration: 109.697285ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:06.534899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:06.065533Z","time spent":"469.356845ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-02T16:10:06.5349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.501994ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-10-02T16:10:06.861254Z","caller":"traceutil/trace.go:171","msg":"trace[1262828272] linearizableReadLoop","detail":"{readStateIndex:26161; appliedIndex:26159; }","duration":"316.503521ms","start":"2026-10-02T16:10:06.544715Z","end":"2026-10-02T16:10:06.861218Z","steps":["trace[1262828272] 'read index received' (duration: 90.532723ms)","trace[1262828272] 'applied index is now lower than readState.Index' (duration: 225.969878ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:06.86136Z","caller":"traceutil/trace.go:171","msg":"trace[222362601] transaction","detail":"{read_only:false; response_revision:25333; number_of_response:1; }","duration":"321.766458ms","start":"2026-10-02T16:10:06.539584Z","end":"2026-10-02T16:10:06.86135Z","steps":["trace[222362601] 'process raft request' (duration: 321.457751ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:06.861455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:06.53956Z","time spent":"321.817989ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T16:10:06.861673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.851229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T16:10:06.861697Z","caller":"traceutil/trace.go:171","msg":"trace[826831478] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:25333; }","duration":"317.008342ms","start":"2026-10-02T16:10:06.544679Z","end":"2026-10-02T16:10:06.861688Z","steps":["trace[826831478] 'agreement among raft nodes before linearized reading' (duration: 316.857379ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:06.861715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:06.544647Z","time spent":"317.062474ms","remote":"127.0.0.1:59450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true "} {"level":"warn","ts":"2026-10-02T16:10:07.089841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.898562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T16:10:07.089963Z","caller":"traceutil/trace.go:171","msg":"trace[1055050155] linearizableReadLoop","detail":"{readStateIndex:26162; appliedIndex:26161; }","duration":"228.638263ms","start":"2026-10-02T16:10:06.861304Z","end":"2026-10-02T16:10:07.089942Z","steps":["trace[1055050155] 'read index received' (duration: 111.132989ms)","trace[1055050155] 'applied index is now lower than readState.Index' (duration: 117.503744ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:07.090079Z","caller":"traceutil/trace.go:171","msg":"trace[526963373] transaction","detail":"{read_only:false; response_revision:25334; number_of_response:1; }","duration":"261.099185ms","start":"2026-10-02T16:10:06.828963Z","end":"2026-10-02T16:10:07.090062Z","steps":["trace[526963373] 'process raft request' (duration: 142.874135ms)","trace[526963373] 'compare' (duration: 117.592455ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:07.090518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.483741ms","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-10-02T16:10:07.090581Z","caller":"traceutil/trace.go:171","msg":"trace[2046134392] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25334; }","duration":"433.578844ms","start":"2026-10-02T16:10:06.656986Z","end":"2026-10-02T16:10:07.090565Z","steps":["trace[2046134392] 'agreement among raft nodes before linearized reading' (duration: 433.177794ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:07.090628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:06.656969Z","time spent":"433.643975ms","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":380,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-10-02T16:10:07.713215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.7191ms","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-10-02T16:10:07.713374Z","caller":"traceutil/trace.go:171","msg":"trace[106491815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25336; }","duration":"232.934245ms","start":"2026-10-02T16:10:07.48042Z","end":"2026-10-02T16:10:07.713354Z","steps":["trace[106491815] 'range keys from in-memory index tree' (duration: 232.514045ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:07.713237Z","caller":"traceutil/trace.go:171","msg":"trace[2062666265] transaction","detail":"{read_only:false; response_revision:25337; number_of_response:1; }","duration":"157.661831ms","start":"2026-10-02T16:10:07.555545Z","end":"2026-10-02T16:10:07.713207Z","steps":["trace[2062666265] 'process raft request' (duration: 125.552887ms)","trace[2062666265] 'compare' (duration: 31.799226ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:08.092872Z","caller":"traceutil/trace.go:171","msg":"trace[485957738] transaction","detail":"{read_only:false; response_revision:25338; number_of_response:1; }","duration":"535.931181ms","start":"2026-10-02T16:10:07.556892Z","end":"2026-10-02T16:10:08.092823Z","steps":["trace[485957738] 'process raft request' (duration: 520.067269ms)","trace[485957738] 'compare' (duration: 15.332889ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:08.092982Z","caller":"traceutil/trace.go:171","msg":"trace[77113594] linearizableReadLoop","detail":"{readStateIndex:26166; appliedIndex:26164; }","duration":"523.222324ms","start":"2026-10-02T16:10:07.569733Z","end":"2026-10-02T16:10:08.092955Z","steps":["trace[77113594] 'read index received' (duration: 111.483038ms)","trace[77113594] 'applied index is now lower than readState.Index' (duration: 411.736136ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:08.093012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:07.556865Z","time spent":"536.068394ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:08.093048Z","caller":"traceutil/trace.go:171","msg":"trace[1595485289] transaction","detail":"{read_only:false; response_revision:25339; number_of_response:1; }","duration":"375.067182ms","start":"2026-10-02T16:10:07.717954Z","end":"2026-10-02T16:10:08.093021Z","steps":["trace[1595485289] 'process raft request' (duration: 374.798396ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:08.093264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.519252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-02T16:10:08.09328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:07.71791Z","time spent":"375.235987ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:08.093343Z","caller":"traceutil/trace.go:171","msg":"trace[1309776324] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25340; }","duration":"523.642794ms","start":"2026-10-02T16:10:07.569679Z","end":"2026-10-02T16:10:08.093322Z","steps":["trace[1309776324] 'agreement among raft nodes before linearized reading' (duration: 523.380058ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:08.09339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:07.569645Z","time spent":"523.735527ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-10-02T16:10:08.093539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.786897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-02T16:10:08.093616Z","caller":"traceutil/trace.go:171","msg":"trace[1536316333] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25342; }","duration":"311.90383ms","start":"2026-10-02T16:10:07.781692Z","end":"2026-10-02T16:10:08.093595Z","steps":["trace[1536316333] 'agreement among raft nodes before linearized reading' (duration: 311.697205ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:08.093679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:07.781651Z","time spent":"311.998462ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-02T16:10:08.46176Z","caller":"traceutil/trace.go:171","msg":"trace[1061000610] transaction","detail":"{read_only:false; response_revision:25344; number_of_response:1; }","duration":"361.569447ms","start":"2026-10-02T16:10:08.100166Z","end":"2026-10-02T16:10:08.461736Z","steps":["trace[1061000610] 'process raft request' (duration: 361.350061ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:08.461899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:08.100145Z","time spent":"361.68532ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:08.462155Z","caller":"traceutil/trace.go:171","msg":"trace[372127957] linearizableReadLoop","detail":"{readStateIndex:26173; appliedIndex:26171; }","duration":"333.398348ms","start":"2026-10-02T16:10:08.128729Z","end":"2026-10-02T16:10:08.462128Z","steps":["trace[372127957] 'read index received' (duration: 332.736522ms)","trace[372127957] 'applied index is now lower than readState.Index' (duration: 659.306µs)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:08.462473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.287614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T16:10:08.463148Z","caller":"traceutil/trace.go:171","msg":"trace[964964354] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25345; }","duration":"328.989171ms","start":"2026-10-02T16:10:08.134105Z","end":"2026-10-02T16:10:08.463095Z","steps":["trace[964964354] 'agreement among raft nodes before linearized reading' (duration: 328.229753ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:08.463261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:08.134046Z","time spent":"329.178896ms","remote":"127.0.0.1:45094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-10-02T16:10:08.462876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.227914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T16:10:08.463555Z","caller":"traceutil/trace.go:171","msg":"trace[1227270945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25345; }","duration":"338.952412ms","start":"2026-10-02T16:10:08.12459Z","end":"2026-10-02T16:10:08.463543Z","steps":["trace[1227270945] 'agreement among raft nodes before linearized reading' (duration: 337.678181ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:08.463588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:08.124572Z","time spent":"339.005823ms","remote":"127.0.0.1:44838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-02T16:10:08.462293Z","caller":"traceutil/trace.go:171","msg":"trace[1903337422] transaction","detail":"{read_only:false; response_revision:25345; number_of_response:1; }","duration":"335.879398ms","start":"2026-10-02T16:10:08.126383Z","end":"2026-10-02T16:10:08.462263Z","steps":["trace[1903337422] 'process raft request' (duration: 335.309674ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:08.463858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:08.126367Z","time spent":"337.426335ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:12.639261Z","caller":"traceutil/trace.go:171","msg":"trace[1498834300] transaction","detail":"{read_only:false; response_revision:25381; number_of_response:1; }","duration":"147.347251ms","start":"2026-10-02T16:10:12.491887Z","end":"2026-10-02T16:10:12.639234Z","steps":["trace[1498834300] 'process raft request' (duration: 147.186768ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:12.849993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.651233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-02T16:10:12.85007Z","caller":"traceutil/trace.go:171","msg":"trace[474193893] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25381; }","duration":"194.781546ms","start":"2026-10-02T16:10:12.65527Z","end":"2026-10-02T16:10:12.850052Z","steps":["trace[474193893] 'range keys from in-memory index tree' (duration: 194.403907ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:12.980285Z","caller":"traceutil/trace.go:171","msg":"trace[1293202557] transaction","detail":"{read_only:false; response_revision:25382; number_of_response:1; }","duration":"125.28442ms","start":"2026-10-02T16:10:12.854977Z","end":"2026-10-02T16:10:12.980261Z","steps":["trace[1293202557] 'process raft request' (duration: 124.87175ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:13.256074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.059462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T16:10:13.25616Z","caller":"traceutil/trace.go:171","msg":"trace[2141112139] transaction","detail":"{read_only:false; response_revision:25383; number_of_response:1; }","duration":"271.424764ms","start":"2026-10-02T16:10:12.984721Z","end":"2026-10-02T16:10:13.256145Z","steps":["trace[2141112139] 'process raft request' (duration: 154.226808ms)","trace[2141112139] 'compare' (duration: 116.863868ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:13.351937Z","caller":"traceutil/trace.go:171","msg":"trace[280545118] transaction","detail":"{read_only:false; response_revision:25384; number_of_response:1; }","duration":"239.929544ms","start":"2026-10-02T16:10:13.111978Z","end":"2026-10-02T16:10:13.351908Z","steps":["trace[280545118] 'process raft request' (duration: 239.822072ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:13.471774Z","caller":"traceutil/trace.go:171","msg":"trace[227323875] transaction","detail":"{read_only:false; response_revision:25385; number_of_response:1; }","duration":"301.11923ms","start":"2026-10-02T16:10:13.170621Z","end":"2026-10-02T16:10:13.47174Z","steps":["trace[227323875] 'process raft request' (duration: 289.833007ms)","trace[227323875] 'compare' (duration: 10.7684ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:13.471932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:13.170601Z","time spent":"301.255373ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:13.471936Z","caller":"traceutil/trace.go:171","msg":"trace[1084774253] transaction","detail":"{read_only:false; response_revision:25386; number_of_response:1; }","duration":"230.931148ms","start":"2026-10-02T16:10:13.240975Z","end":"2026-10-02T16:10:13.471906Z","steps":["trace[1084774253] 'process raft request' (duration: 230.61243ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:13.616891Z","caller":"traceutil/trace.go:171","msg":"trace[1683882771] transaction","detail":"{read_only:false; response_revision:25387; number_of_response:1; }","duration":"147.612109ms","start":"2026-10-02T16:10:13.469254Z","end":"2026-10-02T16:10:13.616867Z","steps":["trace[1683882771] 'process raft request' (duration: 137.006753ms)","trace[1683882771] 'compare' (duration: 10.437682ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:13.617076Z","caller":"traceutil/trace.go:171","msg":"trace[193607349] transaction","detail":"{read_only:false; response_revision:25388; number_of_response:1; }","duration":"140.042436ms","start":"2026-10-02T16:10:13.477008Z","end":"2026-10-02T16:10:13.61705Z","steps":["trace[193607349] 'process raft request' (duration: 139.817921ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:13.854572Z","caller":"traceutil/trace.go:171","msg":"trace[966212024] transaction","detail":"{read_only:false; response_revision:25389; number_of_response:1; }","duration":"123.901817ms","start":"2026-10-02T16:10:13.730643Z","end":"2026-10-02T16:10:13.854545Z","steps":["trace[966212024] 'process raft request' (duration: 123.722783ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:15.375297Z","caller":"traceutil/trace.go:171","msg":"trace[1736825487] transaction","detail":"{read_only:false; response_revision:25400; number_of_response:1; }","duration":"112.083903ms","start":"2026-10-02T16:10:15.263192Z","end":"2026-10-02T16:10:15.375276Z","steps":["trace[1736825487] 'process raft request' (duration: 111.935729ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:15.535206Z","caller":"traceutil/trace.go:171","msg":"trace[1340983193] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"178.441872ms","start":"2026-10-02T16:10:15.356738Z","end":"2026-10-02T16:10:15.53518Z","steps":["trace[1340983193] 'process raft request' (duration: 172.094529ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:15.77374Z","caller":"traceutil/trace.go:171","msg":"trace[980823693] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"147.449724ms","start":"2026-10-02T16:10:15.626255Z","end":"2026-10-02T16:10:15.773704Z","steps":["trace[980823693] 'process raft request' (duration: 120.214698ms)","trace[980823693] 'compare' (duration: 26.860697ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:22.437108Z","caller":"traceutil/trace.go:171","msg":"trace[1978630764] linearizableReadLoop","detail":"{readStateIndex:26352; appliedIndex:26348; }","duration":"270.180794ms","start":"2026-10-02T16:10:22.166912Z","end":"2026-10-02T16:10:22.437093Z","steps":["trace[1978630764] 'read index received' (duration: 79.6416ms)","trace[1978630764] 'applied index is now lower than readState.Index' (duration: 190.538514ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:22.437142Z","caller":"traceutil/trace.go:171","msg":"trace[70567940] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"282.88608ms","start":"2026-10-02T16:10:22.154217Z","end":"2026-10-02T16:10:22.437103Z","steps":["trace[70567940] 'process raft request' (duration: 183.078134ms)","trace[70567940] 'compare' (duration: 99.643602ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:22.437165Z","caller":"traceutil/trace.go:171","msg":"trace[2048178148] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"282.899291ms","start":"2026-10-02T16:10:22.154241Z","end":"2026-10-02T16:10:22.43714Z","steps":["trace[2048178148] 'process raft request' (duration: 282.796388ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:22.437195Z","caller":"traceutil/trace.go:171","msg":"trace[1227796993] transaction","detail":"{read_only:false; response_revision:25520; number_of_response:1; }","duration":"278.513065ms","start":"2026-10-02T16:10:22.158651Z","end":"2026-10-02T16:10:22.437164Z","steps":["trace[1227796993] 'process raft request' (duration: 278.414532ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:22.437265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.843362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-10-02T16:10:22.43729Z","caller":"traceutil/trace.go:171","msg":"trace[123856349] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25520; }","duration":"234.914263ms","start":"2026-10-02T16:10:22.20237Z","end":"2026-10-02T16:10:22.437284Z","steps":["trace[123856349] 'agreement among raft nodes before linearized reading' (duration: 234.818131ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:22.437355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.464899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T16:10:22.437369Z","caller":"traceutil/trace.go:171","msg":"trace[1545515055] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:25520; }","duration":"143.51744ms","start":"2026-10-02T16:10:22.293846Z","end":"2026-10-02T16:10:22.437363Z","steps":["trace[1545515055] 'agreement among raft nodes before linearized reading' (duration: 143.48984ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:22.437388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.089849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T16:10:22.437438Z","caller":"traceutil/trace.go:171","msg":"trace[703710676] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:25520; }","duration":"121.180012ms","start":"2026-10-02T16:10:22.316239Z","end":"2026-10-02T16:10:22.437419Z","steps":["trace[703710676] 'agreement among raft nodes before linearized reading' (duration: 121.090779ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:22.437455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.118623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-369b0fcd-6550-4b13-9ff8-011031b3e397\" ","response":"range_response_count:1 size:3643"} {"level":"warn","ts":"2026-10-02T16:10:22.437434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.498571ms","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-10-02T16:10:22.437531Z","caller":"traceutil/trace.go:171","msg":"trace[1583336148] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-369b0fcd-6550-4b13-9ff8-011031b3e397; range_end:; response_count:1; response_revision:25520; }","duration":"112.240576ms","start":"2026-10-02T16:10:22.325272Z","end":"2026-10-02T16:10:22.437512Z","steps":["trace[1583336148] 'agreement among raft nodes before linearized reading' (duration: 112.036491ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:22.437581Z","caller":"traceutil/trace.go:171","msg":"trace[1365546172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25520; }","duration":"270.645525ms","start":"2026-10-02T16:10:22.166879Z","end":"2026-10-02T16:10:22.437524Z","steps":["trace[1365546172] 'agreement among raft nodes before linearized reading' (duration: 270.296327ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:22.686734Z","caller":"traceutil/trace.go:171","msg":"trace[1539797829] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"244.868204ms","start":"2026-10-02T16:10:22.441831Z","end":"2026-10-02T16:10:22.686699Z","steps":["trace[1539797829] 'process raft request' (duration: 244.7045ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:22.885987Z","caller":"traceutil/trace.go:171","msg":"trace[936230785] linearizableReadLoop","detail":"{readStateIndex:26355; appliedIndex:26352; }","duration":"250.950101ms","start":"2026-10-02T16:10:22.635016Z","end":"2026-10-02T16:10:22.885966Z","steps":["trace[936230785] 'read index received' (duration: 51.566044ms)","trace[936230785] 'applied index is now lower than readState.Index' (duration: 199.383007ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:22.886104Z","caller":"traceutil/trace.go:171","msg":"trace[743295962] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"443.028471ms","start":"2026-10-02T16:10:22.443062Z","end":"2026-10-02T16:10:22.886091Z","steps":["trace[743295962] 'process raft request' (duration: 432.327533ms)","trace[743295962] 'compare' (duration: 10.028812ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:22.886202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:22.443051Z","time spent":"443.082463ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:22.88622Z","caller":"traceutil/trace.go:171","msg":"trace[9207908] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"440.413338ms","start":"2026-10-02T16:10:22.445765Z","end":"2026-10-02T16:10:22.886178Z","steps":["trace[9207908] 'process raft request' (duration: 440.07091ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:22.886252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.202927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T16:10:22.886329Z","caller":"traceutil/trace.go:171","msg":"trace[1340718987] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:25523; }","duration":"251.296089ms","start":"2026-10-02T16:10:22.63501Z","end":"2026-10-02T16:10:22.886306Z","steps":["trace[1340718987] 'agreement among raft nodes before linearized reading' (duration: 251.100604ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:22.886338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.665823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-10-02T16:10:22.886358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:22.445739Z","time spent":"440.545272ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:22.886408Z","caller":"traceutil/trace.go:171","msg":"trace[2140611072] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25523; }","duration":"189.746065ms","start":"2026-10-02T16:10:22.696645Z","end":"2026-10-02T16:10:22.886391Z","steps":["trace[2140611072] 'agreement among raft nodes before linearized reading' (duration: 189.55201ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:23.923762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.067011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T16:10:23.923861Z","caller":"traceutil/trace.go:171","msg":"trace[1894843827] transaction","detail":"{read_only:false; response_revision:25527; number_of_response:1; }","duration":"421.124773ms","start":"2026-10-02T16:10:23.502718Z","end":"2026-10-02T16:10:23.923843Z","steps":["trace[1894843827] 'process raft request' (duration: 189.849177ms)","trace[1894843827] 'compare' (duration: 230.788004ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:23.923918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:23.502693Z","time spent":"421.197184ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T16:10:24.460988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.604701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T16:10:24.461226Z","caller":"traceutil/trace.go:171","msg":"trace[1743550294] transaction","detail":"{read_only:false; response_revision:25531; number_of_response:1; }","duration":"569.164042ms","start":"2026-10-02T16:10:23.892044Z","end":"2026-10-02T16:10:24.461209Z","steps":["trace[1743550294] 'process raft request' (duration: 569.127421ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:24.4613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:23.892021Z","time spent":"569.245284ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:24.461403Z","caller":"traceutil/trace.go:171","msg":"trace[1694599353] linearizableReadLoop","detail":"{readStateIndex:26362; appliedIndex:26358; }","duration":"804.041205ms","start":"2026-10-02T16:10:23.657344Z","end":"2026-10-02T16:10:24.461386Z","steps":["trace[1694599353] 'read index received' (duration: 35.299861ms)","trace[1694599353] 'applied index is now lower than readState.Index' (duration: 768.739064ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:24.461411Z","caller":"traceutil/trace.go:171","msg":"trace[2084614860] transaction","detail":"{read_only:false; response_revision:25529; number_of_response:1; }","duration":"864.152584ms","start":"2026-10-02T16:10:23.597229Z","end":"2026-10-02T16:10:24.461382Z","steps":["trace[2084614860] 'process raft request' (duration: 863.837147ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:24.461503Z","caller":"traceutil/trace.go:171","msg":"trace[75839778] transaction","detail":"{read_only:false; response_revision:25528; number_of_response:1; }","duration":"897.593651ms","start":"2026-10-02T16:10:23.563898Z","end":"2026-10-02T16:10:24.461491Z","steps":["trace[75839778] 'process raft request' (duration: 493.410706ms)","trace[75839778] 'compare' (duration: 403.438967ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:24.461538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.178378ms","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-10-02T16:10:24.461563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:23.597198Z","time spent":"864.293108ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:24.46158Z","caller":"traceutil/trace.go:171","msg":"trace[242564880] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25531; }","duration":"804.276211ms","start":"2026-10-02T16:10:23.657292Z","end":"2026-10-02T16:10:24.461569Z","steps":["trace[242564880] 'agreement among raft nodes before linearized reading' (duration: 804.168808ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:24.461432Z","caller":"traceutil/trace.go:171","msg":"trace[1168111805] transaction","detail":"{read_only:false; response_revision:25530; number_of_response:1; }","duration":"832.163323ms","start":"2026-10-02T16:10:23.629222Z","end":"2026-10-02T16:10:24.461386Z","steps":["trace[1168111805] 'process raft request' (duration: 831.910377ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:24.461607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:23.563866Z","time spent":"897.675622ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T16:10:24.461617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:23.657273Z","time spent":"804.333972ms","remote":"127.0.0.1:43942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-02T16:10:24.461654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:23.629186Z","time spent":"832.43561ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:24.663323Z","caller":"traceutil/trace.go:171","msg":"trace[471876447] transaction","detail":"{read_only:false; response_revision:25532; number_of_response:1; }","duration":"412.160987ms","start":"2026-10-02T16:10:24.251133Z","end":"2026-10-02T16:10:24.663294Z","steps":["trace[471876447] 'process raft request' (duration: 411.973453ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:24.663503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:24.251109Z","time spent":"412.30126ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:24.667118Z","caller":"traceutil/trace.go:171","msg":"trace[1687218848] transaction","detail":"{read_only:false; response_revision:25533; number_of_response:1; }","duration":"225.304582ms","start":"2026-10-02T16:10:24.441789Z","end":"2026-10-02T16:10:24.667094Z","steps":["trace[1687218848] 'process raft request' (duration: 225.136268ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:24.667353Z","caller":"traceutil/trace.go:171","msg":"trace[71709225] transaction","detail":"{read_only:false; response_revision:25534; number_of_response:1; }","duration":"225.2208ms","start":"2026-10-02T16:10:24.442109Z","end":"2026-10-02T16:10:24.66733Z","steps":["trace[71709225] 'process raft request' (duration: 224.931963ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:24.667504Z","caller":"traceutil/trace.go:171","msg":"trace[326823127] transaction","detail":"{read_only:false; response_revision:25535; number_of_response:1; }","duration":"225.302482ms","start":"2026-10-02T16:10:24.442188Z","end":"2026-10-02T16:10:24.667491Z","steps":["trace[326823127] 'process raft request' (duration: 225.19278ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:24.667501Z","caller":"traceutil/trace.go:171","msg":"trace[319652836] linearizableReadLoop","detail":"{readStateIndex:26367; appliedIndex:26363; }","duration":"206.043178ms","start":"2026-10-02T16:10:24.461436Z","end":"2026-10-02T16:10:24.667479Z","steps":["trace[319652836] 'read index received' (duration: 201.662593ms)","trace[319652836] 'applied index is now lower than readState.Index' (duration: 4.379305ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:24.667748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.127287ms","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-10-02T16:10:24.667753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.595764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T16:10:24.667763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.399904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-369b0fcd-6550-4b13-9ff8-011031b3e397\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-10-02T16:10:24.667802Z","caller":"traceutil/trace.go:171","msg":"trace[221560289] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:25535; }","duration":"125.234149ms","start":"2026-10-02T16:10:24.54255Z","end":"2026-10-02T16:10:24.667784Z","steps":["trace[221560289] 'agreement among raft nodes before linearized reading' (duration: 125.141677ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:24.667813Z","caller":"traceutil/trace.go:171","msg":"trace[378144106] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:25535; }","duration":"842.680607ms","start":"2026-10-02T16:10:23.825112Z","end":"2026-10-02T16:10:24.667793Z","steps":["trace[378144106] 'agreement among raft nodes before linearized reading' (duration: 842.587394ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:24.667837Z","caller":"traceutil/trace.go:171","msg":"trace[468862435] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-369b0fcd-6550-4b13-9ff8-011031b3e397; range_end:; response_count:1; response_revision:25535; }","duration":"534.513117ms","start":"2026-10-02T16:10:24.133308Z","end":"2026-10-02T16:10:24.667821Z","steps":["trace[468862435] 'agreement among raft nodes before linearized reading' (duration: 534.280462ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:24.667821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.615032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-10-02T16:10:24.667858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:23.825098Z","time spent":"842.744418ms","remote":"127.0.0.1:59434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true "} {"level":"warn","ts":"2026-10-02T16:10:24.667842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.949797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-02T16:10:24.667895Z","caller":"traceutil/trace.go:171","msg":"trace[2117514917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25535; }","duration":"856.754346ms","start":"2026-10-02T16:10:23.811123Z","end":"2026-10-02T16:10:24.667878Z","steps":["trace[2117514917] 'agreement among raft nodes before linearized reading' (duration: 856.489089ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:24.667931Z","caller":"traceutil/trace.go:171","msg":"trace[1277363255] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25535; }","duration":"170.098052ms","start":"2026-10-02T16:10:24.497815Z","end":"2026-10-02T16:10:24.667913Z","steps":["trace[1277363255] 'agreement among raft nodes before linearized reading' (duration: 169.833315ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:24.66794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:23.811099Z","time spent":"856.832898ms","remote":"127.0.0.1:45084","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-10-02T16:10:24.668234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:24.133289Z","time spent":"534.890406ms","remote":"127.0.0.1:45008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3786,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-369b0fcd-6550-4b13-9ff8-011031b3e397\" "} {"level":"info","ts":"2026-10-02T16:10:29.658016Z","caller":"traceutil/trace.go:171","msg":"trace[371287075] linearizableReadLoop","detail":"{readStateIndex:26421; appliedIndex:26419; }","duration":"179.517588ms","start":"2026-10-02T16:10:29.478468Z","end":"2026-10-02T16:10:29.657986Z","steps":["trace[371287075] 'read index received' (duration: 57.788243ms)","trace[371287075] 'applied index is now lower than readState.Index' (duration: 121.727855ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:29.658116Z","caller":"traceutil/trace.go:171","msg":"trace[406114940] transaction","detail":"{read_only:false; response_revision:25588; number_of_response:1; }","duration":"190.885952ms","start":"2026-10-02T16:10:29.467193Z","end":"2026-10-02T16:10:29.658079Z","steps":["trace[406114940] 'process raft request' (duration: 189.352265ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:29.658287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.808365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10358"} {"level":"warn","ts":"2026-10-02T16:10:29.65831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.690015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T16:10:29.658342Z","caller":"traceutil/trace.go:171","msg":"trace[539818932] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25588; }","duration":"179.900687ms","start":"2026-10-02T16:10:29.478427Z","end":"2026-10-02T16:10:29.658328Z","steps":["trace[539818932] 'agreement among raft nodes before linearized reading' (duration: 179.705782ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:29.658371Z","caller":"traceutil/trace.go:171","msg":"trace[1626476810] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:25588; }","duration":"165.842699ms","start":"2026-10-02T16:10:29.492512Z","end":"2026-10-02T16:10:29.658354Z","steps":["trace[1626476810] 'agreement among raft nodes before linearized reading' (duration: 165.717216ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:29.658322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.002894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T16:10:29.658463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.668522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-02T16:10:29.658502Z","caller":"traceutil/trace.go:171","msg":"trace[41684728] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25588; }","duration":"147.216769ms","start":"2026-10-02T16:10:29.511253Z","end":"2026-10-02T16:10:29.65847Z","steps":["trace[41684728] 'agreement among raft nodes before linearized reading' (duration: 146.994744ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:29.658542Z","caller":"traceutil/trace.go:171","msg":"trace[331533830] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25588; }","duration":"111.804446ms","start":"2026-10-02T16:10:29.546721Z","end":"2026-10-02T16:10:29.658525Z","steps":["trace[331533830] 'agreement among raft nodes before linearized reading' (duration: 111.507259ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:29.805516Z","caller":"traceutil/trace.go:171","msg":"trace[1515459027] transaction","detail":"{read_only:false; response_revision:25589; number_of_response:1; }","duration":"133.683123ms","start":"2026-10-02T16:10:29.671797Z","end":"2026-10-02T16:10:29.80548Z","steps":["trace[1515459027] 'process raft request' (duration: 133.326374ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:37.415921Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22240} {"level":"info","ts":"2026-10-02T16:10:37.531433Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22240,"took":"112.40272ms","hash":1939432232,"current-db-size-bytes":55660544,"current-db-size":"56 MB","current-db-size-in-use-bytes":45543424,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-10-02T16:10:37.531472Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1939432232,"revision":22240,"compact-revision":19891} {"level":"info","ts":"2026-10-02T16:10:42.775609Z","caller":"traceutil/trace.go:171","msg":"trace[710343489] transaction","detail":"{read_only:false; response_revision:25696; number_of_response:1; }","duration":"257.471767ms","start":"2026-10-02T16:10:42.518109Z","end":"2026-10-02T16:10:42.775581Z","steps":["trace[710343489] 'process raft request' (duration: 257.279162ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:42.994855Z","caller":"traceutil/trace.go:171","msg":"trace[827955925] transaction","detail":"{read_only:false; response_revision:25697; number_of_response:1; }","duration":"473.506676ms","start":"2026-10-02T16:10:42.521319Z","end":"2026-10-02T16:10:42.994825Z","steps":["trace[827955925] 'process raft request' (duration: 467.182403ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:42.994952Z","caller":"traceutil/trace.go:171","msg":"trace[2090150673] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"259.965268ms","start":"2026-10-02T16:10:42.73496Z","end":"2026-10-02T16:10:42.994925Z","steps":["trace[2090150673] 'process raft request' (duration: 259.894866ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:42.995022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:42.521303Z","time spent":"473.623948ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:42.995119Z","caller":"traceutil/trace.go:171","msg":"trace[218578772] linearizableReadLoop","detail":"{readStateIndex:26539; appliedIndex:26532; }","duration":"244.124436ms","start":"2026-10-02T16:10:42.750969Z","end":"2026-10-02T16:10:42.995094Z","steps":["trace[218578772] 'read index received' (duration: 24.44637ms)","trace[218578772] 'applied index is now lower than readState.Index' (duration: 219.676666ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:42.995199Z","caller":"traceutil/trace.go:171","msg":"trace[1168818662] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"259.818624ms","start":"2026-10-02T16:10:42.735364Z","end":"2026-10-02T16:10:42.995183Z","steps":["trace[1168818662] 'process raft request' (duration: 259.523497ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:42.995255Z","caller":"traceutil/trace.go:171","msg":"trace[815945227] transaction","detail":"{read_only:false; response_revision:25698; number_of_response:1; }","duration":"472.788819ms","start":"2026-10-02T16:10:42.522451Z","end":"2026-10-02T16:10:42.99524Z","steps":["trace[815945227] 'process raft request' (duration: 472.273216ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:42.995272Z","caller":"traceutil/trace.go:171","msg":"trace[1080270362] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"255.49837ms","start":"2026-10-02T16:10:42.739757Z","end":"2026-10-02T16:10:42.995255Z","steps":["trace[1080270362] 'process raft request' (duration: 255.242264ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:42.995359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:42.522429Z","time spent":"472.86029ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:42.995412Z","caller":"traceutil/trace.go:171","msg":"trace[1991218174] transaction","detail":"{read_only:false; response_revision:25699; number_of_response:1; }","duration":"261.381532ms","start":"2026-10-02T16:10:42.734004Z","end":"2026-10-02T16:10:42.995386Z","steps":["trace[1991218174] 'process raft request' (duration: 260.811208ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:42.995519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.515126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T16:10:42.995567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.607914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-10-02T16:10:42.995566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.853487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-10-02T16:10:42.995589Z","caller":"traceutil/trace.go:171","msg":"trace[275920019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25702; }","duration":"181.621879ms","start":"2026-10-02T16:10:42.813951Z","end":"2026-10-02T16:10:42.995573Z","steps":["trace[275920019] 'agreement among raft nodes before linearized reading' (duration: 181.468625ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:42.995621Z","caller":"traceutil/trace.go:171","msg":"trace[1109776259] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25702; }","duration":"214.711866ms","start":"2026-10-02T16:10:42.780891Z","end":"2026-10-02T16:10:42.995602Z","steps":["trace[1109776259] 'agreement among raft nodes before linearized reading' (duration: 214.531202ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:42.995616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.642448ms","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-10-02T16:10:42.995705Z","caller":"traceutil/trace.go:171","msg":"trace[1599227523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25702; }","duration":"244.760611ms","start":"2026-10-02T16:10:42.750929Z","end":"2026-10-02T16:10:42.99569Z","steps":["trace[1599227523] 'agreement among raft nodes before linearized reading' (duration: 244.479775ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:42.995626Z","caller":"traceutil/trace.go:171","msg":"trace[1242218321] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:25702; }","duration":"189.984411ms","start":"2026-10-02T16:10:42.805627Z","end":"2026-10-02T16:10:42.995611Z","steps":["trace[1242218321] 'agreement among raft nodes before linearized reading' (duration: 189.799926ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:43.404325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.684409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T16:10:43.404557Z","caller":"traceutil/trace.go:171","msg":"trace[8373269] linearizableReadLoop","detail":"{readStateIndex:26542; appliedIndex:26539; }","duration":"399.122433ms","start":"2026-10-02T16:10:43.005419Z","end":"2026-10-02T16:10:43.404541Z","steps":["trace[8373269] 'read index received' (duration: 125.063045ms)","trace[8373269] 'applied index is now lower than readState.Index' (duration: 274.058258ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:43.404641Z","caller":"traceutil/trace.go:171","msg":"trace[204536471] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"403.888007ms","start":"2026-10-02T16:10:43.000741Z","end":"2026-10-02T16:10:43.404629Z","steps":["trace[204536471] 'process raft request' (duration: 403.674852ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:43.404638Z","caller":"traceutil/trace.go:171","msg":"trace[2031893706] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"403.98129ms","start":"2026-10-02T16:10:43.000607Z","end":"2026-10-02T16:10:43.404589Z","steps":["trace[2031893706] 'process raft request' (duration: 129.876992ms)","trace[2031893706] 'compare' (duration: 273.271268ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:43.404718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:43.000721Z","time spent":"403.940479ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T16:10:43.40482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.341388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T16:10:43.404884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:43.000588Z","time spent":"404.161794ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:43.404928Z","caller":"traceutil/trace.go:171","msg":"trace[23935527] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:25705; }","duration":"399.513493ms","start":"2026-10-02T16:10:43.005391Z","end":"2026-10-02T16:10:43.404904Z","steps":["trace[23935527] 'agreement among raft nodes before linearized reading' (duration: 399.297937ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:43.404995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:43.005375Z","time spent":"399.603144ms","remote":"127.0.0.1:59350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true "} {"level":"warn","ts":"2026-10-02T16:10:43.40495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.351961ms","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-10-02T16:10:43.405094Z","caller":"traceutil/trace.go:171","msg":"trace[1679489701] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25705; }","duration":"385.531225ms","start":"2026-10-02T16:10:43.01954Z","end":"2026-10-02T16:10:43.405071Z","steps":["trace[1679489701] 'agreement among raft nodes before linearized reading' (duration: 385.221407ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:43.405148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:43.019491Z","time spent":"385.645378ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-02T16:10:43.405137Z","caller":"traceutil/trace.go:171","msg":"trace[1339158794] transaction","detail":"{read_only:false; response_revision:25705; number_of_response:1; }","duration":"404.342139ms","start":"2026-10-02T16:10:43.000771Z","end":"2026-10-02T16:10:43.405113Z","steps":["trace[1339158794] 'process raft request' (duration: 403.736964ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:43.405271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:43.000757Z","time spent":"404.43589ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T16:10:43.40526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.550386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T16:10:43.405346Z","caller":"traceutil/trace.go:171","msg":"trace[978802293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25705; }","duration":"283.69ms","start":"2026-10-02T16:10:43.121636Z","end":"2026-10-02T16:10:43.405326Z","steps":["trace[978802293] 'agreement among raft nodes before linearized reading' (duration: 283.545966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:43.40536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.619658ms","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-10-02T16:10:43.40544Z","caller":"traceutil/trace.go:171","msg":"trace[241679023] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25705; }","duration":"193.765062ms","start":"2026-10-02T16:10:43.211654Z","end":"2026-10-02T16:10:43.405419Z","steps":["trace[241679023] 'agreement among raft nodes before linearized reading' (duration: 193.517156ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:43.697001Z","caller":"traceutil/trace.go:171","msg":"trace[2008073764] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"287.194764ms","start":"2026-10-02T16:10:43.409768Z","end":"2026-10-02T16:10:43.696963Z","steps":["trace[2008073764] 'process raft request' (duration: 269.424155ms)","trace[2008073764] 'compare' (duration: 17.316388ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:44.004123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.777915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T16:10:44.004229Z","caller":"traceutil/trace.go:171","msg":"trace[872281091] transaction","detail":"{read_only:false; response_revision:25708; number_of_response:1; }","duration":"302.983084ms","start":"2026-10-02T16:10:43.70123Z","end":"2026-10-02T16:10:44.004213Z","steps":["trace[872281091] 'process raft request' (duration: 147.043285ms)","trace[872281091] 'compare' (duration: 155.55779ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:44.004274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:10:43.70121Z","time spent":"303.044906ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:10:48.44111Z","caller":"traceutil/trace.go:171","msg":"trace[835164039] transaction","detail":"{read_only:false; response_revision:25737; number_of_response:1; }","duration":"124.49118ms","start":"2026-10-02T16:10:48.316592Z","end":"2026-10-02T16:10:48.441084Z","steps":["trace[835164039] 'process raft request' (duration: 124.276934ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:48.620477Z","caller":"traceutil/trace.go:171","msg":"trace[1485478883] transaction","detail":"{read_only:false; response_revision:25738; number_of_response:1; }","duration":"166.805592ms","start":"2026-10-02T16:10:48.453618Z","end":"2026-10-02T16:10:48.620423Z","steps":["trace[1485478883] 'process raft request' (duration: 166.472834ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:48.776049Z","caller":"traceutil/trace.go:171","msg":"trace[443662335] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"145.526679ms","start":"2026-10-02T16:10:48.6305Z","end":"2026-10-02T16:10:48.776026Z","steps":["trace[443662335] 'process raft request' (duration: 145.421056ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:48.776079Z","caller":"traceutil/trace.go:171","msg":"trace[1299425943] transaction","detail":"{read_only:false; response_revision:25740; number_of_response:1; }","duration":"147.25387ms","start":"2026-10-02T16:10:48.62878Z","end":"2026-10-02T16:10:48.776034Z","steps":["trace[1299425943] 'process raft request' (duration: 147.011434ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:48.776228Z","caller":"traceutil/trace.go:171","msg":"trace[1982539645] linearizableReadLoop","detail":"{readStateIndex:26579; appliedIndex:26576; }","duration":"143.760616ms","start":"2026-10-02T16:10:48.632424Z","end":"2026-10-02T16:10:48.776185Z","steps":["trace[1982539645] 'read index received' (duration: 38.279613ms)","trace[1982539645] 'applied index is now lower than readState.Index' (duration: 105.479043ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:48.776351Z","caller":"traceutil/trace.go:171","msg":"trace[475191305] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"138.391506ms","start":"2026-10-02T16:10:48.637941Z","end":"2026-10-02T16:10:48.776332Z","steps":["trace[475191305] 'process raft request' (duration: 138.026017ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:48.776402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.94633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T16:10:48.776444Z","caller":"traceutil/trace.go:171","msg":"trace[2125295389] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25742; }","duration":"144.051373ms","start":"2026-10-02T16:10:48.632382Z","end":"2026-10-02T16:10:48.776433Z","steps":["trace[2125295389] 'agreement among raft nodes before linearized reading' (duration: 143.94755ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:48.903182Z","caller":"traceutil/trace.go:171","msg":"trace[433702889] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"109.619143ms","start":"2026-10-02T16:10:48.793541Z","end":"2026-10-02T16:10:48.90316Z","steps":["trace[433702889] 'process raft request' (duration: 77.518229ms)","trace[433702889] 'compare' (duration: 31.856298ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:48.903207Z","caller":"traceutil/trace.go:171","msg":"trace[739995776] transaction","detail":"{read_only:false; response_revision:25744; number_of_response:1; }","duration":"108.081826ms","start":"2026-10-02T16:10:48.795104Z","end":"2026-10-02T16:10:48.903186Z","steps":["trace[739995776] 'process raft request' (duration: 107.966893ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:49.559602Z","caller":"traceutil/trace.go:171","msg":"trace[29396129] transaction","detail":"{read_only:false; response_revision:25757; number_of_response:1; }","duration":"127.068774ms","start":"2026-10-02T16:10:49.432515Z","end":"2026-10-02T16:10:49.559584Z","steps":["trace[29396129] 'process raft request' (duration: 126.976312ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:49.559758Z","caller":"traceutil/trace.go:171","msg":"trace[1634920404] transaction","detail":"{read_only:false; response_revision:25756; number_of_response:1; }","duration":"127.34392ms","start":"2026-10-02T16:10:49.432403Z","end":"2026-10-02T16:10:49.559747Z","steps":["trace[1634920404] 'process raft request' (duration: 107.905531ms)","trace[1634920404] 'compare' (duration: 19.05958ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:49.559846Z","caller":"traceutil/trace.go:171","msg":"trace[1723529561] linearizableReadLoop","detail":"{readStateIndex:26595; appliedIndex:26592; }","duration":"114.408018ms","start":"2026-10-02T16:10:49.445428Z","end":"2026-10-02T16:10:49.559836Z","steps":["trace[1723529561] 'read index received' (duration: 61.481322ms)","trace[1723529561] 'applied index is now lower than readState.Index' (duration: 52.926016ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:10:49.559971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.528851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-02T16:10:49.559997Z","caller":"traceutil/trace.go:171","msg":"trace[1401276394] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25758; }","duration":"114.608363ms","start":"2026-10-02T16:10:49.44538Z","end":"2026-10-02T16:10:49.559989Z","steps":["trace[1401276394] 'agreement among raft nodes before linearized reading' (duration: 114.48072ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:49.56002Z","caller":"traceutil/trace.go:171","msg":"trace[1539525611] transaction","detail":"{read_only:false; response_revision:25758; number_of_response:1; }","duration":"103.059525ms","start":"2026-10-02T16:10:49.456929Z","end":"2026-10-02T16:10:49.559989Z","steps":["trace[1539525611] 'process raft request' (duration: 102.612614ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:51.853571Z","caller":"traceutil/trace.go:171","msg":"trace[842123257] transaction","detail":"{read_only:false; response_revision:25781; number_of_response:1; }","duration":"122.631347ms","start":"2026-10-02T16:10:51.730916Z","end":"2026-10-02T16:10:51.853547Z","steps":["trace[842123257] 'process raft request' (duration: 122.445743ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:55.995924Z","caller":"traceutil/trace.go:171","msg":"trace[2056136513] linearizableReadLoop","detail":"{readStateIndex:26648; appliedIndex:26647; }","duration":"110.376531ms","start":"2026-10-02T16:10:55.885524Z","end":"2026-10-02T16:10:55.995901Z","steps":["trace[2056136513] 'read index received' (duration: 110.237947ms)","trace[2056136513] 'applied index is now lower than readState.Index' (duration: 137.354µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:55.996052Z","caller":"traceutil/trace.go:171","msg":"trace[1830846414] transaction","detail":"{read_only:false; response_revision:25809; number_of_response:1; }","duration":"120.221259ms","start":"2026-10-02T16:10:55.875801Z","end":"2026-10-02T16:10:55.996022Z","steps":["trace[1830846414] 'process raft request' (duration: 119.936392ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:10:55.996168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.632328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-02T16:10:55.996234Z","caller":"traceutil/trace.go:171","msg":"trace[1691760645] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:25809; }","duration":"110.755751ms","start":"2026-10-02T16:10:55.88547Z","end":"2026-10-02T16:10:55.996226Z","steps":["trace[1691760645] 'agreement among raft nodes before linearized reading' (duration: 110.559646ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:57.648723Z","caller":"traceutil/trace.go:171","msg":"trace[1154963956] transaction","detail":"{read_only:false; response_revision:25819; number_of_response:1; }","duration":"106.884286ms","start":"2026-10-02T16:10:57.541815Z","end":"2026-10-02T16:10:57.6487Z","steps":["trace[1154963956] 'process raft request' (duration: 106.63273ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:57.808729Z","caller":"traceutil/trace.go:171","msg":"trace[2052216938] transaction","detail":"{read_only:false; response_revision:25820; number_of_response:1; }","duration":"155.245993ms","start":"2026-10-02T16:10:57.653454Z","end":"2026-10-02T16:10:57.8087Z","steps":["trace[2052216938] 'process raft request' (duration: 78.674587ms)","trace[2052216938] 'compare' (duration: 76.350401ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:10:57.808894Z","caller":"traceutil/trace.go:171","msg":"trace[1261837958] transaction","detail":"{read_only:false; response_revision:25821; number_of_response:1; }","duration":"155.260133ms","start":"2026-10-02T16:10:57.653616Z","end":"2026-10-02T16:10:57.808876Z","steps":["trace[1261837958] 'process raft request' (duration: 155.005397ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:57.809067Z","caller":"traceutil/trace.go:171","msg":"trace[1782363056] transaction","detail":"{read_only:false; response_revision:25822; number_of_response:1; }","duration":"154.808262ms","start":"2026-10-02T16:10:57.654242Z","end":"2026-10-02T16:10:57.80905Z","steps":["trace[1782363056] 'process raft request' (duration: 154.579987ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:10:59.329135Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006831.wal"} {"level":"info","ts":"2026-10-02T16:11:00.217158Z","caller":"traceutil/trace.go:171","msg":"trace[687981548] transaction","detail":"{read_only:false; response_revision:25839; number_of_response:1; }","duration":"190.324789ms","start":"2026-10-02T16:11:00.026807Z","end":"2026-10-02T16:11:00.217132Z","steps":["trace[687981548] 'process raft request' (duration: 189.94494ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:00.611286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.45676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T16:11:00.611402Z","caller":"traceutil/trace.go:171","msg":"trace[762881273] linearizableReadLoop","detail":"{readStateIndex:26680; appliedIndex:26679; }","duration":"146.503562ms","start":"2026-10-02T16:11:00.464881Z","end":"2026-10-02T16:11:00.611385Z","steps":["trace[762881273] 'read index received' (duration: 33.311µs)","trace[762881273] 'applied index is now lower than readState.Index' (duration: 146.468721ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:11:00.611409Z","caller":"traceutil/trace.go:171","msg":"trace[273828973] transaction","detail":"{read_only:false; response_revision:25840; number_of_response:1; }","duration":"389.91445ms","start":"2026-10-02T16:11:00.221469Z","end":"2026-10-02T16:11:00.611383Z","steps":["trace[273828973] 'process raft request' (duration: 102.259135ms)","trace[273828973] 'compare' (duration: 287.097132ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:11:00.611496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:11:00.221448Z","time spent":"390.006133ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T16:11:00.611578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.691897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19028"} {"level":"info","ts":"2026-10-02T16:11:00.611605Z","caller":"traceutil/trace.go:171","msg":"trace[436839808] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25840; }","duration":"146.750088ms","start":"2026-10-02T16:11:00.464845Z","end":"2026-10-02T16:11:00.611596Z","steps":["trace[436839808] 'agreement among raft nodes before linearized reading' (duration: 146.622095ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:00.611999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.074853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-02T16:11:00.612056Z","caller":"traceutil/trace.go:171","msg":"trace[925819551] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25840; }","duration":"127.148406ms","start":"2026-10-02T16:11:00.48489Z","end":"2026-10-02T16:11:00.612039Z","steps":["trace[925819551] 'agreement among raft nodes before linearized reading' (duration: 126.958911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:00.611916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.391161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T16:11:00.612349Z","caller":"traceutil/trace.go:171","msg":"trace[1768631345] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:25840; }","duration":"139.836952ms","start":"2026-10-02T16:11:00.472495Z","end":"2026-10-02T16:11:00.612332Z","steps":["trace[1768631345] 'agreement among raft nodes before linearized reading' (duration: 139.34434ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:11:01.261917Z","caller":"traceutil/trace.go:171","msg":"trace[596440717] transaction","detail":"{read_only:false; response_revision:25844; number_of_response:1; }","duration":"171.030293ms","start":"2026-10-02T16:11:01.090855Z","end":"2026-10-02T16:11:01.261885Z","steps":["trace[596440717] 'process raft request' (duration: 76.899164ms)","trace[596440717] 'compare' (duration: 93.879913ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:11:01.261916Z","caller":"traceutil/trace.go:171","msg":"trace[1655552429] transaction","detail":"{read_only:false; response_revision:25845; number_of_response:1; }","duration":"170.08137ms","start":"2026-10-02T16:11:01.091809Z","end":"2026-10-02T16:11:01.26189Z","steps":["trace[1655552429] 'process raft request' (duration: 169.998678ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:11:01.262141Z","caller":"traceutil/trace.go:171","msg":"trace[927256473] transaction","detail":"{read_only:false; response_revision:25846; number_of_response:1; }","duration":"170.191363ms","start":"2026-10-02T16:11:01.091942Z","end":"2026-10-02T16:11:01.262133Z","steps":["trace[927256473] 'process raft request' (duration: 169.909956ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:11:01.262287Z","caller":"traceutil/trace.go:171","msg":"trace[1878323219] transaction","detail":"{read_only:false; response_revision:25847; number_of_response:1; }","duration":"170.331216ms","start":"2026-10-02T16:11:01.091943Z","end":"2026-10-02T16:11:01.262274Z","steps":["trace[1878323219] 'process raft request' (duration: 170.134152ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:11:01.26227Z","caller":"traceutil/trace.go:171","msg":"trace[1571083900] transaction","detail":"{read_only:false; response_revision:25848; number_of_response:1; }","duration":"170.124682ms","start":"2026-10-02T16:11:01.092125Z","end":"2026-10-02T16:11:01.26225Z","steps":["trace[1571083900] 'process raft request' (duration: 170.078881ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:03.360326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.623767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T16:11:03.360416Z","caller":"traceutil/trace.go:171","msg":"trace[168456572] transaction","detail":"{read_only:false; response_revision:25856; number_of_response:1; }","duration":"290.508814ms","start":"2026-10-02T16:11:03.069889Z","end":"2026-10-02T16:11:03.360397Z","steps":["trace[168456572] 'process raft request' (duration: 157.733343ms)","trace[168456572] 'compare' (duration: 132.33323ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:11:03.445057Z","caller":"traceutil/trace.go:171","msg":"trace[1009441758] transaction","detail":"{read_only:false; response_revision:25859; number_of_response:1; }","duration":"177.451688ms","start":"2026-10-02T16:11:03.267581Z","end":"2026-10-02T16:11:03.445032Z","steps":["trace[1009441758] 'process raft request' (duration: 177.375907ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:11:03.44511Z","caller":"traceutil/trace.go:171","msg":"trace[457899126] transaction","detail":"{read_only:false; response_revision:25858; number_of_response:1; }","duration":"177.570811ms","start":"2026-10-02T16:11:03.267501Z","end":"2026-10-02T16:11:03.445072Z","steps":["trace[457899126] 'process raft request' (duration: 177.314575ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:11:03.445428Z","caller":"traceutil/trace.go:171","msg":"trace[1772660778] transaction","detail":"{read_only:false; response_revision:25860; number_of_response:1; }","duration":"177.758196ms","start":"2026-10-02T16:11:03.267653Z","end":"2026-10-02T16:11:03.445412Z","steps":["trace[1772660778] 'process raft request' (duration: 177.343196ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:11:03.445504Z","caller":"traceutil/trace.go:171","msg":"trace[1386017571] transaction","detail":"{read_only:false; response_revision:25861; number_of_response:1; }","duration":"177.153241ms","start":"2026-10-02T16:11:03.268296Z","end":"2026-10-02T16:11:03.445449Z","steps":["trace[1386017571] 'process raft request' (duration: 177.020208ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:11:06.029581Z","caller":"traceutil/trace.go:171","msg":"trace[686289464] transaction","detail":"{read_only:false; response_revision:25881; number_of_response:1; }","duration":"135.954058ms","start":"2026-10-02T16:11:05.893601Z","end":"2026-10-02T16:11:06.029555Z","steps":["trace[686289464] 'process raft request' (duration: 112.502383ms)","trace[686289464] 'compare' (duration: 23.25136ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:11:06.031879Z","caller":"traceutil/trace.go:171","msg":"trace[533878997] linearizableReadLoop","detail":"{readStateIndex:26723; appliedIndex:26721; }","duration":"137.163396ms","start":"2026-10-02T16:11:05.894697Z","end":"2026-10-02T16:11:06.031861Z","steps":["trace[533878997] 'read index received' (duration: 111.407406ms)","trace[533878997] 'applied index is now lower than readState.Index' (duration: 25.75525ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:11:06.032102Z","caller":"traceutil/trace.go:171","msg":"trace[1524214327] transaction","detail":"{read_only:false; response_revision:25882; number_of_response:1; }","duration":"138.279344ms","start":"2026-10-02T16:11:05.893811Z","end":"2026-10-02T16:11:06.03209Z","steps":["trace[1524214327] 'process raft request' (duration: 137.955616ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:06.03233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.642278ms","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-10-02T16:11:06.032397Z","caller":"traceutil/trace.go:171","msg":"trace[1202907550] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25882; }","duration":"137.739641ms","start":"2026-10-02T16:11:05.894645Z","end":"2026-10-02T16:11:06.032385Z","steps":["trace[1202907550] 'agreement among raft nodes before linearized reading' (duration: 137.634568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:06.03261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.072836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-10-02T16:11:06.032744Z","caller":"traceutil/trace.go:171","msg":"trace[980958399] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:25882; }","duration":"135.23603ms","start":"2026-10-02T16:11:05.89748Z","end":"2026-10-02T16:11:06.032716Z","steps":["trace[980958399] 'agreement among raft nodes before linearized reading' (duration: 134.927272ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:11:22.298558Z","caller":"traceutil/trace.go:171","msg":"trace[1140583591] transaction","detail":"{read_only:false; response_revision:26000; number_of_response:1; }","duration":"189.062419ms","start":"2026-10-02T16:11:22.109462Z","end":"2026-10-02T16:11:22.298524Z","steps":["trace[1140583591] 'process raft request' (duration: 188.852223ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:22.298766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.389075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T16:11:22.298825Z","caller":"traceutil/trace.go:171","msg":"trace[1302478887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26000; }","duration":"179.59659ms","start":"2026-10-02T16:11:22.119212Z","end":"2026-10-02T16:11:22.298809Z","steps":["trace[1302478887] 'agreement among raft nodes before linearized reading' (duration: 179.393446ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:11:22.298503Z","caller":"traceutil/trace.go:171","msg":"trace[991196242] linearizableReadLoop","detail":"{readStateIndex:26845; appliedIndex:26843; }","duration":"179.21491ms","start":"2026-10-02T16:11:22.119263Z","end":"2026-10-02T16:11:22.298478Z","steps":["trace[991196242] 'read index received' (duration: 62.747072ms)","trace[991196242] 'applied index is now lower than readState.Index' (duration: 116.466818ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:11:22.299327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.68855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T16:11:22.299368Z","caller":"traceutil/trace.go:171","msg":"trace[1951010510] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:26000; }","duration":"120.767342ms","start":"2026-10-02T16:11:22.178587Z","end":"2026-10-02T16:11:22.299355Z","steps":["trace[1951010510] 'agreement among raft nodes before linearized reading' (duration: 120.69632ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:38.544909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.339733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-02T16:11:38.545001Z","caller":"traceutil/trace.go:171","msg":"trace[1901608232] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26267; }","duration":"111.458016ms","start":"2026-10-02T16:11:38.433523Z","end":"2026-10-02T16:11:38.544981Z","steps":["trace[1901608232] 'range keys from in-memory index tree' (duration: 111.21337ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:38.545096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.667426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-10-02T16:11:38.545185Z","caller":"traceutil/trace.go:171","msg":"trace[1950779772] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26267; }","duration":"168.83082ms","start":"2026-10-02T16:11:38.376332Z","end":"2026-10-02T16:11:38.545163Z","steps":["trace[1950779772] 'range keys from in-memory index tree' (duration: 168.487451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:38.872115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.805633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-02T16:11:38.872245Z","caller":"traceutil/trace.go:171","msg":"trace[783901682] linearizableReadLoop","detail":"{readStateIndex:27119; appliedIndex:27117; }","duration":"309.83771ms","start":"2026-10-02T16:11:38.562391Z","end":"2026-10-02T16:11:38.872228Z","steps":["trace[783901682] 'read index received' (duration: 64.486515ms)","trace[783901682] 'applied index is now lower than readState.Index' (duration: 245.350115ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:11:38.872336Z","caller":"traceutil/trace.go:171","msg":"trace[1813264544] transaction","detail":"{read_only:false; response_revision:26269; number_of_response:1; }","duration":"322.651039ms","start":"2026-10-02T16:11:38.549672Z","end":"2026-10-02T16:11:38.872323Z","steps":["trace[1813264544] 'process raft request' (duration: 196.548709ms)","trace[1813264544] 'compare' (duration: 125.601828ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:11:38.872395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:11:38.549659Z","time spent":"322.70267ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T16:11:38.872466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.309099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-02T16:11:38.872564Z","caller":"traceutil/trace.go:171","msg":"trace[165583362] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:26269; }","duration":"195.471882ms","start":"2026-10-02T16:11:38.677069Z","end":"2026-10-02T16:11:38.872541Z","steps":["trace[165583362] 'agreement among raft nodes before linearized reading' (duration: 195.313909ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:38.87261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.177178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T16:11:38.872835Z","caller":"traceutil/trace.go:171","msg":"trace[815522270] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:26269; }","duration":"310.408404ms","start":"2026-10-02T16:11:38.562357Z","end":"2026-10-02T16:11:38.872766Z","steps":["trace[815522270] 'agreement among raft nodes before linearized reading' (duration: 310.049595ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:38.872949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:11:38.56234Z","time spent":"310.586648ms","remote":"127.0.0.1:48882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":10,"response size":31,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-02T16:11:39.116643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.400901ms","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-10-02T16:11:39.116765Z","caller":"traceutil/trace.go:171","msg":"trace[1350611917] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:26270; }","duration":"127.560895ms","start":"2026-10-02T16:11:38.989183Z","end":"2026-10-02T16:11:39.116744Z","steps":["trace[1350611917] 'count revisions from in-memory index tree' (duration: 127.35344ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:39.11674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.588112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T16:11:39.116832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.782719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"warn","ts":"2026-10-02T16:11:39.116848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.50274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128061"} {"level":"info","ts":"2026-10-02T16:11:39.116857Z","caller":"traceutil/trace.go:171","msg":"trace[443343678] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26270; }","duration":"143.763106ms","start":"2026-10-02T16:11:38.973067Z","end":"2026-10-02T16:11:39.11683Z","steps":["trace[443343678] 'range keys from in-memory index tree' (duration: 143.543221ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:11:39.116904Z","caller":"traceutil/trace.go:171","msg":"trace[395323321] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:26270; }","duration":"139.892782ms","start":"2026-10-02T16:11:38.976993Z","end":"2026-10-02T16:11:39.116886Z","steps":["trace[395323321] 'range keys from in-memory index tree' (duration: 139.484583ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:11:39.116909Z","caller":"traceutil/trace.go:171","msg":"trace[218129614] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:26270; }","duration":"109.589302ms","start":"2026-10-02T16:11:39.007304Z","end":"2026-10-02T16:11:39.116893Z","steps":["trace[218129614] 'range keys from in-memory index tree' (duration: 109.315645ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:11:39.9512Z","caller":"traceutil/trace.go:171","msg":"trace[84979183] transaction","detail":"{read_only:false; response_revision:26273; number_of_response:1; }","duration":"447.791335ms","start":"2026-10-02T16:11:39.503375Z","end":"2026-10-02T16:11:39.951167Z","steps":["trace[84979183] 'process raft request' (duration: 356.261299ms)","trace[84979183] 'compare' (duration: 91.322651ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T16:11:39.951256Z","caller":"traceutil/trace.go:171","msg":"trace[569571340] linearizableReadLoop","detail":"{readStateIndex:27123; appliedIndex:27122; }","duration":"418.878409ms","start":"2026-10-02T16:11:39.532353Z","end":"2026-10-02T16:11:39.951231Z","steps":["trace[569571340] 'read index received' (duration: 327.398004ms)","trace[569571340] 'applied index is now lower than readState.Index' (duration: 91.478315ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T16:11:39.951349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:11:39.503349Z","time spent":"447.922219ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T16:11:39.951388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.609982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T16:11:39.951504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.731497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-02T16:11:39.951546Z","caller":"traceutil/trace.go:171","msg":"trace[55464041] transaction","detail":"{read_only:false; response_revision:26274; number_of_response:1; }","duration":"361.014603ms","start":"2026-10-02T16:11:39.590522Z","end":"2026-10-02T16:11:39.951537Z","steps":["trace[55464041] 'process raft request' (duration: 360.700036ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:11:39.951564Z","caller":"traceutil/trace.go:171","msg":"trace[1392258920] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:26273; }","duration":"450.834509ms","start":"2026-10-02T16:11:39.500708Z","end":"2026-10-02T16:11:39.951542Z","steps":["trace[1392258920] 'agreement among raft nodes before linearized reading' (duration: 450.600984ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:39.951589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.108984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T16:11:39.951613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:11:39.500664Z","time spent":"450.933382ms","remote":"127.0.0.1:32888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":31,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"info","ts":"2026-10-02T16:11:39.951636Z","caller":"traceutil/trace.go:171","msg":"trace[326791280] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26273; }","duration":"453.210196ms","start":"2026-10-02T16:11:39.498416Z","end":"2026-10-02T16:11:39.951626Z","steps":["trace[326791280] 'agreement among raft nodes before linearized reading' (duration: 452.919959ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:39.951581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.117194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-02T16:11:39.951665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:11:39.498389Z","time spent":"453.269088ms","remote":"127.0.0.1:45050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"info","ts":"2026-10-02T16:11:39.951725Z","caller":"traceutil/trace.go:171","msg":"trace[1067408793] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:26273; }","duration":"453.279059ms","start":"2026-10-02T16:11:39.498393Z","end":"2026-10-02T16:11:39.951672Z","steps":["trace[1067408793] 'agreement among raft nodes before linearized reading' (duration: 452.958771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:39.951775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:11:39.498364Z","time spent":"453.397211ms","remote":"127.0.0.1:45050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"warn","ts":"2026-10-02T16:11:39.951647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:11:39.590496Z","time spent":"361.075795ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:11:39.951923Z","caller":"traceutil/trace.go:171","msg":"trace[1027469651] transaction","detail":"{read_only:false; response_revision:26275; number_of_response:1; }","duration":"358.520313ms","start":"2026-10-02T16:11:39.593392Z","end":"2026-10-02T16:11:39.951912Z","steps":["trace[1027469651] 'process raft request' (duration: 358.100793ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:39.951987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:11:39.593369Z","time spent":"358.585205ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:11:39.952138Z","caller":"traceutil/trace.go:171","msg":"trace[466605710] transaction","detail":"{read_only:false; response_revision:26276; number_of_response:1; }","duration":"355.706106ms","start":"2026-10-02T16:11:39.596421Z","end":"2026-10-02T16:11:39.952127Z","steps":["trace[466605710] 'process raft request' (duration: 355.453889ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T16:11:39.952127Z","caller":"traceutil/trace.go:171","msg":"trace[1939889302] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:26273; }","duration":"453.445422ms","start":"2026-10-02T16:11:39.498662Z","end":"2026-10-02T16:11:39.952107Z","steps":["trace[1939889302] 'agreement among raft nodes before linearized reading' (duration: 452.583121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:39.952322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:11:39.498642Z","time spent":"453.663568ms","remote":"127.0.0.1:45050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/openstack/manila-rabbit-init\" "} {"level":"info","ts":"2026-10-02T16:11:39.952324Z","caller":"traceutil/trace.go:171","msg":"trace[1261456779] transaction","detail":"{read_only:false; response_revision:26278; number_of_response:1; }","duration":"172.422387ms","start":"2026-10-02T16:11:39.779851Z","end":"2026-10-02T16:11:39.952274Z","steps":["trace[1261456779] 'process raft request' (duration: 172.304364ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:39.952182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:11:39.596404Z","time spent":"355.754647ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:11:39.952238Z","caller":"traceutil/trace.go:171","msg":"trace[1741123746] transaction","detail":"{read_only:false; response_revision:26277; number_of_response:1; }","duration":"354.419385ms","start":"2026-10-02T16:11:39.597802Z","end":"2026-10-02T16:11:39.952221Z","steps":["trace[1741123746] 'process raft request' (duration: 354.23696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T16:11:39.952528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T16:11:39.597792Z","time spent":"354.673851ms","remote":"127.0.0.1:45084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T16:15:37.438053Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25659} {"level":"info","ts":"2026-10-02T16:15:37.668784Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25659,"took":"227.684248ms","hash":3469860443,"current-db-size-bytes":57737216,"current-db-size":"58 MB","current-db-size-in-use-bytes":36261888,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-02T16:15:37.668854Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3469860443,"revision":25659,"compact-revision":22240} {"level":"info","ts":"2026-10-02T16:16:49.821987Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2d037ebe7b91690","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T16:16:49.828707Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-02T16:16:49.828849Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-02T16:20:37.444839Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28545} {"level":"info","ts":"2026-10-02T16:20:37.590292Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28545,"took":"140.864423ms","hash":2079960366,"current-db-size-bytes":57737216,"current-db-size":"58 MB","current-db-size-in-use-bytes":31170560,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T16:20:37.590367Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2079960366,"revision":28545,"compact-revision":25659} {"level":"info","ts":"2026-10-02T16:25:37.452887Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30773} {"level":"info","ts":"2026-10-02T16:25:37.569853Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30773,"took":"112.921324ms","hash":2160323104,"current-db-size-bytes":57737216,"current-db-size":"58 MB","current-db-size-in-use-bytes":31473664,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-02T16:25:37.569918Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2160323104,"revision":30773,"compact-revision":28545} {"level":"info","ts":"2026-10-02T16:30:37.460014Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33031} {"level":"info","ts":"2026-10-02T16:30:37.628049Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33031,"took":"163.550972ms","hash":3893016645,"current-db-size-bytes":57737216,"current-db-size":"58 MB","current-db-size-in-use-bytes":30674944,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-02T16:30:37.628123Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3893016645,"revision":33031,"compact-revision":30773} {"level":"info","ts":"2026-10-02T16:35:37.471015Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35292} {"level":"info","ts":"2026-10-02T16:35:37.619891Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35292,"took":"144.125324ms","hash":3730593695,"current-db-size-bytes":59486208,"current-db-size":"60 MB","current-db-size-in-use-bytes":33079296,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-02T16:35:37.619969Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3730593695,"revision":35292,"compact-revision":33031} {"level":"info","ts":"2026-10-02T16:38:21.88581Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e2d037ebe7b91690","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-02T16:38:21.892033Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-02T16:38:21.892149Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-02T16:40:37.478133Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37552} {"level":"info","ts":"2026-10-02T16:40:37.603081Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37552,"took":"120.065276ms","hash":2669187220,"current-db-size-bytes":60755968,"current-db-size":"61 MB","current-db-size-in-use-bytes":32935936,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-02T16:40:37.603134Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2669187220,"revision":37552,"compact-revision":35292}