{"level":"warn","ts":"2026-04-16T22:48:44.621852Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T22:48:44.621963Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-04-16T22:48:44.62204Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T22:48:44.622052Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-04-16T22:48:44.622074Z","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-04-16T22:48:44.622718Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-04-16T22:48:44.622793Z","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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-04-16T22:48:44.627703Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.671991ms"} {"level":"info","ts":"2026-04-16T22:48:44.63412Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-04-16T22:48:44.634243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T22:48:44.634272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-04-16T22:48:44.634283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T22:48:44.634299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-04-16T22:48:44.634348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-04-16T22:48:44.643669Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T22:48:44.646055Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T22:48:44.647389Z","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-04-16T22:48:44.65121Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T22:48:44.651382Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T22:48:44.651546Z","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-04-16T22:48:44.651627Z","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-04-16T22:48:44.651675Z","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-04-16T22:48:44.652626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-04-16T22:48:44.652737Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-04-16T22:48:44.656591Z","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-04-16T22:48:44.656763Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-04-16T22:48:44.656846Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-04-16T22:48:44.656919Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T22:48:44.656982Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T22:48:45.235335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T22:48:45.235385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T22:48:45.235419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-04-16T22:48:45.235432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-04-16T22:48:45.235437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-04-16T22:48:45.235449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-04-16T22:48:45.235455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-04-16T22:48:45.237619Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T22:48:45.237654Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T22:48:45.237683Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:48:45.237735Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T22:48:45.238575Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T22:48:45.23861Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T22:48:45.238935Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:48:45.239053Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:48:45.239095Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:48:45.240933Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T22:48:45.24213Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"warn","ts":"2026-04-16T22:48:58.633703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.715003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:48:58.633876Z","caller":"traceutil/trace.go:171","msg":"trace[66320559] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"252.427889ms","start":"2026-04-16T22:48:58.381425Z","end":"2026-04-16T22:48:58.633853Z","steps":["trace[66320559] 'process raft request' (duration: 126.77744ms)","trace[66320559] 'compare' (duration: 124.534117ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:49:15.544071Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853969866483785295,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T22:49:15.737947Z","caller":"traceutil/trace.go:171","msg":"trace[979689830] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"813.710796ms","start":"2026-04-16T22:49:14.924206Z","end":"2026-04-16T22:49:15.737917Z","steps":["trace[979689830] 'process raft request' (duration: 811.101733ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:15.738005Z","caller":"traceutil/trace.go:171","msg":"trace[1590873369] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"807.429259ms","start":"2026-04-16T22:49:14.930524Z","end":"2026-04-16T22:49:15.737953Z","steps":["trace[1590873369] 'process raft request' (duration: 807.308376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:15.738095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:49:14.924185Z","time spent":"813.82902ms","remote":"127.0.0.1:37054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:49:15.73821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:49:14.930501Z","time spent":"807.600215ms","remote":"127.0.0.1:37054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:49:15.746701Z","caller":"traceutil/trace.go:171","msg":"trace[1390920231] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"695.435506ms","start":"2026-04-16T22:49:15.051244Z","end":"2026-04-16T22:49:15.746679Z","steps":["trace[1390920231] 'process raft request' (duration: 695.19413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:15.74679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:49:15.051224Z","time spent":"695.523418ms","remote":"127.0.0.1:37054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:49:15.746867Z","caller":"traceutil/trace.go:171","msg":"trace[1438580234] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:565; }","duration":"703.674938ms","start":"2026-04-16T22:49:15.043169Z","end":"2026-04-16T22:49:15.746844Z","steps":["trace[1438580234] 'read index received' (duration: 692.140043ms)","trace[1438580234] 'applied index is now lower than readState.Index' (duration: 11.534245ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:49:15.746965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.789061ms","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-04-16T22:49:15.746912Z","caller":"traceutil/trace.go:171","msg":"trace[1071801649] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"676.197564ms","start":"2026-04-16T22:49:15.070693Z","end":"2026-04-16T22:49:15.746891Z","steps":["trace[1071801649] 'process raft request' (duration: 675.964818ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:15.747007Z","caller":"traceutil/trace.go:171","msg":"trace[277392037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:547; }","duration":"703.866613ms","start":"2026-04-16T22:49:15.04313Z","end":"2026-04-16T22:49:15.746996Z","steps":["trace[277392037] 'agreement among raft nodes before linearized reading' (duration: 703.794711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:15.747036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:49:15.043109Z","time spent":"703.920635ms","remote":"127.0.0.1:37232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T22:49:15.748409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:49:15.07067Z","time spent":"676.358868ms","remote":"127.0.0.1:37054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:49:15.747069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.999365ms","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-04-16T22:49:15.747068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.810262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-16T22:49:15.748623Z","caller":"traceutil/trace.go:171","msg":"trace[190077890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:547; }","duration":"705.356375ms","start":"2026-04-16T22:49:15.043218Z","end":"2026-04-16T22:49:15.748574Z","steps":["trace[190077890] 'agreement among raft nodes before linearized reading' (duration: 703.7311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:15.748643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.031341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:49:15.748698Z","caller":"traceutil/trace.go:171","msg":"trace[1879842487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"697.098092ms","start":"2026-04-16T22:49:15.051585Z","end":"2026-04-16T22:49:15.748684Z","steps":["trace[1879842487] 'agreement among raft nodes before linearized reading' (duration: 697.02384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:15.748697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:49:15.043211Z","time spent":"705.454198ms","remote":"127.0.0.1:37232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T22:49:15.748737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:49:15.051579Z","time spent":"697.148173ms","remote":"127.0.0.1:37030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T22:49:15.748775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.070282ms","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-04-16T22:49:15.748856Z","caller":"traceutil/trace.go:171","msg":"trace[824993617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:547; }","duration":"261.206096ms","start":"2026-04-16T22:49:15.487628Z","end":"2026-04-16T22:49:15.748835Z","steps":["trace[824993617] 'agreement among raft nodes before linearized reading' (duration: 260.948039ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:15.749013Z","caller":"traceutil/trace.go:171","msg":"trace[1192428020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:547; }","duration":"184.966499ms","start":"2026-04-16T22:49:15.564007Z","end":"2026-04-16T22:49:15.748974Z","steps":["trace[1192428020] 'agreement among raft nodes before linearized reading' (duration: 183.004715ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:21.9909Z","caller":"traceutil/trace.go:171","msg":"trace[123547592] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"186.749487ms","start":"2026-04-16T22:49:21.804125Z","end":"2026-04-16T22:49:21.990874Z","steps":["trace[123547592] 'read index received' (duration: 184.32323ms)","trace[123547592] 'applied index is now lower than readState.Index' (duration: 2.425217ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:49:21.990967Z","caller":"traceutil/trace.go:171","msg":"trace[213471968] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"207.934175ms","start":"2026-04-16T22:49:21.782998Z","end":"2026-04-16T22:49:21.990932Z","steps":["trace[213471968] 'process raft request' (duration: 205.362914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:21.991282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.214363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-16T22:49:21.991356Z","caller":"traceutil/trace.go:171","msg":"trace[669742990] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"208.346377ms","start":"2026-04-16T22:49:21.782972Z","end":"2026-04-16T22:49:21.991319Z","steps":["trace[669742990] 'agreement among raft nodes before linearized reading' (duration: 208.032668ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:22.025039Z","caller":"traceutil/trace.go:171","msg":"trace[242781176] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"213.93155ms","start":"2026-04-16T22:49:21.811083Z","end":"2026-04-16T22:49:22.025014Z","steps":["trace[242781176] 'process raft request' (duration: 213.639182ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:22.032254Z","caller":"traceutil/trace.go:171","msg":"trace[1652850160] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"173.513387ms","start":"2026-04-16T22:49:21.858702Z","end":"2026-04-16T22:49:22.032216Z","steps":["trace[1652850160] 'process raft request' (duration: 167.309405ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:49:22.435954Z","caller":"traceutil/trace.go:171","msg":"trace[956207385] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"380.576495ms","start":"2026-04-16T22:49:22.055348Z","end":"2026-04-16T22:49:22.435925Z","steps":["trace[956207385] 'read index received' (duration: 380.346478ms)","trace[956207385] 'applied index is now lower than readState.Index' (duration: 228.917µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:49:22.436029Z","caller":"traceutil/trace.go:171","msg":"trace[1773039437] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"389.746649ms","start":"2026-04-16T22:49:22.046236Z","end":"2026-04-16T22:49:22.435982Z","steps":["trace[1773039437] 'process raft request' (duration: 389.464842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:22.436093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.725248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:49:22.436128Z","caller":"traceutil/trace.go:171","msg":"trace[1131566491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"380.815931ms","start":"2026-04-16T22:49:22.055301Z","end":"2026-04-16T22:49:22.436117Z","steps":["trace[1131566491] 'agreement among raft nodes before linearized reading' (duration: 380.727769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:22.436169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:49:22.055278Z","time spent":"380.884224ms","remote":"127.0.0.1:37018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T22:49:22.436232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:49:22.046207Z","time spent":"389.909274ms","remote":"127.0.0.1:37166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:49:22.509786Z","caller":"traceutil/trace.go:171","msg":"trace[588704138] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"421.866372ms","start":"2026-04-16T22:49:22.087892Z","end":"2026-04-16T22:49:22.509758Z","steps":["trace[588704138] 'process raft request' (duration: 421.728418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:49:22.509934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:49:22.087867Z","time spent":"421.992865ms","remote":"127.0.0.1:37216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:49:38.573995Z","caller":"traceutil/trace.go:171","msg":"trace[1653266603] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"196.588407ms","start":"2026-04-16T22:49:38.377373Z","end":"2026-04-16T22:49:38.573961Z","steps":["trace[1653266603] 'process raft request' (duration: 140.952063ms)","trace[1653266603] 'compare' (duration: 55.350067ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:50:04.450576Z","caller":"traceutil/trace.go:171","msg":"trace[82885356] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"176.199352ms","start":"2026-04-16T22:50:04.274341Z","end":"2026-04-16T22:50:04.450541Z","steps":["trace[82885356] 'process raft request' (duration: 175.945375ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:50:04.451662Z","caller":"traceutil/trace.go:171","msg":"trace[1507027530] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"173.832685ms","start":"2026-04-16T22:50:04.277795Z","end":"2026-04-16T22:50:04.451628Z","steps":["trace[1507027530] 'process raft request' (duration: 173.742423ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:50:04.451667Z","caller":"traceutil/trace.go:171","msg":"trace[576598159] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"174.372991ms","start":"2026-04-16T22:50:04.277255Z","end":"2026-04-16T22:50:04.451628Z","steps":["trace[576598159] 'process raft request' (duration: 174.098304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:50:48.0093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.746759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:50:48.009582Z","caller":"traceutil/trace.go:171","msg":"trace[2101701258] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"377.269566ms","start":"2026-04-16T22:50:47.63228Z","end":"2026-04-16T22:50:48.009549Z","steps":["trace[2101701258] 'process raft request' (duration: 33.619852ms)","trace[2101701258] 'compare' (duration: 342.351208ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:50:48.009773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:50:47.632253Z","time spent":"377.40085ms","remote":"127.0.0.1:37232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:50:48.009873Z","caller":"traceutil/trace.go:171","msg":"trace[1923745099] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"368.237574ms","start":"2026-04-16T22:50:47.64155Z","end":"2026-04-16T22:50:48.009788Z","steps":["trace[1923745099] 'process raft request' (duration: 367.900895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:50:48.010142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:50:47.641492Z","time spent":"368.554802ms","remote":"127.0.0.1:37232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:50:48.407978Z","caller":"traceutil/trace.go:171","msg":"trace[1146981798] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"181.588186ms","start":"2026-04-16T22:50:48.22637Z","end":"2026-04-16T22:50:48.407958Z","steps":["trace[1146981798] 'process raft request' (duration: 181.473681ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:50:48.408748Z","caller":"traceutil/trace.go:171","msg":"trace[1258590111] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1886; }","duration":"144.541699ms","start":"2026-04-16T22:50:48.264188Z","end":"2026-04-16T22:50:48.40873Z","steps":["trace[1258590111] 'read index received' (duration: 143.682924ms)","trace[1258590111] 'applied index is now lower than readState.Index' (duration: 858.015µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:50:48.408828Z","caller":"traceutil/trace.go:171","msg":"trace[1140402225] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"182.194283ms","start":"2026-04-16T22:50:48.226627Z","end":"2026-04-16T22:50:48.408821Z","steps":["trace[1140402225] 'process raft request' (duration: 182.022058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:50:48.409092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.885568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c91cdd85-5ee8-4cca-8067-351a9d96a694\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-16T22:50:48.409201Z","caller":"traceutil/trace.go:171","msg":"trace[2080331038] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c91cdd85-5ee8-4cca-8067-351a9d96a694; range_end:; response_count:1; response_revision:1819; }","duration":"145.041953ms","start":"2026-04-16T22:50:48.264141Z","end":"2026-04-16T22:50:48.409183Z","steps":["trace[2080331038] 'agreement among raft nodes before linearized reading' (duration: 144.734284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:31.464811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.270571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:51:31.465305Z","caller":"traceutil/trace.go:171","msg":"trace[1212378530] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"453.10534ms","start":"2026-04-16T22:51:31.012166Z","end":"2026-04-16T22:51:31.465271Z","steps":["trace[1212378530] 'process raft request' (duration: 452.78624ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:31.46536Z","caller":"traceutil/trace.go:171","msg":"trace[1849703258] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"453.812421ms","start":"2026-04-16T22:51:31.011509Z","end":"2026-04-16T22:51:31.465322Z","steps":["trace[1849703258] 'process raft request' (duration: 77.949593ms)","trace[1849703258] 'compare' (duration: 375.133146ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:51:31.465495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:31.012131Z","time spent":"453.270635ms","remote":"127.0.0.1:37232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:51:31.465509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:31.011485Z","time spent":"453.942964ms","remote":"127.0.0.1:37232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:51:32.465632Z","caller":"traceutil/trace.go:171","msg":"trace[717858427] linearizableReadLoop","detail":"{readStateIndex:2193; appliedIndex:2192; }","duration":"203.767353ms","start":"2026-04-16T22:51:32.261843Z","end":"2026-04-16T22:51:32.46561Z","steps":["trace[717858427] 'read index received' (duration: 186.665342ms)","trace[717858427] 'applied index is now lower than readState.Index' (duration: 17.100751ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:32.465699Z","caller":"traceutil/trace.go:171","msg":"trace[1428252363] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"221.668706ms","start":"2026-04-16T22:51:32.244007Z","end":"2026-04-16T22:51:32.465676Z","steps":["trace[1428252363] 'process raft request' (duration: 204.504543ms)","trace[1428252363] 'compare' (duration: 16.790922ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:51:32.465818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.958879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-16T22:51:32.465856Z","caller":"traceutil/trace.go:171","msg":"trace[719481755] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2113; }","duration":"204.039611ms","start":"2026-04-16T22:51:32.261805Z","end":"2026-04-16T22:51:32.465845Z","steps":["trace[719481755] 'agreement among raft nodes before linearized reading' (duration: 203.902737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:32.46599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.69799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12383"} {"level":"info","ts":"2026-04-16T22:51:32.466056Z","caller":"traceutil/trace.go:171","msg":"trace[1782813967] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2113; }","duration":"167.792783ms","start":"2026-04-16T22:51:32.298257Z","end":"2026-04-16T22:51:32.46605Z","steps":["trace[1782813967] 'agreement among raft nodes before linearized reading' (duration: 167.664969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:37.711418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.706775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T22:51:37.71152Z","caller":"traceutil/trace.go:171","msg":"trace[542401492] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2141; }","duration":"138.86544ms","start":"2026-04-16T22:51:37.57263Z","end":"2026-04-16T22:51:37.711496Z","steps":["trace[542401492] 'count revisions from in-memory index tree' (duration: 138.591522ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:22.74493Z","caller":"traceutil/trace.go:171","msg":"trace[76937179] transaction","detail":"{read_only:false; response_revision:2558; number_of_response:1; }","duration":"128.021866ms","start":"2026-04-16T22:52:22.616891Z","end":"2026-04-16T22:52:22.744913Z","steps":["trace[76937179] 'process raft request' (duration: 127.780249ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:17.995307Z","caller":"traceutil/trace.go:171","msg":"trace[2131975049] transaction","detail":"{read_only:false; response_revision:3712; number_of_response:1; }","duration":"145.748439ms","start":"2026-04-16T22:55:17.849521Z","end":"2026-04-16T22:55:17.99527Z","steps":["trace[2131975049] 'process raft request' (duration: 114.502554ms)","trace[2131975049] 'compare' (duration: 31.115351ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:56:48.313578Z","caller":"traceutil/trace.go:171","msg":"trace[383467002] linearizableReadLoop","detail":"{readStateIndex:5115; appliedIndex:5114; }","duration":"129.048577ms","start":"2026-04-16T22:56:48.18451Z","end":"2026-04-16T22:56:48.313559Z","steps":["trace[383467002] 'read index received' (duration: 128.957035ms)","trace[383467002] 'applied index is now lower than readState.Index' (duration: 90.702µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:56:48.313685Z","caller":"traceutil/trace.go:171","msg":"trace[2109234410] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"244.75653ms","start":"2026-04-16T22:56:48.06892Z","end":"2026-04-16T22:56:48.313677Z","steps":["trace[2109234410] 'process raft request' (duration: 244.533984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:56:48.31386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.346456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48ceec15-93ce-4112-a256-941fd89d2a8e\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-16T22:56:48.313889Z","caller":"traceutil/trace.go:171","msg":"trace[773101639] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-48ceec15-93ce-4112-a256-941fd89d2a8e; range_end:; response_count:1; response_revision:4928; }","duration":"129.424138ms","start":"2026-04-16T22:56:48.184458Z","end":"2026-04-16T22:56:48.313882Z","steps":["trace[773101639] 'agreement among raft nodes before linearized reading' (duration: 129.305345ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:56:48.419772Z","caller":"traceutil/trace.go:171","msg":"trace[1989443567] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"100.282668ms","start":"2026-04-16T22:56:48.31946Z","end":"2026-04-16T22:56:48.419742Z","steps":["trace[1989443567] 'process raft request' (duration: 100.076672ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:56:58.05371Z","caller":"traceutil/trace.go:171","msg":"trace[968317813] linearizableReadLoop","detail":"{readStateIndex:5321; appliedIndex:5320; }","duration":"157.956233ms","start":"2026-04-16T22:56:57.895723Z","end":"2026-04-16T22:56:58.05368Z","steps":["trace[968317813] 'read index received' (duration: 157.759927ms)","trace[968317813] 'applied index is now lower than readState.Index' (duration: 195.336µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:56:58.053764Z","caller":"traceutil/trace.go:171","msg":"trace[1443225469] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"165.492615ms","start":"2026-04-16T22:56:57.888231Z","end":"2026-04-16T22:56:58.053723Z","steps":["trace[1443225469] 'process raft request' (duration: 165.271399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:56:58.053977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.251282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-24pvd.18a6f8621126dc28\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-04-16T22:56:58.054066Z","caller":"traceutil/trace.go:171","msg":"trace[1114563630] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-24pvd.18a6f8621126dc28; range_end:; response_count:1; response_revision:5128; }","duration":"158.382536ms","start":"2026-04-16T22:56:57.895669Z","end":"2026-04-16T22:56:58.054052Z","steps":["trace[1114563630] 'agreement among raft nodes before linearized reading' (duration: 158.114748ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:57:05.570023Z","caller":"traceutil/trace.go:171","msg":"trace[786515481] linearizableReadLoop","detail":"{readStateIndex:5491; appliedIndex:5490; }","duration":"163.243533ms","start":"2026-04-16T22:57:05.406752Z","end":"2026-04-16T22:57:05.569996Z","steps":["trace[786515481] 'read index received' (duration: 163.033686ms)","trace[786515481] 'applied index is now lower than readState.Index' (duration: 208.237µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:57:05.570078Z","caller":"traceutil/trace.go:171","msg":"trace[1975873323] transaction","detail":"{read_only:false; response_revision:5294; number_of_response:1; }","duration":"204.374319ms","start":"2026-04-16T22:57:05.36567Z","end":"2026-04-16T22:57:05.570045Z","steps":["trace[1975873323] 'process raft request' (duration: 204.153632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:57:05.570176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.396838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fe55fc31-11eb-4262-ac3c-d84db90d3e9e\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-04-16T22:57:05.570221Z","caller":"traceutil/trace.go:171","msg":"trace[469812699] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fe55fc31-11eb-4262-ac3c-d84db90d3e9e; range_end:; response_count:1; response_revision:5294; }","duration":"163.49501ms","start":"2026-04-16T22:57:05.406715Z","end":"2026-04-16T22:57:05.57021Z","steps":["trace[469812699] 'agreement among raft nodes before linearized reading' (duration: 163.391527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:57:05.8473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.872835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:57:05.847419Z","caller":"traceutil/trace.go:171","msg":"trace[360918231] linearizableReadLoop","detail":"{readStateIndex:5492; appliedIndex:5491; }","duration":"229.776034ms","start":"2026-04-16T22:57:05.617626Z","end":"2026-04-16T22:57:05.847402Z","steps":["trace[360918231] 'read index received' (duration: 106.597419ms)","trace[360918231] 'applied index is now lower than readState.Index' (duration: 123.177425ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:57:05.847502Z","caller":"traceutil/trace.go:171","msg":"trace[375364709] transaction","detail":"{read_only:false; response_revision:5295; number_of_response:1; }","duration":"305.822003ms","start":"2026-04-16T22:57:05.541633Z","end":"2026-04-16T22:57:05.847455Z","steps":["trace[375364709] 'process raft request' (duration: 182.7021ms)","trace[375364709] 'compare' (duration: 122.66675ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:57:05.847776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:57:05.541593Z","time spent":"306.06201ms","remote":"127.0.0.1:37232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:57:05.847803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.184414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9nc5t\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-16T22:57:05.847843Z","caller":"traceutil/trace.go:171","msg":"trace[59807669] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9nc5t; range_end:; response_count:1; response_revision:5295; }","duration":"230.226706ms","start":"2026-04-16T22:57:05.617606Z","end":"2026-04-16T22:57:05.847833Z","steps":["trace[59807669] 'agreement among raft nodes before linearized reading' (duration: 229.842795ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:57:15.821714Z","caller":"traceutil/trace.go:171","msg":"trace[1165538693] transaction","detail":"{read_only:false; response_revision:5436; number_of_response:1; }","duration":"138.589048ms","start":"2026-04-16T22:57:15.683079Z","end":"2026-04-16T22:57:15.821668Z","steps":["trace[1165538693] 'process raft request' (duration: 107.622237ms)","trace[1165538693] 'compare' (duration: 30.724195ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:57:15.821797Z","caller":"traceutil/trace.go:171","msg":"trace[1861256120] transaction","detail":"{read_only:false; response_revision:5438; number_of_response:1; }","duration":"138.2991ms","start":"2026-04-16T22:57:15.68346Z","end":"2026-04-16T22:57:15.821759Z","steps":["trace[1861256120] 'process raft request' (duration: 138.175366ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:57:15.8218Z","caller":"traceutil/trace.go:171","msg":"trace[61066224] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"138.583368ms","start":"2026-04-16T22:57:15.683186Z","end":"2026-04-16T22:57:15.821769Z","steps":["trace[61066224] 'process raft request' (duration: 138.394283ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:57:15.822158Z","caller":"traceutil/trace.go:171","msg":"trace[1302434882] transaction","detail":"{read_only:false; response_revision:5439; number_of_response:1; }","duration":"127.448665ms","start":"2026-04-16T22:57:15.694667Z","end":"2026-04-16T22:57:15.822116Z","steps":["trace[1302434882] 'process raft request' (duration: 127.016243ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:57:16.016251Z","caller":"traceutil/trace.go:171","msg":"trace[2083150419] transaction","detail":"{read_only:false; response_revision:5440; number_of_response:1; }","duration":"184.385526ms","start":"2026-04-16T22:57:15.831798Z","end":"2026-04-16T22:57:16.016184Z","steps":["trace[2083150419] 'process raft request' (duration: 94.576419ms)","trace[2083150419] 'compare' (duration: 89.542219ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:57:16.016296Z","caller":"traceutil/trace.go:171","msg":"trace[738300432] transaction","detail":"{read_only:false; response_revision:5441; number_of_response:1; }","duration":"172.730048ms","start":"2026-04-16T22:57:15.84353Z","end":"2026-04-16T22:57:16.01626Z","steps":["trace[738300432] 'process raft request' (duration: 172.537872ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:57:16.016474Z","caller":"traceutil/trace.go:171","msg":"trace[1411857491] linearizableReadLoop","detail":"{readStateIndex:5642; appliedIndex:5638; }","duration":"118.854754ms","start":"2026-04-16T22:57:15.897541Z","end":"2026-04-16T22:57:16.016395Z","steps":["trace[1411857491] 'read index received' (duration: 28.845751ms)","trace[1411857491] 'applied index is now lower than readState.Index' (duration: 90.006823ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:57:16.016603Z","caller":"traceutil/trace.go:171","msg":"trace[557025061] transaction","detail":"{read_only:false; response_revision:5442; number_of_response:1; }","duration":"132.321452ms","start":"2026-04-16T22:57:15.883924Z","end":"2026-04-16T22:57:16.016245Z","steps":["trace[557025061] 'process raft request' (duration: 132.241ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:57:16.01689Z","caller":"traceutil/trace.go:171","msg":"trace[363617055] transaction","detail":"{read_only:false; response_revision:5443; number_of_response:1; }","duration":"124.119122ms","start":"2026-04-16T22:57:15.89238Z","end":"2026-04-16T22:57:16.016499Z","steps":["trace[363617055] 'process raft request' (duration: 123.830483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:57:16.016942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.378309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9nc5t\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-16T22:57:16.017124Z","caller":"traceutil/trace.go:171","msg":"trace[482582986] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9nc5t; range_end:; response_count:1; response_revision:5443; }","duration":"119.524623ms","start":"2026-04-16T22:57:15.89751Z","end":"2026-04-16T22:57:16.017035Z","steps":["trace[482582986] 'agreement among raft nodes before linearized reading' (duration: 119.083821ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:58:45.26536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3031} {"level":"info","ts":"2026-04-16T22:58:45.339919Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3031,"took":"73.45151ms","hash":2210642522,"current-db-size-bytes":58298368,"current-db-size":"58 MB","current-db-size-in-use-bytes":31850496,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-16T22:58:45.339983Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2210642522,"revision":3031,"compact-revision":-1} {"level":"warn","ts":"2026-04-16T23:03:08.688713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.408282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-16T23:03:08.688848Z","caller":"traceutil/trace.go:171","msg":"trace[177909359] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8112; }","duration":"226.552847ms","start":"2026-04-16T23:03:08.462263Z","end":"2026-04-16T23:03:08.688816Z","steps":["trace[177909359] 'range keys from in-memory index tree' (duration: 226.227677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:08.689001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.985035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T23:03:08.689191Z","caller":"traceutil/trace.go:171","msg":"trace[1093235652] transaction","detail":"{read_only:false; response_revision:8113; number_of_response:1; }","duration":"226.424413ms","start":"2026-04-16T23:03:08.462697Z","end":"2026-04-16T23:03:08.689121Z","steps":["trace[1093235652] 'process raft request' (duration: 74.15046ms)","trace[1093235652] 'compare' (duration: 151.720447ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T23:03:45.27561Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6163} {"level":"info","ts":"2026-04-16T23:03:45.3658Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6163,"took":"88.211566ms","hash":1297823653,"current-db-size-bytes":58298368,"current-db-size":"58 MB","current-db-size-in-use-bytes":23351296,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-16T23:03:45.36588Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1297823653,"revision":6163,"compact-revision":3031} {"level":"info","ts":"2026-04-16T23:03:48.758403Z","caller":"traceutil/trace.go:171","msg":"trace[480376923] linearizableReadLoop","detail":"{readStateIndex:8723; appliedIndex:8721; }","duration":"169.024764ms","start":"2026-04-16T23:03:48.589357Z","end":"2026-04-16T23:03:48.758381Z","steps":["trace[480376923] 'read index received' (duration: 39.949336ms)","trace[480376923] 'applied index is now lower than readState.Index' (duration: 129.074718ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T23:03:48.758464Z","caller":"traceutil/trace.go:171","msg":"trace[1178335081] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8423; }","duration":"176.507985ms","start":"2026-04-16T23:03:48.581923Z","end":"2026-04-16T23:03:48.758431Z","steps":["trace[1178335081] 'process raft request' (duration: 151.85184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:48.758866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.58035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-sg9vn\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-04-16T23:03:48.758953Z","caller":"traceutil/trace.go:171","msg":"trace[1014148270] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-sg9vn; range_end:; response_count:1; response_revision:8423; }","duration":"125.680642ms","start":"2026-04-16T23:03:48.633254Z","end":"2026-04-16T23:03:48.758935Z","steps":["trace[1014148270] 'agreement among raft nodes before linearized reading' (duration: 125.304012ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T23:03:48.758564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.182758ms","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-04-16T23:03:48.7602Z","caller":"traceutil/trace.go:171","msg":"trace[1568595629] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:8423; }","duration":"170.858755ms","start":"2026-04-16T23:03:48.589331Z","end":"2026-04-16T23:03:48.76019Z","steps":["trace[1568595629] 'agreement among raft nodes before linearized reading' (duration: 169.170817ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:03:50.190586Z","caller":"traceutil/trace.go:171","msg":"trace[1801641849] transaction","detail":"{read_only:false; response_revision:8436; number_of_response:1; }","duration":"131.176568ms","start":"2026-04-16T23:03:50.059382Z","end":"2026-04-16T23:03:50.190559Z","steps":["trace[1801641849] 'process raft request' (duration: 130.977032ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T23:04:47.093617Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002471.wal"} {"level":"info","ts":"2026-04-16T23:06:13.145806Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-16T23:06:13.152539Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-16T23:06:13.152622Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-16T23:08:45.28154Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8396} {"level":"info","ts":"2026-04-16T23:08:45.360717Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8396,"took":"76.704532ms","hash":357180198,"current-db-size-bytes":58298368,"current-db-size":"58 MB","current-db-size-in-use-bytes":24932352,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-16T23:08:45.360782Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":357180198,"revision":8396,"compact-revision":6163} {"level":"info","ts":"2026-04-16T23:13:45.28766Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10708} {"level":"info","ts":"2026-04-16T23:13:45.363011Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10708,"took":"73.244371ms","hash":2393526029,"current-db-size-bytes":58298368,"current-db-size":"58 MB","current-db-size-in-use-bytes":20201472,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-16T23:13:45.363075Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2393526029,"revision":10708,"compact-revision":8396}