{"level":"warn","ts":"2026-05-05T11:21:55.054502Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T11:21:55.054638Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.73:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.73:2380","--initial-cluster=instance=https://199.19.213.73:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.73:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.73:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T11:21:55.05474Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T11:21:55.054758Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.73:2380"]} {"level":"info","ts":"2026-05-05T11:21:55.054782Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T11:21:55.055627Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"]} {"level":"info","ts":"2026-05-05T11:21:55.055751Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.73:2380"],"listen-peer-urls":["https://199.19.213.73:2380"],"advertise-client-urls":["https://199.19.213.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.73:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T11:21:55.060941Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.864854ms"} {"level":"info","ts":"2026-05-05T11:21:55.069722Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"34a8f480fa9ab6cb","cluster-id":"4820536600e0938e"} {"level":"info","ts":"2026-05-05T11:21:55.069799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T11:21:55.069819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became follower at term 0"} {"level":"info","ts":"2026-05-05T11:21:55.069834Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 34a8f480fa9ab6cb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T11:21:55.069846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became follower at term 1"} {"level":"info","ts":"2026-05-05T11:21:55.069888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=(3794551520857077451)"} {"level":"warn","ts":"2026-05-05T11:21:55.075715Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T11:21:55.077832Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T11:21:55.079387Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T11:21:55.083153Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"34a8f480fa9ab6cb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T11:21:55.083429Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"34a8f480fa9ab6cb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T11:21:55.08352Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T11:21:55.083738Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T11:21:55.083762Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T11:21:55.085101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=(3794551520857077451)"} {"level":"info","ts":"2026-05-05T11:21:55.085538Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4820536600e0938e","local-member-id":"34a8f480fa9ab6cb","added-peer-id":"34a8f480fa9ab6cb","added-peer-peer-urls":["https://199.19.213.73:2380"]} {"level":"info","ts":"2026-05-05T11:21:55.089009Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T11:21:55.089117Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.73:2380"} {"level":"info","ts":"2026-05-05T11:21:55.089139Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.73:2380"} {"level":"info","ts":"2026-05-05T11:21:55.089431Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"34a8f480fa9ab6cb","initial-advertise-peer-urls":["https://199.19.213.73:2380"],"listen-peer-urls":["https://199.19.213.73:2380"],"advertise-client-urls":["https://199.19.213.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T11:21:55.089512Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T11:21:55.771146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T11:21:55.771222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T11:21:55.771265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb received MsgPreVoteResp from 34a8f480fa9ab6cb at term 1"} {"level":"info","ts":"2026-05-05T11:21:55.771287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became candidate at term 2"} {"level":"info","ts":"2026-05-05T11:21:55.771298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb received MsgVoteResp from 34a8f480fa9ab6cb at term 2"} {"level":"info","ts":"2026-05-05T11:21:55.771314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became leader at term 2"} {"level":"info","ts":"2026-05-05T11:21:55.771328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 34a8f480fa9ab6cb elected leader 34a8f480fa9ab6cb at term 2"} {"level":"info","ts":"2026-05-05T11:21:55.772516Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:21:55.773413Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T11:21:55.773416Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"34a8f480fa9ab6cb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.73:2379]}","request-path":"/0/members/34a8f480fa9ab6cb/attributes","cluster-id":"4820536600e0938e","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T11:21:55.773503Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T11:21:55.773721Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T11:21:55.773805Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T11:21:55.77401Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4820536600e0938e","local-member-id":"34a8f480fa9ab6cb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:21:55.774124Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:21:55.774162Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:21:55.77649Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T11:21:55.77735Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.73:2379"} {"level":"info","ts":"2026-05-05T11:22:30.274437Z","caller":"traceutil/trace.go:171","msg":"trace[690794964] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"116.570824ms","start":"2026-05-05T11:22:30.15783Z","end":"2026-05-05T11:22:30.2744Z","steps":["trace[690794964] 'process raft request' (duration: 115.585604ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:22:30.477989Z","caller":"traceutil/trace.go:171","msg":"trace[907142343] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"142.125386ms","start":"2026-05-05T11:22:30.335838Z","end":"2026-05-05T11:22:30.477963Z","steps":["trace[907142343] 'process raft request' (duration: 142.006774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:22:31.00823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.920227ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:22:31.008367Z","caller":"traceutil/trace.go:171","msg":"trace[1136823037] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"494.582353ms","start":"2026-05-05T11:22:30.513766Z","end":"2026-05-05T11:22:31.008348Z","steps":["trace[1136823037] 'process raft request' (duration: 61.974903ms)","trace[1136823037] 'compare' (duration: 431.752404ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:22:31.008434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:22:30.513737Z","time spent":"494.665734ms","remote":"127.0.0.1:36484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:22:31.027356Z","caller":"traceutil/trace.go:171","msg":"trace[164092286] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"140.858623ms","start":"2026-05-05T11:22:30.88647Z","end":"2026-05-05T11:22:31.027329Z","steps":["trace[164092286] 'process raft request' (duration: 140.23462ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:23:12.032948Z","caller":"traceutil/trace.go:171","msg":"trace[8228138] linearizableReadLoop","detail":"{readStateIndex:1206; appliedIndex:1205; }","duration":"126.06326ms","start":"2026-05-05T11:23:11.906858Z","end":"2026-05-05T11:23:12.032921Z","steps":["trace[8228138] 'read index received' (duration: 125.846876ms)","trace[8228138] 'applied index is now lower than readState.Index' (duration: 215.424µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:23:12.033089Z","caller":"traceutil/trace.go:171","msg":"trace[2088886486] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"171.178575ms","start":"2026-05-05T11:23:11.861863Z","end":"2026-05-05T11:23:12.033041Z","steps":["trace[2088886486] 'process raft request' (duration: 170.9157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:23:12.03317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.298694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T11:23:12.033206Z","caller":"traceutil/trace.go:171","msg":"trace[715875554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1170; }","duration":"126.382166ms","start":"2026-05-05T11:23:11.906813Z","end":"2026-05-05T11:23:12.033195Z","steps":["trace[715875554] 'agreement among raft nodes before linearized reading' (duration: 126.235613ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:23:57.141012Z","caller":"traceutil/trace.go:171","msg":"trace[894549126] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"149.658231ms","start":"2026-05-05T11:23:56.991288Z","end":"2026-05-05T11:23:57.140946Z","steps":["trace[894549126] 'process raft request' (duration: 82.080821ms)","trace[894549126] 'compare' (duration: 67.385126ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:24:41.176894Z","caller":"traceutil/trace.go:171","msg":"trace[1190313694] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"136.87513ms","start":"2026-05-05T11:24:41.039988Z","end":"2026-05-05T11:24:41.176863Z","steps":["trace[1190313694] 'process raft request' (duration: 113.793416ms)","trace[1190313694] 'compare' (duration: 22.85223ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:24:47.030922Z","caller":"traceutil/trace.go:171","msg":"trace[1788818511] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"152.385312ms","start":"2026-05-05T11:24:46.878488Z","end":"2026-05-05T11:24:47.030874Z","steps":["trace[1788818511] 'process raft request' (duration: 152.050276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:24:47.259668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.235687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-05-05T11:24:47.25985Z","caller":"traceutil/trace.go:171","msg":"trace[1760057297] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2152; }","duration":"145.454581ms","start":"2026-05-05T11:24:47.114357Z","end":"2026-05-05T11:24:47.259812Z","steps":["trace[1760057297] 'range keys from in-memory index tree' (duration: 144.978372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:24:47.259697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.487578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:24:47.260069Z","caller":"traceutil/trace.go:171","msg":"trace[730807510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2152; }","duration":"124.925867ms","start":"2026-05-05T11:24:47.135111Z","end":"2026-05-05T11:24:47.260036Z","steps":["trace[730807510] 'range keys from in-memory index tree' (duration: 124.347384ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:25:37.042828Z","caller":"traceutil/trace.go:171","msg":"trace[322656118] transaction","detail":"{read_only:false; response_revision:2595; number_of_response:1; }","duration":"105.889688ms","start":"2026-05-05T11:25:36.936921Z","end":"2026-05-05T11:25:37.042811Z","steps":["trace[322656118] 'process raft request' (duration: 105.762186ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:26:05.753878Z","caller":"traceutil/trace.go:171","msg":"trace[1403529245] transaction","detail":"{read_only:false; response_revision:2771; number_of_response:1; }","duration":"106.069722ms","start":"2026-05-05T11:26:05.647785Z","end":"2026-05-05T11:26:05.753855Z","steps":["trace[1403529245] 'process raft request' (duration: 105.9209ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:26:05.771256Z","caller":"traceutil/trace.go:171","msg":"trace[1209945591] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"108.373707ms","start":"2026-05-05T11:26:05.662858Z","end":"2026-05-05T11:26:05.771232Z","steps":["trace[1209945591] 'process raft request' (duration: 108.204764ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:26:11.59704Z","caller":"traceutil/trace.go:171","msg":"trace[810169111] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"108.197644ms","start":"2026-05-05T11:26:11.488803Z","end":"2026-05-05T11:26:11.597Z","steps":["trace[810169111] 'process raft request' (duration: 107.989879ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:28:37.284455Z","caller":"traceutil/trace.go:171","msg":"trace[244432146] linearizableReadLoop","detail":"{readStateIndex:3913; appliedIndex:3912; }","duration":"110.871593ms","start":"2026-05-05T11:28:37.173548Z","end":"2026-05-05T11:28:37.284419Z","steps":["trace[244432146] 'read index received' (duration: 110.763971ms)","trace[244432146] 'applied index is now lower than readState.Index' (duration: 106.382µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:28:37.284477Z","caller":"traceutil/trace.go:171","msg":"trace[943096543] transaction","detail":"{read_only:false; response_revision:3769; number_of_response:1; }","duration":"111.133828ms","start":"2026-05-05T11:28:37.173308Z","end":"2026-05-05T11:28:37.284442Z","steps":["trace[943096543] 'process raft request' (duration: 110.895334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:28:37.284722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.152389ms","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-05-05T11:28:37.284811Z","caller":"traceutil/trace.go:171","msg":"trace[582238464] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3769; }","duration":"111.321822ms","start":"2026-05-05T11:28:37.173462Z","end":"2026-05-05T11:28:37.284784Z","steps":["trace[582238464] 'agreement among raft nodes before linearized reading' (duration: 111.070667ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:29:56.076779Z","caller":"traceutil/trace.go:171","msg":"trace[304313814] linearizableReadLoop","detail":"{readStateIndex:4956; appliedIndex:4955; }","duration":"104.688645ms","start":"2026-05-05T11:29:55.97207Z","end":"2026-05-05T11:29:56.076758Z","steps":["trace[304313814] 'read index received' (duration: 90.058789ms)","trace[304313814] 'applied index is now lower than readState.Index' (duration: 14.629136ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:29:56.077041Z","caller":"traceutil/trace.go:171","msg":"trace[1704373129] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"113.732195ms","start":"2026-05-05T11:29:55.963296Z","end":"2026-05-05T11:29:56.077028Z","steps":["trace[1704373129] 'process raft request' (duration: 98.823024ms)","trace[1704373129] 'compare' (duration: 14.542634ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:29:56.077148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.713884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-05-05T11:29:56.077223Z","caller":"traceutil/trace.go:171","msg":"trace[1393331456] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4772; }","duration":"113.808316ms","start":"2026-05-05T11:29:55.963396Z","end":"2026-05-05T11:29:56.077204Z","steps":["trace[1393331456] 'agreement among raft nodes before linearized reading' (duration: 113.614712ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:29:56.08954Z","caller":"traceutil/trace.go:171","msg":"trace[1115103568] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"114.109171ms","start":"2026-05-05T11:29:55.975404Z","end":"2026-05-05T11:29:56.089513Z","steps":["trace[1115103568] 'process raft request' (duration: 113.886087ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:29:56.089865Z","caller":"traceutil/trace.go:171","msg":"trace[986656100] transaction","detail":"{read_only:false; response_revision:4774; number_of_response:1; }","duration":"109.096896ms","start":"2026-05-05T11:29:55.980757Z","end":"2026-05-05T11:29:56.089854Z","steps":["trace[986656100] 'process raft request' (duration: 108.651568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:29:56.09007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.898648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-05T11:29:56.090145Z","caller":"traceutil/trace.go:171","msg":"trace[692980412] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4774; }","duration":"106.014349ms","start":"2026-05-05T11:29:55.984108Z","end":"2026-05-05T11:29:56.090122Z","steps":["trace[692980412] 'agreement among raft nodes before linearized reading' (duration: 105.807515ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:29:56.234686Z","caller":"traceutil/trace.go:171","msg":"trace[712452479] transaction","detail":"{read_only:false; response_revision:4776; number_of_response:1; }","duration":"129.497453ms","start":"2026-05-05T11:29:56.105159Z","end":"2026-05-05T11:29:56.234656Z","steps":["trace[712452479] 'process raft request' (duration: 100.838122ms)","trace[712452479] 'compare' (duration: 28.464477ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:29:56.234979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.014174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-6c1c10b3-d074-4ebd-be8f-86d57e18953c\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-05-05T11:29:56.235064Z","caller":"traceutil/trace.go:171","msg":"trace[337583603] range","detail":"{range_begin:/registry/persistentvolumes/pvc-6c1c10b3-d074-4ebd-be8f-86d57e18953c; range_end:; response_count:1; response_revision:4777; }","duration":"137.145767ms","start":"2026-05-05T11:29:56.097899Z","end":"2026-05-05T11:29:56.235044Z","steps":["trace[337583603] 'agreement among raft nodes before linearized reading' (duration: 136.885042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:29:59.679896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.323142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8bacf165-ee02-4a2a-a22f-e536a72eb33e\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-05-05T11:29:59.679997Z","caller":"traceutil/trace.go:171","msg":"trace[929617231] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8bacf165-ee02-4a2a-a22f-e536a72eb33e; range_end:; response_count:1; response_revision:4920; }","duration":"169.474015ms","start":"2026-05-05T11:29:59.510503Z","end":"2026-05-05T11:29:59.679977Z","steps":["trace[929617231] 'range keys from in-memory index tree' (duration: 169.108958ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:29:59.684744Z","caller":"traceutil/trace.go:171","msg":"trace[597139192] linearizableReadLoop","detail":"{readStateIndex:5110; appliedIndex:5109; }","duration":"118.826552ms","start":"2026-05-05T11:29:59.565893Z","end":"2026-05-05T11:29:59.684719Z","steps":["trace[597139192] 'read index received' (duration: 118.690009ms)","trace[597139192] 'applied index is now lower than readState.Index' (duration: 135.593µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:29:59.684831Z","caller":"traceutil/trace.go:171","msg":"trace[1511300728] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"121.476841ms","start":"2026-05-05T11:29:59.563328Z","end":"2026-05-05T11:29:59.684805Z","steps":["trace[1511300728] 'process raft request' (duration: 121.241627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:29:59.684982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.083276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T11:29:59.685028Z","caller":"traceutil/trace.go:171","msg":"trace[1253233559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4921; }","duration":"119.188158ms","start":"2026-05-05T11:29:59.565828Z","end":"2026-05-05T11:29:59.685016Z","steps":["trace[1253233559] 'agreement among raft nodes before linearized reading' (duration: 119.051055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:30:02.076111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.351793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:778242"} {"level":"info","ts":"2026-05-05T11:30:02.076192Z","caller":"traceutil/trace.go:171","msg":"trace[250250277] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4966; }","duration":"108.512527ms","start":"2026-05-05T11:30:01.967661Z","end":"2026-05-05T11:30:02.076174Z","steps":["trace[250250277] 'range keys from in-memory index tree' (duration: 107.853884ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:30:19.430735Z","caller":"traceutil/trace.go:171","msg":"trace[515537652] linearizableReadLoop","detail":"{readStateIndex:5523; appliedIndex:5522; }","duration":"323.34598ms","start":"2026-05-05T11:30:19.107349Z","end":"2026-05-05T11:30:19.430695Z","steps":["trace[515537652] 'read index received' (duration: 323.141006ms)","trace[515537652] 'applied index is now lower than readState.Index' (duration: 203.814µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:30:19.430918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.546864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1278c3ec-7f3f-4ec3-9bbf-34a9f1a8981a\" ","response":"range_response_count:1 size:3765"} {"level":"info","ts":"2026-05-05T11:30:19.430879Z","caller":"traceutil/trace.go:171","msg":"trace[197281417] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"332.861499ms","start":"2026-05-05T11:30:19.09796Z","end":"2026-05-05T11:30:19.430822Z","steps":["trace[197281417] 'process raft request' (duration: 332.558724ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:30:19.430963Z","caller":"traceutil/trace.go:171","msg":"trace[1318573606] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1278c3ec-7f3f-4ec3-9bbf-34a9f1a8981a; range_end:; response_count:1; response_revision:5321; }","duration":"323.627205ms","start":"2026-05-05T11:30:19.107324Z","end":"2026-05-05T11:30:19.430951Z","steps":["trace[1318573606] 'agreement among raft nodes before linearized reading' (duration: 323.533103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:30:19.431165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:30:19.097939Z","time spent":"333.096153ms","remote":"127.0.0.1:36484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:30:19.431125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.715328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:30:19.431388Z","caller":"traceutil/trace.go:171","msg":"trace[1502062980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5321; }","duration":"295.971053ms","start":"2026-05-05T11:30:19.135376Z","end":"2026-05-05T11:30:19.431347Z","steps":["trace[1502062980] 'agreement among raft nodes before linearized reading' (duration: 295.583656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:30:19.431485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:30:19.107308Z","time spent":"323.93177ms","remote":"127.0.0.1:36484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3787,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1278c3ec-7f3f-4ec3-9bbf-34a9f1a8981a\" "} {"level":"info","ts":"2026-05-05T11:30:21.217386Z","caller":"traceutil/trace.go:171","msg":"trace[1273232134] linearizableReadLoop","detail":"{readStateIndex:5551; appliedIndex:5550; }","duration":"101.662748ms","start":"2026-05-05T11:30:21.115683Z","end":"2026-05-05T11:30:21.217346Z","steps":["trace[1273232134] 'read index received' (duration: 97.815265ms)","trace[1273232134] 'applied index is now lower than readState.Index' (duration: 3.846353ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:30:21.217444Z","caller":"traceutil/trace.go:171","msg":"trace[163850145] transaction","detail":"{read_only:false; response_revision:5349; number_of_response:1; }","duration":"107.458607ms","start":"2026-05-05T11:30:21.109949Z","end":"2026-05-05T11:30:21.217407Z","steps":["trace[163850145] 'process raft request' (duration: 103.565784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:30:21.21758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.877542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:30:21.217659Z","caller":"traceutil/trace.go:171","msg":"trace[1345306744] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:5349; }","duration":"102.034476ms","start":"2026-05-05T11:30:21.115607Z","end":"2026-05-05T11:30:21.217642Z","steps":["trace[1345306744] 'agreement among raft nodes before linearized reading' (duration: 101.895293ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:31:55.803593Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3045} {"level":"info","ts":"2026-05-05T11:31:55.890791Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3045,"took":"85.691249ms","hash":1557161809,"current-db-size-bytes":59416576,"current-db-size":"59 MB","current-db-size-in-use-bytes":32362496,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-05T11:31:55.890868Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1557161809,"revision":3045,"compact-revision":-1} {"level":"info","ts":"2026-05-05T11:36:00.712276Z","caller":"traceutil/trace.go:171","msg":"trace[864876697] transaction","detail":"{read_only:false; response_revision:8032; number_of_response:1; }","duration":"159.753601ms","start":"2026-05-05T11:36:00.552501Z","end":"2026-05-05T11:36:00.712255Z","steps":["trace[864876697] 'process raft request' (duration: 159.652739ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:36:12.333843Z","caller":"traceutil/trace.go:171","msg":"trace[1162297847] transaction","detail":"{read_only:false; response_revision:8094; number_of_response:1; }","duration":"163.114365ms","start":"2026-05-05T11:36:12.170696Z","end":"2026-05-05T11:36:12.333811Z","steps":["trace[1162297847] 'process raft request' (duration: 162.613155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:36:12.582847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.98352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-h8tfc\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-05-05T11:36:12.582945Z","caller":"traceutil/trace.go:171","msg":"trace[1186855822] range","detail":"{range_begin:/registry/pods/openstack/keepalived-h8tfc; range_end:; response_count:1; response_revision:8094; }","duration":"130.142602ms","start":"2026-05-05T11:36:12.452782Z","end":"2026-05-05T11:36:12.582924Z","steps":["trace[1186855822] 'range keys from in-memory index tree' (duration: 129.760505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:36:12.582932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.263199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:36:12.583045Z","caller":"traceutil/trace.go:171","msg":"trace[407955630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8094; }","duration":"109.393742ms","start":"2026-05-05T11:36:12.473627Z","end":"2026-05-05T11:36:12.583021Z","steps":["trace[407955630] 'range keys from in-memory index tree' (duration: 109.154658ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:36:14.10663Z","caller":"traceutil/trace.go:171","msg":"trace[1186498888] transaction","detail":"{read_only:false; response_revision:8102; number_of_response:1; }","duration":"105.947778ms","start":"2026-05-05T11:36:14.000612Z","end":"2026-05-05T11:36:14.10656Z","steps":["trace[1186498888] 'process raft request' (duration: 80.468468ms)","trace[1186498888] 'compare' (duration: 25.328087ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:36:41.088916Z","caller":"traceutil/trace.go:171","msg":"trace[1011138893] transaction","detail":"{read_only:false; response_revision:8303; number_of_response:1; }","duration":"167.205351ms","start":"2026-05-05T11:36:40.921655Z","end":"2026-05-05T11:36:41.08886Z","steps":["trace[1011138893] 'process raft request' (duration: 166.944116ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:36:41.28337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.154531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:36:41.283456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.859594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:36:41.283841Z","caller":"traceutil/trace.go:171","msg":"trace[1920406174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8303; }","duration":"150.310703ms","start":"2026-05-05T11:36:41.133494Z","end":"2026-05-05T11:36:41.283805Z","steps":["trace[1920406174] 'range keys from in-memory index tree' (duration: 149.730623ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:36:41.283509Z","caller":"traceutil/trace.go:171","msg":"trace[1734595801] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8303; }","duration":"123.385306ms","start":"2026-05-05T11:36:41.160093Z","end":"2026-05-05T11:36:41.283478Z","steps":["trace[1734595801] 'range keys from in-memory index tree' (duration: 122.972908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:36:54.600068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.459613ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:36:54.600187Z","caller":"traceutil/trace.go:171","msg":"trace[1370436313] transaction","detail":"{read_only:false; response_revision:8414; number_of_response:1; }","duration":"231.80929ms","start":"2026-05-05T11:36:54.368358Z","end":"2026-05-05T11:36:54.600167Z","steps":["trace[1370436313] 'process raft request' (duration: 120.94488ms)","trace[1370436313] 'compare' (duration: 110.221068ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:36:54.62119Z","caller":"traceutil/trace.go:171","msg":"trace[183781662] transaction","detail":"{read_only:false; response_revision:8415; number_of_response:1; }","duration":"249.28919ms","start":"2026-05-05T11:36:54.371859Z","end":"2026-05-05T11:36:54.621148Z","steps":["trace[183781662] 'process raft request' (duration: 249.118437ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:36:55.809694Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6201} {"level":"info","ts":"2026-05-05T11:36:55.914364Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6201,"took":"101.754459ms","hash":4107704359,"current-db-size-bytes":59416576,"current-db-size":"59 MB","current-db-size-in-use-bytes":23433216,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-05T11:36:55.914438Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4107704359,"revision":6201,"compact-revision":3045} {"level":"info","ts":"2026-05-05T11:37:53.375006Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002467.wal"} {"level":"info","ts":"2026-05-05T11:39:21.402755Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"34a8f480fa9ab6cb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-05T11:39:21.407958Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-05T11:39:21.408045Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-05-05T11:41:46.185568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.499179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:41:46.185839Z","caller":"traceutil/trace.go:171","msg":"trace[898868968] linearizableReadLoop","detail":"{readStateIndex:11330; appliedIndex:11329; }","duration":"140.14538ms","start":"2026-05-05T11:41:46.045667Z","end":"2026-05-05T11:41:46.185812Z","steps":["trace[898868968] 'read index received' (duration: 10.188762ms)","trace[898868968] 'applied index is now lower than readState.Index' (duration: 129.953228ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:41:46.186037Z","caller":"traceutil/trace.go:171","msg":"trace[41700807] transaction","detail":"{read_only:false; response_revision:10957; number_of_response:1; }","duration":"244.898253ms","start":"2026-05-05T11:41:45.941104Z","end":"2026-05-05T11:41:46.186003Z","steps":["trace[41700807] 'process raft request' (duration: 114.823613ms)","trace[41700807] 'compare' (duration: 129.248054ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:41:46.186227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.516977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T11:41:46.186371Z","caller":"traceutil/trace.go:171","msg":"trace[888250521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10957; }","duration":"140.726241ms","start":"2026-05-05T11:41:46.045612Z","end":"2026-05-05T11:41:46.186338Z","steps":["trace[888250521] 'agreement among raft nodes before linearized reading' (duration: 140.401596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:41:46.208631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.614396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:41:46.208695Z","caller":"traceutil/trace.go:171","msg":"trace[360213] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:10958; }","duration":"126.710967ms","start":"2026-05-05T11:41:46.081968Z","end":"2026-05-05T11:41:46.208679Z","steps":["trace[360213] 'agreement among raft nodes before linearized reading' (duration: 126.565915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:41:46.208816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.925488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:41:46.208854Z","caller":"traceutil/trace.go:171","msg":"trace[1952901242] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:10958; }","duration":"121.009739ms","start":"2026-05-05T11:41:46.087832Z","end":"2026-05-05T11:41:46.208842Z","steps":["trace[1952901242] 'agreement among raft nodes before linearized reading' (duration: 120.966918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:41:46.208763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.463721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"warn","ts":"2026-05-05T11:41:46.208728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.383597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:41:46.208987Z","caller":"traceutil/trace.go:171","msg":"trace[272565249] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:10958; }","duration":"120.681233ms","start":"2026-05-05T11:41:46.088279Z","end":"2026-05-05T11:41:46.20896Z","steps":["trace[272565249] 'agreement among raft nodes before linearized reading' (duration: 120.351987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:41:46.208978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.0336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:41:46.209Z","caller":"traceutil/trace.go:171","msg":"trace[1927139988] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:10958; }","duration":"119.671904ms","start":"2026-05-05T11:41:46.089271Z","end":"2026-05-05T11:41:46.208943Z","steps":["trace[1927139988] 'agreement among raft nodes before linearized reading' (duration: 119.406069ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:41:46.20908Z","caller":"traceutil/trace.go:171","msg":"trace[1724838804] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:10958; }","duration":"121.238583ms","start":"2026-05-05T11:41:46.087814Z","end":"2026-05-05T11:41:46.209052Z","steps":["trace[1724838804] 'agreement among raft nodes before linearized reading' (duration: 120.923607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:41:46.20928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.026882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:666"} {"level":"info","ts":"2026-05-05T11:41:46.209349Z","caller":"traceutil/trace.go:171","msg":"trace[399215976] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:10958; }","duration":"127.210545ms","start":"2026-05-05T11:41:46.082116Z","end":"2026-05-05T11:41:46.209327Z","steps":["trace[399215976] 'agreement among raft nodes before linearized reading' (duration: 126.666445ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:41:55.820232Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8424} {"level":"info","ts":"2026-05-05T11:41:55.903133Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8424,"took":"79.122801ms","hash":1334847338,"current-db-size-bytes":59416576,"current-db-size":"59 MB","current-db-size-in-use-bytes":26349568,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-05T11:41:55.90323Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1334847338,"revision":8424,"compact-revision":6201} {"level":"info","ts":"2026-05-05T11:46:55.826538Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11038} {"level":"info","ts":"2026-05-05T11:46:55.909847Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11038,"took":"81.020478ms","hash":123248173,"current-db-size-bytes":59416576,"current-db-size":"59 MB","current-db-size-in-use-bytes":27799552,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-05T11:46:55.909989Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":123248173,"revision":11038,"compact-revision":8424}