{"level":"warn","ts":"2026-04-02T21:24:07.637416Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T21:24:07.637531Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.67:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.67:2380","--initial-cluster=instance=https://199.19.213.67:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.67:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.67:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-02T21:24:07.63762Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T21:24:07.637639Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.67:2380"]} {"level":"info","ts":"2026-04-02T21:24:07.63766Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-02T21:24:07.638473Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.67:2379"]} {"level":"info","ts":"2026-04-02T21:24:07.638584Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.67:2380"],"listen-peer-urls":["https://199.19.213.67:2380"],"advertise-client-urls":["https://199.19.213.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.67:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-02T21:24:07.64331Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.387986ms"} {"level":"info","ts":"2026-04-02T21:24:07.651037Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fba6d6d8cd5f215c","cluster-id":"f3c660457a1c2c72"} {"level":"info","ts":"2026-04-02T21:24:07.651137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T21:24:07.651161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became follower at term 0"} {"level":"info","ts":"2026-04-02T21:24:07.651179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fba6d6d8cd5f215c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T21:24:07.651194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became follower at term 1"} {"level":"info","ts":"2026-04-02T21:24:07.651247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c switched to configuration voters=(18133417176300724572)"} {"level":"warn","ts":"2026-04-02T21:24:07.658131Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T21:24:07.659319Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T21:24:07.660382Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-02T21:24:07.6632Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fba6d6d8cd5f215c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T21:24:07.663327Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fba6d6d8cd5f215c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T21:24:07.663577Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T21:24:07.66367Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T21:24:07.663711Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T21:24:07.665651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c switched to configuration voters=(18133417176300724572)"} {"level":"info","ts":"2026-04-02T21:24:07.66765Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f3c660457a1c2c72","local-member-id":"fba6d6d8cd5f215c","added-peer-id":"fba6d6d8cd5f215c","added-peer-peer-urls":["https://199.19.213.67:2380"]} {"level":"info","ts":"2026-04-02T21:24:07.669396Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-02T21:24:07.669519Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.67:2380"} {"level":"info","ts":"2026-04-02T21:24:07.66956Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.67:2380"} {"level":"info","ts":"2026-04-02T21:24:07.669736Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fba6d6d8cd5f215c","initial-advertise-peer-urls":["https://199.19.213.67:2380"],"listen-peer-urls":["https://199.19.213.67:2380"],"advertise-client-urls":["https://199.19.213.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T21:24:07.669796Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T21:24:07.851908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T21:24:07.852022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T21:24:07.852087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c received MsgPreVoteResp from fba6d6d8cd5f215c at term 1"} {"level":"info","ts":"2026-04-02T21:24:07.852118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became candidate at term 2"} {"level":"info","ts":"2026-04-02T21:24:07.852141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c received MsgVoteResp from fba6d6d8cd5f215c at term 2"} {"level":"info","ts":"2026-04-02T21:24:07.852188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became leader at term 2"} {"level":"info","ts":"2026-04-02T21:24:07.85222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fba6d6d8cd5f215c elected leader fba6d6d8cd5f215c at term 2"} {"level":"info","ts":"2026-04-02T21:24:07.853642Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fba6d6d8cd5f215c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.67:2379]}","request-path":"/0/members/fba6d6d8cd5f215c/attributes","cluster-id":"f3c660457a1c2c72","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T21:24:07.853668Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T21:24:07.853735Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T21:24:07.854045Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T21:24:07.854162Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T21:24:07.854049Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T21:24:07.855154Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f3c660457a1c2c72","local-member-id":"fba6d6d8cd5f215c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T21:24:07.855313Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T21:24:07.855357Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T21:24:07.856294Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T21:24:07.856294Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.67:2379"} {"level":"warn","ts":"2026-04-02T21:24:22.399307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.93173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:24:22.399395Z","caller":"traceutil/trace.go:171","msg":"trace[1346882679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:368; }","duration":"114.071643ms","start":"2026-04-02T21:24:22.285309Z","end":"2026-04-02T21:24:22.399381Z","steps":["trace[1346882679] 'range keys from in-memory index tree' (duration: 113.867288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:44.616919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.988645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T21:24:44.617131Z","caller":"traceutil/trace.go:171","msg":"trace[493259130] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"226.683493ms","start":"2026-04-02T21:24:44.390432Z","end":"2026-04-02T21:24:44.617116Z","steps":["trace[493259130] 'process raft request' (duration: 226.612532ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:44.617177Z","caller":"traceutil/trace.go:171","msg":"trace[152923526] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"310.81966ms","start":"2026-04-02T21:24:44.306322Z","end":"2026-04-02T21:24:44.617142Z","steps":["trace[152923526] 'read index received' (duration: 54.18698ms)","trace[152923526] 'applied index is now lower than readState.Index' (duration: 256.6298ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:24:44.617318Z","caller":"traceutil/trace.go:171","msg":"trace[1827535936] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"314.267264ms","start":"2026-04-02T21:24:44.303036Z","end":"2026-04-02T21:24:44.617303Z","steps":["trace[1827535936] 'process raft request' (duration: 57.41241ms)","trace[1827535936] 'compare' (duration: 255.804522ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:24:44.617424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:44.303017Z","time spent":"314.337315ms","remote":"127.0.0.1:38746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T21:24:44.617726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.850429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-02T21:24:44.617772Z","caller":"traceutil/trace.go:171","msg":"trace[774230467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"309.921311ms","start":"2026-04-02T21:24:44.307839Z","end":"2026-04-02T21:24:44.617761Z","steps":["trace[774230467] 'agreement among raft nodes before linearized reading' (duration: 309.775568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:44.617804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:44.307826Z","time spent":"309.969481ms","remote":"127.0.0.1:38474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T21:24:44.61788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.564688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-02T21:24:44.61793Z","caller":"traceutil/trace.go:171","msg":"trace[1387849087] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"237.64227ms","start":"2026-04-02T21:24:44.380269Z","end":"2026-04-02T21:24:44.617912Z","steps":["trace[1387849087] 'agreement among raft nodes before linearized reading' (duration: 237.475066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:44.61812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.783161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-02T21:24:44.618216Z","caller":"traceutil/trace.go:171","msg":"trace[87876792] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"311.947235ms","start":"2026-04-02T21:24:44.306244Z","end":"2026-04-02T21:24:44.618191Z","steps":["trace[87876792] 'agreement among raft nodes before linearized reading' (duration: 311.686919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:44.618345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:44.306206Z","time spent":"312.125838ms","remote":"127.0.0.1:38474","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":"info","ts":"2026-04-02T21:24:44.92843Z","caller":"traceutil/trace.go:171","msg":"trace[1011246655] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"304.688477ms","start":"2026-04-02T21:24:44.623704Z","end":"2026-04-02T21:24:44.928392Z","steps":["trace[1011246655] 'process raft request' (duration: 239.343806ms)","trace[1011246655] 'compare' (duration: 65.148786ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:24:44.928821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:44.623684Z","time spent":"304.839661ms","remote":"127.0.0.1:38474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:24:45.004887Z","caller":"traceutil/trace.go:171","msg":"trace[1234169831] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"381.075686ms","start":"2026-04-02T21:24:44.623784Z","end":"2026-04-02T21:24:45.00486Z","steps":["trace[1234169831] 'process raft request' (duration: 380.864801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:45.005041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:44.623763Z","time spent":"381.200229ms","remote":"127.0.0.1:38474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:24:45.004888Z","caller":"traceutil/trace.go:171","msg":"trace[272849162] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"379.966773ms","start":"2026-04-02T21:24:44.624897Z","end":"2026-04-02T21:24:45.004864Z","steps":["trace[272849162] 'process raft request' (duration: 379.84306ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:45.005131Z","caller":"traceutil/trace.go:171","msg":"trace[1605895260] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"367.744628ms","start":"2026-04-02T21:24:44.637354Z","end":"2026-04-02T21:24:45.005098Z","steps":["trace[1605895260] 'process raft request' (duration: 367.437831ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:24:45.005064Z","caller":"traceutil/trace.go:171","msg":"trace[931172320] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:600; }","duration":"377.373006ms","start":"2026-04-02T21:24:44.627662Z","end":"2026-04-02T21:24:45.005035Z","steps":["trace[931172320] 'read index received' (duration: 235.032503ms)","trace[931172320] 'applied index is now lower than readState.Index' (duration: 142.338303ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:24:45.005254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:44.624877Z","time spent":"380.281869ms","remote":"127.0.0.1:38474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:24:45.005286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.619362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T21:24:45.005297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:44.637324Z","time spent":"367.890661ms","remote":"127.0.0.1:38396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T21:24:45.005331Z","caller":"traceutil/trace.go:171","msg":"trace[1202752965] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:582; }","duration":"377.682823ms","start":"2026-04-02T21:24:44.627632Z","end":"2026-04-02T21:24:45.005315Z","steps":["trace[1202752965] 'agreement among raft nodes before linearized reading' (duration: 377.619422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:24:45.005363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:24:44.627624Z","time spent":"377.731874ms","remote":"127.0.0.1:38448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-02T21:25:12.035879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.659951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.67\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-02T21:25:12.03602Z","caller":"traceutil/trace.go:171","msg":"trace[1379142024] range","detail":"{range_begin:/registry/masterleases/199.19.213.67; range_end:; response_count:1; response_revision:775; }","duration":"137.852885ms","start":"2026-04-02T21:25:11.89814Z","end":"2026-04-02T21:25:12.035992Z","steps":["trace[1379142024] 'range keys from in-memory index tree' (duration: 137.376295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:25:40.57279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.013846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:25:40.572876Z","caller":"traceutil/trace.go:171","msg":"trace[928221910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1169; }","duration":"287.144019ms","start":"2026-04-02T21:25:40.285716Z","end":"2026-04-02T21:25:40.57286Z","steps":["trace[928221910] 'range keys from in-memory index tree' (duration: 286.931184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:25.770845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.145762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-02T21:26:25.770989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.934194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-02T21:26:25.771049Z","caller":"traceutil/trace.go:171","msg":"trace[950718906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1822; }","duration":"292.034595ms","start":"2026-04-02T21:26:25.479Z","end":"2026-04-02T21:26:25.771034Z","steps":["trace[950718906] 'range keys from in-memory index tree' (duration: 291.778511ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:25.770964Z","caller":"traceutil/trace.go:171","msg":"trace[360891506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1822; }","duration":"282.296785ms","start":"2026-04-02T21:26:25.488645Z","end":"2026-04-02T21:26:25.770942Z","steps":["trace[360891506] 'range keys from in-memory index tree' (duration: 282.035489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:25.770861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.854808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-860842c6-eddd-4994-9201-2e549ba29ec1\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-04-02T21:26:25.771197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.539242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-02T21:26:25.771278Z","caller":"traceutil/trace.go:171","msg":"trace[917110582] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-860842c6-eddd-4994-9201-2e549ba29ec1; range_end:; response_count:1; response_revision:1822; }","duration":"407.308327ms","start":"2026-04-02T21:26:25.363935Z","end":"2026-04-02T21:26:25.771243Z","steps":["trace[917110582] 'range keys from in-memory index tree' (duration: 406.654563ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:25.771305Z","caller":"traceutil/trace.go:171","msg":"trace[1543957155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1822; }","duration":"289.696235ms","start":"2026-04-02T21:26:25.481584Z","end":"2026-04-02T21:26:25.77128Z","steps":["trace[1543957155] 'range keys from in-memory index tree' (duration: 289.375098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:25.771395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:26:25.363914Z","time spent":"407.45128ms","remote":"127.0.0.1:38396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-860842c6-eddd-4994-9201-2e549ba29ec1\" "} {"level":"info","ts":"2026-04-02T21:26:31.234206Z","caller":"traceutil/trace.go:171","msg":"trace[1946912745] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"116.527417ms","start":"2026-04-02T21:26:31.117643Z","end":"2026-04-02T21:26:31.234171Z","steps":["trace[1946912745] 'process raft request' (duration: 93.046238ms)","trace[1946912745] 'compare' (duration: 23.239114ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:26:31.441889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.325431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T21:26:31.442082Z","caller":"traceutil/trace.go:171","msg":"trace[2123961631] transaction","detail":"{read_only:false; response_revision:1898; number_of_response:1; }","duration":"202.933274ms","start":"2026-04-02T21:26:31.239133Z","end":"2026-04-02T21:26:31.442067Z","steps":["trace[2123961631] 'process raft request' (duration: 202.864001ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:31.442148Z","caller":"traceutil/trace.go:171","msg":"trace[1726608003] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"203.661139ms","start":"2026-04-02T21:26:31.238458Z","end":"2026-04-02T21:26:31.442119Z","steps":["trace[1726608003] 'process raft request' (duration: 100.031621ms)","trace[1726608003] 'compare' (duration: 103.188627ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:26:31.44244Z","caller":"traceutil/trace.go:171","msg":"trace[295553299] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"203.003655ms","start":"2026-04-02T21:26:31.239417Z","end":"2026-04-02T21:26:31.442421Z","steps":["trace[295553299] 'process raft request' (duration: 202.635756ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:26:31.442571Z","caller":"traceutil/trace.go:171","msg":"trace[356098405] linearizableReadLoop","detail":"{readStateIndex:1956; appliedIndex:1952; }","duration":"156.023065ms","start":"2026-04-02T21:26:31.286537Z","end":"2026-04-02T21:26:31.44256Z","steps":["trace[356098405] 'read index received' (duration: 51.965238ms)","trace[356098405] 'applied index is now lower than readState.Index' (duration: 104.057037ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:26:31.4426Z","caller":"traceutil/trace.go:171","msg":"trace[363314668] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"201.8851ms","start":"2026-04-02T21:26:31.240708Z","end":"2026-04-02T21:26:31.442593Z","steps":["trace[363314668] 'process raft request' (duration: 201.669165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:26:31.442631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.097657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:26:31.442662Z","caller":"traceutil/trace.go:171","msg":"trace[716555660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1900; }","duration":"156.156408ms","start":"2026-04-02T21:26:31.286496Z","end":"2026-04-02T21:26:31.442653Z","steps":["trace[716555660] 'agreement among raft nodes before linearized reading' (duration: 156.105417ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:27:07.923412Z","caller":"traceutil/trace.go:171","msg":"trace[954713183] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"154.863849ms","start":"2026-04-02T21:27:07.768521Z","end":"2026-04-02T21:27:07.923385Z","steps":["trace[954713183] 'process raft request' (duration: 154.683075ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:27:08.110791Z","caller":"traceutil/trace.go:171","msg":"trace[1567593233] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"112.331961ms","start":"2026-04-02T21:27:07.99843Z","end":"2026-04-02T21:27:08.110762Z","steps":["trace[1567593233] 'process raft request' (duration: 112.171438ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:27:08.181129Z","caller":"traceutil/trace.go:171","msg":"trace[1835803159] linearizableReadLoop","detail":"{readStateIndex:2233; appliedIndex:2231; }","duration":"178.804144ms","start":"2026-04-02T21:27:08.002286Z","end":"2026-04-02T21:27:08.18109Z","steps":["trace[1835803159] 'read index received' (duration: 108.383848ms)","trace[1835803159] 'applied index is now lower than readState.Index' (duration: 70.419136ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:27:08.181231Z","caller":"traceutil/trace.go:171","msg":"trace[291778995] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"182.522383ms","start":"2026-04-02T21:27:07.998698Z","end":"2026-04-02T21:27:08.18122Z","steps":["trace[291778995] 'process raft request' (duration: 182.256317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:27:08.181621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.291544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-02T21:27:08.181748Z","caller":"traceutil/trace.go:171","msg":"trace[675242251] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2162; }","duration":"179.464938ms","start":"2026-04-02T21:27:08.00223Z","end":"2026-04-02T21:27:08.181695Z","steps":["trace[675242251] 'agreement among raft nodes before linearized reading' (duration: 179.12001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:27:08.230924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.531414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-02T21:27:08.231008Z","caller":"traceutil/trace.go:171","msg":"trace[1784971208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2164; }","duration":"228.664297ms","start":"2026-04-02T21:27:08.002327Z","end":"2026-04-02T21:27:08.230991Z","steps":["trace[1784971208] 'agreement among raft nodes before linearized reading' (duration: 228.306129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:27:08.231093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.657788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-02T21:27:08.231554Z","caller":"traceutil/trace.go:171","msg":"trace[1916620590] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2164; }","duration":"211.975394ms","start":"2026-04-02T21:27:08.019321Z","end":"2026-04-02T21:27:08.231297Z","steps":["trace[1916620590] 'agreement among raft nodes before linearized reading' (duration: 211.3218ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:27:08.345377Z","caller":"traceutil/trace.go:171","msg":"trace[553867992] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"109.41396ms","start":"2026-04-02T21:27:08.235944Z","end":"2026-04-02T21:27:08.345358Z","steps":["trace[553867992] 'process raft request' (duration: 109.251496ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:28:20.752007Z","caller":"traceutil/trace.go:171","msg":"trace[136356653] transaction","detail":"{read_only:false; response_revision:2714; number_of_response:1; }","duration":"126.139494ms","start":"2026-04-02T21:28:20.625811Z","end":"2026-04-02T21:28:20.75195Z","steps":["trace[136356653] 'process raft request' (duration: 125.837907ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:28:26.1274Z","caller":"traceutil/trace.go:171","msg":"trace[699898564] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"112.781619ms","start":"2026-04-02T21:28:26.014583Z","end":"2026-04-02T21:28:26.127365Z","steps":["trace[699898564] 'process raft request' (duration: 112.610465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:28:26.245794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.08868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:552"} {"level":"info","ts":"2026-04-02T21:28:26.245906Z","caller":"traceutil/trace.go:171","msg":"trace[1357872967] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2747; }","duration":"115.261603ms","start":"2026-04-02T21:28:26.130623Z","end":"2026-04-02T21:28:26.245884Z","steps":["trace[1357872967] 'range keys from in-memory index tree' (duration: 114.899505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:29:28.095092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.98099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T21:29:28.095198Z","caller":"traceutil/trace.go:171","msg":"trace[1316870730] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:3338; }","duration":"130.345148ms","start":"2026-04-02T21:29:27.964835Z","end":"2026-04-02T21:29:28.09518Z","steps":["trace[1316870730] 'count revisions from in-memory index tree' (duration: 129.883839ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:30:38.23675Z","caller":"traceutil/trace.go:171","msg":"trace[1017775947] linearizableReadLoop","detail":"{readStateIndex:4525; appliedIndex:4524; }","duration":"104.170082ms","start":"2026-04-02T21:30:38.132559Z","end":"2026-04-02T21:30:38.236729Z","steps":["trace[1017775947] 'read index received' (duration: 97.675303ms)","trace[1017775947] 'applied index is now lower than readState.Index' (duration: 6.493959ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:30:38.236806Z","caller":"traceutil/trace.go:171","msg":"trace[505276023] transaction","detail":"{read_only:false; response_revision:4344; number_of_response:1; }","duration":"115.158047ms","start":"2026-04-02T21:30:38.121605Z","end":"2026-04-02T21:30:38.236763Z","steps":["trace[505276023] 'process raft request' (duration: 108.652207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:30:38.237115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.520128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-04-02T21:30:38.237209Z","caller":"traceutil/trace.go:171","msg":"trace[955522720] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4344; }","duration":"104.660941ms","start":"2026-04-02T21:30:38.132528Z","end":"2026-04-02T21:30:38.237189Z","steps":["trace[955522720] 'agreement among raft nodes before linearized reading' (duration: 104.311225ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:30:42.63453Z","caller":"traceutil/trace.go:171","msg":"trace[654824441] linearizableReadLoop","detail":"{readStateIndex:4715; appliedIndex:4713; }","duration":"149.334012ms","start":"2026-04-02T21:30:42.485172Z","end":"2026-04-02T21:30:42.634506Z","steps":["trace[654824441] 'read index received' (duration: 72.883386ms)","trace[654824441] 'applied index is now lower than readState.Index' (duration: 76.446975ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:30:42.634663Z","caller":"traceutil/trace.go:171","msg":"trace[1532015239] transaction","detail":"{read_only:false; response_revision:4527; number_of_response:1; }","duration":"178.465282ms","start":"2026-04-02T21:30:42.456184Z","end":"2026-04-02T21:30:42.63465Z","steps":["trace[1532015239] 'process raft request' (duration: 178.258058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:30:42.634733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.548085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T21:30:42.63477Z","caller":"traceutil/trace.go:171","msg":"trace[1019627407] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:4527; }","duration":"149.636087ms","start":"2026-04-02T21:30:42.485126Z","end":"2026-04-02T21:30:42.634762Z","steps":["trace[1019627407] 'agreement among raft nodes before linearized reading' (duration: 149.541135ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:30:42.634805Z","caller":"traceutil/trace.go:171","msg":"trace[480967060] transaction","detail":"{read_only:false; response_revision:4526; number_of_response:1; }","duration":"178.669506ms","start":"2026-04-02T21:30:42.456107Z","end":"2026-04-02T21:30:42.634777Z","steps":["trace[480967060] 'process raft request' (duration: 101.977386ms)","trace[480967060] 'compare' (duration: 76.210232ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:31:04.023485Z","caller":"traceutil/trace.go:171","msg":"trace[587831050] linearizableReadLoop","detail":"{readStateIndex:5195; appliedIndex:5194; }","duration":"102.117562ms","start":"2026-04-02T21:31:03.921319Z","end":"2026-04-02T21:31:04.023436Z","steps":["trace[587831050] 'read index received' (duration: 101.996829ms)","trace[587831050] 'applied index is now lower than readState.Index' (duration: 119.503µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:31:04.02361Z","caller":"traceutil/trace.go:171","msg":"trace[1555971897] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"110.034235ms","start":"2026-04-02T21:31:03.913534Z","end":"2026-04-02T21:31:04.023569Z","steps":["trace[1555971897] 'process raft request' (duration: 109.737798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:31:04.023818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.454919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dxrsh\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-02T21:31:04.024048Z","caller":"traceutil/trace.go:171","msg":"trace[1080344994] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dxrsh; range_end:; response_count:1; response_revision:4989; }","duration":"102.725786ms","start":"2026-04-02T21:31:03.921302Z","end":"2026-04-02T21:31:04.024028Z","steps":["trace[1080344994] 'agreement among raft nodes before linearized reading' (duration: 102.317347ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:31:04.174699Z","caller":"traceutil/trace.go:171","msg":"trace[1519763787] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"144.803231ms","start":"2026-04-02T21:31:04.029872Z","end":"2026-04-02T21:31:04.174675Z","steps":["trace[1519763787] 'process raft request' (duration: 144.618597ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:31:04.322035Z","caller":"traceutil/trace.go:171","msg":"trace[2029769174] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"291.529695ms","start":"2026-04-02T21:31:04.030475Z","end":"2026-04-02T21:31:04.322005Z","steps":["trace[2029769174] 'process raft request' (duration: 282.788155ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:31:04.322158Z","caller":"traceutil/trace.go:171","msg":"trace[553340989] transaction","detail":"{read_only:false; response_revision:4992; number_of_response:1; }","duration":"290.934022ms","start":"2026-04-02T21:31:04.031178Z","end":"2026-04-02T21:31:04.322112Z","steps":["trace[553340989] 'process raft request' (duration: 290.739628ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:31:04.322458Z","caller":"traceutil/trace.go:171","msg":"trace[1431681794] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"290.750378ms","start":"2026-04-02T21:31:04.031691Z","end":"2026-04-02T21:31:04.322441Z","steps":["trace[1431681794] 'process raft request' (duration: 290.36599ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:31:04.322567Z","caller":"traceutil/trace.go:171","msg":"trace[1232994748] linearizableReadLoop","detail":"{readStateIndex:5199; appliedIndex:5196; }","duration":"144.176227ms","start":"2026-04-02T21:31:04.178303Z","end":"2026-04-02T21:31:04.322479Z","steps":["trace[1232994748] 'read index received' (duration: 134.964987ms)","trace[1232994748] 'applied index is now lower than readState.Index' (duration: 9.20667ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:31:04.3231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.788351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus.18a2a78dfce5a4e8\" ","response":"range_response_count:1 size:745"} {"level":"info","ts":"2026-04-02T21:31:04.323206Z","caller":"traceutil/trace.go:171","msg":"trace[1428241264] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus.18a2a78dfce5a4e8; range_end:; response_count:1; response_revision:4995; }","duration":"144.944043ms","start":"2026-04-02T21:31:04.178244Z","end":"2026-04-02T21:31:04.323188Z","steps":["trace[1428241264] 'agreement among raft nodes before linearized reading' (duration: 144.712808ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:31:04.323842Z","caller":"traceutil/trace.go:171","msg":"trace[1025836410] transaction","detail":"{read_only:false; response_revision:4994; number_of_response:1; }","duration":"142.590464ms","start":"2026-04-02T21:31:04.181228Z","end":"2026-04-02T21:31:04.323819Z","steps":["trace[1025836410] 'process raft request' (duration: 141.08318ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:31:14.873577Z","caller":"traceutil/trace.go:171","msg":"trace[1630669117] linearizableReadLoop","detail":"{readStateIndex:5333; appliedIndex:5332; }","duration":"400.677628ms","start":"2026-04-02T21:31:14.472859Z","end":"2026-04-02T21:31:14.873537Z","steps":["trace[1630669117] 'read index received' (duration: 338.1218ms)","trace[1630669117] 'applied index is now lower than readState.Index' (duration: 62.553958ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:31:14.873634Z","caller":"traceutil/trace.go:171","msg":"trace[1933565502] transaction","detail":"{read_only:false; response_revision:5123; number_of_response:1; }","duration":"409.766571ms","start":"2026-04-02T21:31:14.463822Z","end":"2026-04-02T21:31:14.873589Z","steps":["trace[1933565502] 'process raft request' (duration: 347.086731ms)","trace[1933565502] 'compare' (duration: 62.368718ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:31:14.873794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:31:14.463797Z","time spent":"409.904031ms","remote":"127.0.0.1:38474","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-04-02T21:31:14.873874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.98694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-02T21:31:14.873924Z","caller":"traceutil/trace.go:171","msg":"trace[1031681603] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5123; }","duration":"401.079961ms","start":"2026-04-02T21:31:14.472828Z","end":"2026-04-02T21:31:14.873908Z","steps":["trace[1031681603] 'agreement among raft nodes before linearized reading' (duration: 400.88931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:31:14.873978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:31:14.472814Z","time spent":"401.143001ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":669,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-02T21:31:14.874022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.656976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10515"} {"level":"warn","ts":"2026-04-02T21:31:14.874045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.419729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-02T21:31:14.8741Z","caller":"traceutil/trace.go:171","msg":"trace[777724253] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5123; }","duration":"356.737026ms","start":"2026-04-02T21:31:14.517343Z","end":"2026-04-02T21:31:14.87408Z","steps":["trace[777724253] 'agreement among raft nodes before linearized reading' (duration: 356.484285ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:31:14.874121Z","caller":"traceutil/trace.go:171","msg":"trace[1215722974] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5123; }","duration":"343.532729ms","start":"2026-04-02T21:31:14.530567Z","end":"2026-04-02T21:31:14.8741Z","steps":["trace[1215722974] 'agreement among raft nodes before linearized reading' (duration: 343.305048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:31:14.874149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:31:14.517268Z","time spent":"356.868636ms","remote":"127.0.0.1:38396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10539,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-04-02T21:31:14.87418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:31:14.530558Z","time spent":"343.608829ms","remote":"127.0.0.1:38360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-04-02T21:31:15.044892Z","caller":"traceutil/trace.go:171","msg":"trace[733103477] linearizableReadLoop","detail":"{readStateIndex:5334; appliedIndex:5333; }","duration":"162.397697ms","start":"2026-04-02T21:31:14.882469Z","end":"2026-04-02T21:31:15.044867Z","steps":["trace[733103477] 'read index received' (duration: 119.238746ms)","trace[733103477] 'applied index is now lower than readState.Index' (duration: 43.157821ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:31:15.045086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.597668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-98238ee4-d08c-4780-83af-d4fef45fbdc9\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-02T21:31:15.045115Z","caller":"traceutil/trace.go:171","msg":"trace[1424007038] range","detail":"{range_begin:/registry/persistentvolumes/pvc-98238ee4-d08c-4780-83af-d4fef45fbdc9; range_end:; response_count:1; response_revision:5124; }","duration":"162.666058ms","start":"2026-04-02T21:31:14.882439Z","end":"2026-04-02T21:31:15.045105Z","steps":["trace[1424007038] 'agreement among raft nodes before linearized reading' (duration: 162.514747ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:31:15.045245Z","caller":"traceutil/trace.go:171","msg":"trace[255250186] transaction","detail":"{read_only:false; response_revision:5124; number_of_response:1; }","duration":"163.406071ms","start":"2026-04-02T21:31:14.881787Z","end":"2026-04-02T21:31:15.045193Z","steps":["trace[255250186] 'process raft request' (duration: 119.911179ms)","trace[255250186] 'compare' (duration: 43.059191ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:31:15.051958Z","caller":"traceutil/trace.go:171","msg":"trace[554791229] transaction","detail":"{read_only:false; response_revision:5125; number_of_response:1; }","duration":"166.051869ms","start":"2026-04-02T21:31:14.885873Z","end":"2026-04-02T21:31:15.051924Z","steps":["trace[554791229] 'process raft request' (duration: 165.921949ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:31:15.18152Z","caller":"traceutil/trace.go:171","msg":"trace[594602309] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"121.448803ms","start":"2026-04-02T21:31:15.060049Z","end":"2026-04-02T21:31:15.181498Z","steps":["trace[594602309] 'process raft request' (duration: 121.348713ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:31:15.181512Z","caller":"traceutil/trace.go:171","msg":"trace[968104556] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"122.230496ms","start":"2026-04-02T21:31:15.059246Z","end":"2026-04-02T21:31:15.181477Z","steps":["trace[968104556] 'process raft request' (duration: 121.321933ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:31:15.182086Z","caller":"traceutil/trace.go:171","msg":"trace[1294850716] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"121.822316ms","start":"2026-04-02T21:31:15.060244Z","end":"2026-04-02T21:31:15.182066Z","steps":["trace[1294850716] 'process raft request' (duration: 121.197243ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:31:15.182707Z","caller":"traceutil/trace.go:171","msg":"trace[1238753867] transaction","detail":"{read_only:false; response_revision:5131; number_of_response:1; }","duration":"117.113819ms","start":"2026-04-02T21:31:15.065575Z","end":"2026-04-02T21:31:15.182689Z","steps":["trace[1238753867] 'process raft request' (duration: 117.016789ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:31:15.182834Z","caller":"traceutil/trace.go:171","msg":"trace[1136623958] transaction","detail":"{read_only:false; response_revision:5130; number_of_response:1; }","duration":"121.964166ms","start":"2026-04-02T21:31:15.060823Z","end":"2026-04-02T21:31:15.182788Z","steps":["trace[1136623958] 'process raft request' (duration: 120.898773ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:31:52.019304Z","caller":"traceutil/trace.go:171","msg":"trace[986842007] transaction","detail":"{read_only:false; response_revision:5509; number_of_response:1; }","duration":"101.890764ms","start":"2026-04-02T21:31:51.917367Z","end":"2026-04-02T21:31:52.019258Z","steps":["trace[986842007] 'process raft request' (duration: 101.277501ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:34:08.705653Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3204} {"level":"info","ts":"2026-04-02T21:34:08.785901Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3204,"took":"78.535354ms","hash":3866637063,"current-db-size-bytes":61288448,"current-db-size":"61 MB","current-db-size-in-use-bytes":33742848,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-02T21:34:08.78597Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3866637063,"revision":3204,"compact-revision":-1} {"level":"info","ts":"2026-04-02T21:36:44.839784Z","caller":"traceutil/trace.go:171","msg":"trace[433318479] transaction","detail":"{read_only:false; response_revision:7987; number_of_response:1; }","duration":"146.285939ms","start":"2026-04-02T21:36:44.693457Z","end":"2026-04-02T21:36:44.839743Z","steps":["trace[433318479] 'process raft request' (duration: 146.061266ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:36:49.384109Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002130.wal"} {"level":"info","ts":"2026-04-02T21:36:53.475435Z","caller":"traceutil/trace.go:171","msg":"trace[355775240] linearizableReadLoop","detail":"{readStateIndex:8531; appliedIndex:8530; }","duration":"323.652927ms","start":"2026-04-02T21:36:53.151755Z","end":"2026-04-02T21:36:53.475408Z","steps":["trace[355775240] 'read index received' (duration: 242.875087ms)","trace[355775240] 'applied index is now lower than readState.Index' (duration: 80.77668ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:36:53.47556Z","caller":"traceutil/trace.go:171","msg":"trace[1896080011] transaction","detail":"{read_only:false; response_revision:8174; number_of_response:1; }","duration":"375.988459ms","start":"2026-04-02T21:36:53.099559Z","end":"2026-04-02T21:36:53.475548Z","steps":["trace[1896080011] 'process raft request' (duration: 295.031086ms)","trace[1896080011] 'compare' (duration: 80.456255ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:36:53.47566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:36:53.099544Z","time spent":"376.041439ms","remote":"127.0.0.1:38474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T21:36:53.475743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.411742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-02T21:36:53.47582Z","caller":"traceutil/trace.go:171","msg":"trace[868611550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8174; }","duration":"288.493694ms","start":"2026-04-02T21:36:53.187302Z","end":"2026-04-02T21:36:53.475796Z","steps":["trace[868611550] 'agreement among raft nodes before linearized reading' (duration: 288.305091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:36:53.475986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.226025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-02T21:36:53.476017Z","caller":"traceutil/trace.go:171","msg":"trace[443857739] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8174; }","duration":"324.278207ms","start":"2026-04-02T21:36:53.151727Z","end":"2026-04-02T21:36:53.476005Z","steps":["trace[443857739] 'agreement among raft nodes before linearized reading' (duration: 324.192005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:36:53.476044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T21:36:53.151713Z","time spent":"324.323507ms","remote":"127.0.0.1:38314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":684,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-02T21:36:53.47629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.540484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-02T21:36:53.476328Z","caller":"traceutil/trace.go:171","msg":"trace[1233463469] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8174; }","duration":"162.623996ms","start":"2026-04-02T21:36:53.313695Z","end":"2026-04-02T21:36:53.476319Z","steps":["trace[1233463469] 'agreement among raft nodes before linearized reading' (duration: 162.533764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:36:53.476479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.788158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:36:53.476514Z","caller":"traceutil/trace.go:171","msg":"trace[1876230687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8174; }","duration":"190.896979ms","start":"2026-04-02T21:36:53.285609Z","end":"2026-04-02T21:36:53.476506Z","steps":["trace[1876230687] 'agreement among raft nodes before linearized reading' (duration: 190.844279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:36:53.476703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.726224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-02T21:36:53.476739Z","caller":"traceutil/trace.go:171","msg":"trace[1029327346] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8174; }","duration":"284.783075ms","start":"2026-04-02T21:36:53.191948Z","end":"2026-04-02T21:36:53.476731Z","steps":["trace[1029327346] 'agreement among raft nodes before linearized reading' (duration: 284.688593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:37:05.526784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.802619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T21:37:05.526836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.60968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11498"} {"level":"info","ts":"2026-04-02T21:37:05.526861Z","caller":"traceutil/trace.go:171","msg":"trace[1526090557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8254; }","duration":"239.923331ms","start":"2026-04-02T21:37:05.286921Z","end":"2026-04-02T21:37:05.526844Z","steps":["trace[1526090557] 'range keys from in-memory index tree' (duration: 239.726638ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:37:05.526913Z","caller":"traceutil/trace.go:171","msg":"trace[1292832284] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8254; }","duration":"252.734042ms","start":"2026-04-02T21:37:05.27416Z","end":"2026-04-02T21:37:05.526894Z","steps":["trace[1292832284] 'range keys from in-memory index tree' (duration: 252.422287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:37:05.675001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.652045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-02T21:37:05.675123Z","caller":"traceutil/trace.go:171","msg":"trace[1310645315] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8257; }","duration":"118.811347ms","start":"2026-04-02T21:37:05.55628Z","end":"2026-04-02T21:37:05.675091Z","steps":["trace[1310645315] 'agreement among raft nodes before linearized reading' (duration: 118.525543ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:37:06.08789Z","caller":"traceutil/trace.go:171","msg":"trace[1421186219] linearizableReadLoop","detail":"{readStateIndex:8626; appliedIndex:8625; }","duration":"264.730821ms","start":"2026-04-02T21:37:05.823128Z","end":"2026-04-02T21:37:06.087859Z","steps":["trace[1421186219] 'read index received' (duration: 192.044739ms)","trace[1421186219] 'applied index is now lower than readState.Index' (duration: 72.684812ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:37:06.087944Z","caller":"traceutil/trace.go:171","msg":"trace[1497292578] transaction","detail":"{read_only:false; response_revision:8263; number_of_response:1; }","duration":"289.51495ms","start":"2026-04-02T21:37:05.798391Z","end":"2026-04-02T21:37:06.087906Z","steps":["trace[1497292578] 'process raft request' (duration: 216.730126ms)","trace[1497292578] 'compare' (duration: 72.508339ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:37:06.088182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.005165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11498"} {"level":"info","ts":"2026-04-02T21:37:06.088286Z","caller":"traceutil/trace.go:171","msg":"trace[1226193815] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8263; }","duration":"265.175278ms","start":"2026-04-02T21:37:05.823088Z","end":"2026-04-02T21:37:06.088264Z","steps":["trace[1226193815] 'agreement among raft nodes before linearized reading' (duration: 264.880484ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:37:33.871864Z","caller":"traceutil/trace.go:171","msg":"trace[1008164926] linearizableReadLoop","detail":"{readStateIndex:8871; appliedIndex:8870; }","duration":"141.114612ms","start":"2026-04-02T21:37:33.730722Z","end":"2026-04-02T21:37:33.871837Z","steps":["trace[1008164926] 'read index received' (duration: 140.975959ms)","trace[1008164926] 'applied index is now lower than readState.Index' (duration: 137.633µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:37:33.871956Z","caller":"traceutil/trace.go:171","msg":"trace[867673767] transaction","detail":"{read_only:false; response_revision:8496; number_of_response:1; }","duration":"166.638225ms","start":"2026-04-02T21:37:33.705286Z","end":"2026-04-02T21:37:33.871924Z","steps":["trace[867673767] 'process raft request' (duration: 166.38376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:37:33.872086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.346627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T21:37:33.87214Z","caller":"traceutil/trace.go:171","msg":"trace[663234825] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8496; }","duration":"141.438458ms","start":"2026-04-02T21:37:33.730691Z","end":"2026-04-02T21:37:33.872129Z","steps":["trace[663234825] 'agreement among raft nodes before linearized reading' (duration: 141.279455ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:37:34.060351Z","caller":"traceutil/trace.go:171","msg":"trace[1112163831] linearizableReadLoop","detail":"{readStateIndex:8872; appliedIndex:8871; }","duration":"188.381438ms","start":"2026-04-02T21:37:33.871941Z","end":"2026-04-02T21:37:34.060322Z","steps":["trace[1112163831] 'read index received' (duration: 188.036171ms)","trace[1112163831] 'applied index is now lower than readState.Index' (duration: 344.047µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:37:34.060473Z","caller":"traceutil/trace.go:171","msg":"trace[1385803442] transaction","detail":"{read_only:false; response_revision:8497; number_of_response:1; }","duration":"230.440802ms","start":"2026-04-02T21:37:33.830002Z","end":"2026-04-02T21:37:34.060443Z","steps":["trace[1385803442] 'process raft request' (duration: 229.955892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:37:34.060602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.625782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T21:37:34.060664Z","caller":"traceutil/trace.go:171","msg":"trace[1255308459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8497; }","duration":"251.733785ms","start":"2026-04-02T21:37:33.808915Z","end":"2026-04-02T21:37:34.060649Z","steps":["trace[1255308459] 'agreement among raft nodes before linearized reading' (duration: 251.549381ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:37:34.078979Z","caller":"traceutil/trace.go:171","msg":"trace[522159126] transaction","detail":"{read_only:false; response_revision:8498; number_of_response:1; }","duration":"203.731234ms","start":"2026-04-02T21:37:33.875222Z","end":"2026-04-02T21:37:34.078953Z","steps":["trace[522159126] 'process raft request' (duration: 203.605132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:37:34.079253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.584554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-02T21:37:34.079339Z","caller":"traceutil/trace.go:171","msg":"trace[2027500062] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8498; }","duration":"166.720976ms","start":"2026-04-02T21:37:33.912601Z","end":"2026-04-02T21:37:34.079322Z","steps":["trace[2027500062] 'agreement among raft nodes before linearized reading' (duration: 166.4414ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:37:47.378286Z","caller":"traceutil/trace.go:171","msg":"trace[231729566] transaction","detail":"{read_only:false; response_revision:8625; number_of_response:1; }","duration":"276.698415ms","start":"2026-04-02T21:37:47.101562Z","end":"2026-04-02T21:37:47.37826Z","steps":["trace[231729566] 'process raft request' (duration: 276.524092ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:37:47.435306Z","caller":"traceutil/trace.go:171","msg":"trace[307680899] linearizableReadLoop","detail":"{readStateIndex:9008; appliedIndex:9006; }","duration":"148.568409ms","start":"2026-04-02T21:37:47.286716Z","end":"2026-04-02T21:37:47.435284Z","steps":["trace[307680899] 'read index received' (duration: 91.31602ms)","trace[307680899] 'applied index is now lower than readState.Index' (duration: 57.251559ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T21:37:47.435601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.878225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T21:37:47.435696Z","caller":"traceutil/trace.go:171","msg":"trace[1692063764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8626; }","duration":"149.022218ms","start":"2026-04-02T21:37:47.286659Z","end":"2026-04-02T21:37:47.435681Z","steps":["trace[1692063764] 'agreement among raft nodes before linearized reading' (duration: 148.885845ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:37:47.4864Z","caller":"traceutil/trace.go:171","msg":"trace[1001204832] transaction","detail":"{read_only:false; number_of_response:0; response_revision:8626; }","duration":"101.195604ms","start":"2026-04-02T21:37:47.385176Z","end":"2026-04-02T21:37:47.486371Z","steps":["trace[1001204832] 'process raft request' (duration: 86.636714ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:37:48.125767Z","caller":"traceutil/trace.go:171","msg":"trace[1057636848] transaction","detail":"{read_only:false; response_revision:8631; number_of_response:1; }","duration":"191.489862ms","start":"2026-04-02T21:37:47.934253Z","end":"2026-04-02T21:37:48.125743Z","steps":["trace[1057636848] 'process raft request' (duration: 191.36937ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:37:48.3156Z","caller":"traceutil/trace.go:171","msg":"trace[1795204400] linearizableReadLoop","detail":"{readStateIndex:9016; appliedIndex:9015; }","duration":"173.004932ms","start":"2026-04-02T21:37:48.142575Z","end":"2026-04-02T21:37:48.31558Z","steps":["trace[1795204400] 'read index received' (duration: 172.856609ms)","trace[1795204400] 'applied index is now lower than readState.Index' (duration: 147.753µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:37:48.315908Z","caller":"traceutil/trace.go:171","msg":"trace[1901048218] transaction","detail":"{read_only:false; response_revision:8632; number_of_response:1; }","duration":"208.224707ms","start":"2026-04-02T21:37:48.107673Z","end":"2026-04-02T21:37:48.315898Z","steps":["trace[1901048218] 'process raft request' (duration: 207.807149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:37:48.316251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.682856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-02T21:37:48.316368Z","caller":"traceutil/trace.go:171","msg":"trace[1652165123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8632; }","duration":"173.773808ms","start":"2026-04-02T21:37:48.142502Z","end":"2026-04-02T21:37:48.316276Z","steps":["trace[1652165123] 'agreement among raft nodes before linearized reading' (duration: 173.597974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:37:48.316747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.62636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-02T21:37:48.316776Z","caller":"traceutil/trace.go:171","msg":"trace[1047226007] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8632; }","duration":"165.695791ms","start":"2026-04-02T21:37:48.151074Z","end":"2026-04-02T21:37:48.31677Z","steps":["trace[1047226007] 'agreement among raft nodes before linearized reading' (duration: 165.626049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T21:37:48.316995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.976295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-02T21:37:48.317024Z","caller":"traceutil/trace.go:171","msg":"trace[971119845] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8632; }","duration":"165.046287ms","start":"2026-04-02T21:37:48.151972Z","end":"2026-04-02T21:37:48.317018Z","steps":["trace[971119845] 'agreement among raft nodes before linearized reading' (duration: 164.991926ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T21:37:48.710722Z","caller":"traceutil/trace.go:171","msg":"trace[98124753] transaction","detail":"{read_only:false; response_revision:8638; number_of_response:1; }","duration":"139.772787ms","start":"2026-04-02T21:37:48.570899Z","end":"2026-04-02T21:37:48.710672Z","steps":["trace[98124753] 'process raft request' (duration: 109.982754ms)","trace[98124753] 'compare' (duration: 29.531808ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T21:39:08.713152Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6570} {"level":"info","ts":"2026-04-02T21:39:08.812665Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6570,"took":"97.548595ms","hash":3494271871,"current-db-size-bytes":61288448,"current-db-size":"61 MB","current-db-size-in-use-bytes":26980352,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-02T21:39:08.812742Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3494271871,"revision":6570,"compact-revision":3204} {"level":"info","ts":"2026-04-02T21:39:19.90678Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"fba6d6d8cd5f215c","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-02T21:39:19.911707Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-02T21:39:19.911798Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-02T21:44:08.720763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9489} {"level":"info","ts":"2026-04-02T21:44:08.814983Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9489,"took":"91.70786ms","hash":436959106,"current-db-size-bytes":61288448,"current-db-size":"61 MB","current-db-size-in-use-bytes":25464832,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-02T21:44:08.815057Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":436959106,"revision":9489,"compact-revision":6570}