{"level":"warn","ts":"2026-04-19T13:30:05.326899Z","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-19T13:30:05.327025Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.154: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.154:2380","--initial-cluster=instance=https://199.19.213.154:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.154:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.154: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-19T13:30:05.327124Z","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-19T13:30:05.32715Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.154:2380"]} {"level":"info","ts":"2026-04-19T13:30:05.327183Z","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-19T13:30:05.328395Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.154:2379"]} {"level":"info","ts":"2026-04-19T13:30:05.328541Z","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.154:2380"],"listen-peer-urls":["https://199.19.213.154:2380"],"advertise-client-urls":["https://199.19.213.154:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.154:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.154: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-19T13:30:05.333568Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.6618ms"} {"level":"info","ts":"2026-04-19T13:30:05.347269Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f4aebb100319a62d","cluster-id":"23a505f69026554e"} {"level":"info","ts":"2026-04-19T13:30:05.347376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d switched to configuration voters=()"} {"level":"info","ts":"2026-04-19T13:30:05.347406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became follower at term 0"} {"level":"info","ts":"2026-04-19T13:30:05.347423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f4aebb100319a62d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-19T13:30:05.347435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became follower at term 1"} {"level":"info","ts":"2026-04-19T13:30:05.347487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d switched to configuration voters=(17631235268647953965)"} {"level":"warn","ts":"2026-04-19T13:30:05.354428Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-19T13:30:05.356252Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-19T13:30:05.357602Z","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-19T13:30:05.359809Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f4aebb100319a62d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-19T13:30:05.360405Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f4aebb100319a62d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-19T13:30:05.360613Z","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-19T13:30:05.360697Z","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-19T13:30:05.360715Z","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-19T13:30:05.362578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d switched to configuration voters=(17631235268647953965)"} {"level":"info","ts":"2026-04-19T13:30:05.362898Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"23a505f69026554e","local-member-id":"f4aebb100319a62d","added-peer-id":"f4aebb100319a62d","added-peer-peer-urls":["https://199.19.213.154:2380"]} {"level":"info","ts":"2026-04-19T13:30:05.365666Z","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-19T13:30:05.365896Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f4aebb100319a62d","initial-advertise-peer-urls":["https://199.19.213.154:2380"],"listen-peer-urls":["https://199.19.213.154:2380"],"advertise-client-urls":["https://199.19.213.154:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.154:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-19T13:30:05.365912Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.154:2380"} {"level":"info","ts":"2026-04-19T13:30:05.365945Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.154:2380"} {"level":"info","ts":"2026-04-19T13:30:05.365945Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-19T13:30:06.048935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d is starting a new election at term 1"} {"level":"info","ts":"2026-04-19T13:30:06.049038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-19T13:30:06.049096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d received MsgPreVoteResp from f4aebb100319a62d at term 1"} {"level":"info","ts":"2026-04-19T13:30:06.049126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became candidate at term 2"} {"level":"info","ts":"2026-04-19T13:30:06.04914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d received MsgVoteResp from f4aebb100319a62d at term 2"} {"level":"info","ts":"2026-04-19T13:30:06.049159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f4aebb100319a62d became leader at term 2"} {"level":"info","ts":"2026-04-19T13:30:06.049183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f4aebb100319a62d elected leader f4aebb100319a62d at term 2"} {"level":"info","ts":"2026-04-19T13:30:06.050517Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f4aebb100319a62d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.154:2379]}","request-path":"/0/members/f4aebb100319a62d/attributes","cluster-id":"23a505f69026554e","publish-timeout":"7s"} {"level":"info","ts":"2026-04-19T13:30:06.050519Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T13:30:06.050565Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:30:06.050579Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-19T13:30:06.050978Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-19T13:30:06.051105Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-19T13:30:06.051976Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"23a505f69026554e","local-member-id":"f4aebb100319a62d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:30:06.052112Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:30:06.052163Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-19T13:30:06.054059Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-19T13:30:06.05499Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.154:2379"} {"level":"info","ts":"2026-04-19T13:30:33.040481Z","caller":"traceutil/trace.go:171","msg":"trace[1612331067] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"219.933553ms","start":"2026-04-19T13:30:32.820521Z","end":"2026-04-19T13:30:33.040455Z","steps":["trace[1612331067] 'read index received' (duration: 219.716206ms)","trace[1612331067] 'applied index is now lower than readState.Index' (duration: 215.677µs)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:30:33.04066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.119047ms","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-19T13:30:33.040622Z","caller":"traceutil/trace.go:171","msg":"trace[1410582864] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"267.282642ms","start":"2026-04-19T13:30:32.773275Z","end":"2026-04-19T13:30:33.040557Z","steps":["trace[1410582864] 'process raft request' (duration: 267.028335ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:33.040736Z","caller":"traceutil/trace.go:171","msg":"trace[696622063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:467; }","duration":"220.21716ms","start":"2026-04-19T13:30:32.820506Z","end":"2026-04-19T13:30:33.040723Z","steps":["trace[696622063] 'agreement among raft nodes before linearized reading' (duration: 220.087136ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:43.081021Z","caller":"traceutil/trace.go:171","msg":"trace[734103209] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"186.752807ms","start":"2026-04-19T13:30:42.89424Z","end":"2026-04-19T13:30:43.080993Z","steps":["trace[734103209] 'process raft request' (duration: 186.5113ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:30:43.233826Z","caller":"traceutil/trace.go:171","msg":"trace[840654228] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"129.776988ms","start":"2026-04-19T13:30:43.103964Z","end":"2026-04-19T13:30:43.233741Z","steps":["trace[840654228] 'read index received' (duration: 128.458442ms)","trace[840654228] 'applied index is now lower than readState.Index' (duration: 1.317266ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:30:43.233881Z","caller":"traceutil/trace.go:171","msg":"trace[1840359470] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"216.734152ms","start":"2026-04-19T13:30:43.017108Z","end":"2026-04-19T13:30:43.233842Z","steps":["trace[1840359470] 'process raft request' (duration: 215.835557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:43.234184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.76482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-19T13:30:43.234244Z","caller":"traceutil/trace.go:171","msg":"trace[932386943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:617; }","duration":"114.860922ms","start":"2026-04-19T13:30:43.119362Z","end":"2026-04-19T13:30:43.234223Z","steps":["trace[932386943] 'agreement among raft nodes before linearized reading' (duration: 114.642636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:43.23422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.22003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-19T13:30:43.234313Z","caller":"traceutil/trace.go:171","msg":"trace[255688639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"130.345013ms","start":"2026-04-19T13:30:43.103943Z","end":"2026-04-19T13:30:43.234288Z","steps":["trace[255688639] 'agreement among raft nodes before linearized reading' (duration: 130.016224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:43.699622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.368135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-19T13:30:43.699978Z","caller":"traceutil/trace.go:171","msg":"trace[596982710] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"459.008858ms","start":"2026-04-19T13:30:43.240915Z","end":"2026-04-19T13:30:43.699924Z","steps":["trace[596982710] 'process raft request' (duration: 30.624704ms)","trace[596982710] 'compare' (duration: 426.537162ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:30:43.700164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:30:43.240886Z","time spent":"459.175521ms","remote":"127.0.0.1:50994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:30:43.700223Z","caller":"traceutil/trace.go:171","msg":"trace[603362522] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"405.242528ms","start":"2026-04-19T13:30:43.294919Z","end":"2026-04-19T13:30:43.700161Z","steps":["trace[603362522] 'process raft request' (duration: 405.061703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:30:43.700504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:30:43.294902Z","time spent":"405.506135ms","remote":"127.0.0.1:51242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-19T13:31:47.426575Z","caller":"traceutil/trace.go:171","msg":"trace[1324731373] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"109.066175ms","start":"2026-04-19T13:31:47.317472Z","end":"2026-04-19T13:31:47.426538Z","steps":["trace[1324731373] 'process raft request' (duration: 108.828958ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:12.392411Z","caller":"traceutil/trace.go:171","msg":"trace[416031679] linearizableReadLoop","detail":"{readStateIndex:1907; appliedIndex:1906; }","duration":"115.780918ms","start":"2026-04-19T13:32:12.276589Z","end":"2026-04-19T13:32:12.39237Z","steps":["trace[416031679] 'read index received' (duration: 21.559535ms)","trace[416031679] 'applied index is now lower than readState.Index' (duration: 94.219383ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:32:12.392614Z","caller":"traceutil/trace.go:171","msg":"trace[251142292] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"116.647113ms","start":"2026-04-19T13:32:12.275769Z","end":"2026-04-19T13:32:12.392416Z","steps":["trace[251142292] 'process raft request' (duration: 22.46338ms)","trace[251142292] 'compare' (duration: 93.969077ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:32:12.392825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.184999ms","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-19T13:32:12.392961Z","caller":"traceutil/trace.go:171","msg":"trace[863972743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1837; }","duration":"116.358324ms","start":"2026-04-19T13:32:12.276568Z","end":"2026-04-19T13:32:12.392926Z","steps":["trace[863972743] 'agreement among raft nodes before linearized reading' (duration: 115.916962ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:12.661051Z","caller":"traceutil/trace.go:171","msg":"trace[134809215] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"317.558825ms","start":"2026-04-19T13:32:12.343444Z","end":"2026-04-19T13:32:12.661003Z","steps":["trace[134809215] 'process raft request' (duration: 295.862347ms)","trace[134809215] 'compare' (duration: 21.166982ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:32:12.661279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:32:12.343417Z","time spent":"317.732239ms","remote":"127.0.0.1:50994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-19T13:32:12.661418Z","caller":"traceutil/trace.go:171","msg":"trace[294325178] linearizableReadLoop","detail":"{readStateIndex:1909; appliedIndex:1907; }","duration":"259.095499ms","start":"2026-04-19T13:32:12.402295Z","end":"2026-04-19T13:32:12.66139Z","steps":["trace[294325178] 'read index received' (duration: 237.032841ms)","trace[294325178] 'applied index is now lower than readState.Index' (duration: 22.061048ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:32:12.661597Z","caller":"traceutil/trace.go:171","msg":"trace[1278571228] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"262.431814ms","start":"2026-04-19T13:32:12.399137Z","end":"2026-04-19T13:32:12.661569Z","steps":["trace[1278571228] 'process raft request' (duration: 262.112855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:32:12.661862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.455746ms","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":"warn","ts":"2026-04-19T13:32:12.66188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.538762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10630"} {"level":"info","ts":"2026-04-19T13:32:12.662034Z","caller":"traceutil/trace.go:171","msg":"trace[643653448] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1839; }","duration":"259.746118ms","start":"2026-04-19T13:32:12.402253Z","end":"2026-04-19T13:32:12.661999Z","steps":["trace[643653448] 'agreement among raft nodes before linearized reading' (duration: 259.344727ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:32:12.661941Z","caller":"traceutil/trace.go:171","msg":"trace[1168700064] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1839; }","duration":"130.59787ms","start":"2026-04-19T13:32:12.53132Z","end":"2026-04-19T13:32:12.661918Z","steps":["trace[1168700064] 'agreement among raft nodes before linearized reading' (duration: 130.285191ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:02.093101Z","caller":"traceutil/trace.go:171","msg":"trace[1623596222] linearizableReadLoop","detail":"{readStateIndex:2240; appliedIndex:2239; }","duration":"143.300531ms","start":"2026-04-19T13:33:01.949775Z","end":"2026-04-19T13:33:02.093076Z","steps":["trace[1623596222] 'read index received' (duration: 82.671415ms)","trace[1623596222] 'applied index is now lower than readState.Index' (duration: 60.627856ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:33:02.093557Z","caller":"traceutil/trace.go:171","msg":"trace[162246973] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"246.310635ms","start":"2026-04-19T13:33:01.847209Z","end":"2026-04-19T13:33:02.09352Z","steps":["trace[162246973] 'process raft request' (duration: 185.183365ms)","trace[162246973] 'compare' (duration: 60.566244ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:33:02.093793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.99688ms","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-19T13:33:02.093863Z","caller":"traceutil/trace.go:171","msg":"trace[669154630] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2159; }","duration":"144.124044ms","start":"2026-04-19T13:33:01.949725Z","end":"2026-04-19T13:33:02.093849Z","steps":["trace[669154630] 'agreement among raft nodes before linearized reading' (duration: 143.496857ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:02.23547Z","caller":"traceutil/trace.go:171","msg":"trace[1433063690] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"137.045704ms","start":"2026-04-19T13:33:02.098396Z","end":"2026-04-19T13:33:02.235442Z","steps":["trace[1433063690] 'process raft request' (duration: 136.987552ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:33:02.235819Z","caller":"traceutil/trace.go:171","msg":"trace[1006306853] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"197.292569ms","start":"2026-04-19T13:33:02.038485Z","end":"2026-04-19T13:33:02.235777Z","steps":["trace[1006306853] 'process raft request' (duration: 171.970808ms)","trace[1006306853] 'compare' (duration: 24.798837ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:38:24.055718Z","caller":"traceutil/trace.go:171","msg":"trace[735753468] linearizableReadLoop","detail":"{readStateIndex:5193; appliedIndex:5192; }","duration":"228.959377ms","start":"2026-04-19T13:38:23.826709Z","end":"2026-04-19T13:38:24.055668Z","steps":["trace[735753468] 'read index received' (duration: 151.935453ms)","trace[735753468] 'applied index is now lower than readState.Index' (duration: 77.020913ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:38:24.055883Z","caller":"traceutil/trace.go:171","msg":"trace[2082757010] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"252.837862ms","start":"2026-04-19T13:38:23.80297Z","end":"2026-04-19T13:38:24.055808Z","steps":["trace[2082757010] 'process raft request' (duration: 175.824239ms)","trace[2082757010] 'compare' (duration: 76.487587ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:38:24.056199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.05332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-19T13:38:24.056253Z","caller":"traceutil/trace.go:171","msg":"trace[62721977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5006; }","duration":"149.191094ms","start":"2026-04-19T13:38:23.907048Z","end":"2026-04-19T13:38:24.056239Z","steps":["trace[62721977] 'agreement among raft nodes before linearized reading' (duration: 148.910536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:38:24.056283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.368489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16452"} {"level":"info","ts":"2026-04-19T13:38:24.056419Z","caller":"traceutil/trace.go:171","msg":"trace[1044880304] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5006; }","duration":"229.694078ms","start":"2026-04-19T13:38:23.82669Z","end":"2026-04-19T13:38:24.056384Z","steps":["trace[1044880304] 'agreement among raft nodes before linearized reading' (duration: 229.186714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:38:35.773928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.741676ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:38:35.774109Z","caller":"traceutil/trace.go:171","msg":"trace[436004481] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5230; }","duration":"177.947532ms","start":"2026-04-19T13:38:35.59614Z","end":"2026-04-19T13:38:35.774087Z","steps":["trace[436004481] 'range keys from in-memory index tree' (duration: 177.719465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:38:35.774615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.980886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-r46qz\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-04-19T13:38:35.774716Z","caller":"traceutil/trace.go:171","msg":"trace[1239532312] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-r46qz; range_end:; response_count:1; response_revision:5230; }","duration":"106.141031ms","start":"2026-04-19T13:38:35.668552Z","end":"2026-04-19T13:38:35.774693Z","steps":["trace[1239532312] 'range keys from in-memory index tree' (duration: 105.699877ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:38:43.995993Z","caller":"traceutil/trace.go:171","msg":"trace[1119614177] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"220.83408ms","start":"2026-04-19T13:38:43.775128Z","end":"2026-04-19T13:38:43.995962Z","steps":["trace[1119614177] 'process raft request' (duration: 220.636144ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:40:06.077179Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3030} {"level":"info","ts":"2026-04-19T13:40:06.186526Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3030,"took":"106.955638ms","hash":121963470,"current-db-size-bytes":58933248,"current-db-size":"59 MB","current-db-size-in-use-bytes":31928320,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-19T13:40:06.186612Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":121963470,"revision":3030,"compact-revision":-1} {"level":"info","ts":"2026-04-19T13:44:52.31413Z","caller":"traceutil/trace.go:171","msg":"trace[992819331] transaction","detail":"{read_only:false; response_revision:8256; number_of_response:1; }","duration":"111.099578ms","start":"2026-04-19T13:44:52.203Z","end":"2026-04-19T13:44:52.314099Z","steps":["trace[992819331] 'process raft request' (duration: 110.928224ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:44:52.918274Z","caller":"traceutil/trace.go:171","msg":"trace[1645062162] linearizableReadLoop","detail":"{readStateIndex:8543; appliedIndex:8542; }","duration":"289.123412ms","start":"2026-04-19T13:44:52.629121Z","end":"2026-04-19T13:44:52.918245Z","steps":["trace[1645062162] 'read index received' (duration: 288.941556ms)","trace[1645062162] 'applied index is now lower than readState.Index' (duration: 180.846µs)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:44:52.918402Z","caller":"traceutil/trace.go:171","msg":"trace[2064577959] transaction","detail":"{read_only:false; response_revision:8257; number_of_response:1; }","duration":"314.31852ms","start":"2026-04-19T13:44:52.604043Z","end":"2026-04-19T13:44:52.918361Z","steps":["trace[2064577959] 'process raft request' (duration: 314.037622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:44:52.918523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.377629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-19T13:44:52.918554Z","caller":"traceutil/trace.go:171","msg":"trace[1158993154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8257; }","duration":"289.484952ms","start":"2026-04-19T13:44:52.629062Z","end":"2026-04-19T13:44:52.918547Z","steps":["trace[1158993154] 'agreement among raft nodes before linearized reading' (duration: 289.328748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:44:52.918629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-19T13:44:52.60402Z","time spent":"314.483354ms","remote":"127.0.0.1:50994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-19T13:44:52.918651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.490362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-19T13:44:52.918678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.812008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:44:52.918735Z","caller":"traceutil/trace.go:171","msg":"trace[475725229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8257; }","duration":"289.642856ms","start":"2026-04-19T13:44:52.629072Z","end":"2026-04-19T13:44:52.918715Z","steps":["trace[475725229] 'agreement among raft nodes before linearized reading' (duration: 289.378659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:44:52.918724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.575999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-19T13:44:52.91879Z","caller":"traceutil/trace.go:171","msg":"trace[1510739760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8257; }","duration":"225.941501ms","start":"2026-04-19T13:44:52.692824Z","end":"2026-04-19T13:44:52.918766Z","steps":["trace[1510739760] 'agreement among raft nodes before linearized reading' (duration: 225.727766ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:44:52.918833Z","caller":"traceutil/trace.go:171","msg":"trace[2030321355] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:8257; }","duration":"147.758585ms","start":"2026-04-19T13:44:52.771052Z","end":"2026-04-19T13:44:52.918811Z","steps":["trace[2030321355] 'agreement among raft nodes before linearized reading' (duration: 147.444506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:44:52.919161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.48337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-19T13:44:52.919282Z","caller":"traceutil/trace.go:171","msg":"trace[817696849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8257; }","duration":"233.86004ms","start":"2026-04-19T13:44:52.685399Z","end":"2026-04-19T13:44:52.919259Z","steps":["trace[817696849] 'agreement among raft nodes before linearized reading' (duration: 233.304685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:44:53.811047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.658412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-19T13:44:53.811168Z","caller":"traceutil/trace.go:171","msg":"trace[1075041847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8262; }","duration":"116.846297ms","start":"2026-04-19T13:44:53.694286Z","end":"2026-04-19T13:44:53.811132Z","steps":["trace[1075041847] 'range keys from in-memory index tree' (duration: 116.536168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:44:54.593799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.757668ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-19T13:44:54.593914Z","caller":"traceutil/trace.go:171","msg":"trace[599549429] linearizableReadLoop","detail":"{readStateIndex:8553; appliedIndex:8552; }","duration":"123.730819ms","start":"2026-04-19T13:44:54.470162Z","end":"2026-04-19T13:44:54.593893Z","steps":["trace[599549429] 'read index received' (duration: 70.343µs)","trace[599549429] 'applied index is now lower than readState.Index' (duration: 123.659336ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:44:54.594096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.928624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-zbqsp\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-19T13:44:54.594131Z","caller":"traceutil/trace.go:171","msg":"trace[623655892] range","detail":"{range_begin:/registry/pods/openstack/keepalived-zbqsp; range_end:; response_count:1; response_revision:8266; }","duration":"124.030508ms","start":"2026-04-19T13:44:54.47009Z","end":"2026-04-19T13:44:54.59412Z","steps":["trace[623655892] 'agreement among raft nodes before linearized reading' (duration: 123.859543ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:45:06.084295Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6161} {"level":"info","ts":"2026-04-19T13:45:06.189178Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6161,"took":"102.11776ms","hash":2480213923,"current-db-size-bytes":58933248,"current-db-size":"59 MB","current-db-size-in-use-bytes":23728128,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-19T13:45:06.189263Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2480213923,"revision":6161,"compact-revision":3030} {"level":"info","ts":"2026-04-19T13:45:34.754584Z","caller":"traceutil/trace.go:171","msg":"trace[1846033681] linearizableReadLoop","detail":"{readStateIndex:8877; appliedIndex:8876; }","duration":"188.413701ms","start":"2026-04-19T13:45:34.566132Z","end":"2026-04-19T13:45:34.754546Z","steps":["trace[1846033681] 'read index received' (duration: 133.702765ms)","trace[1846033681] 'applied index is now lower than readState.Index' (duration: 54.709126ms)"],"step_count":2} {"level":"warn","ts":"2026-04-19T13:45:34.754976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.801382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-s9sm6\" ","response":"range_response_count:1 size:2804"} {"level":"info","ts":"2026-04-19T13:45:34.755078Z","caller":"traceutil/trace.go:171","msg":"trace[668923239] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-s9sm6; range_end:; response_count:1; response_revision:8575; }","duration":"188.990417ms","start":"2026-04-19T13:45:34.566065Z","end":"2026-04-19T13:45:34.755056Z","steps":["trace[668923239] 'agreement among raft nodes before linearized reading' (duration: 188.645078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:45:34.755125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.72592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-s9sm6\" ","response":"range_response_count:1 size:4673"} {"level":"info","ts":"2026-04-19T13:45:34.755263Z","caller":"traceutil/trace.go:171","msg":"trace[443678162] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-s9sm6; range_end:; response_count:1; response_revision:8575; }","duration":"188.958767ms","start":"2026-04-19T13:45:34.566288Z","end":"2026-04-19T13:45:34.755247Z","steps":["trace[443678162] 'agreement among raft nodes before linearized reading' (duration: 188.602017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:45:34.755608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.975322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-19T13:45:34.755684Z","caller":"traceutil/trace.go:171","msg":"trace[376275897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8575; }","duration":"178.110906ms","start":"2026-04-19T13:45:34.577556Z","end":"2026-04-19T13:45:34.755667Z","steps":["trace[376275897] 'agreement among raft nodes before linearized reading' (duration: 177.432288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:45:34.755896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.727303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-19T13:45:34.756018Z","caller":"traceutil/trace.go:171","msg":"trace[1017089842] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:8575; }","duration":"147.912189ms","start":"2026-04-19T13:45:34.608072Z","end":"2026-04-19T13:45:34.755985Z","steps":["trace[1017089842] 'agreement among raft nodes before linearized reading' (duration: 146.87325ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:45:34.754764Z","caller":"traceutil/trace.go:171","msg":"trace[610820727] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8575; }","duration":"190.402606ms","start":"2026-04-19T13:45:34.564316Z","end":"2026-04-19T13:45:34.754719Z","steps":["trace[610820727] 'process raft request' (duration: 135.592897ms)","trace[610820727] 'compare' (duration: 54.171211ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:45:35.386288Z","caller":"traceutil/trace.go:171","msg":"trace[1352301204] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"128.759928ms","start":"2026-04-19T13:45:35.257493Z","end":"2026-04-19T13:45:35.386253Z","steps":["trace[1352301204] 'process raft request' (duration: 128.535162ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:45:35.386899Z","caller":"traceutil/trace.go:171","msg":"trace[401405618] transaction","detail":"{read_only:false; response_revision:8582; number_of_response:1; }","duration":"127.264536ms","start":"2026-04-19T13:45:35.259598Z","end":"2026-04-19T13:45:35.386862Z","steps":["trace[401405618] 'process raft request' (duration: 126.581657ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:45:35.611335Z","caller":"traceutil/trace.go:171","msg":"trace[2040220170] transaction","detail":"{read_only:false; response_revision:8583; number_of_response:1; }","duration":"262.091011ms","start":"2026-04-19T13:45:35.349205Z","end":"2026-04-19T13:45:35.611296Z","steps":["trace[2040220170] 'process raft request' (duration: 247.741994ms)","trace[2040220170] 'compare' (duration: 13.630988ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:45:35.63476Z","caller":"traceutil/trace.go:171","msg":"trace[1559215362] linearizableReadLoop","detail":"{readStateIndex:8886; appliedIndex:8884; }","duration":"189.588073ms","start":"2026-04-19T13:45:35.445152Z","end":"2026-04-19T13:45:35.63474Z","steps":["trace[1559215362] 'read index received' (duration: 151.813896ms)","trace[1559215362] 'applied index is now lower than readState.Index' (duration: 37.773007ms)"],"step_count":2} {"level":"info","ts":"2026-04-19T13:45:35.634856Z","caller":"traceutil/trace.go:171","msg":"trace[1817868518] transaction","detail":"{read_only:false; response_revision:8585; number_of_response:1; }","duration":"181.480149ms","start":"2026-04-19T13:45:35.45333Z","end":"2026-04-19T13:45:35.63481Z","steps":["trace[1817868518] 'process raft request' (duration: 181.338616ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:45:35.635002Z","caller":"traceutil/trace.go:171","msg":"trace[1600777278] transaction","detail":"{read_only:false; response_revision:8584; number_of_response:1; }","duration":"189.82545ms","start":"2026-04-19T13:45:35.445114Z","end":"2026-04-19T13:45:35.634939Z","steps":["trace[1600777278] 'process raft request' (duration: 189.408759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:45:35.635054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.886161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-s9sm6\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-04-19T13:45:35.635099Z","caller":"traceutil/trace.go:171","msg":"trace[990863784] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-s9sm6; range_end:; response_count:1; response_revision:8585; }","duration":"189.954913ms","start":"2026-04-19T13:45:35.445133Z","end":"2026-04-19T13:45:35.635088Z","steps":["trace[990863784] 'agreement among raft nodes before linearized reading' (duration: 189.733227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:45:35.635512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.990994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"info","ts":"2026-04-19T13:45:35.635569Z","caller":"traceutil/trace.go:171","msg":"trace[415949420] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8585; }","duration":"125.068796ms","start":"2026-04-19T13:45:35.510485Z","end":"2026-04-19T13:45:35.635554Z","steps":["trace[415949420] 'agreement among raft nodes before linearized reading' (duration: 124.832349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-19T13:45:35.635534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.437823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-19T13:45:35.63564Z","caller":"traceutil/trace.go:171","msg":"trace[1047522813] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8585; }","duration":"104.647569ms","start":"2026-04-19T13:45:35.530967Z","end":"2026-04-19T13:45:35.635614Z","steps":["trace[1047522813] 'agreement among raft nodes before linearized reading' (duration: 104.438314ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:45:50.74607Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000237f.wal"} {"level":"info","ts":"2026-04-19T13:46:47.633804Z","caller":"traceutil/trace.go:171","msg":"trace[1322594303] transaction","detail":"{read_only:false; response_revision:9304; number_of_response:1; }","duration":"120.564049ms","start":"2026-04-19T13:46:47.513208Z","end":"2026-04-19T13:46:47.633772Z","steps":["trace[1322594303] 'process raft request' (duration: 120.366194ms)"],"step_count":1} {"level":"info","ts":"2026-04-19T13:47:37.846263Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f4aebb100319a62d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-19T13:47:37.854651Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-19T13:47:37.854772Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-19T13:50:06.093914Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8334} {"level":"info","ts":"2026-04-19T13:50:06.187047Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8334,"took":"89.91407ms","hash":3824560398,"current-db-size-bytes":58933248,"current-db-size":"59 MB","current-db-size-in-use-bytes":24928256,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-19T13:50:06.187129Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3824560398,"revision":8334,"compact-revision":6161} {"level":"info","ts":"2026-04-19T13:55:06.100551Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10724} {"level":"info","ts":"2026-04-19T13:55:06.179663Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10724,"took":"75.81872ms","hash":525165901,"current-db-size-bytes":58933248,"current-db-size":"59 MB","current-db-size-in-use-bytes":20410368,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-19T13:55:06.179738Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":525165901,"revision":10724,"compact-revision":8334}