{"level":"warn","ts":"2026-05-26T21:10:13.085912Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T21:10:13.086029Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-26T21:10:13.08611Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T21:10:13.086127Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-26T21:10:13.086149Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-26T21:10:13.087081Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-05-26T21:10:13.087197Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-26T21:10:13.089907Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.225444ms"} {"level":"info","ts":"2026-05-26T21:10:13.094744Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-05-26T21:10:13.09497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-05-26T21:10:13.095017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-05-26T21:10:13.095045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-26T21:10:13.095066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-05-26T21:10:13.095142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-05-26T21:10:13.099942Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-26T21:10:13.101667Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-26T21:10:13.102849Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-26T21:10:13.105668Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-26T21:10:13.106231Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-26T21:10:13.106487Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T21:10:13.106549Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T21:10:13.106572Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T21:10:13.107007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-05-26T21:10:13.108062Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-26T21:10:13.110829Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-26T21:10:13.111004Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-26T21:10:13.111057Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-26T21:10:13.11129Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-26T21:10:13.111356Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-26T21:10:13.296126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-05-26T21:10:13.296196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-26T21:10:13.296223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-05-26T21:10:13.296242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-05-26T21:10:13.296382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-26T21:10:13.296415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-05-26T21:10:13.296434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-26T21:10:13.297458Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T21:10:13.298352Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T21:10:13.298356Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-05-26T21:10:13.298403Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T21:10:13.298739Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-26T21:10:13.298816Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-26T21:10:13.298989Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T21:10:13.299184Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T21:10:13.299231Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T21:10:13.30098Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-26T21:10:13.301052Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-05-26T21:10:56.228788Z","caller":"traceutil/trace.go:171","msg":"trace[482293009] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"186.248856ms","start":"2026-05-26T21:10:56.042516Z","end":"2026-05-26T21:10:56.228765Z","steps":["trace[482293009] 'process raft request' (duration: 185.786293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:10:56.74255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.288089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T21:10:56.742663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.957206ms","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-05-26T21:10:56.742733Z","caller":"traceutil/trace.go:171","msg":"trace[1012141110] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:587; }","duration":"247.047069ms","start":"2026-05-26T21:10:56.495667Z","end":"2026-05-26T21:10:56.742714Z","steps":["trace[1012141110] 'range keys from in-memory index tree' (duration: 246.77146ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:10:56.742806Z","caller":"traceutil/trace.go:171","msg":"trace[108791220] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"108.091657ms","start":"2026-05-26T21:10:56.634681Z","end":"2026-05-26T21:10:56.742772Z","steps":["trace[108791220] 'process raft request' (duration: 88.719552ms)","trace[108791220] 'compare' (duration: 19.075007ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:10:56.742627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.755651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T21:10:56.742925Z","caller":"traceutil/trace.go:171","msg":"trace[2123528313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:587; }","duration":"313.104851ms","start":"2026-05-26T21:10:56.429806Z","end":"2026-05-26T21:10:56.742911Z","steps":["trace[2123528313] 'range keys from in-memory index tree' (duration: 312.509224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:10:56.742968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:10:56.429758Z","time spent":"313.194384ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-26T21:10:56.74331Z","caller":"traceutil/trace.go:171","msg":"trace[841188643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:587; }","duration":"163.503815ms","start":"2026-05-26T21:10:56.579167Z","end":"2026-05-26T21:10:56.74267Z","steps":["trace[841188643] 'range keys from in-memory index tree' (duration: 163.214427ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:10:56.804499Z","caller":"traceutil/trace.go:171","msg":"trace[253748483] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"109.843427ms","start":"2026-05-26T21:10:56.694606Z","end":"2026-05-26T21:10:56.80445Z","steps":["trace[253748483] 'process raft request' (duration: 104.420152ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:10:57.326951Z","caller":"traceutil/trace.go:171","msg":"trace[1876681374] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"580.099531ms","start":"2026-05-26T21:10:56.746821Z","end":"2026-05-26T21:10:57.326921Z","steps":["trace[1876681374] 'process raft request' (duration: 556.525076ms)","trace[1876681374] 'compare' (duration: 23.422711ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:10:57.327431Z","caller":"traceutil/trace.go:171","msg":"trace[637977783] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:611; }","duration":"505.427502ms","start":"2026-05-26T21:10:56.821979Z","end":"2026-05-26T21:10:57.327407Z","steps":["trace[637977783] 'read index received' (duration: 481.354583ms)","trace[637977783] 'applied index is now lower than readState.Index' (duration: 24.071959ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:10:57.327424Z","caller":"traceutil/trace.go:171","msg":"trace[1782463197] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"580.42198ms","start":"2026-05-26T21:10:56.746895Z","end":"2026-05-26T21:10:57.327317Z","steps":["trace[1782463197] 'process raft request' (duration: 579.989908ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:10:57.327479Z","caller":"traceutil/trace.go:171","msg":"trace[532273266] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"505.605636ms","start":"2026-05-26T21:10:56.821819Z","end":"2026-05-26T21:10:57.327425Z","steps":["trace[532273266] 'process raft request' (duration: 505.269577ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:10:57.327516Z","caller":"traceutil/trace.go:171","msg":"trace[1310300757] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"460.775412ms","start":"2026-05-26T21:10:56.866668Z","end":"2026-05-26T21:10:57.327444Z","steps":["trace[1310300757] 'process raft request' (duration: 460.671779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:10:57.327581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:10:56.746804Z","time spent":"580.227965ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:10:57.327593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:10:56.74689Z","time spent":"580.621646ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:10:57.327655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:10:56.821796Z","time spent":"505.774031ms","remote":"127.0.0.1:47922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:10:57.327696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:10:56.866646Z","time spent":"460.968008ms","remote":"127.0.0.1:48234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T21:10:57.327603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.608387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-26T21:10:57.327797Z","caller":"traceutil/trace.go:171","msg":"trace[959172545] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:593; }","duration":"505.828043ms","start":"2026-05-26T21:10:56.821955Z","end":"2026-05-26T21:10:57.327783Z","steps":["trace[959172545] 'agreement among raft nodes before linearized reading' (duration: 505.551355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:10:57.327856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:10:56.821948Z","time spent":"505.896155ms","remote":"127.0.0.1:47764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"info","ts":"2026-05-26T21:11:11.545252Z","caller":"traceutil/trace.go:171","msg":"trace[997553795] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"104.306209ms","start":"2026-05-26T21:11:11.440923Z","end":"2026-05-26T21:11:11.545229Z","steps":["trace[997553795] 'process raft request' (duration: 104.157885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:11:11.729791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.050973ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:11:11.729954Z","caller":"traceutil/trace.go:171","msg":"trace[934411927] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:751; }","duration":"103.237778ms","start":"2026-05-26T21:11:11.626697Z","end":"2026-05-26T21:11:11.729935Z","steps":["trace[934411927] 'range keys from in-memory index tree' (duration: 103.011251ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:11:11.733676Z","caller":"traceutil/trace.go:171","msg":"trace[764775758] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"153.608122ms","start":"2026-05-26T21:11:11.580054Z","end":"2026-05-26T21:11:11.733662Z","steps":["trace[764775758] 'read index received' (duration: 153.430787ms)","trace[764775758] 'applied index is now lower than readState.Index' (duration: 176.175µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:11:11.733792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.739425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:11:11.73379Z","caller":"traceutil/trace.go:171","msg":"trace[1681577481] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"290.492904ms","start":"2026-05-26T21:11:11.443264Z","end":"2026-05-26T21:11:11.733757Z","steps":["trace[1681577481] 'process raft request' (duration: 290.276977ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:11:11.733823Z","caller":"traceutil/trace.go:171","msg":"trace[1261769985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"153.810157ms","start":"2026-05-26T21:11:11.580002Z","end":"2026-05-26T21:11:11.733812Z","steps":["trace[1261769985] 'agreement among raft nodes before linearized reading' (duration: 153.756476ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:12:25.294582Z","caller":"traceutil/trace.go:171","msg":"trace[1416718146] linearizableReadLoop","detail":"{readStateIndex:1880; appliedIndex:1879; }","duration":"151.92397ms","start":"2026-05-26T21:12:25.142629Z","end":"2026-05-26T21:12:25.294553Z","steps":["trace[1416718146] 'read index received' (duration: 151.716224ms)","trace[1416718146] 'applied index is now lower than readState.Index' (duration: 206.506µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:12:25.294708Z","caller":"traceutil/trace.go:171","msg":"trace[1249757299] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"252.909111ms","start":"2026-05-26T21:12:25.041756Z","end":"2026-05-26T21:12:25.294665Z","steps":["trace[1249757299] 'process raft request' (duration: 252.644453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:12:25.294918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.27081ms","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-05-26T21:12:25.294979Z","caller":"traceutil/trace.go:171","msg":"trace[235115666] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1809; }","duration":"152.377783ms","start":"2026-05-26T21:12:25.142584Z","end":"2026-05-26T21:12:25.294962Z","steps":["trace[235115666] 'agreement among raft nodes before linearized reading' (duration: 152.124806ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:12:25.447991Z","caller":"traceutil/trace.go:171","msg":"trace[128136275] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"146.950686ms","start":"2026-05-26T21:12:25.301013Z","end":"2026-05-26T21:12:25.447964Z","steps":["trace[128136275] 'process raft request' (duration: 139.824313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:12:25.829638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.770277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:12:25.829739Z","caller":"traceutil/trace.go:171","msg":"trace[594378431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1810; }","duration":"251.908861ms","start":"2026-05-26T21:12:25.577808Z","end":"2026-05-26T21:12:25.829717Z","steps":["trace[594378431] 'range keys from in-memory index tree' (duration: 251.662564ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:12:40.180481Z","caller":"traceutil/trace.go:171","msg":"trace[2104252014] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"182.70495ms","start":"2026-05-26T21:12:39.997744Z","end":"2026-05-26T21:12:40.180449Z","steps":["trace[2104252014] 'process raft request' (duration: 182.503634ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:13:16.477Z","caller":"traceutil/trace.go:171","msg":"trace[1602140007] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"105.722782ms","start":"2026-05-26T21:13:16.371245Z","end":"2026-05-26T21:13:16.476968Z","steps":["trace[1602140007] 'process raft request' (duration: 105.387883ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:13:16.477132Z","caller":"traceutil/trace.go:171","msg":"trace[334365922] linearizableReadLoop","detail":"{readStateIndex:2215; appliedIndex:2213; }","duration":"100.873153ms","start":"2026-05-26T21:13:16.376233Z","end":"2026-05-26T21:13:16.477106Z","steps":["trace[334365922] 'read index received' (duration: 17.721376ms)","trace[334365922] 'applied index is now lower than readState.Index' (duration: 83.150647ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:13:16.477295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.051489ms","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-05-26T21:13:16.477413Z","caller":"traceutil/trace.go:171","msg":"trace[1106808782] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2133; }","duration":"101.167492ms","start":"2026-05-26T21:13:16.376184Z","end":"2026-05-26T21:13:16.477351Z","steps":["trace[1106808782] 'agreement among raft nodes before linearized reading' (duration: 100.991257ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:13:22.416123Z","caller":"traceutil/trace.go:171","msg":"trace[1212076904] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"140.28745ms","start":"2026-05-26T21:13:22.275813Z","end":"2026-05-26T21:13:22.416101Z","steps":["trace[1212076904] 'process raft request' (duration: 140.134736ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:13:22.48042Z","caller":"traceutil/trace.go:171","msg":"trace[766305502] linearizableReadLoop","detail":"{readStateIndex:2247; appliedIndex:2244; }","duration":"108.315466ms","start":"2026-05-26T21:13:22.372065Z","end":"2026-05-26T21:13:22.48038Z","steps":["trace[766305502] 'read index received' (duration: 43.988258ms)","trace[766305502] 'applied index is now lower than readState.Index' (duration: 64.325698ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:13:22.480596Z","caller":"traceutil/trace.go:171","msg":"trace[1463412707] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"126.6328ms","start":"2026-05-26T21:13:22.353785Z","end":"2026-05-26T21:13:22.480418Z","steps":["trace[1463412707] 'process raft request' (duration: 126.28483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:13:22.480861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.763319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T21:13:22.481002Z","caller":"traceutil/trace.go:171","msg":"trace[974178573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2163; }","duration":"108.953084ms","start":"2026-05-26T21:13:22.372019Z","end":"2026-05-26T21:13:22.480972Z","steps":["trace[974178573] 'agreement among raft nodes before linearized reading' (duration: 108.510402ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:14:07.662293Z","caller":"traceutil/trace.go:171","msg":"trace[1740353599] transaction","detail":"{read_only:false; response_revision:2584; number_of_response:1; }","duration":"139.143912ms","start":"2026-05-26T21:14:07.523118Z","end":"2026-05-26T21:14:07.662262Z","steps":["trace[1740353599] 'process raft request' (duration: 136.714614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:18:42.918562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.196604ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:18:42.918848Z","caller":"traceutil/trace.go:171","msg":"trace[1209478069] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"151.365052ms","start":"2026-05-26T21:18:42.767422Z","end":"2026-05-26T21:18:42.918787Z","steps":["trace[1209478069] 'process raft request' (duration: 11.712674ms)","trace[1209478069] 'compare' (duration: 138.941777ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:18:43.109783Z","caller":"traceutil/trace.go:171","msg":"trace[1958945456] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"127.474841ms","start":"2026-05-26T21:18:42.982266Z","end":"2026-05-26T21:18:43.109741Z","steps":["trace[1958945456] 'process raft request' (duration: 101.323584ms)","trace[1958945456] 'compare' (duration: 25.960342ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:18:50.308063Z","caller":"traceutil/trace.go:171","msg":"trace[1160305733] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"136.742465ms","start":"2026-05-26T21:18:50.171271Z","end":"2026-05-26T21:18:50.308013Z","steps":["trace[1160305733] 'process raft request' (duration: 136.176619ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:18:50.320486Z","caller":"traceutil/trace.go:171","msg":"trace[1804687533] linearizableReadLoop","detail":"{readStateIndex:5358; appliedIndex:5356; }","duration":"147.127632ms","start":"2026-05-26T21:18:50.173333Z","end":"2026-05-26T21:18:50.32046Z","steps":["trace[1804687533] 'read index received' (duration: 134.209643ms)","trace[1804687533] 'applied index is now lower than readState.Index' (duration: 12.916219ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:18:50.320584Z","caller":"traceutil/trace.go:171","msg":"trace[719195583] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"148.643965ms","start":"2026-05-26T21:18:50.17191Z","end":"2026-05-26T21:18:50.320554Z","steps":["trace[719195583] 'process raft request' (duration: 148.411398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:18:50.320786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.41883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-fa839628-7bfd-4de2-83b6-bb51aacb86b2\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-05-26T21:18:50.320845Z","caller":"traceutil/trace.go:171","msg":"trace[1095145282] range","detail":"{range_begin:/registry/persistentvolumes/pvc-fa839628-7bfd-4de2-83b6-bb51aacb86b2; range_end:; response_count:1; response_revision:5162; }","duration":"147.509612ms","start":"2026-05-26T21:18:50.173315Z","end":"2026-05-26T21:18:50.320824Z","steps":["trace[1095145282] 'agreement among raft nodes before linearized reading' (duration: 147.287666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:18:50.321099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.022851ms","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-05-26T21:18:50.321143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.413666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-26T21:18:50.321235Z","caller":"traceutil/trace.go:171","msg":"trace[1167884634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5162; }","duration":"122.538729ms","start":"2026-05-26T21:18:50.198675Z","end":"2026-05-26T21:18:50.321213Z","steps":["trace[1167884634] 'agreement among raft nodes before linearized reading' (duration: 122.232771ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:18:50.321227Z","caller":"traceutil/trace.go:171","msg":"trace[875270647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5162; }","duration":"124.160885ms","start":"2026-05-26T21:18:50.197016Z","end":"2026-05-26T21:18:50.321177Z","steps":["trace[875270647] 'agreement among raft nodes before linearized reading' (duration: 123.827806ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:18:55.002707Z","caller":"traceutil/trace.go:171","msg":"trace[1661635356] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"193.450255ms","start":"2026-05-26T21:18:54.809228Z","end":"2026-05-26T21:18:55.002678Z","steps":["trace[1661635356] 'process raft request' (duration: 193.146306ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:19:03.474817Z","caller":"traceutil/trace.go:171","msg":"trace[1565235345] linearizableReadLoop","detail":"{readStateIndex:5606; appliedIndex:5604; }","duration":"171.506858ms","start":"2026-05-26T21:19:03.303282Z","end":"2026-05-26T21:19:03.474788Z","steps":["trace[1565235345] 'read index received' (duration: 22.575415ms)","trace[1565235345] 'applied index is now lower than readState.Index' (duration: 148.929933ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:19:03.474888Z","caller":"traceutil/trace.go:171","msg":"trace[1590028236] transaction","detail":"{read_only:false; response_revision:5406; number_of_response:1; }","duration":"175.608304ms","start":"2026-05-26T21:19:03.299234Z","end":"2026-05-26T21:19:03.474843Z","steps":["trace[1590028236] 'process raft request' (duration: 123.022013ms)","trace[1590028236] 'compare' (duration: 52.395985ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:19:03.475117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.813997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-26T21:19:03.475166Z","caller":"traceutil/trace.go:171","msg":"trace[1971918887] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5406; }","duration":"171.909899ms","start":"2026-05-26T21:19:03.303242Z","end":"2026-05-26T21:19:03.475151Z","steps":["trace[1971918887] 'agreement among raft nodes before linearized reading' (duration: 171.721654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:19:03.475188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.849641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ca8ac02b-e122-4aca-9c12-bcb3369027a2\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-05-26T21:19:03.475271Z","caller":"traceutil/trace.go:171","msg":"trace[536270712] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ca8ac02b-e122-4aca-9c12-bcb3369027a2; range_end:; response_count:1; response_revision:5406; }","duration":"155.981775ms","start":"2026-05-26T21:19:03.319265Z","end":"2026-05-26T21:19:03.475247Z","steps":["trace[536270712] 'agreement among raft nodes before linearized reading' (duration: 155.84682ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:20:13.891637Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2965} {"level":"info","ts":"2026-05-26T21:20:13.962068Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2965,"took":"68.464834ms","hash":396796652,"current-db-size-bytes":58851328,"current-db-size":"59 MB","current-db-size-in-use-bytes":34205696,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-26T21:20:13.96214Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":396796652,"revision":2965,"compact-revision":-1} {"level":"warn","ts":"2026-05-26T21:25:06.357048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.300304ms","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-05-26T21:25:06.357137Z","caller":"traceutil/trace.go:171","msg":"trace[1099491433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8219; }","duration":"103.450159ms","start":"2026-05-26T21:25:06.25367Z","end":"2026-05-26T21:25:06.35712Z","steps":["trace[1099491433] 'range keys from in-memory index tree' (duration: 103.150801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:25:06.357103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.045871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-26T21:25:06.357159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.632685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-26T21:25:06.357223Z","caller":"traceutil/trace.go:171","msg":"trace[985054197] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8219; }","duration":"196.238416ms","start":"2026-05-26T21:25:06.160952Z","end":"2026-05-26T21:25:06.357191Z","steps":["trace[985054197] 'count revisions from in-memory index tree' (duration: 195.901987ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:25:06.357248Z","caller":"traceutil/trace.go:171","msg":"trace[1349492583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8219; }","duration":"108.78742ms","start":"2026-05-26T21:25:06.248437Z","end":"2026-05-26T21:25:06.357225Z","steps":["trace[1349492583] 'range keys from in-memory index tree' (duration: 108.388189ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:25:13.898211Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6067} {"level":"info","ts":"2026-05-26T21:25:13.987088Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6067,"took":"86.978863ms","hash":2873947764,"current-db-size-bytes":58851328,"current-db-size":"59 MB","current-db-size-in-use-bytes":23269376,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-26T21:25:13.987147Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2873947764,"revision":6067,"compact-revision":2965} {"level":"warn","ts":"2026-05-26T21:25:18.542332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.424026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:25:18.542452Z","caller":"traceutil/trace.go:171","msg":"trace[1390230591] transaction","detail":"{read_only:false; response_revision:8287; number_of_response:1; }","duration":"275.773449ms","start":"2026-05-26T21:25:18.266626Z","end":"2026-05-26T21:25:18.5424Z","steps":["trace[1390230591] 'process raft request' (duration: 104.929492ms)","trace[1390230591] 'compare' (duration: 170.125336ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:25:19.403676Z","caller":"traceutil/trace.go:171","msg":"trace[1215159687] transaction","detail":"{read_only:false; response_revision:8291; number_of_response:1; }","duration":"261.822643ms","start":"2026-05-26T21:25:19.141814Z","end":"2026-05-26T21:25:19.403636Z","steps":["trace[1215159687] 'process raft request' (duration: 261.657608ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:25:19.423808Z","caller":"traceutil/trace.go:171","msg":"trace[419428329] linearizableReadLoop","detail":"{readStateIndex:8584; appliedIndex:8582; }","duration":"166.827523ms","start":"2026-05-26T21:25:19.256953Z","end":"2026-05-26T21:25:19.42378Z","steps":["trace[419428329] 'read index received' (duration: 146.461546ms)","trace[419428329] 'applied index is now lower than readState.Index' (duration: 20.364327ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:25:19.423864Z","caller":"traceutil/trace.go:171","msg":"trace[1687366245] transaction","detail":"{read_only:false; response_revision:8292; number_of_response:1; }","duration":"213.672279ms","start":"2026-05-26T21:25:19.210165Z","end":"2026-05-26T21:25:19.423837Z","steps":["trace[1687366245] 'process raft request' (duration: 213.429253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:25:19.424067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.09129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lnqbq\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-26T21:25:19.424141Z","caller":"traceutil/trace.go:171","msg":"trace[4408405] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lnqbq; range_end:; response_count:1; response_revision:8292; }","duration":"167.213123ms","start":"2026-05-26T21:25:19.256905Z","end":"2026-05-26T21:25:19.424118Z","steps":["trace[4408405] 'agreement among raft nodes before linearized reading' (duration: 167.009728ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:25:20.70028Z","caller":"traceutil/trace.go:171","msg":"trace[1969283707] linearizableReadLoop","detail":"{readStateIndex:8589; appliedIndex:8588; }","duration":"110.87842ms","start":"2026-05-26T21:25:20.589374Z","end":"2026-05-26T21:25:20.700252Z","steps":["trace[1969283707] 'read index received' (duration: 110.691435ms)","trace[1969283707] 'applied index is now lower than readState.Index' (duration: 185.845µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:25:20.700391Z","caller":"traceutil/trace.go:171","msg":"trace[35171562] transaction","detail":"{read_only:false; response_revision:8297; number_of_response:1; }","duration":"112.732642ms","start":"2026-05-26T21:25:20.587608Z","end":"2026-05-26T21:25:20.700341Z","steps":["trace[35171562] 'process raft request' (duration: 112.476304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:25:20.700532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.143387ms","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-05-26T21:25:20.700594Z","caller":"traceutil/trace.go:171","msg":"trace[615002214] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8297; }","duration":"111.24632ms","start":"2026-05-26T21:25:20.589335Z","end":"2026-05-26T21:25:20.700582Z","steps":["trace[615002214] 'agreement among raft nodes before linearized reading' (duration: 111.058965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:25:20.700747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.155447ms","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-05-26T21:25:20.700837Z","caller":"traceutil/trace.go:171","msg":"trace[1452598319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8297; }","duration":"111.277811ms","start":"2026-05-26T21:25:20.58954Z","end":"2026-05-26T21:25:20.700818Z","steps":["trace[1452598319] 'agreement among raft nodes before linearized reading' (duration: 111.052815ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:25:48.169632Z","caller":"traceutil/trace.go:171","msg":"trace[1712053949] transaction","detail":"{read_only:false; response_revision:8500; number_of_response:1; }","duration":"334.060629ms","start":"2026-05-26T21:25:47.835546Z","end":"2026-05-26T21:25:48.169606Z","steps":["trace[1712053949] 'process raft request' (duration: 333.889415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:25:48.169945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:25:47.835512Z","time spent":"334.203834ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:25:50.986298Z","caller":"traceutil/trace.go:171","msg":"trace[1442643273] transaction","detail":"{read_only:false; response_revision:8512; number_of_response:1; }","duration":"108.872013ms","start":"2026-05-26T21:25:50.877398Z","end":"2026-05-26T21:25:50.98627Z","steps":["trace[1442643273] 'process raft request' (duration: 108.697298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:25:51.157269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.693552ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:25:51.157442Z","caller":"traceutil/trace.go:171","msg":"trace[1581794113] linearizableReadLoop","detail":"{readStateIndex:8813; appliedIndex:8811; }","duration":"278.236908ms","start":"2026-05-26T21:25:50.879186Z","end":"2026-05-26T21:25:51.157423Z","steps":["trace[1581794113] 'read index received' (duration: 106.909877ms)","trace[1581794113] 'applied index is now lower than readState.Index' (duration: 171.325651ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:25:51.157605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.414483ms","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-05-26T21:25:51.157599Z","caller":"traceutil/trace.go:171","msg":"trace[1506410248] transaction","detail":"{read_only:false; response_revision:8513; number_of_response:1; }","duration":"279.734081ms","start":"2026-05-26T21:25:50.877823Z","end":"2026-05-26T21:25:51.157557Z","steps":["trace[1506410248] 'process raft request' (duration: 149.666868ms)","trace[1506410248] 'compare' (duration: 129.562309ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:25:51.157643Z","caller":"traceutil/trace.go:171","msg":"trace[898894831] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8513; }","duration":"278.473955ms","start":"2026-05-26T21:25:50.879158Z","end":"2026-05-26T21:25:51.157632Z","steps":["trace[898894831] 'agreement among raft nodes before linearized reading' (duration: 278.329071ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:26:00.902339Z","caller":"traceutil/trace.go:171","msg":"trace[721196989] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8603; }","duration":"121.656029ms","start":"2026-05-26T21:26:00.780651Z","end":"2026-05-26T21:26:00.902307Z","steps":["trace[721196989] 'process raft request' (duration: 53.167638ms)","trace[721196989] 'compare' (duration: 67.797411ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:26:00.902694Z","caller":"traceutil/trace.go:171","msg":"trace[1642017621] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8603; }","duration":"121.968938ms","start":"2026-05-26T21:26:00.780701Z","end":"2026-05-26T21:26:00.90267Z","steps":["trace[1642017621] 'process raft request' (duration: 121.047883ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:26:01.751376Z","caller":"traceutil/trace.go:171","msg":"trace[726555488] transaction","detail":"{read_only:false; response_revision:8613; number_of_response:1; }","duration":"175.271041ms","start":"2026-05-26T21:26:01.576076Z","end":"2026-05-26T21:26:01.751348Z","steps":["trace[726555488] 'process raft request' (duration: 126.235648ms)","trace[726555488] 'compare' (duration: 48.645602ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:26:01.902813Z","caller":"traceutil/trace.go:171","msg":"trace[875954229] linearizableReadLoop","detail":"{readStateIndex:8920; appliedIndex:8918; }","duration":"151.709286ms","start":"2026-05-26T21:26:01.751084Z","end":"2026-05-26T21:26:01.902793Z","steps":["trace[875954229] 'read index received' (duration: 25.347945ms)","trace[875954229] 'applied index is now lower than readState.Index' (duration: 126.360381ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:26:01.903004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.190332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lnqbq\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-26T21:26:01.902993Z","caller":"traceutil/trace.go:171","msg":"trace[3210790] transaction","detail":"{read_only:false; response_revision:8616; number_of_response:1; }","duration":"147.23356ms","start":"2026-05-26T21:26:01.755724Z","end":"2026-05-26T21:26:01.902957Z","steps":["trace[3210790] 'process raft request' (duration: 146.971592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:26:01.903114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.972134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-26T21:26:01.903151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.451312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-72m82\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-05-26T21:26:01.903166Z","caller":"traceutil/trace.go:171","msg":"trace[2102884314] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8617; }","duration":"157.071907ms","start":"2026-05-26T21:26:01.74608Z","end":"2026-05-26T21:26:01.903152Z","steps":["trace[2102884314] 'agreement among raft nodes before linearized reading' (duration: 156.912843ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:26:01.903184Z","caller":"traceutil/trace.go:171","msg":"trace[2058892919] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-72m82; range_end:; response_count:1; response_revision:8617; }","duration":"160.518094ms","start":"2026-05-26T21:26:01.742658Z","end":"2026-05-26T21:26:01.903176Z","steps":["trace[2058892919] 'agreement among raft nodes before linearized reading' (duration: 160.40387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:26:01.903306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.180665ms","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-05-26T21:26:01.903042Z","caller":"traceutil/trace.go:171","msg":"trace[981109042] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lnqbq; range_end:; response_count:1; response_revision:8617; }","duration":"189.280936ms","start":"2026-05-26T21:26:01.71375Z","end":"2026-05-26T21:26:01.903031Z","steps":["trace[981109042] 'agreement among raft nodes before linearized reading' (duration: 189.124111ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:26:01.903371Z","caller":"traceutil/trace.go:171","msg":"trace[1616752212] transaction","detail":"{read_only:false; response_revision:8615; number_of_response:1; }","duration":"160.429621ms","start":"2026-05-26T21:26:01.742928Z","end":"2026-05-26T21:26:01.903358Z","steps":["trace[1616752212] 'process raft request' (duration: 149.665617ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:26:01.903283Z","caller":"traceutil/trace.go:171","msg":"trace[1618365348] transaction","detail":"{read_only:false; response_revision:8617; number_of_response:1; }","duration":"147.294532ms","start":"2026-05-26T21:26:01.755956Z","end":"2026-05-26T21:26:01.90325Z","steps":["trace[1618365348] 'process raft request' (duration: 146.796878ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:26:01.903382Z","caller":"traceutil/trace.go:171","msg":"trace[1260018194] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8617; }","duration":"165.262078ms","start":"2026-05-26T21:26:01.738094Z","end":"2026-05-26T21:26:01.903356Z","steps":["trace[1260018194] 'agreement among raft nodes before linearized reading' (duration: 165.144494ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:26:25.230542Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023f3.wal"} {"level":"warn","ts":"2026-05-26T21:27:13.554863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744946010112337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T21:27:14.361804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744946010112337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T21:27:14.862508Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744946010112337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T21:27:15.856033Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744946010112337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T21:27:17.072796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.715161408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-26T21:27:17.072934Z","caller":"traceutil/trace.go:171","msg":"trace[230486794] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.715332472s","start":"2026-05-26T21:27:14.357581Z","end":"2026-05-26T21:27:17.072913Z","steps":["trace[230486794] 'agreement among raft nodes before linearized reading' (duration: 2.715182658s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:27:17.072985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:14.35757Z","time spent":"2.715407065s","remote":"127.0.0.1:47744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T21:27:17.072855Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744946010112337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T21:27:18.295448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744946010112337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T21:27:18.296719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:12.608383Z","time spent":"5.688278127s","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-05-26T21:27:18.29983Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"5.691346703s","expected-duration":"1s"} {"level":"info","ts":"2026-05-26T21:27:18.31562Z","caller":"traceutil/trace.go:171","msg":"trace[2118726171] linearizableReadLoop","detail":"{readStateIndex:9661; appliedIndex:9656; }","duration":"5.261597652s","start":"2026-05-26T21:27:13.053985Z","end":"2026-05-26T21:27:18.315583Z","steps":["trace[2118726171] 'read index received' (duration: 5.246172807s)","trace[2118726171] 'applied index is now lower than readState.Index' (duration: 15.423765ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:27:18.315619Z","caller":"traceutil/trace.go:171","msg":"trace[1498099491] transaction","detail":"{read_only:false; response_revision:9334; number_of_response:1; }","duration":"3.953581634s","start":"2026-05-26T21:27:14.361981Z","end":"2026-05-26T21:27:18.315562Z","steps":["trace[1498099491] 'process raft request' (duration: 3.953340777s)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:27:18.315763Z","caller":"traceutil/trace.go:171","msg":"trace[218589671] transaction","detail":"{read_only:false; response_revision:9335; number_of_response:1; }","duration":"3.953734078s","start":"2026-05-26T21:27:14.36202Z","end":"2026-05-26T21:27:18.315754Z","steps":["trace[218589671] 'process raft request' (duration: 3.95343645s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:27:18.315896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:14.362013Z","time spent":"3.95378194s","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:27:18.315902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:14.361969Z","time spent":"3.95379653s","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:27:18.31616Z","caller":"traceutil/trace.go:171","msg":"trace[184792061] transaction","detail":"{read_only:false; response_revision:9336; number_of_response:1; }","duration":"3.587718921s","start":"2026-05-26T21:27:14.728429Z","end":"2026-05-26T21:27:18.316148Z","steps":["trace[184792061] 'process raft request' (duration: 3.587058743s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:27:18.316209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:14.728391Z","time spent":"3.587793514s","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:27:18.3163Z","caller":"traceutil/trace.go:171","msg":"trace[1970465490] transaction","detail":"{read_only:false; response_revision:9337; number_of_response:1; }","duration":"2.454764599s","start":"2026-05-26T21:27:15.861517Z","end":"2026-05-26T21:27:18.316281Z","steps":["trace[1970465490] 'process raft request' (duration: 2.453999168s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:27:18.316346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:15.861494Z","time spent":"2.45482755s","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:27:18.316471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.262492706s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:27:18.316497Z","caller":"traceutil/trace.go:171","msg":"trace[294501093] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:9338; }","duration":"5.262564548s","start":"2026-05-26T21:27:13.053924Z","end":"2026-05-26T21:27:18.316489Z","steps":["trace[294501093] 'agreement among raft nodes before linearized reading' (duration: 5.262513236s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:27:18.316523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:13.053897Z","time spent":"5.26262107s","remote":"127.0.0.1:47320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:27:18.316767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.164842263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T21:27:18.316803Z","caller":"traceutil/trace.go:171","msg":"trace[162938960] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:9338; }","duration":"5.164965307s","start":"2026-05-26T21:27:13.151832Z","end":"2026-05-26T21:27:18.316797Z","steps":["trace[162938960] 'agreement among raft nodes before linearized reading' (duration: 5.164882314s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:27:18.316837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:13.151795Z","time spent":"5.165025308s","remote":"127.0.0.1:48208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":27,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:27:18.317082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.955034765s","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-05-26T21:27:18.317127Z","caller":"traceutil/trace.go:171","msg":"trace[1476838286] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9338; }","duration":"3.955111737s","start":"2026-05-26T21:27:14.362004Z","end":"2026-05-26T21:27:18.317115Z","steps":["trace[1476838286] 'agreement among raft nodes before linearized reading' (duration: 3.955014835s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:27:18.317158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:14.361987Z","time spent":"3.955161088s","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-26T21:27:18.317393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.829236144s","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-05-26T21:27:18.317462Z","caller":"traceutil/trace.go:171","msg":"trace[772561285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9338; }","duration":"4.829358008s","start":"2026-05-26T21:27:13.488084Z","end":"2026-05-26T21:27:18.317442Z","steps":["trace[772561285] 'agreement among raft nodes before linearized reading' (duration: 4.829191193s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:27:18.317498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:13.488053Z","time spent":"4.82943889s","remote":"127.0.0.1:48014","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":"warn","ts":"2026-05-26T21:27:18.317681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.955716194s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-26T21:27:18.317667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.112308163s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:27:18.317705Z","caller":"traceutil/trace.go:171","msg":"trace[1451330133] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9338; }","duration":"3.955750385s","start":"2026-05-26T21:27:14.361948Z","end":"2026-05-26T21:27:18.317698Z","steps":["trace[1451330133] 'agreement among raft nodes before linearized reading' (duration: 3.955697123s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:27:18.317726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:14.361944Z","time spent":"3.955777405s","remote":"127.0.0.1:48014","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":"info","ts":"2026-05-26T21:27:18.317759Z","caller":"traceutil/trace.go:171","msg":"trace[1373356620] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:9338; }","duration":"5.112472517s","start":"2026-05-26T21:27:13.205271Z","end":"2026-05-26T21:27:18.317743Z","steps":["trace[1373356620] 'agreement among raft nodes before linearized reading' (duration: 5.112331053s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:27:18.317797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:13.20523Z","time spent":"5.112559879s","remote":"127.0.0.1:47250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:27:18.317834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.605478928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-26T21:27:18.317868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.80927291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T21:27:18.317896Z","caller":"traceutil/trace.go:171","msg":"trace[40322301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9338; }","duration":"4.605600451s","start":"2026-05-26T21:27:13.712281Z","end":"2026-05-26T21:27:18.317882Z","steps":["trace[40322301] 'agreement among raft nodes before linearized reading' (duration: 4.605452357s)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:27:18.317908Z","caller":"traceutil/trace.go:171","msg":"trace[810635209] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9338; }","duration":"4.809371132s","start":"2026-05-26T21:27:13.508527Z","end":"2026-05-26T21:27:18.317898Z","steps":["trace[810635209] 'agreement among raft nodes before linearized reading' (duration: 4.8092952s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:27:18.317926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:13.712256Z","time spent":"4.605664273s","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-26T21:27:18.317931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:13.508497Z","time spent":"4.809428324s","remote":"127.0.0.1:46888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:27:18.318127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.955872449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:27:18.318147Z","caller":"traceutil/trace.go:171","msg":"trace[1871029517] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:9338; }","duration":"3.95590304s","start":"2026-05-26T21:27:14.362238Z","end":"2026-05-26T21:27:18.318141Z","steps":["trace[1871029517] 'agreement among raft nodes before linearized reading' (duration: 3.955870459s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:27:18.318164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:14.362233Z","time spent":"3.955926831s","remote":"127.0.0.1:47266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:27:18.318229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.956571458s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-lnqbq\" ","response":"range_response_count:1 size:6524"} {"level":"warn","ts":"2026-05-26T21:27:18.318221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.921208317s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:27:18.31825Z","caller":"traceutil/trace.go:171","msg":"trace[688812917] range","detail":"{range_begin:/registry/pods/openstack/keepalived-lnqbq; range_end:; response_count:1; response_revision:9338; }","duration":"3.95660507s","start":"2026-05-26T21:27:14.361637Z","end":"2026-05-26T21:27:18.318242Z","steps":["trace[688812917] 'agreement among raft nodes before linearized reading' (duration: 3.956523378s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:27:18.318249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.59186825s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-05-26T21:27:18.318269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:14.36163Z","time spent":"3.95663543s","remote":"127.0.0.1:47922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-lnqbq\" "} {"level":"info","ts":"2026-05-26T21:27:18.318276Z","caller":"traceutil/trace.go:171","msg":"trace[523014268] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9338; }","duration":"3.591940812s","start":"2026-05-26T21:27:14.726327Z","end":"2026-05-26T21:27:18.318268Z","steps":["trace[523014268] 'agreement among raft nodes before linearized reading' (duration: 3.591840169s)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:27:18.318273Z","caller":"traceutil/trace.go:171","msg":"trace[1810166308] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:9338; }","duration":"2.921308449s","start":"2026-05-26T21:27:15.396948Z","end":"2026-05-26T21:27:18.318257Z","steps":["trace[1810166308] 'agreement among raft nodes before linearized reading' (duration: 2.921218157s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:27:18.318295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:14.726301Z","time spent":"3.591990373s","remote":"127.0.0.1:47922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-26T21:27:18.318308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:15.396923Z","time spent":"2.921378751s","remote":"127.0.0.1:47174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:27:18.318349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.606012094s","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-05-26T21:27:18.318382Z","caller":"traceutil/trace.go:171","msg":"trace[374465565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9338; }","duration":"4.606084765s","start":"2026-05-26T21:27:13.712288Z","end":"2026-05-26T21:27:18.318373Z","steps":["trace[374465565] 'agreement among raft nodes before linearized reading' (duration: 4.606020084s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:27:18.318403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:27:13.71228Z","time spent":"4.606118596s","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-26T21:28:02.557151Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":10003,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T21:28:02.562364Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10003} {"level":"info","ts":"2026-05-26T21:28:02.562445Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5003} {"level":"info","ts":"2026-05-26T21:30:13.905076Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8262} {"level":"info","ts":"2026-05-26T21:30:13.979967Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8262,"took":"72.697785ms","hash":439786241,"current-db-size-bytes":58851328,"current-db-size":"59 MB","current-db-size-in-use-bytes":25960448,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-26T21:30:13.980031Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":439786241,"revision":8262,"compact-revision":6067} {"level":"info","ts":"2026-05-26T21:35:13.914709Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10730} {"level":"info","ts":"2026-05-26T21:35:13.999019Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10730,"took":"80.34191ms","hash":4142781280,"current-db-size-bytes":58851328,"current-db-size":"59 MB","current-db-size-in-use-bytes":27312128,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-26T21:35:13.999092Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4142781280,"revision":10730,"compact-revision":8262} {"level":"info","ts":"2026-05-26T21:37:43.7796Z","caller":"traceutil/trace.go:171","msg":"trace[825258668] transaction","detail":"{read_only:false; response_revision:13867; number_of_response:1; }","duration":"111.749349ms","start":"2026-05-26T21:37:43.667808Z","end":"2026-05-26T21:37:43.779558Z","steps":["trace[825258668] 'process raft request' (duration: 111.556614ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:37:43.779888Z","caller":"traceutil/trace.go:171","msg":"trace[12410533] transaction","detail":"{read_only:false; response_revision:13868; number_of_response:1; }","duration":"111.904252ms","start":"2026-05-26T21:37:43.667954Z","end":"2026-05-26T21:37:43.779858Z","steps":["trace[12410533] 'process raft request' (duration: 111.550953ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:37:44.811129Z","caller":"traceutil/trace.go:171","msg":"trace[1252136359] transaction","detail":"{read_only:false; response_revision:13872; number_of_response:1; }","duration":"123.941172ms","start":"2026-05-26T21:37:44.687162Z","end":"2026-05-26T21:37:44.811103Z","steps":["trace[1252136359] 'process raft request' (duration: 123.788548ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:37:44.818359Z","caller":"traceutil/trace.go:171","msg":"trace[596776600] transaction","detail":"{read_only:false; response_revision:13873; number_of_response:1; }","duration":"124.037806ms","start":"2026-05-26T21:37:44.694289Z","end":"2026-05-26T21:37:44.818327Z","steps":["trace[596776600] 'process raft request' (duration: 123.697407ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:37:44.81875Z","caller":"traceutil/trace.go:171","msg":"trace[2028039717] transaction","detail":"{read_only:false; response_revision:13874; number_of_response:1; }","duration":"123.687206ms","start":"2026-05-26T21:37:44.695033Z","end":"2026-05-26T21:37:44.81872Z","steps":["trace[2028039717] 'process raft request' (duration: 123.092ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:37:53.952471Z","caller":"traceutil/trace.go:171","msg":"trace[1429730117] linearizableReadLoop","detail":"{readStateIndex:14520; appliedIndex:14519; }","duration":"124.19999ms","start":"2026-05-26T21:37:53.828243Z","end":"2026-05-26T21:37:53.952443Z","steps":["trace[1429730117] 'read index received' (duration: 123.999005ms)","trace[1429730117] 'applied index is now lower than readState.Index' (duration: 199.695µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:37:53.95261Z","caller":"traceutil/trace.go:171","msg":"trace[1495741438] transaction","detail":"{read_only:false; response_revision:14043; number_of_response:1; }","duration":"140.332886ms","start":"2026-05-26T21:37:53.812247Z","end":"2026-05-26T21:37:53.95258Z","steps":["trace[1495741438] 'process raft request' (duration: 139.974286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:37:53.952776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.51704ms","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-05-26T21:37:53.952837Z","caller":"traceutil/trace.go:171","msg":"trace[75800795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14043; }","duration":"124.670494ms","start":"2026-05-26T21:37:53.828151Z","end":"2026-05-26T21:37:53.952821Z","steps":["trace[75800795] 'agreement among raft nodes before linearized reading' (duration: 124.470518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:37:53.953075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.109233ms","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-05-26T21:37:53.953213Z","caller":"traceutil/trace.go:171","msg":"trace[1736595630] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14043; }","duration":"115.264259ms","start":"2026-05-26T21:37:53.837919Z","end":"2026-05-26T21:37:53.953183Z","steps":["trace[1736595630] 'agreement among raft nodes before linearized reading' (duration: 114.845586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:37:53.953101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.95837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-26T21:37:53.953357Z","caller":"traceutil/trace.go:171","msg":"trace[580844532] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14043; }","duration":"120.27795ms","start":"2026-05-26T21:37:53.833053Z","end":"2026-05-26T21:37:53.953331Z","steps":["trace[580844532] 'agreement among raft nodes before linearized reading' (duration: 119.706683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:37:54.233963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.457573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:37:54.234148Z","caller":"traceutil/trace.go:171","msg":"trace[1581308416] linearizableReadLoop","detail":"{readStateIndex:14523; appliedIndex:14521; }","duration":"143.357952ms","start":"2026-05-26T21:37:54.090773Z","end":"2026-05-26T21:37:54.234131Z","steps":["trace[1581308416] 'read index received' (duration: 53.881µs)","trace[1581308416] 'applied index is now lower than readState.Index' (duration: 143.302581ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:37:54.234295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.526637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-7p7dz.18b33b4b5a13bf6c\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-05-26T21:37:54.234373Z","caller":"traceutil/trace.go:171","msg":"trace[707574191] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-7p7dz.18b33b4b5a13bf6c; range_end:; response_count:1; response_revision:14046; }","duration":"143.609469ms","start":"2026-05-26T21:37:54.09071Z","end":"2026-05-26T21:37:54.234319Z","steps":["trace[707574191] 'agreement among raft nodes before linearized reading' (duration: 143.477815ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:37:54.234322Z","caller":"traceutil/trace.go:171","msg":"trace[191513472] transaction","detail":"{read_only:false; response_revision:14045; number_of_response:1; }","duration":"276.412823ms","start":"2026-05-26T21:37:53.957871Z","end":"2026-05-26T21:37:54.234284Z","steps":["trace[191513472] 'process raft request' (duration: 131.543128ms)","trace[191513472] 'compare' (duration: 144.31127ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:37:54.234596Z","caller":"traceutil/trace.go:171","msg":"trace[1352995325] transaction","detail":"{read_only:false; response_revision:14046; number_of_response:1; }","duration":"276.489966ms","start":"2026-05-26T21:37:53.958077Z","end":"2026-05-26T21:37:54.234567Z","steps":["trace[1352995325] 'process raft request' (duration: 275.989902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:39:24.8344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.642642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T21:39:24.834709Z","caller":"traceutil/trace.go:171","msg":"trace[1541298556] transaction","detail":"{read_only:false; response_revision:15077; number_of_response:1; }","duration":"368.927949ms","start":"2026-05-26T21:39:24.465747Z","end":"2026-05-26T21:39:24.834675Z","steps":["trace[1541298556] 'process raft request' (duration: 142.944738ms)","trace[1541298556] 'compare' (duration: 225.447146ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:39:24.834731Z","caller":"traceutil/trace.go:171","msg":"trace[1984544350] transaction","detail":"{read_only:false; response_revision:15078; number_of_response:1; }","duration":"300.817129ms","start":"2026-05-26T21:39:24.533892Z","end":"2026-05-26T21:39:24.834709Z","steps":["trace[1984544350] 'process raft request' (duration: 300.606133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:39:24.834859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.640616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-26T21:39:24.834873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:39:24.465726Z","time spent":"369.062454ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:39:24.834893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:39:24.533858Z","time spent":"300.951773ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:39:24.83492Z","caller":"traceutil/trace.go:171","msg":"trace[518585586] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:15078; }","duration":"359.76812ms","start":"2026-05-26T21:39:24.475137Z","end":"2026-05-26T21:39:24.834905Z","steps":["trace[518585586] 'agreement among raft nodes before linearized reading' (duration: 359.631456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:39:24.834972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:39:24.475103Z","time spent":"359.859293ms","remote":"127.0.0.1:44502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"info","ts":"2026-05-26T21:39:24.834823Z","caller":"traceutil/trace.go:171","msg":"trace[719365880] linearizableReadLoop","detail":"{readStateIndex:15587; appliedIndex:15586; }","duration":"359.315457ms","start":"2026-05-26T21:39:24.475209Z","end":"2026-05-26T21:39:24.834525Z","steps":["trace[719365880] 'read index received' (duration: 5.649811ms)","trace[719365880] 'applied index is now lower than readState.Index' (duration: 353.664656ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:39:24.994626Z","caller":"traceutil/trace.go:171","msg":"trace[372266547] linearizableReadLoop","detail":"{readStateIndex:15589; appliedIndex:15588; }","duration":"159.58301ms","start":"2026-05-26T21:39:24.83501Z","end":"2026-05-26T21:39:24.994593Z","steps":["trace[372266547] 'read index received' (duration: 124.63611ms)","trace[372266547] 'applied index is now lower than readState.Index' (duration: 34.94543ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:39:24.994869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.918402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-kjkhz\" ","response":"range_response_count:1 size:20763"} {"level":"warn","ts":"2026-05-26T21:39:24.994915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.283752ms","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-05-26T21:39:24.994958Z","caller":"traceutil/trace.go:171","msg":"trace[1645087943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:15079; }","duration":"242.381264ms","start":"2026-05-26T21:39:24.752561Z","end":"2026-05-26T21:39:24.994942Z","steps":["trace[1645087943] 'agreement among raft nodes before linearized reading' (duration: 242.193849ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:39:24.994954Z","caller":"traceutil/trace.go:171","msg":"trace[1449269548] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-kjkhz; range_end:; response_count:1; response_revision:15079; }","duration":"206.039095ms","start":"2026-05-26T21:39:24.788895Z","end":"2026-05-26T21:39:24.994934Z","steps":["trace[1449269548] 'agreement among raft nodes before linearized reading' (duration: 205.772148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:39:24.995087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.283327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:39:24.995123Z","caller":"traceutil/trace.go:171","msg":"trace[199569992] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15079; }","duration":"448.351238ms","start":"2026-05-26T21:39:24.546763Z","end":"2026-05-26T21:39:24.995114Z","steps":["trace[199569992] 'agreement among raft nodes before linearized reading' (duration: 448.300317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:39:24.995157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:39:24.546744Z","time spent":"448.40557ms","remote":"127.0.0.1:47982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-05-26T21:39:24.995055Z","caller":"traceutil/trace.go:171","msg":"trace[873248386] transaction","detail":"{read_only:false; response_revision:15079; number_of_response:1; }","duration":"357.770692ms","start":"2026-05-26T21:39:24.637171Z","end":"2026-05-26T21:39:24.994942Z","steps":["trace[873248386] 'process raft request' (duration: 322.582756ms)","trace[873248386] 'compare' (duration: 34.532058ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:39:24.995248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.57534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T21:39:24.995318Z","caller":"traceutil/trace.go:171","msg":"trace[1043995767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15079; }","duration":"415.683023ms","start":"2026-05-26T21:39:24.579616Z","end":"2026-05-26T21:39:24.995299Z","steps":["trace[1043995767] 'agreement among raft nodes before linearized reading' (duration: 415.478448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:39:24.995362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:39:24.579601Z","time spent":"415.753665ms","remote":"127.0.0.1:47744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T21:39:24.995374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.961719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-26T21:39:24.995491Z","caller":"traceutil/trace.go:171","msg":"trace[1160610486] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15079; }","duration":"447.140335ms","start":"2026-05-26T21:39:24.548323Z","end":"2026-05-26T21:39:24.995463Z","steps":["trace[1160610486] 'agreement among raft nodes before linearized reading' (duration: 446.802975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:39:24.995547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:39:24.548297Z","time spent":"447.241128ms","remote":"127.0.0.1:47982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5545,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-05-26T21:39:24.995538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.918516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-05-26T21:39:24.995489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.857571ms","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-05-26T21:39:24.995613Z","caller":"traceutil/trace.go:171","msg":"trace[46292441] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15079; }","duration":"448.053561ms","start":"2026-05-26T21:39:24.547541Z","end":"2026-05-26T21:39:24.995594Z","steps":["trace[46292441] 'agreement among raft nodes before linearized reading' (duration: 447.391381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:39:24.995685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:39:24.547516Z","time spent":"448.160983ms","remote":"127.0.0.1:47898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-05-26T21:39:24.99553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:39:24.637146Z","time spent":"358.132463ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:39:24.995909Z","caller":"traceutil/trace.go:171","msg":"trace[1625547686] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:15079; }","duration":"194.073287ms","start":"2026-05-26T21:39:24.801571Z","end":"2026-05-26T21:39:24.995645Z","steps":["trace[1625547686] 'agreement among raft nodes before linearized reading' (duration: 193.48638ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:40:13.920671Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12838} {"level":"info","ts":"2026-05-26T21:40:13.9959Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12838,"took":"73.102341ms","hash":3614297283,"current-db-size-bytes":58851328,"current-db-size":"59 MB","current-db-size-in-use-bytes":30674944,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-26T21:40:13.995975Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3614297283,"revision":12838,"compact-revision":10730} {"level":"info","ts":"2026-05-26T21:45:13.927204Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15383} {"level":"info","ts":"2026-05-26T21:45:14.026491Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15383,"took":"95.642704ms","hash":1443088299,"current-db-size-bytes":58851328,"current-db-size":"59 MB","current-db-size-in-use-bytes":31952896,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-26T21:45:14.026597Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1443088299,"revision":15383,"compact-revision":12838} {"level":"info","ts":"2026-05-26T21:45:18.208066Z","caller":"traceutil/trace.go:171","msg":"trace[931722082] transaction","detail":"{read_only:false; response_revision:17722; number_of_response:1; }","duration":"271.452536ms","start":"2026-05-26T21:45:17.936577Z","end":"2026-05-26T21:45:18.20803Z","steps":["trace[931722082] 'process raft request' (duration: 271.215369ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:48:42.248747Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":20004,"local-member-snapshot-index":10003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T21:48:42.253778Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20004} {"level":"info","ts":"2026-05-26T21:48:42.253866Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15004} {"level":"info","ts":"2026-05-26T21:50:13.937772Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17689} {"level":"info","ts":"2026-05-26T21:50:14.040992Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17689,"took":"98.082209ms","hash":1125948432,"current-db-size-bytes":58851328,"current-db-size":"59 MB","current-db-size-in-use-bytes":34033664,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-26T21:50:14.041079Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1125948432,"revision":17689,"compact-revision":15383} {"level":"info","ts":"2026-05-26T21:50:51.623333Z","caller":"traceutil/trace.go:171","msg":"trace[1458230701] transaction","detail":"{read_only:false; response_revision:20252; number_of_response:1; }","duration":"119.441114ms","start":"2026-05-26T21:50:51.503857Z","end":"2026-05-26T21:50:51.623298Z","steps":["trace[1458230701] 'process raft request' (duration: 119.196387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:50:57.036502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.079413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-26T21:50:57.036635Z","caller":"traceutil/trace.go:171","msg":"trace[1337918291] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:20278; }","duration":"279.251687ms","start":"2026-05-26T21:50:56.75736Z","end":"2026-05-26T21:50:57.036611Z","steps":["trace[1337918291] 'count revisions from in-memory index tree' (duration: 278.99662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:50:57.036767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.632402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T21:50:57.036876Z","caller":"traceutil/trace.go:171","msg":"trace[1666958150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20278; }","duration":"258.787226ms","start":"2026-05-26T21:50:56.778062Z","end":"2026-05-26T21:50:57.036849Z","steps":["trace[1666958150] 'range keys from in-memory index tree' (duration: 258.352284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:50:57.330865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.8878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-26T21:50:57.330996Z","caller":"traceutil/trace.go:171","msg":"trace[326404536] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:20279; }","duration":"172.101386ms","start":"2026-05-26T21:50:57.158861Z","end":"2026-05-26T21:50:57.330963Z","steps":["trace[326404536] 'range keys from in-memory index tree' (duration: 171.569261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:50:57.330952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.895873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-26T21:50:57.331097Z","caller":"traceutil/trace.go:171","msg":"trace[333546169] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20279; }","duration":"151.106739ms","start":"2026-05-26T21:50:57.179965Z","end":"2026-05-26T21:50:57.331072Z","steps":["trace[333546169] 'range keys from in-memory index tree' (duration: 150.680058ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:50:57.55511Z","caller":"traceutil/trace.go:171","msg":"trace[804077634] transaction","detail":"{read_only:false; response_revision:20281; number_of_response:1; }","duration":"217.198425ms","start":"2026-05-26T21:50:57.337889Z","end":"2026-05-26T21:50:57.555087Z","steps":["trace[804077634] 'process raft request' (duration: 217.055901ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:50:57.946951Z","caller":"traceutil/trace.go:171","msg":"trace[2113650761] transaction","detail":"{read_only:false; response_revision:20283; number_of_response:1; }","duration":"286.397448ms","start":"2026-05-26T21:50:57.66052Z","end":"2026-05-26T21:50:57.946918Z","steps":["trace[2113650761] 'process raft request' (duration: 285.856002ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:50:58.130929Z","caller":"traceutil/trace.go:171","msg":"trace[628914243] linearizableReadLoop","detail":"{readStateIndex:20969; appliedIndex:20968; }","duration":"141.413628ms","start":"2026-05-26T21:50:57.989487Z","end":"2026-05-26T21:50:58.1309Z","steps":["trace[628914243] 'read index received' (duration: 114.843896ms)","trace[628914243] 'applied index is now lower than readState.Index' (duration: 26.568762ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:50:58.131042Z","caller":"traceutil/trace.go:171","msg":"trace[342838297] transaction","detail":"{read_only:false; response_revision:20284; number_of_response:1; }","duration":"148.385034ms","start":"2026-05-26T21:50:57.982645Z","end":"2026-05-26T21:50:58.13103Z","steps":["trace[342838297] 'process raft request' (duration: 121.668848ms)","trace[342838297] 'compare' (duration: 26.065888ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:50:58.131462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.947773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-26T21:50:58.131508Z","caller":"traceutil/trace.go:171","msg":"trace[1207484138] transaction","detail":"{read_only:false; response_revision:20285; number_of_response:1; }","duration":"138.474516ms","start":"2026-05-26T21:50:57.992955Z","end":"2026-05-26T21:50:58.13143Z","steps":["trace[1207484138] 'process raft request' (duration: 137.812197ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:50:58.13156Z","caller":"traceutil/trace.go:171","msg":"trace[566048738] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20285; }","duration":"142.100747ms","start":"2026-05-26T21:50:57.989437Z","end":"2026-05-26T21:50:58.131538Z","steps":["trace[566048738] 'agreement among raft nodes before linearized reading' (duration: 141.755077ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:50:58.131548Z","caller":"traceutil/trace.go:171","msg":"trace[72440665] transaction","detail":"{read_only:false; response_revision:20286; number_of_response:1; }","duration":"136.265644ms","start":"2026-05-26T21:50:57.995237Z","end":"2026-05-26T21:50:58.131503Z","steps":["trace[72440665] 'process raft request' (duration: 136.059698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:50:58.131926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.347953ms","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-05-26T21:50:58.13207Z","caller":"traceutil/trace.go:171","msg":"trace[2118002542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20286; }","duration":"119.540028ms","start":"2026-05-26T21:50:58.012507Z","end":"2026-05-26T21:50:58.132047Z","steps":["trace[2118002542] 'agreement among raft nodes before linearized reading' (duration: 118.966981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:54:49.532171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.570833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:54:49.532308Z","caller":"traceutil/trace.go:171","msg":"trace[1057094379] linearizableReadLoop","detail":"{readStateIndex:23419; appliedIndex:23418; }","duration":"120.280919ms","start":"2026-05-26T21:54:49.412007Z","end":"2026-05-26T21:54:49.532288Z","steps":["trace[1057094379] 'read index received' (duration: 46.731µs)","trace[1057094379] 'applied index is now lower than readState.Index' (duration: 120.232638ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:54:49.532468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.469343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-26T21:54:49.532501Z","caller":"traceutil/trace.go:171","msg":"trace[760185588] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22660; }","duration":"120.538245ms","start":"2026-05-26T21:54:49.411954Z","end":"2026-05-26T21:54:49.532492Z","steps":["trace[760185588] 'agreement among raft nodes before linearized reading' (duration: 120.385281ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:54:49.532611Z","caller":"traceutil/trace.go:171","msg":"trace[1625152168] transaction","detail":"{read_only:false; response_revision:22660; number_of_response:1; }","duration":"282.886618ms","start":"2026-05-26T21:54:49.249683Z","end":"2026-05-26T21:54:49.532569Z","steps":["trace[1625152168] 'process raft request' (duration: 111.804122ms)","trace[1625152168] 'compare' (duration: 169.920373ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:54:49.82998Z","caller":"traceutil/trace.go:171","msg":"trace[1718321405] transaction","detail":"{read_only:false; response_revision:22662; number_of_response:1; }","duration":"204.11106ms","start":"2026-05-26T21:54:49.625812Z","end":"2026-05-26T21:54:49.829923Z","steps":["trace[1718321405] 'process raft request' (duration: 162.325293ms)","trace[1718321405] 'compare' (duration: 41.509349ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:54:49.83089Z","caller":"traceutil/trace.go:171","msg":"trace[1619239343] transaction","detail":"{read_only:false; response_revision:22664; number_of_response:1; }","duration":"202.890684ms","start":"2026-05-26T21:54:49.627965Z","end":"2026-05-26T21:54:49.830856Z","steps":["trace[1619239343] 'process raft request' (duration: 202.74795ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:54:49.830996Z","caller":"traceutil/trace.go:171","msg":"trace[921689389] transaction","detail":"{read_only:false; response_revision:22663; number_of_response:1; }","duration":"205.119197ms","start":"2026-05-26T21:54:49.625825Z","end":"2026-05-26T21:54:49.830944Z","steps":["trace[921689389] 'process raft request' (duration: 204.020166ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:55:13.944652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20060} {"level":"info","ts":"2026-05-26T21:55:14.054617Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20060,"took":"106.165015ms","hash":34944221,"current-db-size-bytes":58851328,"current-db-size":"59 MB","current-db-size-in-use-bytes":40640512,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-05-26T21:55:14.054683Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":34944221,"revision":20060,"compact-revision":17689} {"level":"info","ts":"2026-05-26T21:58:26.152Z","caller":"traceutil/trace.go:171","msg":"trace[1907533298] transaction","detail":"{read_only:false; response_revision:24850; number_of_response:1; }","duration":"117.57658ms","start":"2026-05-26T21:58:26.034125Z","end":"2026-05-26T21:58:26.151702Z","steps":["trace[1907533298] 'process raft request' (duration: 117.398385ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:26.980291Z","caller":"traceutil/trace.go:171","msg":"trace[526558071] transaction","detail":"{read_only:false; response_revision:24857; number_of_response:1; }","duration":"198.30532ms","start":"2026-05-26T21:58:26.781953Z","end":"2026-05-26T21:58:26.980258Z","steps":["trace[526558071] 'process raft request' (duration: 198.114554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:27.209561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.035008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:27.209666Z","caller":"traceutil/trace.go:171","msg":"trace[1231478547] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:24857; }","duration":"177.187123ms","start":"2026-05-26T21:58:27.032454Z","end":"2026-05-26T21:58:27.209641Z","steps":["trace[1231478547] 'count revisions from in-memory index tree' (duration: 176.950506ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:27.246791Z","caller":"traceutil/trace.go:171","msg":"trace[1156152697] linearizableReadLoop","detail":"{readStateIndex:25674; appliedIndex:25673; }","duration":"147.827884ms","start":"2026-05-26T21:58:27.098934Z","end":"2026-05-26T21:58:27.246762Z","steps":["trace[1156152697] 'read index received' (duration: 147.568357ms)","trace[1156152697] 'applied index is now lower than readState.Index' (duration: 258.037µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:27.24688Z","caller":"traceutil/trace.go:171","msg":"trace[736955075] transaction","detail":"{read_only:false; response_revision:24858; number_of_response:1; }","duration":"148.947256ms","start":"2026-05-26T21:58:27.097889Z","end":"2026-05-26T21:58:27.246837Z","steps":["trace[736955075] 'process raft request' (duration: 148.699859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:27.246985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.641749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-26T21:58:27.247033Z","caller":"traceutil/trace.go:171","msg":"trace[985271029] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:24858; }","duration":"141.705811ms","start":"2026-05-26T21:58:27.105313Z","end":"2026-05-26T21:58:27.247019Z","steps":["trace[985271029] 'agreement among raft nodes before linearized reading' (duration: 141.606288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:27.247077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.124462ms","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-05-26T21:58:27.247173Z","caller":"traceutil/trace.go:171","msg":"trace[1061384070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24858; }","duration":"148.274696ms","start":"2026-05-26T21:58:27.098877Z","end":"2026-05-26T21:58:27.247151Z","steps":["trace[1061384070] 'agreement among raft nodes before linearized reading' (duration: 148.036319ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:27.653118Z","caller":"traceutil/trace.go:171","msg":"trace[1573924825] transaction","detail":"{read_only:false; response_revision:24859; number_of_response:1; }","duration":"401.580489ms","start":"2026-05-26T21:58:27.251484Z","end":"2026-05-26T21:58:27.653065Z","steps":["trace[1573924825] 'process raft request' (duration: 399.225464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:27.653403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:27.251468Z","time spent":"401.788135ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:58:27.653409Z","caller":"traceutil/trace.go:171","msg":"trace[808176572] transaction","detail":"{read_only:false; response_revision:24860; number_of_response:1; }","duration":"401.390173ms","start":"2026-05-26T21:58:27.251962Z","end":"2026-05-26T21:58:27.653352Z","steps":["trace[808176572] 'process raft request' (duration: 400.982062ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:27.65349Z","caller":"traceutil/trace.go:171","msg":"trace[252813726] transaction","detail":"{read_only:false; response_revision:24861; number_of_response:1; }","duration":"236.330983ms","start":"2026-05-26T21:58:27.417102Z","end":"2026-05-26T21:58:27.653433Z","steps":["trace[252813726] 'process raft request' (duration: 236.175688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:27.653582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:27.251928Z","time spent":"401.566189ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:58:31.938573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.151506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:31.938699Z","caller":"traceutil/trace.go:171","msg":"trace[828607593] linearizableReadLoop","detail":"{readStateIndex:25707; appliedIndex:25706; }","duration":"247.979021ms","start":"2026-05-26T21:58:31.6907Z","end":"2026-05-26T21:58:31.938679Z","steps":["trace[828607593] 'read index received' (duration: 50.544397ms)","trace[828607593] 'applied index is now lower than readState.Index' (duration: 197.433504ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:31.938844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.149067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T21:58:31.938879Z","caller":"traceutil/trace.go:171","msg":"trace[2002586820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24890; }","duration":"248.184538ms","start":"2026-05-26T21:58:31.690685Z","end":"2026-05-26T21:58:31.938869Z","steps":["trace[2002586820] 'agreement among raft nodes before linearized reading' (duration: 248.044814ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:31.93892Z","caller":"traceutil/trace.go:171","msg":"trace[1728954516] transaction","detail":"{read_only:false; response_revision:24890; number_of_response:1; }","duration":"266.597288ms","start":"2026-05-26T21:58:31.672282Z","end":"2026-05-26T21:58:31.938879Z","steps":["trace[1728954516] 'process raft request' (duration: 69.03844ms)","trace[1728954516] 'compare' (duration: 197.032833ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:31.951607Z","caller":"traceutil/trace.go:171","msg":"trace[1353292586] transaction","detail":"{read_only:false; response_revision:24891; number_of_response:1; }","duration":"231.391804ms","start":"2026-05-26T21:58:31.720192Z","end":"2026-05-26T21:58:31.951583Z","steps":["trace[1353292586] 'process raft request' (duration: 231.271661ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:31.959764Z","caller":"traceutil/trace.go:171","msg":"trace[658179847] transaction","detail":"{read_only:false; response_revision:24892; number_of_response:1; }","duration":"205.76133ms","start":"2026-05-26T21:58:31.753984Z","end":"2026-05-26T21:58:31.959745Z","steps":["trace[658179847] 'process raft request' (duration: 205.522554ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:32.116373Z","caller":"traceutil/trace.go:171","msg":"trace[135907046] transaction","detail":"{read_only:false; response_revision:24895; number_of_response:1; }","duration":"101.941349ms","start":"2026-05-26T21:58:32.0144Z","end":"2026-05-26T21:58:32.116341Z","steps":["trace[135907046] 'process raft request' (duration: 101.741763ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:32.581721Z","caller":"traceutil/trace.go:171","msg":"trace[418314769] transaction","detail":"{read_only:false; response_revision:24896; number_of_response:1; }","duration":"408.086653ms","start":"2026-05-26T21:58:32.173606Z","end":"2026-05-26T21:58:32.581693Z","steps":["trace[418314769] 'process raft request' (duration: 407.920389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.5819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:32.17358Z","time spent":"408.235218ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:58:32.624905Z","caller":"traceutil/trace.go:171","msg":"trace[562716754] linearizableReadLoop","detail":"{readStateIndex:25714; appliedIndex:25712; }","duration":"348.934492ms","start":"2026-05-26T21:58:32.275944Z","end":"2026-05-26T21:58:32.624879Z","steps":["trace[562716754] 'read index received' (duration: 305.516857ms)","trace[562716754] 'applied index is now lower than readState.Index' (duration: 43.416335ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:32.625036Z","caller":"traceutil/trace.go:171","msg":"trace[127027817] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"382.456988ms","start":"2026-05-26T21:58:32.242569Z","end":"2026-05-26T21:58:32.625026Z","steps":["trace[127027817] 'process raft request' (duration: 382.15829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.625158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:32.242542Z","time spent":"382.52245ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:58:32.625326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.390395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-26T21:58:32.625356Z","caller":"traceutil/trace.go:171","msg":"trace[638768097] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24897; }","duration":"349.460936ms","start":"2026-05-26T21:58:32.275885Z","end":"2026-05-26T21:58:32.625346Z","steps":["trace[638768097] 'agreement among raft nodes before linearized reading' (duration: 349.354043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.625383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:32.275862Z","time spent":"349.515518ms","remote":"127.0.0.1:48014","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-05-26T21:58:32.625507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.270219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-26T21:58:32.62553Z","caller":"traceutil/trace.go:171","msg":"trace[963021434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24897; }","duration":"254.33418ms","start":"2026-05-26T21:58:32.37119Z","end":"2026-05-26T21:58:32.625524Z","steps":["trace[963021434] 'agreement among raft nodes before linearized reading' (duration: 254.270709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:32.927841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.788625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:32.927975Z","caller":"traceutil/trace.go:171","msg":"trace[1575542912] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"296.610855ms","start":"2026-05-26T21:58:32.631339Z","end":"2026-05-26T21:58:32.92795Z","steps":["trace[1575542912] 'process raft request' (duration: 149.529962ms)","trace[1575542912] 'compare' (duration: 146.26508ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:32.93016Z","caller":"traceutil/trace.go:171","msg":"trace[599130134] transaction","detail":"{read_only:false; response_revision:24899; number_of_response:1; }","duration":"298.719345ms","start":"2026-05-26T21:58:32.631411Z","end":"2026-05-26T21:58:32.93013Z","steps":["trace[599130134] 'process raft request' (duration: 298.53541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:33.116447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.335332ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-26T21:58:33.116835Z","caller":"traceutil/trace.go:171","msg":"trace[851164948] linearizableReadLoop","detail":"{readStateIndex:25717; appliedIndex:25714; }","duration":"437.630366ms","start":"2026-05-26T21:58:32.679184Z","end":"2026-05-26T21:58:33.116815Z","steps":["trace[851164948] 'read index received' (duration: 101.697291ms)","trace[851164948] 'applied index is now lower than readState.Index' (duration: 335.931985ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:33.116923Z","caller":"traceutil/trace.go:171","msg":"trace[1662815307] transaction","detail":"{read_only:false; response_revision:24900; number_of_response:1; }","duration":"406.604781ms","start":"2026-05-26T21:58:32.71027Z","end":"2026-05-26T21:58:33.116875Z","steps":["trace[1662815307] 'process raft request' (duration: 406.345304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:33.116994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.425139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:33.117035Z","caller":"traceutil/trace.go:171","msg":"trace[1149464270] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:24900; }","duration":"303.523891ms","start":"2026-05-26T21:58:32.813497Z","end":"2026-05-26T21:58:33.11702Z","steps":["trace[1149464270] 'agreement among raft nodes before linearized reading' (duration: 303.441279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:33.117023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.825073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-05-26T21:58:33.117168Z","caller":"traceutil/trace.go:171","msg":"trace[338354685] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:24900; }","duration":"438.004888ms","start":"2026-05-26T21:58:32.679114Z","end":"2026-05-26T21:58:33.117119Z","steps":["trace[338354685] 'agreement among raft nodes before linearized reading' (duration: 437.764191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:33.11719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:32.71024Z","time spent":"406.781416ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:58:33.117235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:32.67909Z","time spent":"438.131982ms","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":1588,"request content":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" "} {"level":"warn","ts":"2026-05-26T21:58:33.117269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.714899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-05-26T21:58:33.117083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:32.813459Z","time spent":"303.613124ms","remote":"127.0.0.1:41074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-05-26T21:58:33.117407Z","caller":"traceutil/trace.go:171","msg":"trace[1647384552] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24900; }","duration":"114.903004ms","start":"2026-05-26T21:58:33.002471Z","end":"2026-05-26T21:58:33.117374Z","steps":["trace[1647384552] 'agreement among raft nodes before linearized reading' (duration: 114.565055ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:33.348894Z","caller":"traceutil/trace.go:171","msg":"trace[670191439] linearizableReadLoop","detail":"{readStateIndex:25719; appliedIndex:25718; }","duration":"144.803569ms","start":"2026-05-26T21:58:33.204065Z","end":"2026-05-26T21:58:33.348868Z","steps":["trace[670191439] 'read index received' (duration: 144.649735ms)","trace[670191439] 'applied index is now lower than readState.Index' (duration: 152.964µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:33.348943Z","caller":"traceutil/trace.go:171","msg":"trace[347749347] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"226.353072ms","start":"2026-05-26T21:58:33.122549Z","end":"2026-05-26T21:58:33.348902Z","steps":["trace[347749347] 'process raft request' (duration: 226.179847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:33.349082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.008794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:186"} {"level":"info","ts":"2026-05-26T21:58:33.349109Z","caller":"traceutil/trace.go:171","msg":"trace[500504477] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:24901; }","duration":"145.092306ms","start":"2026-05-26T21:58:33.204009Z","end":"2026-05-26T21:58:33.349101Z","steps":["trace[500504477] 'agreement among raft nodes before linearized reading' (duration: 144.981573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:33.685079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.140706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:33.685168Z","caller":"traceutil/trace.go:171","msg":"trace[687749277] linearizableReadLoop","detail":"{readStateIndex:25720; appliedIndex:25719; }","duration":"336.186463ms","start":"2026-05-26T21:58:33.348969Z","end":"2026-05-26T21:58:33.685155Z","steps":["trace[687749277] 'read index received' (duration: 183.922323ms)","trace[687749277] 'applied index is now lower than readState.Index' (duration: 152.26124ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:33.685231Z","caller":"traceutil/trace.go:171","msg":"trace[343596758] transaction","detail":"{read_only:false; response_revision:24902; number_of_response:1; }","duration":"416.169902ms","start":"2026-05-26T21:58:33.269055Z","end":"2026-05-26T21:58:33.685225Z","steps":["trace[343596758] 'process raft request' (duration: 263.811479ms)","trace[343596758] 'compare' (duration: 152.047574ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:33.685264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:33.269029Z","time spent":"416.218353ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:58:33.685424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.333302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-26T21:58:33.685442Z","caller":"traceutil/trace.go:171","msg":"trace[581336956] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24902; }","duration":"350.391734ms","start":"2026-05-26T21:58:33.335046Z","end":"2026-05-26T21:58:33.685438Z","steps":["trace[581336956] 'agreement among raft nodes before linearized reading' (duration: 350.307471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:33.685456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:33.335023Z","time spent":"350.430365ms","remote":"127.0.0.1:47922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-26T21:58:33.685586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.266037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-j72wd\" ","response":"range_response_count:1 size:4483"} {"level":"info","ts":"2026-05-26T21:58:33.685805Z","caller":"traceutil/trace.go:171","msg":"trace[369812905] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-j72wd; range_end:; response_count:1; response_revision:24902; }","duration":"335.506154ms","start":"2026-05-26T21:58:33.350279Z","end":"2026-05-26T21:58:33.685785Z","steps":["trace[369812905] 'agreement among raft nodes before linearized reading' (duration: 335.142594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:33.685786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.301042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-26T21:58:33.685872Z","caller":"traceutil/trace.go:171","msg":"trace[771508634] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:24902; }","duration":"474.459088ms","start":"2026-05-26T21:58:33.211394Z","end":"2026-05-26T21:58:33.685853Z","steps":["trace[771508634] 'agreement among raft nodes before linearized reading' (duration: 474.289383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:33.685842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.459256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-26T21:58:33.685883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:33.350266Z","time spent":"335.571236ms","remote":"127.0.0.1:47922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4505,"request content":"key:\"/registry/pods/kube-system/kube-proxy-j72wd\" "} {"level":"warn","ts":"2026-05-26T21:58:33.685909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:33.211365Z","time spent":"474.53355ms","remote":"127.0.0.1:32876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":23,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"info","ts":"2026-05-26T21:58:33.685922Z","caller":"traceutil/trace.go:171","msg":"trace[1431336149] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24902; }","duration":"112.606919ms","start":"2026-05-26T21:58:33.573296Z","end":"2026-05-26T21:58:33.685903Z","steps":["trace[1431336149] 'agreement among raft nodes before linearized reading' (duration: 112.416934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:34.028044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.860949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-05-26T21:58:34.028185Z","caller":"traceutil/trace.go:171","msg":"trace[623604658] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24903; }","duration":"242.055725ms","start":"2026-05-26T21:58:33.786099Z","end":"2026-05-26T21:58:34.028155Z","steps":["trace[623604658] 'range keys from in-memory index tree' (duration: 241.605832ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:34.121508Z","caller":"traceutil/trace.go:171","msg":"trace[615598746] transaction","detail":"{read_only:false; response_revision:24904; number_of_response:1; }","duration":"156.349494ms","start":"2026-05-26T21:58:33.965124Z","end":"2026-05-26T21:58:34.121474Z","steps":["trace[615598746] 'process raft request' (duration: 156.176559ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:34.188227Z","caller":"traceutil/trace.go:171","msg":"trace[1373474325] transaction","detail":"{read_only:false; response_revision:24905; number_of_response:1; }","duration":"222.845062ms","start":"2026-05-26T21:58:33.965356Z","end":"2026-05-26T21:58:34.188201Z","steps":["trace[1373474325] 'process raft request' (duration: 222.595925ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:34.188708Z","caller":"traceutil/trace.go:171","msg":"trace[564982635] transaction","detail":"{read_only:false; response_revision:24906; number_of_response:1; }","duration":"156.420126ms","start":"2026-05-26T21:58:34.032255Z","end":"2026-05-26T21:58:34.188675Z","steps":["trace[564982635] 'process raft request' (duration: 155.905721ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:34.188956Z","caller":"traceutil/trace.go:171","msg":"trace[1974656848] transaction","detail":"{read_only:false; response_revision:24907; number_of_response:1; }","duration":"156.245281ms","start":"2026-05-26T21:58:34.032676Z","end":"2026-05-26T21:58:34.188921Z","steps":["trace[1974656848] 'process raft request' (duration: 155.867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:34.457617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.576513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:34.457747Z","caller":"traceutil/trace.go:171","msg":"trace[1158994135] linearizableReadLoop","detail":"{readStateIndex:25726; appliedIndex:25725; }","duration":"268.877322ms","start":"2026-05-26T21:58:34.188847Z","end":"2026-05-26T21:58:34.457724Z","steps":["trace[1158994135] 'read index received' (duration: 153.125133ms)","trace[1158994135] 'applied index is now lower than readState.Index' (duration: 115.750369ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:34.457811Z","caller":"traceutil/trace.go:171","msg":"trace[534125174] transaction","detail":"{read_only:false; response_revision:24908; number_of_response:1; }","duration":"335.912135ms","start":"2026-05-26T21:58:34.121868Z","end":"2026-05-26T21:58:34.45778Z","steps":["trace[534125174] 'process raft request' (duration: 220.055454ms)","trace[534125174] 'compare' (duration: 115.418779ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:34.45797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:34.121842Z","time spent":"336.04859ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:58:34.457846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.44513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:34.458135Z","caller":"traceutil/trace.go:171","msg":"trace[615954585] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24908; }","duration":"279.780201ms","start":"2026-05-26T21:58:34.17834Z","end":"2026-05-26T21:58:34.45812Z","steps":["trace[615954585] 'agreement among raft nodes before linearized reading' (duration: 279.445091ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:35.153137Z","caller":"traceutil/trace.go:171","msg":"trace[128648992] transaction","detail":"{read_only:false; response_revision:24911; number_of_response:1; }","duration":"126.664057ms","start":"2026-05-26T21:58:35.026447Z","end":"2026-05-26T21:58:35.153111Z","steps":["trace[128648992] 'process raft request' (duration: 126.489112ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:35.49234Z","caller":"traceutil/trace.go:171","msg":"trace[1201401152] transaction","detail":"{read_only:false; response_revision:24912; number_of_response:1; }","duration":"465.842075ms","start":"2026-05-26T21:58:35.026468Z","end":"2026-05-26T21:58:35.49231Z","steps":["trace[1201401152] 'process raft request' (duration: 465.542386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:35.492544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:35.026443Z","time spent":"465.990818ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:58:35.492842Z","caller":"traceutil/trace.go:171","msg":"trace[1208269674] transaction","detail":"{read_only:false; response_revision:24913; number_of_response:1; }","duration":"466.245775ms","start":"2026-05-26T21:58:35.026558Z","end":"2026-05-26T21:58:35.492804Z","steps":["trace[1208269674] 'process raft request' (duration: 465.69689ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:35.492892Z","caller":"traceutil/trace.go:171","msg":"trace[1118003626] linearizableReadLoop","detail":"{readStateIndex:25732; appliedIndex:25728; }","duration":"411.387576ms","start":"2026-05-26T21:58:35.081472Z","end":"2026-05-26T21:58:35.492859Z","steps":["trace[1118003626] 'read index received' (duration: 71.498179ms)","trace[1118003626] 'applied index is now lower than readState.Index' (duration: 339.888287ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:35.493033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:35.02655Z","time spent":"466.386099ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:58:35.493047Z","caller":"traceutil/trace.go:171","msg":"trace[1576846380] transaction","detail":"{read_only:false; response_revision:24915; number_of_response:1; }","duration":"369.542655ms","start":"2026-05-26T21:58:35.12349Z","end":"2026-05-26T21:58:35.493033Z","steps":["trace[1576846380] 'process raft request' (duration: 369.308228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:35.493127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:35.123461Z","time spent":"369.623686ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:58:35.49314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.634663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:35.49304Z","caller":"traceutil/trace.go:171","msg":"trace[1573600391] transaction","detail":"{read_only:false; response_revision:24914; number_of_response:1; }","duration":"466.368058ms","start":"2026-05-26T21:58:35.026655Z","end":"2026-05-26T21:58:35.493023Z","steps":["trace[1573600391] 'process raft request' (duration: 465.947937ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:35.493182Z","caller":"traceutil/trace.go:171","msg":"trace[2124133427] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:24915; }","duration":"411.741546ms","start":"2026-05-26T21:58:35.081428Z","end":"2026-05-26T21:58:35.493169Z","steps":["trace[2124133427] 'agreement among raft nodes before linearized reading' (duration: 411.639483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:35.493229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:35.081402Z","time spent":"411.815738ms","remote":"127.0.0.1:44126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:58:35.493318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:35.026643Z","time spent":"466.575945ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:58:35.677062Z","caller":"traceutil/trace.go:171","msg":"trace[2031557056] linearizableReadLoop","detail":"{readStateIndex:25734; appliedIndex:25733; }","duration":"184.051668ms","start":"2026-05-26T21:58:35.492982Z","end":"2026-05-26T21:58:35.677033Z","steps":["trace[2031557056] 'read index received' (duration: 143.351308ms)","trace[2031557056] 'applied index is now lower than readState.Index' (duration: 40.69921ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:35.67725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.284035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:35.677282Z","caller":"traceutil/trace.go:171","msg":"trace[925656712] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:24916; }","duration":"541.345726ms","start":"2026-05-26T21:58:35.135923Z","end":"2026-05-26T21:58:35.677268Z","steps":["trace[925656712] 'agreement among raft nodes before linearized reading' (duration: 541.249434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:35.677271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.525595ms","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-05-26T21:58:35.677265Z","caller":"traceutil/trace.go:171","msg":"trace[1993183018] transaction","detail":"{read_only:false; response_revision:24916; number_of_response:1; }","duration":"314.347497ms","start":"2026-05-26T21:58:35.36286Z","end":"2026-05-26T21:58:35.677207Z","steps":["trace[1993183018] 'process raft request' (duration: 273.478462ms)","trace[1993183018] 'compare' (duration: 40.532965ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:35.677506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:35.362835Z","time spent":"314.545013ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:58:35.677368Z","caller":"traceutil/trace.go:171","msg":"trace[877740066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24916; }","duration":"323.686931ms","start":"2026-05-26T21:58:35.353656Z","end":"2026-05-26T21:58:35.677343Z","steps":["trace[877740066] 'agreement among raft nodes before linearized reading' (duration: 323.420443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:35.677315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:35.135892Z","time spent":"541.414529ms","remote":"127.0.0.1:47254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:58:35.677641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:35.353635Z","time spent":"323.949137ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-26T21:58:35.67927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.327809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-26T21:58:35.679375Z","caller":"traceutil/trace.go:171","msg":"trace[987713739] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:24916; }","duration":"488.484922ms","start":"2026-05-26T21:58:35.190864Z","end":"2026-05-26T21:58:35.679349Z","steps":["trace[987713739] 'agreement among raft nodes before linearized reading' (duration: 486.270671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:35.679426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:35.190849Z","time spent":"488.566295ms","remote":"127.0.0.1:47808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2636,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-05-26T21:58:35.803193Z","caller":"traceutil/trace.go:171","msg":"trace[1682149986] transaction","detail":"{read_only:false; response_revision:24917; number_of_response:1; }","duration":"120.856282ms","start":"2026-05-26T21:58:35.682299Z","end":"2026-05-26T21:58:35.803156Z","steps":["trace[1682149986] 'process raft request' (duration: 112.333021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:36.196603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.16382ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:36.196715Z","caller":"traceutil/trace.go:171","msg":"trace[998045406] linearizableReadLoop","detail":"{readStateIndex:25736; appliedIndex:25734; }","duration":"491.250461ms","start":"2026-05-26T21:58:35.705445Z","end":"2026-05-26T21:58:36.196696Z","steps":["trace[998045406] 'read index received' (duration: 89.203269ms)","trace[998045406] 'applied index is now lower than readState.Index' (duration: 402.045672ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:36.19717Z","caller":"traceutil/trace.go:171","msg":"trace[2005718537] transaction","detail":"{read_only:false; response_revision:24918; number_of_response:1; }","duration":"505.74651ms","start":"2026-05-26T21:58:35.691389Z","end":"2026-05-26T21:58:36.197135Z","steps":["trace[2005718537] 'process raft request' (duration: 315.950092ms)","trace[2005718537] 'compare' (duration: 188.813771ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:36.197336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:35.691353Z","time spent":"505.890904ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:58:36.197402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.940101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-26T21:58:36.19749Z","caller":"traceutil/trace.go:171","msg":"trace[735926023] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24918; }","duration":"492.078724ms","start":"2026-05-26T21:58:35.705392Z","end":"2026-05-26T21:58:36.197471Z","steps":["trace[735926023] 'agreement among raft nodes before linearized reading' (duration: 491.836728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:36.197528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:35.705373Z","time spent":"492.144536ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-26T21:58:36.212273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.560123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:36.212353Z","caller":"traceutil/trace.go:171","msg":"trace[704642144] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:24920; }","duration":"388.683316ms","start":"2026-05-26T21:58:35.823652Z","end":"2026-05-26T21:58:36.212336Z","steps":["trace[704642144] 'agreement among raft nodes before linearized reading' (duration: 388.534562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:36.212387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:35.823624Z","time spent":"388.755617ms","remote":"127.0.0.1:44524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:58:36.212597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.864373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-26T21:58:36.212647Z","caller":"traceutil/trace.go:171","msg":"trace[921956611] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:24920; }","duration":"137.949475ms","start":"2026-05-26T21:58:36.074685Z","end":"2026-05-26T21:58:36.212635Z","steps":["trace[921956611] 'agreement among raft nodes before linearized reading' (duration: 137.842592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:36.504225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.099799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:36.504327Z","caller":"traceutil/trace.go:171","msg":"trace[608762582] transaction","detail":"{read_only:false; response_revision:24922; number_of_response:1; }","duration":"287.485078ms","start":"2026-05-26T21:58:36.216824Z","end":"2026-05-26T21:58:36.504309Z","steps":["trace[608762582] 'process raft request' (duration: 143.217224ms)","trace[608762582] 'compare' (duration: 143.986556ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:36.589968Z","caller":"traceutil/trace.go:171","msg":"trace[976528428] transaction","detail":"{read_only:false; response_revision:24923; number_of_response:1; }","duration":"372.607631ms","start":"2026-05-26T21:58:36.217324Z","end":"2026-05-26T21:58:36.589932Z","steps":["trace[976528428] 'process raft request' (duration: 372.409965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:36.59019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:36.217307Z","time spent":"372.774286ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:58:36.592421Z","caller":"traceutil/trace.go:171","msg":"trace[1193234484] transaction","detail":"{read_only:false; response_revision:24924; number_of_response:1; }","duration":"129.014713ms","start":"2026-05-26T21:58:36.463364Z","end":"2026-05-26T21:58:36.592379Z","steps":["trace[1193234484] 'process raft request' (duration: 128.512449ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:47.188539Z","caller":"traceutil/trace.go:171","msg":"trace[405552572] linearizableReadLoop","detail":"{readStateIndex:25879; appliedIndex:25878; }","duration":"157.475267ms","start":"2026-05-26T21:58:47.031039Z","end":"2026-05-26T21:58:47.188515Z","steps":["trace[405552572] 'read index received' (duration: 157.317532ms)","trace[405552572] 'applied index is now lower than readState.Index' (duration: 156.654µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:47.188647Z","caller":"traceutil/trace.go:171","msg":"trace[940942158] transaction","detail":"{read_only:false; response_revision:25058; number_of_response:1; }","duration":"210.596557ms","start":"2026-05-26T21:58:46.978003Z","end":"2026-05-26T21:58:47.1886Z","steps":["trace[940942158] 'process raft request' (duration: 210.317719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:47.188754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.696252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f37587b8-1692-4d3d-a966-b42cd084e9e4\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-26T21:58:47.188806Z","caller":"traceutil/trace.go:171","msg":"trace[255601923] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f37587b8-1692-4d3d-a966-b42cd084e9e4; range_end:; response_count:1; response_revision:25058; }","duration":"157.768894ms","start":"2026-05-26T21:58:47.031027Z","end":"2026-05-26T21:58:47.188796Z","steps":["trace[255601923] 'agreement among raft nodes before linearized reading' (duration: 157.587529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:47.582011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.515856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:47.58213Z","caller":"traceutil/trace.go:171","msg":"trace[1433723554] linearizableReadLoop","detail":"{readStateIndex:25880; appliedIndex:25879; }","duration":"393.48577ms","start":"2026-05-26T21:58:47.188624Z","end":"2026-05-26T21:58:47.58211Z","steps":["trace[1433723554] 'read index received' (duration: 234.624145ms)","trace[1433723554] 'applied index is now lower than readState.Index' (duration: 158.859995ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:47.582215Z","caller":"traceutil/trace.go:171","msg":"trace[913495325] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"541.099048ms","start":"2026-05-26T21:58:47.041104Z","end":"2026-05-26T21:58:47.582203Z","steps":["trace[913495325] 'process raft request' (duration: 382.1272ms)","trace[913495325] 'compare' (duration: 158.32443ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:47.582272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:47.04102Z","time spent":"541.218162ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:58:47.582396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.030229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-26T21:58:47.582511Z","caller":"traceutil/trace.go:171","msg":"trace[330056563] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:25059; }","duration":"398.177373ms","start":"2026-05-26T21:58:47.184307Z","end":"2026-05-26T21:58:47.582484Z","steps":["trace[330056563] 'agreement among raft nodes before linearized reading' (duration: 397.924756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:47.582566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:47.184221Z","time spent":"398.333478ms","remote":"127.0.0.1:47764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":162,"request content":"key:\"/registry/masterleases/199.204.45.162\" "} {"level":"info","ts":"2026-05-26T21:58:47.583955Z","caller":"traceutil/trace.go:171","msg":"trace[1796233754] transaction","detail":"{read_only:false; response_revision:25060; number_of_response:1; }","duration":"388.787718ms","start":"2026-05-26T21:58:47.195148Z","end":"2026-05-26T21:58:47.583935Z","steps":["trace[1796233754] 'process raft request' (duration: 388.678485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:47.584051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:47.195124Z","time spent":"388.883811ms","remote":"127.0.0.1:47922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3743,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:58:47.595907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.351767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:47.596003Z","caller":"traceutil/trace.go:171","msg":"trace[1732829309] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25061; }","duration":"200.514741ms","start":"2026-05-26T21:58:47.395469Z","end":"2026-05-26T21:58:47.595984Z","steps":["trace[1732829309] 'agreement among raft nodes before linearized reading' (duration: 200.288115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:48.215437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.492918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:48.215548Z","caller":"traceutil/trace.go:171","msg":"trace[1505896943] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:25065; }","duration":"111.674692ms","start":"2026-05-26T21:58:48.103848Z","end":"2026-05-26T21:58:48.215523Z","steps":["trace[1505896943] 'count revisions from in-memory index tree' (duration: 111.391785ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:48.357643Z","caller":"traceutil/trace.go:171","msg":"trace[1293660625] transaction","detail":"{read_only:false; response_revision:25066; number_of_response:1; }","duration":"106.576169ms","start":"2026-05-26T21:58:48.25103Z","end":"2026-05-26T21:58:48.357606Z","steps":["trace[1293660625] 'process raft request' (duration: 106.325282ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:48.369676Z","caller":"traceutil/trace.go:171","msg":"trace[770144957] transaction","detail":"{read_only:false; response_revision:25067; number_of_response:1; }","duration":"118.611248ms","start":"2026-05-26T21:58:48.251041Z","end":"2026-05-26T21:58:48.369653Z","steps":["trace[770144957] 'process raft request' (duration: 118.3118ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:48.36982Z","caller":"traceutil/trace.go:171","msg":"trace[1843077985] transaction","detail":"{read_only:false; response_revision:25068; number_of_response:1; }","duration":"100.965171ms","start":"2026-05-26T21:58:48.268777Z","end":"2026-05-26T21:58:48.369742Z","steps":["trace[1843077985] 'process raft request' (duration: 100.757465ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:48.66266Z","caller":"traceutil/trace.go:171","msg":"trace[656166727] linearizableReadLoop","detail":"{readStateIndex:25891; appliedIndex:25890; }","duration":"279.686137ms","start":"2026-05-26T21:58:48.382943Z","end":"2026-05-26T21:58:48.662629Z","steps":["trace[656166727] 'read index received' (duration: 279.526863ms)","trace[656166727] 'applied index is now lower than readState.Index' (duration: 157.414µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:48.662764Z","caller":"traceutil/trace.go:171","msg":"trace[1155682450] transaction","detail":"{read_only:false; response_revision:25069; number_of_response:1; }","duration":"287.727495ms","start":"2026-05-26T21:58:48.374995Z","end":"2026-05-26T21:58:48.662723Z","steps":["trace[1155682450] 'process raft request' (duration: 287.424776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:48.662842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.864782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:48.662903Z","caller":"traceutil/trace.go:171","msg":"trace[1551765515] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:25069; }","duration":"279.994766ms","start":"2026-05-26T21:58:48.382888Z","end":"2026-05-26T21:58:48.662883Z","steps":["trace[1551765515] 'agreement among raft nodes before linearized reading' (duration: 279.857023ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:48.760379Z","caller":"traceutil/trace.go:171","msg":"trace[1881964781] transaction","detail":"{read_only:false; response_revision:25070; number_of_response:1; }","duration":"185.129648ms","start":"2026-05-26T21:58:48.575213Z","end":"2026-05-26T21:58:48.760342Z","steps":["trace[1881964781] 'process raft request' (duration: 184.888681ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:48.76072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.937317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T21:58:48.760824Z","caller":"traceutil/trace.go:171","msg":"trace[879645367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25071; }","duration":"188.099471ms","start":"2026-05-26T21:58:48.572701Z","end":"2026-05-26T21:58:48.7608Z","steps":["trace[879645367] 'agreement among raft nodes before linearized reading' (duration: 187.808273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:48.760886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.697358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-26T21:58:48.760977Z","caller":"traceutil/trace.go:171","msg":"trace[534533808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25071; }","duration":"103.854973ms","start":"2026-05-26T21:58:48.657099Z","end":"2026-05-26T21:58:48.760954Z","steps":["trace[534533808] 'agreement among raft nodes before linearized reading' (duration: 103.541654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:48.76097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.331238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:48.761023Z","caller":"traceutil/trace.go:171","msg":"trace[1396553107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25071; }","duration":"182.40059ms","start":"2026-05-26T21:58:48.578608Z","end":"2026-05-26T21:58:48.761009Z","steps":["trace[1396553107] 'agreement among raft nodes before linearized reading' (duration: 182.289577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:48.76099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.491989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-05-26T21:58:48.761059Z","caller":"traceutil/trace.go:171","msg":"trace[1969772726] transaction","detail":"{read_only:false; response_revision:25071; number_of_response:1; }","duration":"116.929952ms","start":"2026-05-26T21:58:48.644103Z","end":"2026-05-26T21:58:48.761033Z","steps":["trace[1969772726] 'process raft request' (duration: 116.170821ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:48.761098Z","caller":"traceutil/trace.go:171","msg":"trace[239362634] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:25071; }","duration":"105.656374ms","start":"2026-05-26T21:58:48.655417Z","end":"2026-05-26T21:58:48.761074Z","steps":["trace[239362634] 'agreement among raft nodes before linearized reading' (duration: 105.296704ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:48.822562Z","caller":"traceutil/trace.go:171","msg":"trace[2064769875] transaction","detail":"{read_only:false; response_revision:25072; number_of_response:1; }","duration":"119.38563ms","start":"2026-05-26T21:58:48.703143Z","end":"2026-05-26T21:58:48.822529Z","steps":["trace[2064769875] 'process raft request' (duration: 119.181114ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:48.822575Z","caller":"traceutil/trace.go:171","msg":"trace[578323806] transaction","detail":"{read_only:false; response_revision:25073; number_of_response:1; }","duration":"115.291525ms","start":"2026-05-26T21:58:48.707263Z","end":"2026-05-26T21:58:48.822555Z","steps":["trace[578323806] 'process raft request' (duration: 115.202542ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:48.922569Z","caller":"traceutil/trace.go:171","msg":"trace[1905233782] transaction","detail":"{read_only:false; response_revision:25074; number_of_response:1; }","duration":"156.129259ms","start":"2026-05-26T21:58:48.766399Z","end":"2026-05-26T21:58:48.922528Z","steps":["trace[1905233782] 'process raft request' (duration: 107.98611ms)","trace[1905233782] 'compare' (duration: 47.80729ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:48.922594Z","caller":"traceutil/trace.go:171","msg":"trace[1916671559] transaction","detail":"{read_only:false; response_revision:25075; number_of_response:1; }","duration":"155.684886ms","start":"2026-05-26T21:58:48.766873Z","end":"2026-05-26T21:58:48.922557Z","steps":["trace[1916671559] 'process raft request' (duration: 155.523631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:48.922702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.689596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-f46636c8-59f8-4f67-936b-a9036b2578aa\" ","response":"range_response_count:1 size:1324"} {"level":"info","ts":"2026-05-26T21:58:48.922778Z","caller":"traceutil/trace.go:171","msg":"trace[1707299559] range","detail":"{range_begin:/registry/persistentvolumes/pvc-f46636c8-59f8-4f67-936b-a9036b2578aa; range_end:; response_count:1; response_revision:25075; }","duration":"155.819491ms","start":"2026-05-26T21:58:48.766938Z","end":"2026-05-26T21:58:48.922757Z","steps":["trace[1707299559] 'agreement among raft nodes before linearized reading' (duration: 155.567514ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:49.723726Z","caller":"traceutil/trace.go:171","msg":"trace[252283525] transaction","detail":"{read_only:false; response_revision:25076; number_of_response:1; }","duration":"121.174801ms","start":"2026-05-26T21:58:49.602498Z","end":"2026-05-26T21:58:49.723673Z","steps":["trace[252283525] 'process raft request' (duration: 120.913374ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:49.871793Z","caller":"traceutil/trace.go:171","msg":"trace[232988626] transaction","detail":"{read_only:false; response_revision:25077; number_of_response:1; }","duration":"142.565476ms","start":"2026-05-26T21:58:49.729186Z","end":"2026-05-26T21:58:49.871751Z","steps":["trace[232988626] 'process raft request' (duration: 119.810793ms)","trace[232988626] 'compare' (duration: 22.191426ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:58:49.872146Z","caller":"traceutil/trace.go:171","msg":"trace[168666054] transaction","detail":"{read_only:false; response_revision:25078; number_of_response:1; }","duration":"142.838723ms","start":"2026-05-26T21:58:49.729259Z","end":"2026-05-26T21:58:49.872098Z","steps":["trace[168666054] 'process raft request' (duration: 142.618717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:50.336027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.1775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:560"} {"level":"info","ts":"2026-05-26T21:58:50.33613Z","caller":"traceutil/trace.go:171","msg":"trace[611327608] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25079; }","duration":"179.368535ms","start":"2026-05-26T21:58:50.156737Z","end":"2026-05-26T21:58:50.336106Z","steps":["trace[611327608] 'range keys from in-memory index tree' (duration: 179.033685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:50.915826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.151551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:58:50.916174Z","caller":"traceutil/trace.go:171","msg":"trace[1756227344] transaction","detail":"{read_only:false; response_revision:25081; number_of_response:1; }","duration":"553.278482ms","start":"2026-05-26T21:58:50.362827Z","end":"2026-05-26T21:58:50.916105Z","steps":["trace[1756227344] 'process raft request' (duration: 369.706929ms)","trace[1756227344] 'compare' (duration: 183.019537ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:50.916379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:50.362809Z","time spent":"553.460418ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:58:50.916486Z","caller":"traceutil/trace.go:171","msg":"trace[959960442] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"541.977055ms","start":"2026-05-26T21:58:50.374448Z","end":"2026-05-26T21:58:50.916425Z","steps":["trace[959960442] 'process raft request' (duration: 541.49765ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:50.916579Z","caller":"traceutil/trace.go:171","msg":"trace[125688952] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"541.47508ms","start":"2026-05-26T21:58:50.375047Z","end":"2026-05-26T21:58:50.916522Z","steps":["trace[125688952] 'process raft request' (duration: 541.313706ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:50.916646Z","caller":"traceutil/trace.go:171","msg":"trace[1083562543] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"228.339588ms","start":"2026-05-26T21:58:50.688288Z","end":"2026-05-26T21:58:50.916628Z","steps":["trace[1083562543] 'process raft request' (duration: 228.283157ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:50.916667Z","caller":"traceutil/trace.go:171","msg":"trace[1173666852] linearizableReadLoop","detail":"{readStateIndex:25905; appliedIndex:25902; }","duration":"417.420507ms","start":"2026-05-26T21:58:50.499223Z","end":"2026-05-26T21:58:50.916643Z","steps":["trace[1173666852] 'read index received' (duration: 233.311328ms)","trace[1173666852] 'applied index is now lower than readState.Index' (duration: 184.105619ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:58:50.916697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:50.374426Z","time spent":"542.152219ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:58:50.916776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:50.37504Z","time spent":"541.633025ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:58:50.91682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.596001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-26T21:58:50.916882Z","caller":"traceutil/trace.go:171","msg":"trace[823233437] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25084; }","duration":"417.694534ms","start":"2026-05-26T21:58:50.49917Z","end":"2026-05-26T21:58:50.916864Z","steps":["trace[823233437] 'agreement among raft nodes before linearized reading' (duration: 417.55943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:50.916928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:50.499146Z","time spent":"417.774257ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-05-26T21:58:50.992409Z","caller":"traceutil/trace.go:171","msg":"trace[1640688670] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"225.508728ms","start":"2026-05-26T21:58:50.766867Z","end":"2026-05-26T21:58:50.992376Z","steps":["trace[1640688670] 'process raft request' (duration: 225.007164ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:50.992676Z","caller":"traceutil/trace.go:171","msg":"trace[1520428336] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"163.663921ms","start":"2026-05-26T21:58:50.829001Z","end":"2026-05-26T21:58:50.992665Z","steps":["trace[1520428336] 'process raft request' (duration: 163.024844ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:50.992808Z","caller":"traceutil/trace.go:171","msg":"trace[999441048] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"163.561619ms","start":"2026-05-26T21:58:50.829237Z","end":"2026-05-26T21:58:50.992799Z","steps":["trace[999441048] 'process raft request' (duration: 162.835719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:50.993001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.966822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-05-26T21:58:50.993042Z","caller":"traceutil/trace.go:171","msg":"trace[1673173780] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25088; }","duration":"316.021604ms","start":"2026-05-26T21:58:50.677005Z","end":"2026-05-26T21:58:50.993027Z","steps":["trace[1673173780] 'agreement among raft nodes before linearized reading' (duration: 315.872519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:50.993078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:50.676911Z","time spent":"316.157828ms","remote":"127.0.0.1:46732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3258,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-26T21:58:50.993295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.100658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-26T21:58:50.993296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.437165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:58:50.993342Z","caller":"traceutil/trace.go:171","msg":"trace[164868059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25088; }","duration":"326.17528ms","start":"2026-05-26T21:58:50.667157Z","end":"2026-05-26T21:58:50.993332Z","steps":["trace[164868059] 'agreement among raft nodes before linearized reading' (duration: 326.071467ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:50.993368Z","caller":"traceutil/trace.go:171","msg":"trace[844793473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25088; }","duration":"413.558659ms","start":"2026-05-26T21:58:50.579791Z","end":"2026-05-26T21:58:50.99335Z","steps":["trace[844793473] 'agreement among raft nodes before linearized reading' (duration: 413.450876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:50.993402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:50.579767Z","time spent":"413.62855ms","remote":"127.0.0.1:47748","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-05-26T21:58:50.993375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:58:50.667142Z","time spent":"326.227201ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-26T21:58:52.356842Z","caller":"traceutil/trace.go:171","msg":"trace[1554296536] transaction","detail":"{read_only:false; response_revision:25097; number_of_response:1; }","duration":"161.878481ms","start":"2026-05-26T21:58:52.194932Z","end":"2026-05-26T21:58:52.35681Z","steps":["trace[1554296536] 'process raft request' (duration: 161.554772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:56.407573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.205108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-26T21:58:56.407668Z","caller":"traceutil/trace.go:171","msg":"trace[1491111000] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25141; }","duration":"157.353403ms","start":"2026-05-26T21:58:56.250286Z","end":"2026-05-26T21:58:56.40764Z","steps":["trace[1491111000] 'range keys from in-memory index tree' (duration: 156.878449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:58:56.408137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.544018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10359"} {"level":"info","ts":"2026-05-26T21:58:56.408223Z","caller":"traceutil/trace.go:171","msg":"trace[1665666615] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25141; }","duration":"186.717422ms","start":"2026-05-26T21:58:56.221484Z","end":"2026-05-26T21:58:56.408202Z","steps":["trace[1665666615] 'range keys from in-memory index tree' (duration: 186.296691ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:56.626339Z","caller":"traceutil/trace.go:171","msg":"trace[540903981] transaction","detail":"{read_only:false; response_revision:25142; number_of_response:1; }","duration":"208.792306ms","start":"2026-05-26T21:58:56.417506Z","end":"2026-05-26T21:58:56.626299Z","steps":["trace[540903981] 'process raft request' (duration: 208.540869ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:56.80756Z","caller":"traceutil/trace.go:171","msg":"trace[1072809681] transaction","detail":"{read_only:false; response_revision:25144; number_of_response:1; }","duration":"172.001216ms","start":"2026-05-26T21:58:56.635523Z","end":"2026-05-26T21:58:56.807525Z","steps":["trace[1072809681] 'process raft request' (duration: 171.597145ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:56.807937Z","caller":"traceutil/trace.go:171","msg":"trace[591959432] transaction","detail":"{read_only:false; response_revision:25145; number_of_response:1; }","duration":"171.319618ms","start":"2026-05-26T21:58:56.636598Z","end":"2026-05-26T21:58:56.807918Z","steps":["trace[591959432] 'process raft request' (duration: 170.808923ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:58:56.808173Z","caller":"traceutil/trace.go:171","msg":"trace[866220517] transaction","detail":"{read_only:false; response_revision:25146; number_of_response:1; }","duration":"171.102841ms","start":"2026-05-26T21:58:56.636992Z","end":"2026-05-26T21:58:56.808095Z","steps":["trace[866220517] 'process raft request' (duration: 170.847224ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:15.984145Z","caller":"traceutil/trace.go:171","msg":"trace[1183590672] transaction","detail":"{read_only:false; response_revision:25312; number_of_response:1; }","duration":"161.199163ms","start":"2026-05-26T21:59:15.822907Z","end":"2026-05-26T21:59:15.984107Z","steps":["trace[1183590672] 'process raft request' (duration: 161.010207ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:16.168028Z","caller":"traceutil/trace.go:171","msg":"trace[412133862] linearizableReadLoop","detail":"{readStateIndex:26142; appliedIndex:26141; }","duration":"149.150743ms","start":"2026-05-26T21:59:16.018857Z","end":"2026-05-26T21:59:16.168008Z","steps":["trace[412133862] 'read index received' (duration: 139.395807ms)","trace[412133862] 'applied index is now lower than readState.Index' (duration: 9.753616ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:16.168302Z","caller":"traceutil/trace.go:171","msg":"trace[582538732] transaction","detail":"{read_only:false; response_revision:25313; number_of_response:1; }","duration":"151.5548ms","start":"2026-05-26T21:59:16.016735Z","end":"2026-05-26T21:59:16.16829Z","steps":["trace[582538732] 'process raft request' (duration: 141.492395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:16.168518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.679477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-26T21:59:16.168549Z","caller":"traceutil/trace.go:171","msg":"trace[1997349427] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25313; }","duration":"149.735139ms","start":"2026-05-26T21:59:16.018805Z","end":"2026-05-26T21:59:16.16854Z","steps":["trace[1997349427] 'agreement among raft nodes before linearized reading' (duration: 149.623546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:16.168689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.086982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3163"} {"level":"info","ts":"2026-05-26T21:59:16.168714Z","caller":"traceutil/trace.go:171","msg":"trace[15391524] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25313; }","duration":"131.139683ms","start":"2026-05-26T21:59:16.037568Z","end":"2026-05-26T21:59:16.168708Z","steps":["trace[15391524] 'agreement among raft nodes before linearized reading' (duration: 131.071441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:16.16888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.674631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-26T21:59:16.168897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.966875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:59:16.168953Z","caller":"traceutil/trace.go:171","msg":"trace[1154916323] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:25313; }","duration":"112.798315ms","start":"2026-05-26T21:59:16.056138Z","end":"2026-05-26T21:59:16.168936Z","steps":["trace[1154916323] 'agreement among raft nodes before linearized reading' (duration: 112.652101ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:16.16896Z","caller":"traceutil/trace.go:171","msg":"trace[1279304882] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:25313; }","duration":"122.066297ms","start":"2026-05-26T21:59:16.046879Z","end":"2026-05-26T21:59:16.168946Z","steps":["trace[1279304882] 'agreement among raft nodes before linearized reading' (duration: 121.932553ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:16.368322Z","caller":"traceutil/trace.go:171","msg":"trace[1335766400] transaction","detail":"{read_only:false; response_revision:25315; number_of_response:1; }","duration":"120.762949ms","start":"2026-05-26T21:59:16.24753Z","end":"2026-05-26T21:59:16.368293Z","steps":["trace[1335766400] 'process raft request' (duration: 120.584984ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:16.439376Z","caller":"traceutil/trace.go:171","msg":"trace[649936164] transaction","detail":"{read_only:false; response_revision:25316; number_of_response:1; }","duration":"169.522366ms","start":"2026-05-26T21:59:16.269816Z","end":"2026-05-26T21:59:16.439338Z","steps":["trace[649936164] 'process raft request' (duration: 169.334171ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:16.588541Z","caller":"traceutil/trace.go:171","msg":"trace[957239008] transaction","detail":"{read_only:false; response_revision:25317; number_of_response:1; }","duration":"210.69295ms","start":"2026-05-26T21:59:16.377809Z","end":"2026-05-26T21:59:16.588502Z","steps":["trace[957239008] 'process raft request' (duration: 193.495583ms)","trace[957239008] 'compare' (duration: 17.039843ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:16.794077Z","caller":"traceutil/trace.go:171","msg":"trace[315958130] linearizableReadLoop","detail":"{readStateIndex:26148; appliedIndex:26147; }","duration":"130.516956ms","start":"2026-05-26T21:59:16.66354Z","end":"2026-05-26T21:59:16.794057Z","steps":["trace[315958130] 'read index received' (duration: 59.711127ms)","trace[315958130] 'applied index is now lower than readState.Index' (duration: 70.804889ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:16.794164Z","caller":"traceutil/trace.go:171","msg":"trace[1850297541] transaction","detail":"{read_only:false; response_revision:25319; number_of_response:1; }","duration":"132.386808ms","start":"2026-05-26T21:59:16.661748Z","end":"2026-05-26T21:59:16.794135Z","steps":["trace[1850297541] 'process raft request' (duration: 61.567999ms)","trace[1850297541] 'compare' (duration: 70.519301ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:59:16.79428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.722142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-26T21:59:16.794303Z","caller":"traceutil/trace.go:171","msg":"trace[1721589751] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25319; }","duration":"130.767833ms","start":"2026-05-26T21:59:16.663528Z","end":"2026-05-26T21:59:16.794295Z","steps":["trace[1721589751] 'agreement among raft nodes before linearized reading' (duration: 130.635319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:16.794461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.596547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-26T21:59:16.794546Z","caller":"traceutil/trace.go:171","msg":"trace[162927201] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:25319; }","duration":"130.72951ms","start":"2026-05-26T21:59:16.663799Z","end":"2026-05-26T21:59:16.794528Z","steps":["trace[162927201] 'agreement among raft nodes before linearized reading' (duration: 130.459213ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:17.306136Z","caller":"traceutil/trace.go:171","msg":"trace[1192642263] linearizableReadLoop","detail":"{readStateIndex:26152; appliedIndex:26150; }","duration":"248.093696ms","start":"2026-05-26T21:59:17.058011Z","end":"2026-05-26T21:59:17.306105Z","steps":["trace[1192642263] 'read index received' (duration: 4.60682ms)","trace[1192642263] 'applied index is now lower than readState.Index' (duration: 243.485216ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:17.306202Z","caller":"traceutil/trace.go:171","msg":"trace[2019859097] transaction","detail":"{read_only:false; response_revision:25323; number_of_response:1; }","duration":"272.937778ms","start":"2026-05-26T21:59:17.033233Z","end":"2026-05-26T21:59:17.306171Z","steps":["trace[2019859097] 'process raft request' (duration: 272.807984ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:17.306169Z","caller":"traceutil/trace.go:171","msg":"trace[1154650462] transaction","detail":"{read_only:false; response_revision:25322; number_of_response:1; }","duration":"273.275908ms","start":"2026-05-26T21:59:17.032856Z","end":"2026-05-26T21:59:17.306132Z","steps":["trace[1154650462] 'process raft request' (duration: 272.98008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:17.306386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.356693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-05-26T21:59:17.306436Z","caller":"traceutil/trace.go:171","msg":"trace[633919458] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25323; }","duration":"248.487867ms","start":"2026-05-26T21:59:17.057936Z","end":"2026-05-26T21:59:17.306424Z","steps":["trace[633919458] 'agreement among raft nodes before linearized reading' (duration: 248.284691ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:17.318903Z","caller":"traceutil/trace.go:171","msg":"trace[360037254] transaction","detail":"{read_only:false; response_revision:25324; number_of_response:1; }","duration":"224.332095ms","start":"2026-05-26T21:59:17.094542Z","end":"2026-05-26T21:59:17.318874Z","steps":["trace[360037254] 'process raft request' (duration: 224.173261ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:17.318914Z","caller":"traceutil/trace.go:171","msg":"trace[96745941] transaction","detail":"{read_only:false; response_revision:25325; number_of_response:1; }","duration":"223.338807ms","start":"2026-05-26T21:59:17.09555Z","end":"2026-05-26T21:59:17.318889Z","steps":["trace[96745941] 'process raft request' (duration: 223.265225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:17.31937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.583657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-26T21:59:17.319412Z","caller":"traceutil/trace.go:171","msg":"trace[1730800775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25326; }","duration":"176.66028ms","start":"2026-05-26T21:59:17.142737Z","end":"2026-05-26T21:59:17.319398Z","steps":["trace[1730800775] 'agreement among raft nodes before linearized reading' (duration: 176.497464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:17.31945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.812007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-26T21:59:17.319333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.799062ms","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-05-26T21:59:17.319541Z","caller":"traceutil/trace.go:171","msg":"trace[1600558452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25326; }","duration":"200.072391ms","start":"2026-05-26T21:59:17.119457Z","end":"2026-05-26T21:59:17.31953Z","steps":["trace[1600558452] 'agreement among raft nodes before linearized reading' (duration: 199.643988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:17.319556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.948024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-26T21:59:17.319584Z","caller":"traceutil/trace.go:171","msg":"trace[339990140] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:25326; }","duration":"132.969815ms","start":"2026-05-26T21:59:17.186604Z","end":"2026-05-26T21:59:17.319574Z","steps":["trace[339990140] 'agreement among raft nodes before linearized reading' (duration: 132.886182ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:17.319583Z","caller":"traceutil/trace.go:171","msg":"trace[1667290563] transaction","detail":"{read_only:false; response_revision:25326; number_of_response:1; }","duration":"223.363999ms","start":"2026-05-26T21:59:17.096206Z","end":"2026-05-26T21:59:17.31957Z","steps":["trace[1667290563] 'process raft request' (duration: 222.644358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:17.319451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.008734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-26T21:59:17.319666Z","caller":"traceutil/trace.go:171","msg":"trace[1874780638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25326; }","duration":"180.266941ms","start":"2026-05-26T21:59:17.13939Z","end":"2026-05-26T21:59:17.319657Z","steps":["trace[1874780638] 'agreement among raft nodes before linearized reading' (duration: 179.960613ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:17.31953Z","caller":"traceutil/trace.go:171","msg":"trace[447080676] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:25326; }","duration":"197.9205ms","start":"2026-05-26T21:59:17.12158Z","end":"2026-05-26T21:59:17.3195Z","steps":["trace[447080676] 'agreement among raft nodes before linearized reading' (duration: 197.816307ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:17.476539Z","caller":"traceutil/trace.go:171","msg":"trace[1928684624] transaction","detail":"{read_only:false; response_revision:25327; number_of_response:1; }","duration":"151.470547ms","start":"2026-05-26T21:59:17.325016Z","end":"2026-05-26T21:59:17.476487Z","steps":["trace[1928684624] 'process raft request' (duration: 149.914583ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:17.476598Z","caller":"traceutil/trace.go:171","msg":"trace[1758189113] transaction","detail":"{read_only:false; response_revision:25329; number_of_response:1; }","duration":"151.363394ms","start":"2026-05-26T21:59:17.325196Z","end":"2026-05-26T21:59:17.47656Z","steps":["trace[1758189113] 'process raft request' (duration: 151.245971ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:17.476804Z","caller":"traceutil/trace.go:171","msg":"trace[1151519668] transaction","detail":"{read_only:false; response_revision:25328; number_of_response:1; }","duration":"151.631411ms","start":"2026-05-26T21:59:17.325132Z","end":"2026-05-26T21:59:17.476764Z","steps":["trace[1151519668] 'process raft request' (duration: 151.23314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:17.889222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.930472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:59:17.889345Z","caller":"traceutil/trace.go:171","msg":"trace[2103323254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25331; }","duration":"310.103197ms","start":"2026-05-26T21:59:17.579207Z","end":"2026-05-26T21:59:17.88931Z","steps":["trace[2103323254] 'range keys from in-memory index tree' (duration: 309.83727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:17.889405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:59:17.579177Z","time spent":"310.21402ms","remote":"127.0.0.1:47744","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-05-26T21:59:17.889533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.692622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-26T21:59:17.889597Z","caller":"traceutil/trace.go:171","msg":"trace[509754995] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:25331; }","duration":"254.772854ms","start":"2026-05-26T21:59:17.634808Z","end":"2026-05-26T21:59:17.889581Z","steps":["trace[509754995] 'range keys from in-memory index tree' (duration: 254.521258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:18.347222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.667023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-26T21:59:18.347289Z","caller":"traceutil/trace.go:171","msg":"trace[546304184] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25332; }","duration":"168.793666ms","start":"2026-05-26T21:59:18.178479Z","end":"2026-05-26T21:59:18.347272Z","steps":["trace[546304184] 'range keys from in-memory index tree' (duration: 168.491107ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:18.359316Z","caller":"traceutil/trace.go:171","msg":"trace[1267811560] transaction","detail":"{read_only:false; response_revision:25333; number_of_response:1; }","duration":"178.628714ms","start":"2026-05-26T21:59:18.180637Z","end":"2026-05-26T21:59:18.359266Z","steps":["trace[1267811560] 'process raft request' (duration: 178.48697ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:19.434513Z","caller":"traceutil/trace.go:171","msg":"trace[278023721] transaction","detail":"{read_only:false; response_revision:25338; number_of_response:1; }","duration":"122.082128ms","start":"2026-05-26T21:59:19.312392Z","end":"2026-05-26T21:59:19.434474Z","steps":["trace[278023721] 'process raft request' (duration: 121.876581ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:19.465612Z","caller":"traceutil/trace.go:171","msg":"trace[64126043] transaction","detail":"{read_only:false; response_revision:25339; number_of_response:1; }","duration":"152.188458ms","start":"2026-05-26T21:59:19.313387Z","end":"2026-05-26T21:59:19.465575Z","steps":["trace[64126043] 'process raft request' (duration: 151.933841ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:19.465763Z","caller":"traceutil/trace.go:171","msg":"trace[1127422410] transaction","detail":"{read_only:false; response_revision:25340; number_of_response:1; }","duration":"140.22816ms","start":"2026-05-26T21:59:19.325504Z","end":"2026-05-26T21:59:19.465732Z","steps":["trace[1127422410] 'process raft request' (duration: 139.988623ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:19.466406Z","caller":"traceutil/trace.go:171","msg":"trace[902891605] transaction","detail":"{read_only:false; response_revision:25341; number_of_response:1; }","duration":"140.770245ms","start":"2026-05-26T21:59:19.325592Z","end":"2026-05-26T21:59:19.466362Z","steps":["trace[902891605] 'process raft request' (duration: 139.942762ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:19.466506Z","caller":"traceutil/trace.go:171","msg":"trace[1740422914] transaction","detail":"{read_only:false; response_revision:25342; number_of_response:1; }","duration":"138.446629ms","start":"2026-05-26T21:59:19.328044Z","end":"2026-05-26T21:59:19.466491Z","steps":["trace[1740422914] 'process raft request' (duration: 138.235353ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:19.630288Z","caller":"traceutil/trace.go:171","msg":"trace[412384273] transaction","detail":"{read_only:false; response_revision:25344; number_of_response:1; }","duration":"144.419088ms","start":"2026-05-26T21:59:19.485846Z","end":"2026-05-26T21:59:19.630265Z","steps":["trace[412384273] 'process raft request' (duration: 143.966495ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:19.630534Z","caller":"traceutil/trace.go:171","msg":"trace[1603310925] transaction","detail":"{read_only:false; response_revision:25345; number_of_response:1; }","duration":"140.964591ms","start":"2026-05-26T21:59:19.48956Z","end":"2026-05-26T21:59:19.630525Z","steps":["trace[1603310925] 'process raft request' (duration: 140.401984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:21.155173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.82723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:59:21.155302Z","caller":"traceutil/trace.go:171","msg":"trace[923668778] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25352; }","duration":"138.987285ms","start":"2026-05-26T21:59:21.016282Z","end":"2026-05-26T21:59:21.15527Z","steps":["trace[923668778] 'count revisions from in-memory index tree' (duration: 138.714648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:21.155872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.389216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-26T21:59:21.155919Z","caller":"traceutil/trace.go:171","msg":"trace[1496526932] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25352; }","duration":"139.450228ms","start":"2026-05-26T21:59:21.016458Z","end":"2026-05-26T21:59:21.155908Z","steps":["trace[1496526932] 'range keys from in-memory index tree' (duration: 139.253622ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:32.226691Z","caller":"traceutil/trace.go:171","msg":"trace[506068790] transaction","detail":"{read_only:false; response_revision:25434; number_of_response:1; }","duration":"104.973605ms","start":"2026-05-26T21:59:32.1217Z","end":"2026-05-26T21:59:32.226674Z","steps":["trace[506068790] 'process raft request' (duration: 104.698107ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:42.675565Z","caller":"traceutil/trace.go:171","msg":"trace[1498163022] transaction","detail":"{read_only:false; response_revision:25509; number_of_response:1; }","duration":"156.521009ms","start":"2026-05-26T21:59:42.518996Z","end":"2026-05-26T21:59:42.675517Z","steps":["trace[1498163022] 'process raft request' (duration: 156.278243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:42.862658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.97514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T21:59:42.862835Z","caller":"traceutil/trace.go:171","msg":"trace[1947972953] transaction","detail":"{read_only:false; response_revision:25510; number_of_response:1; }","duration":"182.813573ms","start":"2026-05-26T21:59:42.679989Z","end":"2026-05-26T21:59:42.862802Z","steps":["trace[1947972953] 'process raft request' (duration: 80.423911ms)","trace[1947972953] 'compare' (duration: 101.600469ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:42.863142Z","caller":"traceutil/trace.go:171","msg":"trace[529721575] linearizableReadLoop","detail":"{readStateIndex:26347; appliedIndex:26344; }","duration":"130.502426ms","start":"2026-05-26T21:59:42.732615Z","end":"2026-05-26T21:59:42.863117Z","steps":["trace[529721575] 'read index received' (duration: 27.745244ms)","trace[529721575] 'applied index is now lower than readState.Index' (duration: 102.755951ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:42.863218Z","caller":"traceutil/trace.go:171","msg":"trace[292920039] transaction","detail":"{read_only:false; response_revision:25511; number_of_response:1; }","duration":"182.987997ms","start":"2026-05-26T21:59:42.680193Z","end":"2026-05-26T21:59:42.863181Z","steps":["trace[292920039] 'process raft request' (duration: 182.784832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:42.8633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.66779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-26T21:59:42.863335Z","caller":"traceutil/trace.go:171","msg":"trace[1799388540] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:25512; }","duration":"130.765982ms","start":"2026-05-26T21:59:42.732557Z","end":"2026-05-26T21:59:42.863323Z","steps":["trace[1799388540] 'agreement among raft nodes before linearized reading' (duration: 130.663839ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:42.86333Z","caller":"traceutil/trace.go:171","msg":"trace[1437360421] transaction","detail":"{read_only:false; response_revision:25512; number_of_response:1; }","duration":"182.937625ms","start":"2026-05-26T21:59:42.680308Z","end":"2026-05-26T21:59:42.863245Z","steps":["trace[1437360421] 'process raft request' (duration: 182.779901ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:43.339078Z","caller":"traceutil/trace.go:171","msg":"trace[450669141] linearizableReadLoop","detail":"{readStateIndex:26348; appliedIndex:26347; }","duration":"108.453223ms","start":"2026-05-26T21:59:43.230598Z","end":"2026-05-26T21:59:43.339051Z","steps":["trace[450669141] 'read index received' (duration: 108.167255ms)","trace[450669141] 'applied index is now lower than readState.Index' (duration: 284.748µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:43.339467Z","caller":"traceutil/trace.go:171","msg":"trace[2062872151] transaction","detail":"{read_only:false; response_revision:25513; number_of_response:1; }","duration":"110.714817ms","start":"2026-05-26T21:59:43.228734Z","end":"2026-05-26T21:59:43.339449Z","steps":["trace[2062872151] 'process raft request' (duration: 110.145581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:43.339778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.111511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:59:43.339832Z","caller":"traceutil/trace.go:171","msg":"trace[1918345055] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:25513; }","duration":"109.259815ms","start":"2026-05-26T21:59:43.230557Z","end":"2026-05-26T21:59:43.339817Z","steps":["trace[1918345055] 'agreement among raft nodes before linearized reading' (duration: 109.103331ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:43.639895Z","caller":"traceutil/trace.go:171","msg":"trace[913252507] transaction","detail":"{read_only:false; response_revision:25514; number_of_response:1; }","duration":"167.50615ms","start":"2026-05-26T21:59:43.472362Z","end":"2026-05-26T21:59:43.639868Z","steps":["trace[913252507] 'process raft request' (duration: 166.157003ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:43.696052Z","caller":"traceutil/trace.go:171","msg":"trace[357496524] transaction","detail":"{read_only:false; response_revision:25516; number_of_response:1; }","duration":"138.49277ms","start":"2026-05-26T21:59:43.557532Z","end":"2026-05-26T21:59:43.696025Z","steps":["trace[357496524] 'process raft request' (duration: 138.14726ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:43.696221Z","caller":"traceutil/trace.go:171","msg":"trace[175126140] transaction","detail":"{read_only:false; response_revision:25517; number_of_response:1; }","duration":"136.583827ms","start":"2026-05-26T21:59:43.559514Z","end":"2026-05-26T21:59:43.696098Z","steps":["trace[175126140] 'process raft request' (duration: 136.421852ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:43.696382Z","caller":"traceutil/trace.go:171","msg":"trace[231621108] linearizableReadLoop","detail":"{readStateIndex:26350; appliedIndex:26348; }","duration":"162.031545ms","start":"2026-05-26T21:59:43.534322Z","end":"2026-05-26T21:59:43.696354Z","steps":["trace[231621108] 'read index received' (duration: 104.209833ms)","trace[231621108] 'applied index is now lower than readState.Index' (duration: 57.820332ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:43.696423Z","caller":"traceutil/trace.go:171","msg":"trace[217285486] transaction","detail":"{read_only:false; response_revision:25519; number_of_response:1; }","duration":"133.271853ms","start":"2026-05-26T21:59:43.56314Z","end":"2026-05-26T21:59:43.696412Z","steps":["trace[217285486] 'process raft request' (duration: 133.054087ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:43.696264Z","caller":"traceutil/trace.go:171","msg":"trace[1472585348] transaction","detail":"{read_only:false; response_revision:25518; number_of_response:1; }","duration":"134.758575ms","start":"2026-05-26T21:59:43.561481Z","end":"2026-05-26T21:59:43.696239Z","steps":["trace[1472585348] 'process raft request' (duration: 134.57681ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:43.69585Z","caller":"traceutil/trace.go:171","msg":"trace[102156546] transaction","detail":"{read_only:false; response_revision:25515; number_of_response:1; }","duration":"170.994669ms","start":"2026-05-26T21:59:43.524812Z","end":"2026-05-26T21:59:43.695806Z","steps":["trace[102156546] 'process raft request' (duration: 170.716891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:43.696563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.895162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:59:43.696592Z","caller":"traceutil/trace.go:171","msg":"trace[799998725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25519; }","duration":"115.988945ms","start":"2026-05-26T21:59:43.580596Z","end":"2026-05-26T21:59:43.696585Z","steps":["trace[799998725] 'agreement among raft nodes before linearized reading' (duration: 115.904532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:43.696733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.402816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418304"} {"level":"info","ts":"2026-05-26T21:59:43.696791Z","caller":"traceutil/trace.go:171","msg":"trace[1081760902] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25519; }","duration":"162.505679ms","start":"2026-05-26T21:59:43.53427Z","end":"2026-05-26T21:59:43.696775Z","steps":["trace[1081760902] 'agreement among raft nodes before linearized reading' (duration: 162.20734ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:43.875401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.474188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:521"} {"level":"info","ts":"2026-05-26T21:59:43.875494Z","caller":"traceutil/trace.go:171","msg":"trace[1140466747] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:25520; }","duration":"111.637223ms","start":"2026-05-26T21:59:43.763835Z","end":"2026-05-26T21:59:43.875472Z","steps":["trace[1140466747] 'range keys from in-memory index tree' (duration: 111.265772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:43.875843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.705949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T21:59:43.875962Z","caller":"traceutil/trace.go:171","msg":"trace[794033025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25520; }","duration":"131.928575ms","start":"2026-05-26T21:59:43.744016Z","end":"2026-05-26T21:59:43.875944Z","steps":["trace[794033025] 'range keys from in-memory index tree' (duration: 131.478642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:43.876461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.225515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-26T21:59:43.876572Z","caller":"traceutil/trace.go:171","msg":"trace[539472345] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25520; }","duration":"131.37498ms","start":"2026-05-26T21:59:43.745173Z","end":"2026-05-26T21:59:43.876548Z","steps":["trace[539472345] 'range keys from in-memory index tree' (duration: 130.798322ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:44.008136Z","caller":"traceutil/trace.go:171","msg":"trace[718052385] transaction","detail":"{read_only:false; response_revision:25521; number_of_response:1; }","duration":"127.903711ms","start":"2026-05-26T21:59:43.880203Z","end":"2026-05-26T21:59:44.008106Z","steps":["trace[718052385] 'process raft request' (duration: 127.713206ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:44.318881Z","caller":"traceutil/trace.go:171","msg":"trace[1876945915] linearizableReadLoop","detail":"{readStateIndex:26357; appliedIndex:26355; }","duration":"436.172635ms","start":"2026-05-26T21:59:43.882685Z","end":"2026-05-26T21:59:44.318858Z","steps":["trace[1876945915] 'read index received' (duration: 125.305838ms)","trace[1876945915] 'applied index is now lower than readState.Index' (duration: 310.865967ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:44.319052Z","caller":"traceutil/trace.go:171","msg":"trace[2002843517] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"437.912885ms","start":"2026-05-26T21:59:43.881037Z","end":"2026-05-26T21:59:44.31895Z","steps":["trace[2002843517] 'process raft request' (duration: 437.679408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:44.319116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.387371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:59:44.319253Z","caller":"traceutil/trace.go:171","msg":"trace[1104419127] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25522; }","duration":"436.535976ms","start":"2026-05-26T21:59:43.882673Z","end":"2026-05-26T21:59:44.319209Z","steps":["trace[1104419127] 'agreement among raft nodes before linearized reading' (duration: 436.275578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:44.319328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:59:43.882664Z","time spent":"436.64686ms","remote":"127.0.0.1:47928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-05-26T21:59:44.319376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:59:43.881014Z","time spent":"438.226605ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:59:44.614775Z","caller":"traceutil/trace.go:171","msg":"trace[1743174513] linearizableReadLoop","detail":"{readStateIndex:26358; appliedIndex:26357; }","duration":"295.812674ms","start":"2026-05-26T21:59:44.318934Z","end":"2026-05-26T21:59:44.614747Z","steps":["trace[1743174513] 'read index received' (duration: 284.176095ms)","trace[1743174513] 'applied index is now lower than readState.Index' (duration: 11.635429ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T21:59:44.614861Z","caller":"traceutil/trace.go:171","msg":"trace[1058442799] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"487.879616ms","start":"2026-05-26T21:59:44.12692Z","end":"2026-05-26T21:59:44.6148Z","steps":["trace[1058442799] 'process raft request' (duration: 476.33461ms)","trace[1058442799] 'compare' (duration: 11.190806ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T21:59:44.614966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.666608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:59:44.615015Z","caller":"traceutil/trace.go:171","msg":"trace[1171143423] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:25523; }","duration":"437.778161ms","start":"2026-05-26T21:59:44.177222Z","end":"2026-05-26T21:59:44.615Z","steps":["trace[1171143423] 'agreement among raft nodes before linearized reading' (duration: 437.650137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:44.615054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:59:44.177201Z","time spent":"437.841593ms","remote":"127.0.0.1:46792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:59:44.615049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:59:44.126882Z","time spent":"488.064601ms","remote":"127.0.0.1:34838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418362,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:59:44.615193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.056174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:59:44.615278Z","caller":"traceutil/trace.go:171","msg":"trace[2094200011] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:25523; }","duration":"532.190718ms","start":"2026-05-26T21:59:44.08307Z","end":"2026-05-26T21:59:44.61526Z","steps":["trace[2094200011] 'agreement among raft nodes before linearized reading' (duration: 532.033183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:44.615334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:59:44.08305Z","time spent":"532.262969ms","remote":"127.0.0.1:47266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-26T21:59:44.615446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.156421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:59:44.615543Z","caller":"traceutil/trace.go:171","msg":"trace[1586833192] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:25523; }","duration":"518.526331ms","start":"2026-05-26T21:59:44.09699Z","end":"2026-05-26T21:59:44.615517Z","steps":["trace[1586833192] 'agreement among raft nodes before linearized reading' (duration: 518.103339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:44.615592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:59:44.096978Z","time spent":"518.599914ms","remote":"127.0.0.1:44536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true "} {"level":"info","ts":"2026-05-26T21:59:44.655384Z","caller":"traceutil/trace.go:171","msg":"trace[2142191975] transaction","detail":"{read_only:false; response_revision:25525; number_of_response:1; }","duration":"310.318213ms","start":"2026-05-26T21:59:44.345036Z","end":"2026-05-26T21:59:44.655354Z","steps":["trace[2142191975] 'process raft request' (duration: 310.21409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:44.655452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.958878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:59:44.655484Z","caller":"traceutil/trace.go:171","msg":"trace[524960573] transaction","detail":"{read_only:false; response_revision:25524; number_of_response:1; }","duration":"319.300426ms","start":"2026-05-26T21:59:44.336158Z","end":"2026-05-26T21:59:44.655459Z","steps":["trace[524960573] 'process raft request' (duration: 318.946195ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:44.655515Z","caller":"traceutil/trace.go:171","msg":"trace[306676875] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25525; }","duration":"160.03865ms","start":"2026-05-26T21:59:44.495458Z","end":"2026-05-26T21:59:44.655496Z","steps":["trace[306676875] 'agreement among raft nodes before linearized reading' (duration: 159.917076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:44.65552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.299699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:59:44.655599Z","caller":"traceutil/trace.go:171","msg":"trace[1626832728] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:25525; }","duration":"289.434113ms","start":"2026-05-26T21:59:44.366147Z","end":"2026-05-26T21:59:44.655581Z","steps":["trace[1626832728] 'agreement among raft nodes before linearized reading' (duration: 289.280389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:44.655618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:59:44.336126Z","time spent":"319.431509ms","remote":"127.0.0.1:47808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":859,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T21:59:44.656223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.610366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T21:59:44.656285Z","caller":"traceutil/trace.go:171","msg":"trace[1909858066] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25525; }","duration":"331.531791ms","start":"2026-05-26T21:59:44.324735Z","end":"2026-05-26T21:59:44.656266Z","steps":["trace[1909858066] 'agreement among raft nodes before linearized reading' (duration: 330.594225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:44.656321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:59:44.32472Z","time spent":"331.591312ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-05-26T21:59:44.65632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:59:44.345012Z","time spent":"311.225177ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T21:59:44.889557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.696004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-26T21:59:44.889658Z","caller":"traceutil/trace.go:171","msg":"trace[1359288303] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25525; }","duration":"156.852689ms","start":"2026-05-26T21:59:44.732786Z","end":"2026-05-26T21:59:44.889639Z","steps":["trace[1359288303] 'count revisions from in-memory index tree' (duration: 156.585361ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T21:59:45.228059Z","caller":"traceutil/trace.go:171","msg":"trace[1855270992] transaction","detail":"{read_only:false; response_revision:25528; number_of_response:1; }","duration":"333.482246ms","start":"2026-05-26T21:59:44.894553Z","end":"2026-05-26T21:59:45.228035Z","steps":["trace[1855270992] 'process raft request' (duration: 333.434575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:45.228212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:59:44.894534Z","time spent":"333.60198ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T21:59:45.228364Z","caller":"traceutil/trace.go:171","msg":"trace[226348586] transaction","detail":"{read_only:false; response_revision:25527; number_of_response:1; }","duration":"333.823536ms","start":"2026-05-26T21:59:44.894527Z","end":"2026-05-26T21:59:45.22835Z","steps":["trace[226348586] 'process raft request' (duration: 333.312492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T21:59:45.228417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T21:59:44.894503Z","time spent":"333.887878ms","remote":"127.0.0.1:48014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T22:00:03.564312Z","caller":"traceutil/trace.go:171","msg":"trace[554833356] transaction","detail":"{read_only:false; response_revision:25716; number_of_response:1; }","duration":"141.929177ms","start":"2026-05-26T22:00:03.422359Z","end":"2026-05-26T22:00:03.564288Z","steps":["trace[554833356] 'process raft request' (duration: 141.775502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T22:00:03.924022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.996243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend\" ","response":"range_response_count:1 size:1042"} {"level":"info","ts":"2026-05-26T22:00:03.924114Z","caller":"traceutil/trace.go:171","msg":"trace[2138069953] range","detail":"{range_begin:/registry/serviceaccounts/ingress-nginx/ingress-nginx-backend; range_end:; response_count:1; response_revision:25716; }","duration":"236.163168ms","start":"2026-05-26T22:00:03.687924Z","end":"2026-05-26T22:00:03.924088Z","steps":["trace[2138069953] 'range keys from in-memory index tree' (duration: 235.724796ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T22:00:03.92419Z","caller":"traceutil/trace.go:171","msg":"trace[1650123679] transaction","detail":"{read_only:false; response_revision:25717; number_of_response:1; }","duration":"159.00225ms","start":"2026-05-26T22:00:03.765169Z","end":"2026-05-26T22:00:03.924171Z","steps":["trace[1650123679] 'process raft request' (duration: 157.733724ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T22:00:03.964119Z","caller":"traceutil/trace.go:171","msg":"trace[1464364770] transaction","detail":"{read_only:false; response_revision:25718; number_of_response:1; }","duration":"189.527522ms","start":"2026-05-26T22:00:03.774562Z","end":"2026-05-26T22:00:03.964089Z","steps":["trace[1464364770] 'process raft request' (duration: 189.315126ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T22:00:03.964483Z","caller":"traceutil/trace.go:171","msg":"trace[608864319] linearizableReadLoop","detail":"{readStateIndex:26560; appliedIndex:26555; }","duration":"149.054548ms","start":"2026-05-26T22:00:03.815409Z","end":"2026-05-26T22:00:03.964464Z","steps":["trace[608864319] 'read index received' (duration: 107.478844ms)","trace[608864319] 'applied index is now lower than readState.Index' (duration: 41.574424ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T22:00:03.964569Z","caller":"traceutil/trace.go:171","msg":"trace[2093230694] transaction","detail":"{read_only:false; response_revision:25721; number_of_response:1; }","duration":"189.238693ms","start":"2026-05-26T22:00:03.775314Z","end":"2026-05-26T22:00:03.964553Z","steps":["trace[2093230694] 'process raft request' (duration: 189.096169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T22:00:03.96477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.342936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-26T22:00:03.964787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.546951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-26T22:00:03.964826Z","caller":"traceutil/trace.go:171","msg":"trace[554869196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25721; }","duration":"149.447489ms","start":"2026-05-26T22:00:03.815365Z","end":"2026-05-26T22:00:03.964812Z","steps":["trace[554869196] 'agreement among raft nodes before linearized reading' (duration: 149.293335ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T22:00:03.96488Z","caller":"traceutil/trace.go:171","msg":"trace[120300017] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:25721; }","duration":"144.677804ms","start":"2026-05-26T22:00:03.820178Z","end":"2026-05-26T22:00:03.964856Z","steps":["trace[120300017] 'agreement among raft nodes before linearized reading' (duration: 144.466148ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T22:00:03.964583Z","caller":"traceutil/trace.go:171","msg":"trace[1792575393] transaction","detail":"{read_only:false; response_revision:25720; number_of_response:1; }","duration":"189.797868ms","start":"2026-05-26T22:00:03.774755Z","end":"2026-05-26T22:00:03.964553Z","steps":["trace[1792575393] 'process raft request' (duration: 189.563061ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T22:00:03.965435Z","caller":"traceutil/trace.go:171","msg":"trace[480652378] transaction","detail":"{read_only:false; response_revision:25719; number_of_response:1; }","duration":"189.854891ms","start":"2026-05-26T22:00:03.774697Z","end":"2026-05-26T22:00:03.964551Z","steps":["trace[480652378] 'process raft request' (duration: 189.348217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T22:00:04.224332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.591495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-26T22:00:04.224788Z","caller":"traceutil/trace.go:171","msg":"trace[1247883853] transaction","detail":"{read_only:false; response_revision:25722; number_of_response:1; }","duration":"255.451342ms","start":"2026-05-26T22:00:03.969275Z","end":"2026-05-26T22:00:04.224726Z","steps":["trace[1247883853] 'process raft request' (duration: 100.358604ms)","trace[1247883853] 'compare' (duration: 154.327397ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T22:00:04.432823Z","caller":"traceutil/trace.go:171","msg":"trace[156962325] transaction","detail":"{read_only:false; response_revision:25723; number_of_response:1; }","duration":"202.979171ms","start":"2026-05-26T22:00:04.229788Z","end":"2026-05-26T22:00:04.432768Z","steps":["trace[156962325] 'process raft request' (duration: 176.173674ms)","trace[156962325] 'compare' (duration: 26.378175ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T22:00:13.9581Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22928} {"level":"info","ts":"2026-05-26T22:00:14.097793Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22928,"took":"135.072634ms","hash":2017667552,"current-db-size-bytes":58851328,"current-db-size":"59 MB","current-db-size-in-use-bytes":35618816,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-26T22:00:14.09793Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2017667552,"revision":22928,"compact-revision":20060} {"level":"info","ts":"2026-05-26T22:00:37.198056Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069a0.wal"} {"level":"info","ts":"2026-05-26T22:05:13.965587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25967} {"level":"info","ts":"2026-05-26T22:05:14.116639Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25967,"took":"146.912005ms","hash":1735474299,"current-db-size-bytes":58851328,"current-db-size":"59 MB","current-db-size-in-use-bytes":35647488,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-05-26T22:05:14.116716Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1735474299,"revision":25967,"compact-revision":22928} {"level":"info","ts":"2026-05-26T22:05:58.862856Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":30005,"local-member-snapshot-index":20004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T22:05:58.871289Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30005} {"level":"info","ts":"2026-05-26T22:05:58.871455Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25005} {"level":"info","ts":"2026-05-26T22:07:44.58682Z","caller":"traceutil/trace.go:171","msg":"trace[1861986864] transaction","detail":"{read_only:false; response_revision:29846; number_of_response:1; }","duration":"224.524042ms","start":"2026-05-26T22:07:44.362266Z","end":"2026-05-26T22:07:44.58679Z","steps":["trace[1861986864] 'process raft request' (duration: 223.826871ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T22:10:13.975578Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28749} {"level":"info","ts":"2026-05-26T22:10:14.119868Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28749,"took":"137.552371ms","hash":2207071489,"current-db-size-bytes":58851328,"current-db-size":"59 MB","current-db-size-in-use-bytes":30937088,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-26T22:10:14.119945Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2207071489,"revision":28749,"compact-revision":25967} {"level":"info","ts":"2026-05-26T22:15:13.983274Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30969} {"level":"info","ts":"2026-05-26T22:15:14.103984Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30969,"took":"116.281879ms","hash":4259830620,"current-db-size-bytes":58851328,"current-db-size":"59 MB","current-db-size-in-use-bytes":30203904,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-26T22:15:14.104306Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4259830620,"revision":30969,"compact-revision":28749} {"level":"info","ts":"2026-05-26T22:20:13.997597Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33236} {"level":"info","ts":"2026-05-26T22:20:14.152945Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33236,"took":"150.788535ms","hash":3891944241,"current-db-size-bytes":60313600,"current-db-size":"60 MB","current-db-size-in-use-bytes":31948800,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-26T22:20:14.153009Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3891944241,"revision":33236,"compact-revision":30969} {"level":"info","ts":"2026-05-26T22:25:14.008089Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35534} {"level":"info","ts":"2026-05-26T22:25:14.145446Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35534,"took":"133.348223ms","hash":2380955415,"current-db-size-bytes":61157376,"current-db-size":"61 MB","current-db-size-in-use-bytes":31285248,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-26T22:25:14.145506Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2380955415,"revision":35534,"compact-revision":33236} {"level":"info","ts":"2026-05-26T22:27:29.811856Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2d372260bb4c37a","local-member-applied-index":40006,"local-member-snapshot-index":30005,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-26T22:27:29.816626Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40006} {"level":"info","ts":"2026-05-26T22:27:29.81688Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35006} {"level":"info","ts":"2026-05-26T22:30:14.014105Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37764} {"level":"info","ts":"2026-05-26T22:30:14.136662Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37764,"took":"117.65885ms","hash":1030663196,"current-db-size-bytes":61157376,"current-db-size":"61 MB","current-db-size-in-use-bytes":32096256,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-26T22:30:14.136716Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1030663196,"revision":37764,"compact-revision":35534}