{"level":"warn","ts":"2026-04-13T21:10:46.762755Z","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-13T21:10:46.76284Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22: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.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22: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-13T21:10:46.7629Z","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-13T21:10:46.762912Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-04-13T21:10:46.762926Z","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-13T21:10:46.763541Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-04-13T21:10:46.763643Z","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.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22: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-13T21:10:46.768256Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.411176ms"} {"level":"info","ts":"2026-04-13T21:10:46.779934Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-04-13T21:10:46.780054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T21:10:46.780081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-04-13T21:10:46.780102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T21:10:46.780118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-04-13T21:10:46.780205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-04-13T21:10:46.795451Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T21:10:46.797528Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T21:10:46.79896Z","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-13T21:10:46.802047Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T21:10:46.802606Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T21:10:46.802611Z","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-13T21:10:46.80275Z","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-13T21:10:46.802793Z","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-13T21:10:46.803418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-04-13T21:10:46.80446Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-04-13T21:10:46.806119Z","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-13T21:10:46.806336Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T21:10:46.806413Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T21:10:46.806379Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-13T21:10:46.806973Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-13T21:10:47.381419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T21:10:47.381491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T21:10:47.381528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-04-13T21:10:47.381542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-04-13T21:10:47.381548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-04-13T21:10:47.381556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-04-13T21:10:47.381592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-04-13T21:10:47.383229Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:10:47.384985Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T21:10:47.385074Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T21:10:47.385406Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T21:10:47.386111Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T21:10:47.386137Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T21:10:47.389261Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-04-13T21:10:47.391136Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T21:10:47.396046Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:10:47.396148Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:10:47.396191Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T21:11:04.502714Z","caller":"traceutil/trace.go:171","msg":"trace[2061708926] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"172.64184ms","start":"2026-04-13T21:11:04.330054Z","end":"2026-04-13T21:11:04.502696Z","steps":["trace[2061708926] 'process raft request' (duration: 172.460865ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:04.502653Z","caller":"traceutil/trace.go:171","msg":"trace[1336374561] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:454; }","duration":"124.614521ms","start":"2026-04-13T21:11:04.378016Z","end":"2026-04-13T21:11:04.50263Z","steps":["trace[1336374561] 'read index received' (duration: 124.449307ms)","trace[1336374561] 'applied index is now lower than readState.Index' (duration: 164.584µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:11:04.502834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.771487ms","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-13T21:11:04.502949Z","caller":"traceutil/trace.go:171","msg":"trace[1986601458] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:443; }","duration":"124.965131ms","start":"2026-04-13T21:11:04.377973Z","end":"2026-04-13T21:11:04.502938Z","steps":["trace[1986601458] 'agreement among raft nodes before linearized reading' (duration: 124.768616ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:09.81611Z","caller":"traceutil/trace.go:171","msg":"trace[1315466967] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"118.371396ms","start":"2026-04-13T21:11:09.697708Z","end":"2026-04-13T21:11:09.816079Z","steps":["trace[1315466967] 'process raft request' (duration: 118.224762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:17.758503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.094959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:11:17.758617Z","caller":"traceutil/trace.go:171","msg":"trace[355586421] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"137.692827ms","start":"2026-04-13T21:11:17.620907Z","end":"2026-04-13T21:11:17.758599Z","steps":["trace[355586421] 'process raft request' (duration: 15.673084ms)","trace[355586421] 'compare' (duration: 120.948825ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:11:17.765363Z","caller":"traceutil/trace.go:171","msg":"trace[25764919] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"128.163466ms","start":"2026-04-13T21:11:17.63718Z","end":"2026-04-13T21:11:17.765344Z","steps":["trace[25764919] 'process raft request' (duration: 127.858768ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:17.771148Z","caller":"traceutil/trace.go:171","msg":"trace[857269237] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"124.443918ms","start":"2026-04-13T21:11:17.64667Z","end":"2026-04-13T21:11:17.771114Z","steps":["trace[857269237] 'process raft request' (duration: 124.259313ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:17.771285Z","caller":"traceutil/trace.go:171","msg":"trace[776354249] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"122.539197ms","start":"2026-04-13T21:11:17.648718Z","end":"2026-04-13T21:11:17.771257Z","steps":["trace[776354249] 'process raft request' (duration: 122.30231ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:17.771318Z","caller":"traceutil/trace.go:171","msg":"trace[1580086308] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"116.331694ms","start":"2026-04-13T21:11:17.654967Z","end":"2026-04-13T21:11:17.771298Z","steps":["trace[1580086308] 'process raft request' (duration: 116.133988ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:17.77141Z","caller":"traceutil/trace.go:171","msg":"trace[1561098687] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"122.419354ms","start":"2026-04-13T21:11:17.648917Z","end":"2026-04-13T21:11:17.771336Z","steps":["trace[1561098687] 'process raft request' (duration: 122.128016ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:17.771458Z","caller":"traceutil/trace.go:171","msg":"trace[888488390] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"115.560882ms","start":"2026-04-13T21:11:17.655882Z","end":"2026-04-13T21:11:17.771443Z","steps":["trace[888488390] 'process raft request' (duration: 115.337487ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:17.771469Z","caller":"traceutil/trace.go:171","msg":"trace[1362692668] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"114.088834ms","start":"2026-04-13T21:11:17.657367Z","end":"2026-04-13T21:11:17.771456Z","steps":["trace[1362692668] 'process raft request' (duration: 113.897489ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:17.771334Z","caller":"traceutil/trace.go:171","msg":"trace[1799703035] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"116.066825ms","start":"2026-04-13T21:11:17.655249Z","end":"2026-04-13T21:11:17.771316Z","steps":["trace[1799703035] 'process raft request' (duration: 115.922351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:18.163364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.824647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:11:18.163503Z","caller":"traceutil/trace.go:171","msg":"trace[1010180705] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"355.517611ms","start":"2026-04-13T21:11:17.807955Z","end":"2026-04-13T21:11:18.163472Z","steps":["trace[1010180705] 'process raft request' (duration: 241.344765ms)","trace[1010180705] 'compare' (duration: 113.664932ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:11:18.163569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:17.80792Z","time spent":"355.618393ms","remote":"127.0.0.1:48454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:11:18.165116Z","caller":"traceutil/trace.go:171","msg":"trace[915284400] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"348.187968ms","start":"2026-04-13T21:11:17.81691Z","end":"2026-04-13T21:11:18.165098Z","steps":["trace[915284400] 'process raft request' (duration: 347.91839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:18.165224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:17.816886Z","time spent":"348.287491ms","remote":"127.0.0.1:48454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:11:18.165205Z","caller":"traceutil/trace.go:171","msg":"trace[322995070] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"339.258092ms","start":"2026-04-13T21:11:17.825906Z","end":"2026-04-13T21:11:18.165164Z","steps":["trace[322995070] 'process raft request' (duration: 339.080736ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:18.165404Z","caller":"traceutil/trace.go:171","msg":"trace[735909098] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"338.45179ms","start":"2026-04-13T21:11:17.826942Z","end":"2026-04-13T21:11:18.165394Z","steps":["trace[735909098] 'process raft request' (duration: 338.13312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:18.165423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:17.825888Z","time spent":"339.420026ms","remote":"127.0.0.1:48454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:11:18.165504Z","caller":"traceutil/trace.go:171","msg":"trace[1628145500] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:553; }","duration":"319.173111ms","start":"2026-04-13T21:11:17.846235Z","end":"2026-04-13T21:11:18.165408Z","steps":["trace[1628145500] 'read index received' (duration: 203.105785ms)","trace[1628145500] 'applied index is now lower than readState.Index' (duration: 116.066156ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:11:18.165457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:17.826932Z","time spent":"338.498242ms","remote":"127.0.0.1:48454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:11:18.165529Z","caller":"traceutil/trace.go:171","msg":"trace[1026537255] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"333.828758ms","start":"2026-04-13T21:11:17.831673Z","end":"2026-04-13T21:11:18.165502Z","steps":["trace[1026537255] 'process raft request' (duration: 333.606893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:18.1657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:17.831648Z","time spent":"333.974513ms","remote":"127.0.0.1:48454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T21:11:18.165706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.459298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T21:11:18.165834Z","caller":"traceutil/trace.go:171","msg":"trace[958462829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:542; }","duration":"319.628363ms","start":"2026-04-13T21:11:17.846193Z","end":"2026-04-13T21:11:18.165821Z","steps":["trace[958462829] 'agreement among raft nodes before linearized reading' (duration: 319.395417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:18.165924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:17.846177Z","time spent":"319.730355ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T21:11:24.145074Z","caller":"traceutil/trace.go:171","msg":"trace[1986226818] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"151.143617ms","start":"2026-04-13T21:11:23.993899Z","end":"2026-04-13T21:11:24.145043Z","steps":["trace[1986226818] 'process raft request' (duration: 150.658265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:24.792097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.922482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:11:24.792567Z","caller":"traceutil/trace.go:171","msg":"trace[1193479178] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"561.859811ms","start":"2026-04-13T21:11:24.230667Z","end":"2026-04-13T21:11:24.792527Z","steps":["trace[1193479178] 'process raft request' (duration: 321.378745ms)","trace[1193479178] 'compare' (duration: 239.728478ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:11:24.792769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:24.230641Z","time spent":"562.026905ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:11:24.792882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.603729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q29g5\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-13T21:11:24.792962Z","caller":"traceutil/trace.go:171","msg":"trace[1865182606] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q29g5; range_end:; response_count:1; response_revision:621; }","duration":"420.734942ms","start":"2026-04-13T21:11:24.372214Z","end":"2026-04-13T21:11:24.792949Z","steps":["trace[1865182606] 'agreement among raft nodes before linearized reading' (duration: 420.591139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:24.793008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:11:24.372192Z","time spent":"420.806925ms","remote":"127.0.0.1:48610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-q29g5\" "} {"level":"info","ts":"2026-04-13T21:11:24.792891Z","caller":"traceutil/trace.go:171","msg":"trace[1109077372] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"420.413755ms","start":"2026-04-13T21:11:24.372267Z","end":"2026-04-13T21:11:24.792681Z","steps":["trace[1109077372] 'read index received' (duration: 179.713423ms)","trace[1109077372] 'applied index is now lower than readState.Index' (duration: 240.696212ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:11:24.793913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.928278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:11:24.793997Z","caller":"traceutil/trace.go:171","msg":"trace[1237437296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"218.042901ms","start":"2026-04-13T21:11:24.57593Z","end":"2026-04-13T21:11:24.793973Z","steps":["trace[1237437296] 'agreement among raft nodes before linearized reading' (duration: 217.906558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:11:24.794018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.924936ms","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-13T21:11:24.794082Z","caller":"traceutil/trace.go:171","msg":"trace[438499867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"209.024288ms","start":"2026-04-13T21:11:24.585041Z","end":"2026-04-13T21:11:24.794065Z","steps":["trace[438499867] 'agreement among raft nodes before linearized reading' (duration: 208.848914ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:24.793902Z","caller":"traceutil/trace.go:171","msg":"trace[1157806813] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"227.503145ms","start":"2026-04-13T21:11:24.566371Z","end":"2026-04-13T21:11:24.793874Z","steps":["trace[1157806813] 'process raft request' (duration: 226.12243ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:11:40.131671Z","caller":"traceutil/trace.go:171","msg":"trace[278081488] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"163.382538ms","start":"2026-04-13T21:11:39.968265Z","end":"2026-04-13T21:11:40.131647Z","steps":["trace[278081488] 'process raft request' (duration: 163.192384ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:12:08.86487Z","caller":"traceutil/trace.go:171","msg":"trace[2054818766] linearizableReadLoop","detail":"{readStateIndex:1211; appliedIndex:1210; }","duration":"450.96106ms","start":"2026-04-13T21:12:08.413885Z","end":"2026-04-13T21:12:08.864846Z","steps":["trace[2054818766] 'read index received' (duration: 450.703674ms)","trace[2054818766] 'applied index is now lower than readState.Index' (duration: 256.436µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:12:08.864943Z","caller":"traceutil/trace.go:171","msg":"trace[1309932369] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"537.788996ms","start":"2026-04-13T21:12:08.327127Z","end":"2026-04-13T21:12:08.864916Z","steps":["trace[1309932369] 'process raft request' (duration: 537.578201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:08.864997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.102924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:12:08.865032Z","caller":"traceutil/trace.go:171","msg":"trace[606432657] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1183; }","duration":"451.212166ms","start":"2026-04-13T21:12:08.41381Z","end":"2026-04-13T21:12:08.865022Z","steps":["trace[606432657] 'agreement among raft nodes before linearized reading' (duration: 451.133294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:12:08.865071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:08.413775Z","time spent":"451.286978ms","remote":"127.0.0.1:48540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":7,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-13T21:12:08.865082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:12:08.327102Z","time spent":"537.900629ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:12:54.988179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.774419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:12:54.988291Z","caller":"traceutil/trace.go:171","msg":"trace[1797601585] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1849; }","duration":"237.946363ms","start":"2026-04-13T21:12:54.750325Z","end":"2026-04-13T21:12:54.988272Z","steps":["trace[1797601585] 'count revisions from in-memory index tree' (duration: 237.520252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:09.788471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.394548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-13T21:13:09.788558Z","caller":"traceutil/trace.go:171","msg":"trace[1967935847] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1969; }","duration":"113.51778ms","start":"2026-04-13T21:13:09.675016Z","end":"2026-04-13T21:13:09.788534Z","steps":["trace[1967935847] 'range keys from in-memory index tree' (duration: 113.208633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:09.788588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.784514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-13T21:13:09.788456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.640371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-13T21:13:09.788636Z","caller":"traceutil/trace.go:171","msg":"trace[2026854341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1969; }","duration":"106.867756ms","start":"2026-04-13T21:13:09.681754Z","end":"2026-04-13T21:13:09.788622Z","steps":["trace[2026854341] 'range keys from in-memory index tree' (duration: 106.664761ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:09.78866Z","caller":"traceutil/trace.go:171","msg":"trace[1122707826] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1969; }","duration":"106.878836ms","start":"2026-04-13T21:13:09.681756Z","end":"2026-04-13T21:13:09.788635Z","steps":["trace[1122707826] 'range keys from in-memory index tree' (duration: 106.405095ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:43.8082Z","caller":"traceutil/trace.go:171","msg":"trace[1967516661] linearizableReadLoop","detail":"{readStateIndex:2238; appliedIndex:2237; }","duration":"171.155322ms","start":"2026-04-13T21:13:43.637024Z","end":"2026-04-13T21:13:43.80818Z","steps":["trace[1967516661] 'read index received' (duration: 171.012209ms)","trace[1967516661] 'applied index is now lower than readState.Index' (duration: 142.233µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:13:43.808277Z","caller":"traceutil/trace.go:171","msg":"trace[1895714165] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"271.601179ms","start":"2026-04-13T21:13:43.536643Z","end":"2026-04-13T21:13:43.808244Z","steps":["trace[1895714165] 'process raft request' (duration: 271.400524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:43.808411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.355697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-13T21:13:43.808473Z","caller":"traceutil/trace.go:171","msg":"trace[897536456] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2164; }","duration":"171.49245ms","start":"2026-04-13T21:13:43.636969Z","end":"2026-04-13T21:13:43.808462Z","steps":["trace[897536456] 'agreement among raft nodes before linearized reading' (duration: 171.299386ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:43.926393Z","caller":"traceutil/trace.go:171","msg":"trace[2143577638] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"279.355381ms","start":"2026-04-13T21:13:43.647003Z","end":"2026-04-13T21:13:43.926359Z","steps":["trace[2143577638] 'process raft request' (duration: 259.66777ms)","trace[2143577638] 'compare' (duration: 19.556308ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:13:49.57432Z","caller":"traceutil/trace.go:171","msg":"trace[676829945] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"157.619045ms","start":"2026-04-13T21:13:49.416677Z","end":"2026-04-13T21:13:49.574296Z","steps":["trace[676829945] 'process raft request' (duration: 157.466541ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:49.853699Z","caller":"traceutil/trace.go:171","msg":"trace[790256266] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"274.538422ms","start":"2026-04-13T21:13:49.57913Z","end":"2026-04-13T21:13:49.853668Z","steps":["trace[790256266] 'process raft request' (duration: 262.02812ms)","trace[790256266] 'compare' (duration: 12.197484ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:13:50.229231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.506531ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:13:50.229407Z","caller":"traceutil/trace.go:171","msg":"trace[1746802482] linearizableReadLoop","detail":"{readStateIndex:2269; appliedIndex:2267; }","duration":"537.888355ms","start":"2026-04-13T21:13:49.691503Z","end":"2026-04-13T21:13:50.229391Z","steps":["trace[1746802482] 'read index received' (duration: 149.670817ms)","trace[1746802482] 'applied index is now lower than readState.Index' (duration: 388.216508ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:13:50.229498Z","caller":"traceutil/trace.go:171","msg":"trace[1853285269] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"650.301211ms","start":"2026-04-13T21:13:49.579186Z","end":"2026-04-13T21:13:50.229487Z","steps":["trace[1853285269] 'process raft request' (duration: 506.451831ms)","trace[1853285269] 'compare' (duration: 143.382928ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:13:50.229568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:49.579148Z","time spent":"650.387994ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:13:50.229698Z","caller":"traceutil/trace.go:171","msg":"trace[523820367] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"401.230905ms","start":"2026-04-13T21:13:49.82844Z","end":"2026-04-13T21:13:50.229671Z","steps":["trace[523820367] 'process raft request' (duration: 400.895696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:50.229791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.248135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:13:50.229892Z","caller":"traceutil/trace.go:171","msg":"trace[209855505] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2195; }","duration":"538.411399ms","start":"2026-04-13T21:13:49.691457Z","end":"2026-04-13T21:13:50.229869Z","steps":["trace[209855505] 'agreement among raft nodes before linearized reading' (duration: 538.208794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:50.22995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:49.691433Z","time spent":"538.504441ms","remote":"127.0.0.1:48656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-13T21:13:50.230097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:49.828421Z","time spent":"401.369398ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:13:50.519543Z","caller":"traceutil/trace.go:171","msg":"trace[320410800] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"572.479128ms","start":"2026-04-13T21:13:49.947036Z","end":"2026-04-13T21:13:50.519515Z","steps":["trace[320410800] 'process raft request' (duration: 562.296744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:50.519811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:49.947015Z","time spent":"572.663423ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:13:50.519911Z","caller":"traceutil/trace.go:171","msg":"trace[1497627867] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"560.427208ms","start":"2026-04-13T21:13:49.959452Z","end":"2026-04-13T21:13:50.519879Z","steps":["trace[1497627867] 'process raft request' (duration: 560.226143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:50.520066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:49.959426Z","time spent":"560.559951ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:13:50.520274Z","caller":"traceutil/trace.go:171","msg":"trace[1677257403] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"526.88176ms","start":"2026-04-13T21:13:49.993382Z","end":"2026-04-13T21:13:50.520264Z","steps":["trace[1677257403] 'process raft request' (duration: 526.373028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:50.52034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:49.993357Z","time spent":"526.949172ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:13:50.520509Z","caller":"traceutil/trace.go:171","msg":"trace[1078895153] linearizableReadLoop","detail":"{readStateIndex:2273; appliedIndex:2270; }","duration":"291.056334ms","start":"2026-04-13T21:13:50.229443Z","end":"2026-04-13T21:13:50.520499Z","steps":["trace[1078895153] 'read index received' (duration: 279.907046ms)","trace[1078895153] 'applied index is now lower than readState.Index' (duration: 11.148358ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:13:50.52069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.602193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-04-13T21:13:50.52068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.452615ms","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-13T21:13:50.520736Z","caller":"traceutil/trace.go:171","msg":"trace[965221364] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2198; }","duration":"667.696345ms","start":"2026-04-13T21:13:49.853029Z","end":"2026-04-13T21:13:50.520725Z","steps":["trace[965221364] 'agreement among raft nodes before linearized reading' (duration: 667.548701ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:13:50.520752Z","caller":"traceutil/trace.go:171","msg":"trace[1743419231] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2198; }","duration":"497.567348ms","start":"2026-04-13T21:13:50.023167Z","end":"2026-04-13T21:13:50.520734Z","steps":["trace[1743419231] 'agreement among raft nodes before linearized reading' (duration: 497.367463ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:50.52077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:49.853008Z","time spent":"667.752326ms","remote":"127.0.0.1:44624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-13T21:13:50.520737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.648339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-13T21:13:50.520803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:50.023149Z","time spent":"497.64566ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-13T21:13:50.520812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.823619ms","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-13T21:13:50.520944Z","caller":"traceutil/trace.go:171","msg":"trace[1754373224] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2198; }","duration":"492.981745ms","start":"2026-04-13T21:13:50.027946Z","end":"2026-04-13T21:13:50.520928Z","steps":["trace[1754373224] 'agreement among raft nodes before linearized reading' (duration: 492.607275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:50.520987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:50.027934Z","time spent":"493.041416ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-04-13T21:13:50.52084Z","caller":"traceutil/trace.go:171","msg":"trace[95811031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2198; }","duration":"519.787872ms","start":"2026-04-13T21:13:50.001031Z","end":"2026-04-13T21:13:50.520819Z","steps":["trace[95811031] 'agreement among raft nodes before linearized reading' (duration: 519.529856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:13:50.521062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:13:50.001019Z","time spent":"520.0235ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-13T21:14:58.999226Z","caller":"traceutil/trace.go:171","msg":"trace[1856460051] transaction","detail":"{read_only:false; response_revision:2764; number_of_response:1; }","duration":"219.025081ms","start":"2026-04-13T21:14:58.780171Z","end":"2026-04-13T21:14:58.999196Z","steps":["trace[1856460051] 'process raft request' (duration: 218.816716ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:59.061319Z","caller":"traceutil/trace.go:171","msg":"trace[534469382] linearizableReadLoop","detail":"{readStateIndex:2862; appliedIndex:2860; }","duration":"211.755504ms","start":"2026-04-13T21:14:58.849533Z","end":"2026-04-13T21:14:59.061289Z","steps":["trace[534469382] 'read index received' (duration: 149.518241ms)","trace[534469382] 'applied index is now lower than readState.Index' (duration: 62.235773ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:14:59.061488Z","caller":"traceutil/trace.go:171","msg":"trace[1094890151] transaction","detail":"{read_only:false; response_revision:2765; number_of_response:1; }","duration":"277.345028ms","start":"2026-04-13T21:14:58.784098Z","end":"2026-04-13T21:14:59.061443Z","steps":["trace[1094890151] 'process raft request' (duration: 253.849873ms)","trace[1094890151] 'compare' (duration: 23.152926ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T21:14:59.061738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.152704ms","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-13T21:14:59.061803Z","caller":"traceutil/trace.go:171","msg":"trace[1476268575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2765; }","duration":"212.298649ms","start":"2026-04-13T21:14:58.849487Z","end":"2026-04-13T21:14:59.061786Z","steps":["trace[1476268575] 'agreement among raft nodes before linearized reading' (duration: 211.96203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:14:59.061821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.704583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-13T21:14:59.061773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.723134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-13T21:14:59.061921Z","caller":"traceutil/trace.go:171","msg":"trace[1695158039] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2765; }","duration":"211.856597ms","start":"2026-04-13T21:14:58.850044Z","end":"2026-04-13T21:14:59.0619Z","steps":["trace[1695158039] 'agreement among raft nodes before linearized reading' (duration: 211.571329ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:14:59.061965Z","caller":"traceutil/trace.go:171","msg":"trace[317282178] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2765; }","duration":"211.97861ms","start":"2026-04-13T21:14:58.849965Z","end":"2026-04-13T21:14:59.061944Z","steps":["trace[317282178] 'agreement among raft nodes before linearized reading' (duration: 211.495867ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:15:00.08609Z","caller":"traceutil/trace.go:171","msg":"trace[1248170017] transaction","detail":"{read_only:false; response_revision:2769; number_of_response:1; }","duration":"168.349141ms","start":"2026-04-13T21:14:59.91771Z","end":"2026-04-13T21:15:00.086059Z","steps":["trace[1248170017] 'process raft request' (duration: 168.189117ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:17:25.326004Z","caller":"traceutil/trace.go:171","msg":"trace[153709490] transaction","detail":"{read_only:false; response_revision:3734; number_of_response:1; }","duration":"237.929715ms","start":"2026-04-13T21:17:25.088045Z","end":"2026-04-13T21:17:25.325975Z","steps":["trace[153709490] 'process raft request' (duration: 237.611937ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:18:39.27668Z","caller":"traceutil/trace.go:171","msg":"trace[1529724662] transaction","detail":"{read_only:false; response_revision:4572; number_of_response:1; }","duration":"104.397496ms","start":"2026-04-13T21:18:39.172255Z","end":"2026-04-13T21:18:39.276652Z","steps":["trace[1529724662] 'process raft request' (duration: 90.993023ms)","trace[1529724662] 'compare' (duration: 13.298961ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:18:45.987456Z","caller":"traceutil/trace.go:171","msg":"trace[975629573] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"112.757952ms","start":"2026-04-13T21:18:45.874672Z","end":"2026-04-13T21:18:45.98743Z","steps":["trace[975629573] 'process raft request' (duration: 112.611429ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:18:48.920424Z","caller":"traceutil/trace.go:171","msg":"trace[1278348789] transaction","detail":"{read_only:false; response_revision:4899; number_of_response:1; }","duration":"257.722217ms","start":"2026-04-13T21:18:48.662642Z","end":"2026-04-13T21:18:48.920365Z","steps":["trace[1278348789] 'process raft request' (duration: 257.552073ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:18:49.059627Z","caller":"traceutil/trace.go:171","msg":"trace[1202599076] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"395.990594ms","start":"2026-04-13T21:18:48.663602Z","end":"2026-04-13T21:18:49.059592Z","steps":["trace[1202599076] 'process raft request' (duration: 392.203241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:18:49.059814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:18:48.663582Z","time spent":"396.124417ms","remote":"127.0.0.1:48494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T21:18:51.017073Z","caller":"traceutil/trace.go:171","msg":"trace[1560839078] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"132.403033ms","start":"2026-04-13T21:18:50.88464Z","end":"2026-04-13T21:18:51.017043Z","steps":["trace[1560839078] 'process raft request' (duration: 132.162377ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:18:59.992987Z","caller":"traceutil/trace.go:171","msg":"trace[1457663078] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"113.174195ms","start":"2026-04-13T21:18:59.879779Z","end":"2026-04-13T21:18:59.992953Z","steps":["trace[1457663078] 'process raft request' (duration: 113.035002ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:19:03.789995Z","caller":"traceutil/trace.go:171","msg":"trace[2116549945] transaction","detail":"{read_only:false; response_revision:5169; number_of_response:1; }","duration":"117.439831ms","start":"2026-04-13T21:19:03.672529Z","end":"2026-04-13T21:19:03.789969Z","steps":["trace[2116549945] 'process raft request' (duration: 117.276927ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:19:08.108779Z","caller":"traceutil/trace.go:171","msg":"trace[1983612677] linearizableReadLoop","detail":"{readStateIndex:5484; appliedIndex:5483; }","duration":"247.992878ms","start":"2026-04-13T21:19:07.860763Z","end":"2026-04-13T21:19:08.108756Z","steps":["trace[1983612677] 'read index received' (duration: 247.897935ms)","trace[1983612677] 'applied index is now lower than readState.Index' (duration: 93.743µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:19:08.108878Z","caller":"traceutil/trace.go:171","msg":"trace[1898041600] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"249.136126ms","start":"2026-04-13T21:19:07.859714Z","end":"2026-04-13T21:19:08.10885Z","steps":["trace[1898041600] 'process raft request' (duration: 248.89709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:19:08.109009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.204713ms","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-13T21:19:08.109053Z","caller":"traceutil/trace.go:171","msg":"trace[48271817] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5296; }","duration":"248.315466ms","start":"2026-04-13T21:19:07.860726Z","end":"2026-04-13T21:19:08.109041Z","steps":["trace[48271817] 'agreement among raft nodes before linearized reading' (duration: 248.142501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:19:08.609308Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512801694264009,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T21:19:08.624736Z","caller":"traceutil/trace.go:171","msg":"trace[276771468] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"521.695073ms","start":"2026-04-13T21:19:08.103017Z","end":"2026-04-13T21:19:08.624712Z","steps":["trace[276771468] 'process raft request' (duration: 521.645372ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:19:08.624813Z","caller":"traceutil/trace.go:171","msg":"trace[325383105] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"605.047266ms","start":"2026-04-13T21:19:08.019734Z","end":"2026-04-13T21:19:08.624781Z","steps":["trace[325383105] 'process raft request' (duration: 604.78074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:19:08.624985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:19:08.102993Z","time spent":"521.872117ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:19:08.625033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:19:08.019701Z","time spent":"605.237101ms","remote":"127.0.0.1:48704","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-13T21:19:08.626682Z","caller":"traceutil/trace.go:171","msg":"trace[264273801] linearizableReadLoop","detail":"{readStateIndex:5487; appliedIndex:5484; }","duration":"517.811407ms","start":"2026-04-13T21:19:08.108846Z","end":"2026-04-13T21:19:08.626658Z","steps":["trace[264273801] 'read index received' (duration: 515.649673ms)","trace[264273801] 'applied index is now lower than readState.Index' (duration: 2.160534ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:19:08.626764Z","caller":"traceutil/trace.go:171","msg":"trace[2112287617] transaction","detail":"{read_only:false; response_revision:5299; number_of_response:1; }","duration":"513.128021ms","start":"2026-04-13T21:19:08.11359Z","end":"2026-04-13T21:19:08.626718Z","steps":["trace[2112287617] 'process raft request' (duration: 512.930436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:19:08.626925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:19:08.113573Z","time spent":"513.266994ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:19:08.626935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.316004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d5b83a41-d6dd-4309-b896-532e1e103758\" ","response":"range_response_count:1 size:3762"} {"level":"info","ts":"2026-04-13T21:19:08.627004Z","caller":"traceutil/trace.go:171","msg":"trace[1517282910] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d5b83a41-d6dd-4309-b896-532e1e103758; range_end:; response_count:1; response_revision:5299; }","duration":"712.426606ms","start":"2026-04-13T21:19:07.914556Z","end":"2026-04-13T21:19:08.626982Z","steps":["trace[1517282910] 'agreement among raft nodes before linearized reading' (duration: 712.207551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:19:08.627048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:19:07.91453Z","time spent":"712.508059ms","remote":"127.0.0.1:48610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3785,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d5b83a41-d6dd-4309-b896-532e1e103758\" "} {"level":"warn","ts":"2026-04-13T21:19:08.627071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.851169ms","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-13T21:19:08.62718Z","caller":"traceutil/trace.go:171","msg":"trace[1422559955] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5299; }","duration":"207.966512ms","start":"2026-04-13T21:19:08.419151Z","end":"2026-04-13T21:19:08.627118Z","steps":["trace[1422559955] 'agreement among raft nodes before linearized reading' (duration: 207.717605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:19:08.627455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.260939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-13T21:19:08.62751Z","caller":"traceutil/trace.go:171","msg":"trace[196105514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5299; }","duration":"212.464704ms","start":"2026-04-13T21:19:08.415035Z","end":"2026-04-13T21:19:08.627499Z","steps":["trace[196105514] 'agreement among raft nodes before linearized reading' (duration: 211.755867ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:19:08.863493Z","caller":"traceutil/trace.go:171","msg":"trace[699460289] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"231.003824ms","start":"2026-04-13T21:19:08.632463Z","end":"2026-04-13T21:19:08.863467Z","steps":["trace[699460289] 'process raft request' (duration: 230.926342ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:19:08.863641Z","caller":"traceutil/trace.go:171","msg":"trace[2131081014] transaction","detail":"{read_only:false; response_revision:5302; number_of_response:1; }","duration":"230.385279ms","start":"2026-04-13T21:19:08.633229Z","end":"2026-04-13T21:19:08.863614Z","steps":["trace[2131081014] 'process raft request' (duration: 230.203794ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:19:08.863829Z","caller":"traceutil/trace.go:171","msg":"trace[1733606722] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"231.327612ms","start":"2026-04-13T21:19:08.632184Z","end":"2026-04-13T21:19:08.863512Z","steps":["trace[1733606722] 'process raft request' (duration: 170.406448ms)","trace[1733606722] 'compare' (duration: 60.657558ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:19:15.540663Z","caller":"traceutil/trace.go:171","msg":"trace[897982395] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"168.552181ms","start":"2026-04-13T21:19:15.372079Z","end":"2026-04-13T21:19:15.540631Z","steps":["trace[897982395] 'process raft request' (duration: 168.332016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:19:15.541494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.019037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-13T21:19:15.541579Z","caller":"traceutil/trace.go:171","msg":"trace[402148315] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5414; }","duration":"100.150701ms","start":"2026-04-13T21:19:15.441408Z","end":"2026-04-13T21:19:15.541558Z","steps":["trace[402148315] 'agreement among raft nodes before linearized reading' (duration: 99.214948ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:19:19.966762Z","caller":"traceutil/trace.go:171","msg":"trace[735560629] transaction","detail":"{read_only:false; response_revision:5460; number_of_response:1; }","duration":"116.099848ms","start":"2026-04-13T21:19:19.850638Z","end":"2026-04-13T21:19:19.966738Z","steps":["trace[735560629] 'process raft request' (duration: 115.918133ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:19:59.887857Z","caller":"traceutil/trace.go:171","msg":"trace[1805633353] transaction","detail":"{read_only:false; response_revision:5802; number_of_response:1; }","duration":"109.999415ms","start":"2026-04-13T21:19:59.777832Z","end":"2026-04-13T21:19:59.887832Z","steps":["trace[1805633353] 'process raft request' (duration: 109.834001ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:19:59.914486Z","caller":"traceutil/trace.go:171","msg":"trace[1297876265] transaction","detail":"{read_only:false; response_revision:5803; number_of_response:1; }","duration":"135.768176ms","start":"2026-04-13T21:19:59.778697Z","end":"2026-04-13T21:19:59.914465Z","steps":["trace[1297876265] 'process raft request' (duration: 135.654394ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:20:47.440179Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3033} {"level":"info","ts":"2026-04-13T21:20:47.526667Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3033,"took":"84.297749ms","hash":2956567442,"current-db-size-bytes":57974784,"current-db-size":"58 MB","current-db-size-in-use-bytes":31956992,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-13T21:20:47.526748Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2956567442,"revision":3033,"compact-revision":-1} {"level":"info","ts":"2026-04-13T21:25:00.66019Z","caller":"traceutil/trace.go:171","msg":"trace[1893481585] linearizableReadLoop","detail":"{readStateIndex:8332; appliedIndex:8331; }","duration":"159.000391ms","start":"2026-04-13T21:25:00.501166Z","end":"2026-04-13T21:25:00.660166Z","steps":["trace[1893481585] 'read index received' (duration: 158.833397ms)","trace[1893481585] 'applied index is now lower than readState.Index' (duration: 165.984µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:25:00.660267Z","caller":"traceutil/trace.go:171","msg":"trace[1149650594] transaction","detail":"{read_only:false; response_revision:8059; number_of_response:1; }","duration":"368.364745ms","start":"2026-04-13T21:25:00.291869Z","end":"2026-04-13T21:25:00.660234Z","steps":["trace[1149650594] 'process raft request' (duration: 368.114539ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:00.660497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:25:00.291853Z","time spent":"368.506668ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:25:00.660495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.197949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jckk6\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-13T21:25:00.660567Z","caller":"traceutil/trace.go:171","msg":"trace[1082259439] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jckk6; range_end:; response_count:1; response_revision:8059; }","duration":"115.327232ms","start":"2026-04-13T21:25:00.545222Z","end":"2026-04-13T21:25:00.660549Z","steps":["trace[1082259439] 'agreement among raft nodes before linearized reading' (duration: 115.102657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:00.660568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.507933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T21:25:00.660661Z","caller":"traceutil/trace.go:171","msg":"trace[187168766] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:8059; }","duration":"106.670827ms","start":"2026-04-13T21:25:00.553971Z","end":"2026-04-13T21:25:00.660642Z","steps":["trace[187168766] 'agreement among raft nodes before linearized reading' (duration: 106.485562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:00.660851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.690018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-13T21:25:00.660896Z","caller":"traceutil/trace.go:171","msg":"trace[1536149762] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:8059; }","duration":"159.788151ms","start":"2026-04-13T21:25:00.501097Z","end":"2026-04-13T21:25:00.660885Z","steps":["trace[1536149762] 'agreement among raft nodes before linearized reading' (duration: 159.192676ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:25:00.874675Z","caller":"traceutil/trace.go:171","msg":"trace[1825771746] linearizableReadLoop","detail":"{readStateIndex:8334; appliedIndex:8332; }","duration":"208.361ms","start":"2026-04-13T21:25:00.66623Z","end":"2026-04-13T21:25:00.874591Z","steps":["trace[1825771746] 'read index received' (duration: 186.420673ms)","trace[1825771746] 'applied index is now lower than readState.Index' (duration: 21.938817ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:25:00.874841Z","caller":"traceutil/trace.go:171","msg":"trace[545142822] transaction","detail":"{read_only:false; response_revision:8060; number_of_response:1; }","duration":"210.175075ms","start":"2026-04-13T21:25:00.66465Z","end":"2026-04-13T21:25:00.874825Z","steps":["trace[545142822] 'process raft request' (duration: 209.766885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:00.875089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.178154ms","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-13T21:25:00.875179Z","caller":"traceutil/trace.go:171","msg":"trace[1070766934] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8060; }","duration":"183.323787ms","start":"2026-04-13T21:25:00.691834Z","end":"2026-04-13T21:25:00.875157Z","steps":["trace[1070766934] 'agreement among raft nodes before linearized reading' (duration: 183.077121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:00.87537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.899652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-13T21:25:00.87548Z","caller":"traceutil/trace.go:171","msg":"trace[555200018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8060; }","duration":"178.081656ms","start":"2026-04-13T21:25:00.697376Z","end":"2026-04-13T21:25:00.875458Z","steps":["trace[555200018] 'agreement among raft nodes before linearized reading' (duration: 177.590354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:00.875606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.351325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:223831"} {"level":"info","ts":"2026-04-13T21:25:00.8757Z","caller":"traceutil/trace.go:171","msg":"trace[1929295889] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8060; }","duration":"209.484478ms","start":"2026-04-13T21:25:00.666196Z","end":"2026-04-13T21:25:00.875681Z","steps":["trace[1929295889] 'agreement among raft nodes before linearized reading' (duration: 208.75407ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:25:12.133578Z","caller":"traceutil/trace.go:171","msg":"trace[266762166] transaction","detail":"{read_only:false; response_revision:8119; number_of_response:1; }","duration":"142.751346ms","start":"2026-04-13T21:25:11.990786Z","end":"2026-04-13T21:25:12.133537Z","steps":["trace[266762166] 'process raft request' (duration: 142.51426ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:25:12.185348Z","caller":"traceutil/trace.go:171","msg":"trace[979829283] transaction","detail":"{read_only:false; response_revision:8120; number_of_response:1; }","duration":"193.755776ms","start":"2026-04-13T21:25:11.991564Z","end":"2026-04-13T21:25:12.18532Z","steps":["trace[979829283] 'process raft request' (duration: 193.608653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:12.365966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.462077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T21:25:12.366364Z","caller":"traceutil/trace.go:171","msg":"trace[1964401495] transaction","detail":"{read_only:false; response_revision:8121; number_of_response:1; }","duration":"186.916786ms","start":"2026-04-13T21:25:12.179403Z","end":"2026-04-13T21:25:12.36632Z","steps":["trace[1964401495] 'process raft request' (duration: 23.994778ms)","trace[1964401495] 'compare' (duration: 162.20426ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:25:13.324125Z","caller":"traceutil/trace.go:171","msg":"trace[1546518481] linearizableReadLoop","detail":"{readStateIndex:8402; appliedIndex:8399; }","duration":"385.947074ms","start":"2026-04-13T21:25:12.938149Z","end":"2026-04-13T21:25:13.324096Z","steps":["trace[1546518481] 'read index received' (duration: 15.734663ms)","trace[1546518481] 'applied index is now lower than readState.Index' (duration: 370.210841ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:25:13.324195Z","caller":"traceutil/trace.go:171","msg":"trace[2035890230] transaction","detail":"{read_only:false; response_revision:8124; number_of_response:1; }","duration":"388.346003ms","start":"2026-04-13T21:25:12.935809Z","end":"2026-04-13T21:25:13.324155Z","steps":["trace[2035890230] 'process raft request' (duration: 388.086687ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:25:13.324262Z","caller":"traceutil/trace.go:171","msg":"trace[863780255] transaction","detail":"{read_only:false; response_revision:8125; number_of_response:1; }","duration":"386.60158ms","start":"2026-04-13T21:25:12.93765Z","end":"2026-04-13T21:25:13.324251Z","steps":["trace[863780255] 'process raft request' (duration: 386.401335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:13.324535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:25:12.937619Z","time spent":"386.795245ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:25:13.324593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:25:12.935775Z","time spent":"388.528878ms","remote":"127.0.0.1:48704","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-13T21:25:13.325084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.923167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jckk6\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-13T21:25:13.325168Z","caller":"traceutil/trace.go:171","msg":"trace[1883291934] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jckk6; range_end:; response_count:1; response_revision:8125; }","duration":"387.060231ms","start":"2026-04-13T21:25:12.93809Z","end":"2026-04-13T21:25:13.32515Z","steps":["trace[1883291934] 'agreement among raft nodes before linearized reading' (duration: 386.191079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:13.325234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:25:12.93807Z","time spent":"387.135203ms","remote":"127.0.0.1:48610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-jckk6\" "} {"level":"info","ts":"2026-04-13T21:25:14.923835Z","caller":"traceutil/trace.go:171","msg":"trace[887800818] transaction","detail":"{read_only:false; response_revision:8132; number_of_response:1; }","duration":"165.957753ms","start":"2026-04-13T21:25:14.757835Z","end":"2026-04-13T21:25:14.923793Z","steps":["trace[887800818] 'process raft request' (duration: 165.691187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:41.955347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011512801694272172,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T21:25:42.045888Z","caller":"traceutil/trace.go:171","msg":"trace[2031735269] linearizableReadLoop","detail":"{readStateIndex:8615; appliedIndex:8614; }","duration":"591.597569ms","start":"2026-04-13T21:25:41.454267Z","end":"2026-04-13T21:25:42.045865Z","steps":["trace[2031735269] 'read index received' (duration: 591.358404ms)","trace[2031735269] 'applied index is now lower than readState.Index' (duration: 238.275µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:25:42.046268Z","caller":"traceutil/trace.go:171","msg":"trace[491597054] transaction","detail":"{read_only:false; response_revision:8330; number_of_response:1; }","duration":"605.64132ms","start":"2026-04-13T21:25:41.440606Z","end":"2026-04-13T21:25:42.046248Z","steps":["trace[491597054] 'process raft request' (duration: 605.098386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:42.046504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:25:41.440583Z","time spent":"605.740232ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T21:25:42.046799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.528673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-13T21:25:42.04684Z","caller":"traceutil/trace.go:171","msg":"trace[394816982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8330; }","duration":"592.597034ms","start":"2026-04-13T21:25:41.454232Z","end":"2026-04-13T21:25:42.046829Z","steps":["trace[394816982] 'agreement among raft nodes before linearized reading' (duration: 592.467541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:42.046873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:25:41.454218Z","time spent":"592.648356ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-13T21:25:42.047059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.388816ms","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-13T21:25:42.047096Z","caller":"traceutil/trace.go:171","msg":"trace[1841507692] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8330; }","duration":"577.442777ms","start":"2026-04-13T21:25:41.469643Z","end":"2026-04-13T21:25:42.047085Z","steps":["trace[1841507692] 'agreement among raft nodes before linearized reading' (duration: 577.355225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:42.047122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:25:41.469631Z","time spent":"577.485758ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-13T21:25:42.047411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.393331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:25:42.047452Z","caller":"traceutil/trace.go:171","msg":"trace[1265862234] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8330; }","duration":"465.445402ms","start":"2026-04-13T21:25:41.581997Z","end":"2026-04-13T21:25:42.047443Z","steps":["trace[1265862234] 'agreement among raft nodes before linearized reading' (duration: 465.381191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:42.047478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:25:41.581991Z","time spent":"465.481773ms","remote":"127.0.0.1:48430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T21:25:42.047693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.491311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-13T21:25:42.04772Z","caller":"traceutil/trace.go:171","msg":"trace[1650708914] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8330; }","duration":"489.541202ms","start":"2026-04-13T21:25:41.558173Z","end":"2026-04-13T21:25:42.047714Z","steps":["trace[1650708914] 'agreement among raft nodes before linearized reading' (duration: 489.46968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:42.047739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:25:41.558163Z","time spent":"489.571232ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-13T21:25:42.047849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.434908ms","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-13T21:25:42.04787Z","caller":"traceutil/trace.go:171","msg":"trace[1030779857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8330; }","duration":"493.47729ms","start":"2026-04-13T21:25:41.554388Z","end":"2026-04-13T21:25:42.047865Z","steps":["trace[1030779857] 'agreement among raft nodes before linearized reading' (duration: 493.421048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:42.047888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:25:41.554378Z","time spent":"493.50577ms","remote":"127.0.0.1:48704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T21:25:42.047998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.534912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-jckk6\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-13T21:25:42.04802Z","caller":"traceutil/trace.go:171","msg":"trace[1779705439] range","detail":"{range_begin:/registry/pods/openstack/keepalived-jckk6; range_end:; response_count:1; response_revision:8330; }","duration":"526.573422ms","start":"2026-04-13T21:25:41.521441Z","end":"2026-04-13T21:25:42.048014Z","steps":["trace[1779705439] 'agreement among raft nodes before linearized reading' (duration: 526.515261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:42.048036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T21:25:41.52143Z","time spent":"526.602844ms","remote":"127.0.0.1:48610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-jckk6\" "} {"level":"warn","ts":"2026-04-13T21:25:44.730771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.677674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:25:44.730927Z","caller":"traceutil/trace.go:171","msg":"trace[1198151097] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:8347; }","duration":"170.907339ms","start":"2026-04-13T21:25:44.559979Z","end":"2026-04-13T21:25:44.730887Z","steps":["trace[1198151097] 'count revisions from in-memory index tree' (duration: 170.574761ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:25:47.447932Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6174} {"level":"info","ts":"2026-04-13T21:25:47.546077Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6174,"took":"95.984876ms","hash":646720318,"current-db-size-bytes":57974784,"current-db-size":"58 MB","current-db-size-in-use-bytes":23044096,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-13T21:25:47.546157Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":646720318,"revision":6174,"compact-revision":3033} {"level":"info","ts":"2026-04-13T21:25:53.981508Z","caller":"traceutil/trace.go:171","msg":"trace[2074536392] linearizableReadLoop","detail":"{readStateIndex:8726; appliedIndex:8725; }","duration":"105.024672ms","start":"2026-04-13T21:25:53.876451Z","end":"2026-04-13T21:25:53.981476Z","steps":["trace[2074536392] 'read index received' (duration: 104.783476ms)","trace[2074536392] 'applied index is now lower than readState.Index' (duration: 240.106µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:25:53.981655Z","caller":"traceutil/trace.go:171","msg":"trace[1521625411] transaction","detail":"{read_only:false; response_revision:8435; number_of_response:1; }","duration":"105.631817ms","start":"2026-04-13T21:25:53.875989Z","end":"2026-04-13T21:25:53.98162Z","steps":["trace[1521625411] 'process raft request' (duration: 105.33084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:53.981761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.299588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-jgv8x\" ","response":"range_response_count:1 size:2802"} {"level":"info","ts":"2026-04-13T21:25:53.98207Z","caller":"traceutil/trace.go:171","msg":"trace[1199462694] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-jgv8x; range_end:; response_count:1; response_revision:8435; }","duration":"105.664827ms","start":"2026-04-13T21:25:53.876386Z","end":"2026-04-13T21:25:53.982051Z","steps":["trace[1199462694] 'agreement among raft nodes before linearized reading' (duration: 105.219686ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:25:54.51294Z","caller":"traceutil/trace.go:171","msg":"trace[1456812080] transaction","detail":"{read_only:false; response_revision:8444; number_of_response:1; }","duration":"117.234065ms","start":"2026-04-13T21:25:54.395677Z","end":"2026-04-13T21:25:54.512911Z","steps":["trace[1456812080] 'process raft request' (duration: 117.03984ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:25:54.535529Z","caller":"traceutil/trace.go:171","msg":"trace[1199003176] transaction","detail":"{read_only:false; response_revision:8445; number_of_response:1; }","duration":"131.733045ms","start":"2026-04-13T21:25:54.403769Z","end":"2026-04-13T21:25:54.535502Z","steps":["trace[1199003176] 'process raft request' (duration: 131.5286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:25:54.69338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.240037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"warn","ts":"2026-04-13T21:25:54.69342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.375743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T21:25:54.693506Z","caller":"traceutil/trace.go:171","msg":"trace[593134558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8445; }","duration":"116.505346ms","start":"2026-04-13T21:25:54.576978Z","end":"2026-04-13T21:25:54.693483Z","steps":["trace[593134558] 'range keys from in-memory index tree' (duration: 116.312431ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:25:54.693491Z","caller":"traceutil/trace.go:171","msg":"trace[1052279631] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:8445; }","duration":"140.37417ms","start":"2026-04-13T21:25:54.553082Z","end":"2026-04-13T21:25:54.693456Z","steps":["trace[1052279631] 'range keys from in-memory index tree' (duration: 139.997031ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:26:44.598154Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002427.wal"} {"level":"info","ts":"2026-04-13T21:27:09.635806Z","caller":"traceutil/trace.go:171","msg":"trace[427914564] linearizableReadLoop","detail":"{readStateIndex:9502; appliedIndex:9501; }","duration":"114.524587ms","start":"2026-04-13T21:27:09.521258Z","end":"2026-04-13T21:27:09.635783Z","steps":["trace[427914564] 'read index received' (duration: 114.316482ms)","trace[427914564] 'applied index is now lower than readState.Index' (duration: 207.265µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:27:09.63595Z","caller":"traceutil/trace.go:171","msg":"trace[462348963] transaction","detail":"{read_only:false; response_revision:9190; number_of_response:1; }","duration":"152.05095ms","start":"2026-04-13T21:27:09.483854Z","end":"2026-04-13T21:27:09.635905Z","steps":["trace[462348963] 'process raft request' (duration: 151.791914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T21:27:09.636017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.738943ms","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-13T21:27:09.636065Z","caller":"traceutil/trace.go:171","msg":"trace[1640007033] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9190; }","duration":"114.825775ms","start":"2026-04-13T21:27:09.521226Z","end":"2026-04-13T21:27:09.636052Z","steps":["trace[1640007033] 'agreement among raft nodes before linearized reading' (duration: 114.688251ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T21:28:19.050971Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fed19d9291630e09","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-13T21:28:19.056818Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-13T21:28:19.05696Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-13T21:29:26.390775Z","caller":"traceutil/trace.go:171","msg":"trace[1598375960] transaction","detail":"{read_only:false; response_revision:10226; number_of_response:1; }","duration":"103.037899ms","start":"2026-04-13T21:29:26.287712Z","end":"2026-04-13T21:29:26.39075Z","steps":["trace[1598375960] 'process raft request' (duration: 45.201504ms)","trace[1598375960] 'compare' (duration: 57.62565ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T21:30:47.454077Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8364} {"level":"info","ts":"2026-04-13T21:30:47.533018Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8364,"took":"76.802275ms","hash":4230212661,"current-db-size-bytes":57974784,"current-db-size":"58 MB","current-db-size-in-use-bytes":24711168,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-13T21:30:47.533089Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4230212661,"revision":8364,"compact-revision":6174} {"level":"info","ts":"2026-04-13T21:35:47.460852Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10709} {"level":"info","ts":"2026-04-13T21:35:47.539022Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10709,"took":"75.020997ms","hash":2530129963,"current-db-size-bytes":57974784,"current-db-size":"58 MB","current-db-size-in-use-bytes":20111360,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-13T21:35:47.539104Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2530129963,"revision":10709,"compact-revision":8364}