{"level":"warn","ts":"2026-09-02T01:15:58.268677Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-02T01:15:58.268833Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.54: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://162.253.55.54:2380","--initial-cluster=instance=https://162.253.55.54:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.54:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.54: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-09-02T01:15:58.268993Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-02T01:15:58.269025Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.54:2380"]} {"level":"info","ts":"2026-09-02T01:15:58.269074Z","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-09-02T01:15:58.270325Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.54:2379"]} {"level":"info","ts":"2026-09-02T01:15:58.270501Z","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://162.253.55.54:2380"],"listen-peer-urls":["https://162.253.55.54:2380"],"advertise-client-urls":["https://162.253.55.54:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.54:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.54: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-09-02T01:15:58.27449Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.535884ms"} {"level":"info","ts":"2026-09-02T01:15:58.280745Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"353030364a385dbf","cluster-id":"1e619908a4cffd8f"} {"level":"info","ts":"2026-09-02T01:15:58.280843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf switched to configuration voters=()"} {"level":"info","ts":"2026-09-02T01:15:58.280873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf became follower at term 0"} {"level":"info","ts":"2026-09-02T01:15:58.280892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 353030364a385dbf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-02T01:15:58.280904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf became follower at term 1"} {"level":"info","ts":"2026-09-02T01:15:58.280957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf switched to configuration voters=(3832616292623867327)"} {"level":"warn","ts":"2026-09-02T01:15:58.286818Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-02T01:15:58.289026Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-02T01:15:58.290572Z","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-09-02T01:15:58.294459Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"353030364a385dbf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-02T01:15:58.296573Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"353030364a385dbf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-02T01:15:58.296951Z","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-09-02T01:15:58.297088Z","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-09-02T01:15:58.297124Z","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-09-02T01:15:58.301267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf switched to configuration voters=(3832616292623867327)"} {"level":"info","ts":"2026-09-02T01:15:58.301518Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1e619908a4cffd8f","local-member-id":"353030364a385dbf","added-peer-id":"353030364a385dbf","added-peer-peer-urls":["https://162.253.55.54:2380"]} {"level":"info","ts":"2026-09-02T01:15:58.303397Z","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-09-02T01:15:58.303645Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.54:2380"} {"level":"info","ts":"2026-09-02T01:15:58.303721Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.54:2380"} {"level":"info","ts":"2026-09-02T01:15:58.304055Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"353030364a385dbf","initial-advertise-peer-urls":["https://162.253.55.54:2380"],"listen-peer-urls":["https://162.253.55.54:2380"],"advertise-client-urls":["https://162.253.55.54:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.54:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-02T01:15:58.304137Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-02T01:15:59.082171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf is starting a new election at term 1"} {"level":"info","ts":"2026-09-02T01:15:59.082244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf became pre-candidate at term 1"} {"level":"info","ts":"2026-09-02T01:15:59.082262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf received MsgPreVoteResp from 353030364a385dbf at term 1"} {"level":"info","ts":"2026-09-02T01:15:59.082277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf became candidate at term 2"} {"level":"info","ts":"2026-09-02T01:15:59.082284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf received MsgVoteResp from 353030364a385dbf at term 2"} {"level":"info","ts":"2026-09-02T01:15:59.082295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"353030364a385dbf became leader at term 2"} {"level":"info","ts":"2026-09-02T01:15:59.082305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 353030364a385dbf elected leader 353030364a385dbf at term 2"} {"level":"info","ts":"2026-09-02T01:15:59.083518Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-02T01:15:59.084435Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-02T01:15:59.084433Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"353030364a385dbf","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.54:2379]}","request-path":"/0/members/353030364a385dbf/attributes","cluster-id":"1e619908a4cffd8f","publish-timeout":"7s"} {"level":"info","ts":"2026-09-02T01:15:59.084531Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-02T01:15:59.084983Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-02T01:15:59.085114Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-02T01:15:59.085301Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1e619908a4cffd8f","local-member-id":"353030364a385dbf","cluster-version":"3.5"} {"level":"info","ts":"2026-09-02T01:15:59.085432Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-02T01:15:59.085474Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-02T01:15:59.086307Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-02T01:15:59.086705Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.54:2379"} {"level":"warn","ts":"2026-09-02T01:16:18.295634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.498781ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-02T01:16:18.295791Z","caller":"traceutil/trace.go:171","msg":"trace[850180257] linearizableReadLoop","detail":"{readStateIndex:396; appliedIndex:395; }","duration":"449.859684ms","start":"2026-09-02T01:16:17.845895Z","end":"2026-09-02T01:16:18.295755Z","steps":["trace[850180257] 'read index received' (duration: 158.9604ms)","trace[850180257] 'applied index is now lower than readState.Index' (duration: 290.897494ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:16:18.295965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.047929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:16:18.296058Z","caller":"traceutil/trace.go:171","msg":"trace[1573758758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:382; }","duration":"450.185424ms","start":"2026-09-02T01:16:17.845848Z","end":"2026-09-02T01:16:18.296033Z","steps":["trace[1573758758] 'agreement among raft nodes before linearized reading' (duration: 450.029579ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:16:18.296107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:16:17.845827Z","time spent":"450.269796ms","remote":"127.0.0.1:36034","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-09-02T01:16:18.296132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.734035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-02T01:16:18.296274Z","caller":"traceutil/trace.go:171","msg":"trace[1170766786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:382; }","duration":"244.936741ms","start":"2026-09-02T01:16:18.051307Z","end":"2026-09-02T01:16:18.296244Z","steps":["trace[1170766786] 'agreement among raft nodes before linearized reading' (duration: 244.654053ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:16:37.351358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.154859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-02T01:16:37.3515Z","caller":"traceutil/trace.go:171","msg":"trace[1824016618] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:570; }","duration":"110.988609ms","start":"2026-09-02T01:16:37.240491Z","end":"2026-09-02T01:16:37.35148Z","steps":["trace[1824016618] 'read index received' (duration: 8.840561ms)","trace[1824016618] 'applied index is now lower than readState.Index' (duration: 102.144348ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:16:37.351686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.171704ms","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-09-02T01:16:37.351777Z","caller":"traceutil/trace.go:171","msg":"trace[1852574665] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:550; }","duration":"111.306738ms","start":"2026-09-02T01:16:37.240445Z","end":"2026-09-02T01:16:37.351751Z","steps":["trace[1852574665] 'agreement among raft nodes before linearized reading' (duration: 111.160254ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:16:37.351705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.176134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:16:37.351919Z","caller":"traceutil/trace.go:171","msg":"trace[1705273069] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:550; }","duration":"111.437642ms","start":"2026-09-02T01:16:37.240465Z","end":"2026-09-02T01:16:37.351903Z","steps":["trace[1705273069] 'agreement among raft nodes before linearized reading' (duration: 111.191645ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:16:37.351513Z","caller":"traceutil/trace.go:171","msg":"trace[1865209770] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"230.855181ms","start":"2026-09-02T01:16:37.120626Z","end":"2026-09-02T01:16:37.351481Z","steps":["trace[1865209770] 'process raft request' (duration: 128.828417ms)","trace[1865209770] 'compare' (duration: 100.926241ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:16:45.007461Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6755294298912443980,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-02T01:16:45.008178Z","caller":"traceutil/trace.go:171","msg":"trace[926200670] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"501.424555ms","start":"2026-09-02T01:16:44.506685Z","end":"2026-09-02T01:16:45.00811Z","steps":["trace[926200670] 'read index received' (duration: 501.216328ms)","trace[926200670] 'applied index is now lower than readState.Index' (duration: 207.067µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:16:45.008297Z","caller":"traceutil/trace.go:171","msg":"trace[835032372] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"708.603485ms","start":"2026-09-02T01:16:44.299684Z","end":"2026-09-02T01:16:45.008287Z","steps":["trace[835032372] 'process raft request' (duration: 708.281285ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:16:45.008404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:16:44.299652Z","time spent":"708.676917ms","remote":"127.0.0.1:36246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T01:16:45.008685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.97327ms","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-09-02T01:16:45.00877Z","caller":"traceutil/trace.go:171","msg":"trace[2075021071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:608; }","duration":"502.119115ms","start":"2026-09-02T01:16:44.506632Z","end":"2026-09-02T01:16:45.008751Z","steps":["trace[2075021071] 'agreement among raft nodes before linearized reading' (duration: 501.900788ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:16:45.008815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:16:44.506609Z","time spent":"502.197507ms","remote":"127.0.0.1:40080","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":"info","ts":"2026-09-02T01:17:33.288077Z","caller":"traceutil/trace.go:171","msg":"trace[1937610045] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"271.928264ms","start":"2026-09-02T01:17:33.016118Z","end":"2026-09-02T01:17:33.288046Z","steps":["trace[1937610045] 'process raft request' (duration: 271.629505ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:18:20.452558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.821339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-02T01:18:20.452631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.151566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-02T01:18:20.452696Z","caller":"traceutil/trace.go:171","msg":"trace[886949471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1857; }","duration":"368.016645ms","start":"2026-09-02T01:18:20.084655Z","end":"2026-09-02T01:18:20.452672Z","steps":["trace[886949471] 'range keys from in-memory index tree' (duration: 367.622303ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:18:20.452755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:18:20.084634Z","time spent":"368.107368ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-02T01:18:20.452846Z","caller":"traceutil/trace.go:171","msg":"trace[1946600895] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"198.017976ms","start":"2026-09-02T01:18:20.25481Z","end":"2026-09-02T01:18:20.452828Z","steps":["trace[1946600895] 'process raft request' (duration: 197.931434ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:18:20.452926Z","caller":"traceutil/trace.go:171","msg":"trace[1838640352] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"332.811945ms","start":"2026-09-02T01:18:20.120067Z","end":"2026-09-02T01:18:20.452879Z","steps":["trace[1838640352] 'process raft request' (duration: 44.288197ms)","trace[1838640352] 'compare' (duration: 288.009152ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:18:20.453092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:18:20.120042Z","time spent":"332.960169ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T01:18:21.169858Z","caller":"traceutil/trace.go:171","msg":"trace[1434478316] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1931; }","duration":"324.680605ms","start":"2026-09-02T01:18:20.845159Z","end":"2026-09-02T01:18:21.169839Z","steps":["trace[1434478316] 'read index received' (duration: 324.51713ms)","trace[1434478316] 'applied index is now lower than readState.Index' (duration: 162.865µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:18:21.169932Z","caller":"traceutil/trace.go:171","msg":"trace[1678695067] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"358.640968ms","start":"2026-09-02T01:18:20.811263Z","end":"2026-09-02T01:18:21.169904Z","steps":["trace[1678695067] 'process raft request' (duration: 358.464093ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:18:21.169981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.802088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:18:21.170005Z","caller":"traceutil/trace.go:171","msg":"trace[1395986107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1861; }","duration":"324.891882ms","start":"2026-09-02T01:18:20.845107Z","end":"2026-09-02T01:18:21.169999Z","steps":["trace[1395986107] 'agreement among raft nodes before linearized reading' (duration: 324.82569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:18:21.170031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:18:20.845087Z","time spent":"324.940142ms","remote":"127.0.0.1:36046","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-09-02T01:18:21.170072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:18:20.811228Z","time spent":"358.770892ms","remote":"127.0.0.1:36246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T01:18:21.170111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.49468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ed049b3c-08f5-416d-96a1-3fd231fb1992\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-09-02T01:18:21.170216Z","caller":"traceutil/trace.go:171","msg":"trace[919676662] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ed049b3c-08f5-416d-96a1-3fd231fb1992; range_end:; response_count:1; response_revision:1861; }","duration":"113.640265ms","start":"2026-09-02T01:18:21.056562Z","end":"2026-09-02T01:18:21.170202Z","steps":["trace[919676662] 'agreement among raft nodes before linearized reading' (duration: 113.49016ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:18:21.170223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.541621ms","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-09-02T01:18:21.170312Z","caller":"traceutil/trace.go:171","msg":"trace[521034653] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1861; }","duration":"220.718286ms","start":"2026-09-02T01:18:20.949571Z","end":"2026-09-02T01:18:21.170289Z","steps":["trace[521034653] 'agreement among raft nodes before linearized reading' (duration: 220.444668ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:18:34.307533Z","caller":"traceutil/trace.go:171","msg":"trace[65530891] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"152.712038ms","start":"2026-09-02T01:18:34.154787Z","end":"2026-09-02T01:18:34.307499Z","steps":["trace[65530891] 'process raft request' (duration: 152.504322ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:18:34.696169Z","caller":"traceutil/trace.go:171","msg":"trace[505087210] linearizableReadLoop","detail":"{readStateIndex:2045; appliedIndex:2044; }","duration":"184.27643ms","start":"2026-09-02T01:18:34.511866Z","end":"2026-09-02T01:18:34.696142Z","steps":["trace[505087210] 'read index received' (duration: 184.125546ms)","trace[505087210] 'applied index is now lower than readState.Index' (duration: 149.384µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:18:34.696255Z","caller":"traceutil/trace.go:171","msg":"trace[88788677] transaction","detail":"{read_only:false; response_revision:1970; number_of_response:1; }","duration":"185.97847ms","start":"2026-09-02T01:18:34.510225Z","end":"2026-09-02T01:18:34.696204Z","steps":["trace[88788677] 'process raft request' (duration: 185.767344ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:18:34.696382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.505737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-r2ftw\" ","response":"range_response_count:1 size:5851"} {"level":"info","ts":"2026-09-02T01:18:34.696426Z","caller":"traceutil/trace.go:171","msg":"trace[1198985870] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-r2ftw; range_end:; response_count:1; response_revision:1970; }","duration":"184.61745ms","start":"2026-09-02T01:18:34.511791Z","end":"2026-09-02T01:18:34.696408Z","steps":["trace[1198985870] 'agreement among raft nodes before linearized reading' (duration: 184.464796ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:18:34.698951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.378643ms","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-09-02T01:18:34.699004Z","caller":"traceutil/trace.go:171","msg":"trace[645601789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1971; }","duration":"162.486787ms","start":"2026-09-02T01:18:34.536504Z","end":"2026-09-02T01:18:34.698991Z","steps":["trace[645601789] 'agreement among raft nodes before linearized reading' (duration: 162.348103ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:18:34.699004Z","caller":"traceutil/trace.go:171","msg":"trace[2098759783] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"185.851226ms","start":"2026-09-02T01:18:34.513137Z","end":"2026-09-02T01:18:34.698988Z","steps":["trace[2098759783] 'process raft request' (duration: 185.629409ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:19:05.860193Z","caller":"traceutil/trace.go:171","msg":"trace[1534866603] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2234; }","duration":"100.382864ms","start":"2026-09-02T01:19:05.759787Z","end":"2026-09-02T01:19:05.860169Z","steps":["trace[1534866603] 'read index received' (duration: 100.143867ms)","trace[1534866603] 'applied index is now lower than readState.Index' (duration: 237.917µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:19:05.860275Z","caller":"traceutil/trace.go:171","msg":"trace[1410516021] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"151.806799ms","start":"2026-09-02T01:19:05.708439Z","end":"2026-09-02T01:19:05.860246Z","steps":["trace[1410516021] 'process raft request' (duration: 151.577902ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:19:05.860467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.659403ms","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-09-02T01:19:05.860512Z","caller":"traceutil/trace.go:171","msg":"trace[868087897] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2154; }","duration":"100.751386ms","start":"2026-09-02T01:19:05.75975Z","end":"2026-09-02T01:19:05.860501Z","steps":["trace[868087897] 'agreement among raft nodes before linearized reading' (duration: 100.586841ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:19:12.89716Z","caller":"traceutil/trace.go:171","msg":"trace[2077561297] linearizableReadLoop","detail":"{readStateIndex:2275; appliedIndex:2274; }","duration":"311.619202ms","start":"2026-09-02T01:19:12.585518Z","end":"2026-09-02T01:19:12.897137Z","steps":["trace[2077561297] 'read index received' (duration: 311.461937ms)","trace[2077561297] 'applied index is now lower than readState.Index' (duration: 156.095µs)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:19:12.89724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:19:12.482513Z","time spent":"414.706326ms","remote":"127.0.0.1:36082","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-02T01:19:12.897407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.8857ms","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-09-02T01:19:12.897471Z","caller":"traceutil/trace.go:171","msg":"trace[214401341] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2191; }","duration":"312.000663ms","start":"2026-09-02T01:19:12.585451Z","end":"2026-09-02T01:19:12.897451Z","steps":["trace[214401341] 'agreement among raft nodes before linearized reading' (duration: 311.816578ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:19:12.89755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:19:12.585426Z","time spent":"312.109466ms","remote":"127.0.0.1:49420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-02T01:19:13.193864Z","caller":"traceutil/trace.go:171","msg":"trace[68255418] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"364.429136ms","start":"2026-09-02T01:19:12.829367Z","end":"2026-09-02T01:19:13.193796Z","steps":["trace[68255418] 'process raft request' (duration: 364.037094ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:19:13.194049Z","caller":"traceutil/trace.go:171","msg":"trace[2058849832] linearizableReadLoop","detail":"{readStateIndex:2277; appliedIndex:2275; }","duration":"296.753374ms","start":"2026-09-02T01:19:12.897247Z","end":"2026-09-02T01:19:13.194001Z","steps":["trace[2058849832] 'read index received' (duration: 296.28161ms)","trace[2058849832] 'applied index is now lower than readState.Index' (duration: 468.544µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:19:13.194085Z","caller":"traceutil/trace.go:171","msg":"trace[1176723603] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"362.785827ms","start":"2026-09-02T01:19:12.83126Z","end":"2026-09-02T01:19:13.194046Z","steps":["trace[1176723603] 'process raft request' (duration: 362.431547ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:19:13.194233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.570621ms","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-09-02T01:19:13.194241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:19:12.829332Z","time spent":"364.700564ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T01:19:13.194304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.474572ms","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-09-02T01:19:13.194284Z","caller":"traceutil/trace.go:171","msg":"trace[1999934854] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2193; }","duration":"431.707256ms","start":"2026-09-02T01:19:12.762561Z","end":"2026-09-02T01:19:13.194268Z","steps":["trace[1999934854] 'agreement among raft nodes before linearized reading' (duration: 431.595592ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:19:13.194381Z","caller":"traceutil/trace.go:171","msg":"trace[1000917768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2193; }","duration":"320.602125ms","start":"2026-09-02T01:19:12.873762Z","end":"2026-09-02T01:19:13.194364Z","steps":["trace[1000917768] 'agreement among raft nodes before linearized reading' (duration: 320.366358ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:19:13.194449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:19:12.762526Z","time spent":"431.907291ms","remote":"127.0.0.1:49358","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-09-02T01:19:13.194479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:19:12.831223Z","time spent":"363.072865ms","remote":"127.0.0.1:40080","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-09-02T01:19:13.194421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:19:12.873744Z","time spent":"320.669628ms","remote":"127.0.0.1:40080","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-09-02T01:19:13.197076Z","caller":"traceutil/trace.go:171","msg":"trace[528505179] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"298.271509ms","start":"2026-09-02T01:19:12.898786Z","end":"2026-09-02T01:19:13.197058Z","steps":["trace[528505179] 'process raft request' (duration: 297.393583ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:19:13.197419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.856605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-02T01:19:13.19746Z","caller":"traceutil/trace.go:171","msg":"trace[1277312881] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:2194; }","duration":"211.967278ms","start":"2026-09-02T01:19:12.985482Z","end":"2026-09-02T01:19:13.197449Z","steps":["trace[1277312881] 'agreement among raft nodes before linearized reading' (duration: 211.880126ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:19:13.197444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.189185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:19:13.198144Z","caller":"traceutil/trace.go:171","msg":"trace[875238888] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2194; }","duration":"265.968437ms","start":"2026-09-02T01:19:12.932148Z","end":"2026-09-02T01:19:13.198116Z","steps":["trace[875238888] 'agreement among raft nodes before linearized reading' (duration: 264.892535ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:20:37.066265Z","caller":"traceutil/trace.go:171","msg":"trace[1319191381] transaction","detail":"{read_only:false; response_revision:2842; number_of_response:1; }","duration":"144.019091ms","start":"2026-09-02T01:20:36.92222Z","end":"2026-09-02T01:20:37.066239Z","steps":["trace[1319191381] 'process raft request' (duration: 143.826545ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:25:16.746367Z","caller":"traceutil/trace.go:171","msg":"trace[1106487231] transaction","detail":"{read_only:false; response_revision:5134; number_of_response:1; }","duration":"122.326513ms","start":"2026-09-02T01:25:16.623967Z","end":"2026-09-02T01:25:16.746294Z","steps":["trace[1106487231] 'process raft request' (duration: 47.119848ms)","trace[1106487231] 'compare' (duration: 74.762662ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:25:16.746426Z","caller":"traceutil/trace.go:171","msg":"trace[830064828] transaction","detail":"{read_only:false; response_revision:5135; number_of_response:1; }","duration":"122.365324ms","start":"2026-09-02T01:25:16.624006Z","end":"2026-09-02T01:25:16.746372Z","steps":["trace[830064828] 'process raft request' (duration: 122.206759ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:25:19.28721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.678314ms","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-09-02T01:25:19.287298Z","caller":"traceutil/trace.go:171","msg":"trace[1094321140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5249; }","duration":"157.816239ms","start":"2026-09-02T01:25:19.129462Z","end":"2026-09-02T01:25:19.287278Z","steps":["trace[1094321140] 'range keys from in-memory index tree' (duration: 157.436718ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:25:19.287406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.408318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d949f6f3-7849-4557-8cb0-0a1483a75e73\" ","response":"range_response_count:1 size:3720"} {"level":"info","ts":"2026-09-02T01:25:19.287521Z","caller":"traceutil/trace.go:171","msg":"trace[67825399] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d949f6f3-7849-4557-8cb0-0a1483a75e73; range_end:; response_count:1; response_revision:5249; }","duration":"117.573393ms","start":"2026-09-02T01:25:19.16992Z","end":"2026-09-02T01:25:19.287493Z","steps":["trace[67825399] 'range keys from in-memory index tree' (duration: 117.247824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:25:21.532821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.963931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-02T01:25:21.533098Z","caller":"traceutil/trace.go:171","msg":"trace[48511159] transaction","detail":"{read_only:false; response_revision:5281; number_of_response:1; }","duration":"158.748266ms","start":"2026-09-02T01:25:21.374281Z","end":"2026-09-02T01:25:21.533029Z","steps":["trace[48511159] 'process raft request' (duration: 52.393343ms)","trace[48511159] 'compare' (duration: 105.767506ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:25:21.53315Z","caller":"traceutil/trace.go:171","msg":"trace[955362521] linearizableReadLoop","detail":"{readStateIndex:5482; appliedIndex:5481; }","duration":"139.403857ms","start":"2026-09-02T01:25:21.393714Z","end":"2026-09-02T01:25:21.533117Z","steps":["trace[955362521] 'read index received' (duration: 32.995852ms)","trace[955362521] 'applied index is now lower than readState.Index' (duration: 106.405295ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:25:21.533384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.452121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:25:21.533455Z","caller":"traceutil/trace.go:171","msg":"trace[1787154661] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:5282; }","duration":"114.580925ms","start":"2026-09-02T01:25:21.418861Z","end":"2026-09-02T01:25:21.533441Z","steps":["trace[1787154661] 'agreement among raft nodes before linearized reading' (duration: 114.430201ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:25:21.533719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.117649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:25:21.533759Z","caller":"traceutil/trace.go:171","msg":"trace[1555621613] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5282; }","duration":"101.195561ms","start":"2026-09-02T01:25:21.432555Z","end":"2026-09-02T01:25:21.53375Z","steps":["trace[1555621613] 'agreement among raft nodes before linearized reading' (duration: 101.123809ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:25:21.5352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.428356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787113"} {"level":"info","ts":"2026-09-02T01:25:21.535339Z","caller":"traceutil/trace.go:171","msg":"trace[527432485] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5282; }","duration":"141.633632ms","start":"2026-09-02T01:25:21.393675Z","end":"2026-09-02T01:25:21.535308Z","steps":["trace[527432485] 'agreement among raft nodes before linearized reading' (duration: 139.51252ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:25:34.270005Z","caller":"traceutil/trace.go:171","msg":"trace[2146364816] transaction","detail":"{read_only:false; response_revision:5501; number_of_response:1; }","duration":"152.420459ms","start":"2026-09-02T01:25:34.117546Z","end":"2026-09-02T01:25:34.269966Z","steps":["trace[2146364816] 'process raft request' (duration: 152.142391ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:25:34.401573Z","caller":"traceutil/trace.go:171","msg":"trace[1257296714] transaction","detail":"{read_only:false; response_revision:5503; number_of_response:1; }","duration":"118.355606ms","start":"2026-09-02T01:25:34.283191Z","end":"2026-09-02T01:25:34.401546Z","steps":["trace[1257296714] 'process raft request' (duration: 111.186765ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:25:34.410576Z","caller":"traceutil/trace.go:171","msg":"trace[661798032] transaction","detail":"{read_only:false; response_revision:5505; number_of_response:1; }","duration":"121.474118ms","start":"2026-09-02T01:25:34.289069Z","end":"2026-09-02T01:25:34.410543Z","steps":["trace[661798032] 'process raft request' (duration: 121.408206ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:25:34.410612Z","caller":"traceutil/trace.go:171","msg":"trace[1871184717] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"121.646083ms","start":"2026-09-02T01:25:34.288926Z","end":"2026-09-02T01:25:34.410572Z","steps":["trace[1871184717] 'process raft request' (duration: 121.350264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:25:45.066285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.208882ms","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-09-02T01:25:45.066421Z","caller":"traceutil/trace.go:171","msg":"trace[910193130] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5671; }","duration":"117.356657ms","start":"2026-09-02T01:25:44.949045Z","end":"2026-09-02T01:25:45.066402Z","steps":["trace[910193130] 'range keys from in-memory index tree' (duration: 117.194462ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:25:45.418935Z","caller":"traceutil/trace.go:171","msg":"trace[1470652425] linearizableReadLoop","detail":"{readStateIndex:5883; appliedIndex:5882; }","duration":"105.136356ms","start":"2026-09-02T01:25:45.313775Z","end":"2026-09-02T01:25:45.418911Z","steps":["trace[1470652425] 'read index received' (duration: 104.92655ms)","trace[1470652425] 'applied index is now lower than readState.Index' (duration: 208.986µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:25:45.419006Z","caller":"traceutil/trace.go:171","msg":"trace[1860279619] transaction","detail":"{read_only:false; response_revision:5672; number_of_response:1; }","duration":"263.946585ms","start":"2026-09-02T01:25:45.155023Z","end":"2026-09-02T01:25:45.41897Z","steps":["trace[1860279619] 'process raft request' (duration: 263.731779ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:25:45.41918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.374794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b7b53c77-9caf-4c5e-ae0c-3e4814b58a3f\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-02T01:25:45.419282Z","caller":"traceutil/trace.go:171","msg":"trace[2146580580] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b7b53c77-9caf-4c5e-ae0c-3e4814b58a3f; range_end:; response_count:1; response_revision:5672; }","duration":"105.539109ms","start":"2026-09-02T01:25:45.313724Z","end":"2026-09-02T01:25:45.419264Z","steps":["trace[2146580580] 'agreement among raft nodes before linearized reading' (duration: 105.269201ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:25:59.070396Z","caller":"traceutil/trace.go:171","msg":"trace[1609900215] transaction","detail":"{read_only:false; response_revision:5874; number_of_response:1; }","duration":"195.012595ms","start":"2026-09-02T01:25:58.875357Z","end":"2026-09-02T01:25:59.070369Z","steps":["trace[1609900215] 'process raft request' (duration: 104.427826ms)","trace[1609900215] 'compare' (duration: 90.238868ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:25:59.070794Z","caller":"traceutil/trace.go:171","msg":"trace[285822466] transaction","detail":"{read_only:false; response_revision:5875; number_of_response:1; }","duration":"156.345065ms","start":"2026-09-02T01:25:58.91442Z","end":"2026-09-02T01:25:59.070765Z","steps":["trace[285822466] 'process raft request' (duration: 155.862841ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:25:59.162761Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2965} {"level":"warn","ts":"2026-09-02T01:25:59.341024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.248483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-02T01:25:59.34119Z","caller":"traceutil/trace.go:171","msg":"trace[41949292] transaction","detail":"{read_only:false; response_revision:5877; number_of_response:1; }","duration":"200.543587ms","start":"2026-09-02T01:25:59.140615Z","end":"2026-09-02T01:25:59.341159Z","steps":["trace[41949292] 'process raft request' (duration: 47.053996ms)","trace[41949292] 'compare' (duration: 146.703251ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:25:59.341318Z","caller":"traceutil/trace.go:171","msg":"trace[1701706589] linearizableReadLoop","detail":"{readStateIndex:6096; appliedIndex:6095; }","duration":"175.068246ms","start":"2026-09-02T01:25:59.166209Z","end":"2026-09-02T01:25:59.341277Z","steps":["trace[1701706589] 'read index received' (duration: 21.246586ms)","trace[1701706589] 'applied index is now lower than readState.Index' (duration: 153.81905ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:25:59.341604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.372996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0fea2cc6-c6a5-440e-9272-c8a6484396e8\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-09-02T01:25:59.341684Z","caller":"traceutil/trace.go:171","msg":"trace[365888813] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0fea2cc6-c6a5-440e-9272-c8a6484396e8; range_end:; response_count:1; response_revision:5878; }","duration":"175.51102ms","start":"2026-09-02T01:25:59.166153Z","end":"2026-09-02T01:25:59.341664Z","steps":["trace[365888813] 'agreement among raft nodes before linearized reading' (duration: 175.263772ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:25:59.401322Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2965,"took":"236.94532ms","hash":147539917,"current-db-size-bytes":57450496,"current-db-size":"58 MB","current-db-size-in-use-bytes":32509952,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-02T01:25:59.401398Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":147539917,"revision":2965,"compact-revision":-1} {"level":"warn","ts":"2026-09-02T01:26:27.654943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.168304ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-09-02T01:30:59.17057Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5876} {"level":"info","ts":"2026-09-02T01:30:59.265882Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5876,"took":"92.793439ms","hash":3065790341,"current-db-size-bytes":57450496,"current-db-size":"58 MB","current-db-size-in-use-bytes":26075136,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-02T01:30:59.265939Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3065790341,"revision":5876,"compact-revision":2965} {"level":"info","ts":"2026-09-02T01:32:10.134276Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000022ed.wal"} {"level":"info","ts":"2026-09-02T01:32:13.47315Z","caller":"traceutil/trace.go:171","msg":"trace[1405997802] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"503.271683ms","start":"2026-09-02T01:32:12.96985Z","end":"2026-09-02T01:32:13.473121Z","steps":["trace[1405997802] 'process raft request' (duration: 503.113558ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:32:13.473325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:32:12.969814Z","time spent":"503.409437ms","remote":"127.0.0.1:36236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20211,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T01:32:13.474094Z","caller":"traceutil/trace.go:171","msg":"trace[322993623] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"472.490097ms","start":"2026-09-02T01:32:13.001582Z","end":"2026-09-02T01:32:13.474072Z","steps":["trace[322993623] 'process raft request' (duration: 472.316832ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:32:13.474283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:32:13.001547Z","time spent":"472.60967ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T01:32:13.47436Z","caller":"traceutil/trace.go:171","msg":"trace[2041693196] transaction","detail":"{read_only:false; response_revision:8647; number_of_response:1; }","duration":"351.158798ms","start":"2026-09-02T01:32:13.123179Z","end":"2026-09-02T01:32:13.474338Z","steps":["trace[2041693196] 'process raft request' (duration: 350.839598ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:32:13.4745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:32:13.123142Z","time spent":"351.283961ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T01:32:24.450854Z","caller":"traceutil/trace.go:171","msg":"trace[873845781] linearizableReadLoop","detail":"{readStateIndex:9016; appliedIndex:9015; }","duration":"261.667107ms","start":"2026-09-02T01:32:24.189154Z","end":"2026-09-02T01:32:24.450821Z","steps":["trace[873845781] 'read index received' (duration: 261.42995ms)","trace[873845781] 'applied index is now lower than readState.Index' (duration: 236.147µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:32:24.450965Z","caller":"traceutil/trace.go:171","msg":"trace[1284975852] transaction","detail":"{read_only:false; response_revision:8704; number_of_response:1; }","duration":"316.5513ms","start":"2026-09-02T01:32:24.134393Z","end":"2026-09-02T01:32:24.450944Z","steps":["trace[1284975852] 'process raft request' (duration: 316.225031ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:32:24.451102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:32:24.134375Z","time spent":"316.645133ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T01:32:24.451127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.675385ms","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-09-02T01:32:24.451175Z","caller":"traceutil/trace.go:171","msg":"trace[1143452284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8704; }","duration":"248.775167ms","start":"2026-09-02T01:32:24.20238Z","end":"2026-09-02T01:32:24.451155Z","steps":["trace[1143452284] 'agreement among raft nodes before linearized reading' (duration: 248.611683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:32:24.45116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.996177ms","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-09-02T01:32:24.451151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.852367ms","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-09-02T01:32:24.451251Z","caller":"traceutil/trace.go:171","msg":"trace[24430794] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8704; }","duration":"262.134131ms","start":"2026-09-02T01:32:24.189095Z","end":"2026-09-02T01:32:24.451229Z","steps":["trace[24430794] 'agreement among raft nodes before linearized reading' (duration: 261.873953ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:32:24.45128Z","caller":"traceutil/trace.go:171","msg":"trace[1195686174] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:8704; }","duration":"219.030253ms","start":"2026-09-02T01:32:24.232232Z","end":"2026-09-02T01:32:24.451263Z","steps":["trace[1195686174] 'agreement among raft nodes before linearized reading' (duration: 218.854527ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:32:24.451354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.422748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-02T01:32:24.451399Z","caller":"traceutil/trace.go:171","msg":"trace[869124345] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8704; }","duration":"246.486ms","start":"2026-09-02T01:32:24.204902Z","end":"2026-09-02T01:32:24.451388Z","steps":["trace[869124345] 'agreement among raft nodes before linearized reading' (duration: 246.373267ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:32:25.327984Z","caller":"traceutil/trace.go:171","msg":"trace[1409548599] transaction","detail":"{read_only:false; response_revision:8710; number_of_response:1; }","duration":"110.50589ms","start":"2026-09-02T01:32:25.217449Z","end":"2026-09-02T01:32:25.327955Z","steps":["trace[1409548599] 'process raft request' (duration: 110.342086ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:32:25.723217Z","caller":"traceutil/trace.go:171","msg":"trace[905739500] transaction","detail":"{read_only:false; response_revision:8713; number_of_response:1; }","duration":"113.674774ms","start":"2026-09-02T01:32:25.609496Z","end":"2026-09-02T01:32:25.723171Z","steps":["trace[905739500] 'process raft request' (duration: 23.618444ms)","trace[905739500] 'compare' (duration: 89.790572ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:32:55.74144Z","caller":"traceutil/trace.go:171","msg":"trace[861814921] transaction","detail":"{read_only:false; response_revision:8925; number_of_response:1; }","duration":"522.359823ms","start":"2026-09-02T01:32:55.219039Z","end":"2026-09-02T01:32:55.741398Z","steps":["trace[861814921] 'process raft request' (duration: 522.025063ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:32:55.741701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:32:55.219018Z","time spent":"522.527628ms","remote":"127.0.0.1:36132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1119,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T01:32:58.008673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.307749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-02T01:32:58.008778Z","caller":"traceutil/trace.go:171","msg":"trace[64133506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8936; }","duration":"120.502944ms","start":"2026-09-02T01:32:57.888252Z","end":"2026-09-02T01:32:58.008755Z","steps":["trace[64133506] 'range keys from in-memory index tree' (duration: 119.804594ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:32:58.136216Z","caller":"traceutil/trace.go:171","msg":"trace[1589521166] transaction","detail":"{read_only:false; response_revision:8937; number_of_response:1; }","duration":"121.616617ms","start":"2026-09-02T01:32:58.014563Z","end":"2026-09-02T01:32:58.13618Z","steps":["trace[1589521166] 'process raft request' (duration: 121.36476ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:33:06.852713Z","caller":"traceutil/trace.go:171","msg":"trace[668473534] transaction","detail":"{read_only:false; response_revision:8982; number_of_response:1; }","duration":"121.126942ms","start":"2026-09-02T01:33:06.731549Z","end":"2026-09-02T01:33:06.852676Z","steps":["trace[668473534] 'process raft request' (duration: 50.891116ms)","trace[668473534] 'compare' (duration: 70.01256ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:33:06.873764Z","caller":"traceutil/trace.go:171","msg":"trace[460792757] transaction","detail":"{read_only:false; response_revision:8983; number_of_response:1; }","duration":"141.573253ms","start":"2026-09-02T01:33:06.732156Z","end":"2026-09-02T01:33:06.87373Z","steps":["trace[460792757] 'process raft request' (duration: 141.350907ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:33:06.874028Z","caller":"traceutil/trace.go:171","msg":"trace[997823090] transaction","detail":"{read_only:false; response_revision:8985; number_of_response:1; }","duration":"105.932836ms","start":"2026-09-02T01:33:06.768077Z","end":"2026-09-02T01:33:06.874009Z","steps":["trace[997823090] 'process raft request' (duration: 105.791601ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:33:06.874111Z","caller":"traceutil/trace.go:171","msg":"trace[980980472] transaction","detail":"{read_only:false; response_revision:8984; number_of_response:1; }","duration":"107.539022ms","start":"2026-09-02T01:33:06.766524Z","end":"2026-09-02T01:33:06.874063Z","steps":["trace[980980472] 'process raft request' (duration: 107.152521ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:33:06.874135Z","caller":"traceutil/trace.go:171","msg":"trace[523389433] linearizableReadLoop","detail":"{readStateIndex:9308; appliedIndex:9304; }","duration":"100.107535ms","start":"2026-09-02T01:33:06.773867Z","end":"2026-09-02T01:33:06.873975Z","steps":["trace[523389433] 'read index received' (duration: 8.626924ms)","trace[523389433] 'applied index is now lower than readState.Index' (duration: 91.478561ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:33:06.874337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.453394ms","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-09-02T01:33:06.874724Z","caller":"traceutil/trace.go:171","msg":"trace[331122099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8985; }","duration":"100.882597ms","start":"2026-09-02T01:33:06.773826Z","end":"2026-09-02T01:33:06.874709Z","steps":["trace[331122099] 'agreement among raft nodes before linearized reading' (duration: 100.341541ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:33:09.159358Z","caller":"traceutil/trace.go:171","msg":"trace[1679877997] transaction","detail":"{read_only:false; response_revision:9009; number_of_response:1; }","duration":"185.972389ms","start":"2026-09-02T01:33:08.973358Z","end":"2026-09-02T01:33:09.159331Z","steps":["trace[1679877997] 'process raft request' (duration: 185.817675ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:33:21.480813Z","caller":"traceutil/trace.go:171","msg":"trace[855917557] transaction","detail":"{read_only:false; response_revision:9137; number_of_response:1; }","duration":"134.090212ms","start":"2026-09-02T01:33:21.346694Z","end":"2026-09-02T01:33:21.480784Z","steps":["trace[855917557] 'process raft request' (duration: 133.875786ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:34:10.671895Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"353030364a385dbf","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-02T01:34:10.678913Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-02T01:34:10.679029Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-02T01:34:37.669251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.094579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:34:37.669356Z","caller":"traceutil/trace.go:171","msg":"trace[2107098087] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:9856; }","duration":"109.253973ms","start":"2026-09-02T01:34:37.560081Z","end":"2026-09-02T01:34:37.669335Z","steps":["trace[2107098087] 'count revisions from in-memory index tree' (duration: 108.992735ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:34:38.010599Z","caller":"traceutil/trace.go:171","msg":"trace[1663149213] transaction","detail":"{read_only:false; response_revision:9857; number_of_response:1; }","duration":"181.526879ms","start":"2026-09-02T01:34:37.829041Z","end":"2026-09-02T01:34:38.010568Z","steps":["trace[1663149213] 'process raft request' (duration: 181.313023ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:35:59.177504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8022} {"level":"info","ts":"2026-09-02T01:35:59.249393Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8022,"took":"69.507284ms","hash":968483931,"current-db-size-bytes":57450496,"current-db-size":"58 MB","current-db-size-in-use-bytes":27656192,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-02T01:35:59.24947Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":968483931,"revision":8022,"compact-revision":5876} {"level":"info","ts":"2026-09-02T01:40:25.241619Z","caller":"traceutil/trace.go:171","msg":"trace[1070599781] linearizableReadLoop","detail":"{readStateIndex:12761; appliedIndex:12760; }","duration":"128.709363ms","start":"2026-09-02T01:40:25.112883Z","end":"2026-09-02T01:40:25.241593Z","steps":["trace[1070599781] 'read index received' (duration: 128.306461ms)","trace[1070599781] 'applied index is now lower than readState.Index' (duration: 402.032µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:40:25.241627Z","caller":"traceutil/trace.go:171","msg":"trace[1434512765] transaction","detail":"{read_only:false; response_revision:12332; number_of_response:1; }","duration":"134.542355ms","start":"2026-09-02T01:40:25.10706Z","end":"2026-09-02T01:40:25.241602Z","steps":["trace[1434512765] 'process raft request' (duration: 134.098322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:40:25.241858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.95522ms","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-09-02T01:40:25.24191Z","caller":"traceutil/trace.go:171","msg":"trace[844325686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:12332; }","duration":"129.039683ms","start":"2026-09-02T01:40:25.112858Z","end":"2026-09-02T01:40:25.241898Z","steps":["trace[844325686] 'agreement among raft nodes before linearized reading' (duration: 128.827797ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:40:25.242018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.575243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-02T01:40:25.24206Z","caller":"traceutil/trace.go:171","msg":"trace[1521556274] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:12332; }","duration":"105.643306ms","start":"2026-09-02T01:40:25.136402Z","end":"2026-09-02T01:40:25.242046Z","steps":["trace[1521556274] 'agreement among raft nodes before linearized reading' (duration: 105.577844ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:40:25.430892Z","caller":"traceutil/trace.go:171","msg":"trace[392839705] linearizableReadLoop","detail":"{readStateIndex:12762; appliedIndex:12761; }","duration":"102.595485ms","start":"2026-09-02T01:40:25.328271Z","end":"2026-09-02T01:40:25.430866Z","steps":["trace[392839705] 'read index received' (duration: 52.684159ms)","trace[392839705] 'applied index is now lower than readState.Index' (duration: 49.910186ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:40:25.43105Z","caller":"traceutil/trace.go:171","msg":"trace[977208754] transaction","detail":"{read_only:false; response_revision:12333; number_of_response:1; }","duration":"184.814922ms","start":"2026-09-02T01:40:25.246113Z","end":"2026-09-02T01:40:25.430928Z","steps":["trace[977208754] 'process raft request' (duration: 134.801002ms)","trace[977208754] 'compare' (duration: 49.849095ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:40:25.432018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.301817ms","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-09-02T01:40:25.432105Z","caller":"traceutil/trace.go:171","msg":"trace[1676040000] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:12333; }","duration":"103.887593ms","start":"2026-09-02T01:40:25.328195Z","end":"2026-09-02T01:40:25.432083Z","steps":["trace[1676040000] 'agreement among raft nodes before linearized reading' (duration: 102.75343ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:40:25.60567Z","caller":"traceutil/trace.go:171","msg":"trace[684350305] transaction","detail":"{read_only:false; response_revision:12334; number_of_response:1; }","duration":"128.291791ms","start":"2026-09-02T01:40:25.477329Z","end":"2026-09-02T01:40:25.605621Z","steps":["trace[684350305] 'process raft request' (duration: 128.138177ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:40:25.622127Z","caller":"traceutil/trace.go:171","msg":"trace[1774482626] transaction","detail":"{read_only:false; response_revision:12335; number_of_response:1; }","duration":"144.297572ms","start":"2026-09-02T01:40:25.477806Z","end":"2026-09-02T01:40:25.622103Z","steps":["trace[1774482626] 'process raft request' (duration: 144.159488ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:40:26.776659Z","caller":"traceutil/trace.go:171","msg":"trace[280760179] transaction","detail":"{read_only:false; response_revision:12338; number_of_response:1; }","duration":"141.55523ms","start":"2026-09-02T01:40:26.635048Z","end":"2026-09-02T01:40:26.776603Z","steps":["trace[280760179] 'process raft request' (duration: 141.413826ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:40:26.776728Z","caller":"traceutil/trace.go:171","msg":"trace[693711535] transaction","detail":"{read_only:false; response_revision:12337; number_of_response:1; }","duration":"149.601057ms","start":"2026-09-02T01:40:26.627103Z","end":"2026-09-02T01:40:26.776704Z","steps":["trace[693711535] 'process raft request' (duration: 112.345472ms)","trace[693711535] 'compare' (duration: 36.839823ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:40:59.188116Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10392} {"level":"info","ts":"2026-09-02T01:40:59.288654Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10392,"took":"97.000441ms","hash":2153310362,"current-db-size-bytes":57450496,"current-db-size":"58 MB","current-db-size-in-use-bytes":25206784,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-02T01:40:59.288749Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2153310362,"revision":10392,"compact-revision":8022} {"level":"info","ts":"2026-09-02T01:45:59.19711Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12557} {"level":"info","ts":"2026-09-02T01:45:59.286058Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12557,"took":"84.624771ms","hash":1203682616,"current-db-size-bytes":57450496,"current-db-size":"58 MB","current-db-size-in-use-bytes":27541504,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-02T01:45:59.286111Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1203682616,"revision":12557,"compact-revision":10392} {"level":"info","ts":"2026-09-02T01:46:31.622606Z","caller":"traceutil/trace.go:171","msg":"trace[70687871] transaction","detail":"{read_only:false; response_revision:14806; number_of_response:1; }","duration":"108.566793ms","start":"2026-09-02T01:46:31.514022Z","end":"2026-09-02T01:46:31.622589Z","steps":["trace[70687871] 'process raft request' (duration: 108.44895ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:47:13.418761Z","caller":"traceutil/trace.go:171","msg":"trace[1348493981] transaction","detail":"{read_only:false; response_revision:15038; number_of_response:1; }","duration":"303.240591ms","start":"2026-09-02T01:47:13.115491Z","end":"2026-09-02T01:47:13.418732Z","steps":["trace[1348493981] 'process raft request' (duration: 303.041645ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:47:13.419081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:47:13.115445Z","time spent":"303.515479ms","remote":"127.0.0.1:40080","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-09-02T01:47:13.419633Z","caller":"traceutil/trace.go:171","msg":"trace[367227690] linearizableReadLoop","detail":"{readStateIndex:15565; appliedIndex:15563; }","duration":"300.116709ms","start":"2026-09-02T01:47:13.119492Z","end":"2026-09-02T01:47:13.419609Z","steps":["trace[367227690] 'read index received' (duration: 299.150621ms)","trace[367227690] 'applied index is now lower than readState.Index' (duration: 964.868µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:47:13.419673Z","caller":"traceutil/trace.go:171","msg":"trace[207489802] transaction","detail":"{read_only:false; response_revision:15039; number_of_response:1; }","duration":"302.415766ms","start":"2026-09-02T01:47:13.117239Z","end":"2026-09-02T01:47:13.419655Z","steps":["trace[207489802] 'process raft request' (duration: 302.247701ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:47:13.419853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.349426ms","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-09-02T01:47:13.419908Z","caller":"traceutil/trace.go:171","msg":"trace[2075585384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15039; }","duration":"300.457529ms","start":"2026-09-02T01:47:13.119436Z","end":"2026-09-02T01:47:13.419894Z","steps":["trace[2075585384] 'agreement among raft nodes before linearized reading' (duration: 300.290195ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:47:13.419956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:47:13.11942Z","time spent":"300.524491ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-02T01:47:13.419965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.216488ms","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-09-02T01:47:13.420065Z","caller":"traceutil/trace.go:171","msg":"trace[2049861792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15039; }","duration":"292.346232ms","start":"2026-09-02T01:47:13.127695Z","end":"2026-09-02T01:47:13.420041Z","steps":["trace[2049861792] 'agreement among raft nodes before linearized reading' (duration: 292.120615ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:47:13.420127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.053921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-w4hwr\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-09-02T01:47:13.420191Z","caller":"traceutil/trace.go:171","msg":"trace[691973930] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-w4hwr; range_end:; response_count:1; response_revision:15039; }","duration":"237.161594ms","start":"2026-09-02T01:47:13.183016Z","end":"2026-09-02T01:47:13.420177Z","steps":["trace[691973930] 'agreement among raft nodes before linearized reading' (duration: 237.052301ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:47:13.420267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:47:13.117223Z","time spent":"302.642594ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T01:47:31.373366Z","caller":"traceutil/trace.go:171","msg":"trace[63573282] linearizableReadLoop","detail":"{readStateIndex:15779; appliedIndex:15777; }","duration":"117.767553ms","start":"2026-09-02T01:47:31.255571Z","end":"2026-09-02T01:47:31.373338Z","steps":["trace[63573282] 'read index received' (duration: 102.292499ms)","trace[63573282] 'applied index is now lower than readState.Index' (duration: 15.473934ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:47:31.373482Z","caller":"traceutil/trace.go:171","msg":"trace[1656718846] transaction","detail":"{read_only:false; response_revision:15241; number_of_response:1; }","duration":"124.412437ms","start":"2026-09-02T01:47:31.249011Z","end":"2026-09-02T01:47:31.373423Z","steps":["trace[1656718846] 'process raft request' (duration: 108.845631ms)","trace[1656718846] 'compare' (duration: 15.134214ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:47:31.373662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.077763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w2c78\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-09-02T01:47:31.373709Z","caller":"traceutil/trace.go:171","msg":"trace[1074965893] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w2c78; range_end:; response_count:1; response_revision:15242; }","duration":"118.761072ms","start":"2026-09-02T01:47:31.254932Z","end":"2026-09-02T01:47:31.373693Z","steps":["trace[1074965893] 'agreement among raft nodes before linearized reading' (duration: 118.538656ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:47:31.373831Z","caller":"traceutil/trace.go:171","msg":"trace[192251670] transaction","detail":"{read_only:false; response_revision:15242; number_of_response:1; }","duration":"124.375376ms","start":"2026-09-02T01:47:31.249422Z","end":"2026-09-02T01:47:31.373797Z","steps":["trace[192251670] 'process raft request' (duration: 123.849821ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:47:37.156648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.680443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d78f1525-af25-4d2e-b457-5611fe5d94f8\" ","response":"range_response_count:1 size:3636"} {"level":"info","ts":"2026-09-02T01:47:37.156758Z","caller":"traceutil/trace.go:171","msg":"trace[2128116163] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d78f1525-af25-4d2e-b457-5611fe5d94f8; range_end:; response_count:1; response_revision:15353; }","duration":"143.863668ms","start":"2026-09-02T01:47:37.012871Z","end":"2026-09-02T01:47:37.156735Z","steps":["trace[2128116163] 'range keys from in-memory index tree' (duration: 143.416435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:47:37.555172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.111705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-02T01:47:37.555295Z","caller":"traceutil/trace.go:171","msg":"trace[330307981] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:15355; }","duration":"262.29285ms","start":"2026-09-02T01:47:37.292976Z","end":"2026-09-02T01:47:37.555269Z","steps":["trace[330307981] 'count revisions from in-memory index tree' (duration: 261.987652ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:47:37.555333Z","caller":"traceutil/trace.go:171","msg":"trace[266496289] transaction","detail":"{read_only:false; response_revision:15356; number_of_response:1; }","duration":"196.496921ms","start":"2026-09-02T01:47:37.358805Z","end":"2026-09-02T01:47:37.555301Z","steps":["trace[266496289] 'process raft request' (duration: 177.950658ms)","trace[266496289] 'compare' (duration: 18.025858ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:47:37.555851Z","caller":"traceutil/trace.go:171","msg":"trace[1515812818] linearizableReadLoop","detail":"{readStateIndex:15894; appliedIndex:15893; }","duration":"127.678594ms","start":"2026-09-02T01:47:37.428125Z","end":"2026-09-02T01:47:37.555804Z","steps":["trace[1515812818] 'read index received' (duration: 108.552903ms)","trace[1515812818] 'applied index is now lower than readState.Index' (duration: 19.123351ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:47:37.556169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.013664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-w2c78\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-09-02T01:47:37.556236Z","caller":"traceutil/trace.go:171","msg":"trace[666178033] range","detail":"{range_begin:/registry/pods/openstack/keepalived-w2c78; range_end:; response_count:1; response_revision:15356; }","duration":"128.136367ms","start":"2026-09-02T01:47:37.428083Z","end":"2026-09-02T01:47:37.556219Z","steps":["trace[666178033] 'agreement among raft nodes before linearized reading' (duration: 127.907031ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:47:37.683923Z","caller":"traceutil/trace.go:171","msg":"trace[893515277] transaction","detail":"{read_only:false; response_revision:15359; number_of_response:1; }","duration":"106.460242ms","start":"2026-09-02T01:47:37.57743Z","end":"2026-09-02T01:47:37.68389Z","steps":["trace[893515277] 'process raft request' (duration: 105.472433ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:47:37.6862Z","caller":"traceutil/trace.go:171","msg":"trace[760405590] linearizableReadLoop","detail":"{readStateIndex:15897; appliedIndex:15895; }","duration":"105.680699ms","start":"2026-09-02T01:47:37.580491Z","end":"2026-09-02T01:47:37.686171Z","steps":["trace[760405590] 'read index received' (duration: 6.255303ms)","trace[760405590] 'applied index is now lower than readState.Index' (duration: 99.423576ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:47:37.686458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.933166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-02T01:47:37.686514Z","caller":"traceutil/trace.go:171","msg":"trace[276726680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15359; }","duration":"106.08622ms","start":"2026-09-02T01:47:37.580415Z","end":"2026-09-02T01:47:37.686501Z","steps":["trace[276726680] 'agreement among raft nodes before linearized reading' (duration: 105.878934ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:48:30.049468Z","caller":"traceutil/trace.go:171","msg":"trace[186009584] transaction","detail":"{read_only:false; response_revision:15991; number_of_response:1; }","duration":"176.667021ms","start":"2026-09-02T01:48:29.872786Z","end":"2026-09-02T01:48:30.049453Z","steps":["trace[186009584] 'process raft request' (duration: 176.554278ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:48:30.04948Z","caller":"traceutil/trace.go:171","msg":"trace[1928671521] linearizableReadLoop","detail":"{readStateIndex:16544; appliedIndex:16544; }","duration":"113.88882ms","start":"2026-09-02T01:48:29.935567Z","end":"2026-09-02T01:48:30.049456Z","steps":["trace[1928671521] 'read index received' (duration: 113.88046ms)","trace[1928671521] 'applied index is now lower than readState.Index' (duration: 6.76µs)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:48:30.04971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.133828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-02T01:48:30.04977Z","caller":"traceutil/trace.go:171","msg":"trace[1749186204] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15991; }","duration":"114.233081ms","start":"2026-09-02T01:48:29.93552Z","end":"2026-09-02T01:48:30.049753Z","steps":["trace[1749186204] 'agreement among raft nodes before linearized reading' (duration: 114.048485ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:48:30.059889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.930506ms","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-09-02T01:48:30.059946Z","caller":"traceutil/trace.go:171","msg":"trace[599170678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15992; }","duration":"120.023289ms","start":"2026-09-02T01:48:29.939908Z","end":"2026-09-02T01:48:30.059932Z","steps":["trace[599170678] 'agreement among raft nodes before linearized reading' (duration: 119.784402ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:48:30.060059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.746995ms","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-09-02T01:48:30.060127Z","caller":"traceutil/trace.go:171","msg":"trace[1923847210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15992; }","duration":"114.832597ms","start":"2026-09-02T01:48:29.945275Z","end":"2026-09-02T01:48:30.060107Z","steps":["trace[1923847210] 'agreement among raft nodes before linearized reading' (duration: 114.675483ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:48:49.86149Z","caller":"traceutil/trace.go:171","msg":"trace[1424186408] linearizableReadLoop","detail":"{readStateIndex:16652; appliedIndex:16651; }","duration":"305.561119ms","start":"2026-09-02T01:48:49.55589Z","end":"2026-09-02T01:48:49.861451Z","steps":["trace[1424186408] 'read index received' (duration: 305.356033ms)","trace[1424186408] 'applied index is now lower than readState.Index' (duration: 203.596µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:48:49.861565Z","caller":"traceutil/trace.go:171","msg":"trace[944456306] transaction","detail":"{read_only:false; response_revision:16095; number_of_response:1; }","duration":"306.553398ms","start":"2026-09-02T01:48:49.554964Z","end":"2026-09-02T01:48:49.861517Z","steps":["trace[944456306] 'process raft request' (duration: 306.272159ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:48:49.861766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.855627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-02T01:48:49.861849Z","caller":"traceutil/trace.go:171","msg":"trace[661848732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16095; }","duration":"305.977901ms","start":"2026-09-02T01:48:49.555852Z","end":"2026-09-02T01:48:49.861829Z","steps":["trace[661848732] 'agreement among raft nodes before linearized reading' (duration: 305.762754ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:48:49.861891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:48:49.555834Z","time spent":"306.046422ms","remote":"127.0.0.1:40080","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-09-02T01:48:49.861757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:48:49.554939Z","time spent":"306.710292ms","remote":"127.0.0.1:40080","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-09-02T01:50:59.204323Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14563} {"level":"info","ts":"2026-09-02T01:50:59.288443Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14563,"took":"81.411934ms","hash":1349164627,"current-db-size-bytes":57450496,"current-db-size":"58 MB","current-db-size-in-use-bytes":31170560,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-02T01:50:59.2885Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1349164627,"revision":14563,"compact-revision":12557} {"level":"info","ts":"2026-09-02T01:55:24.025828Z","caller":"traceutil/trace.go:171","msg":"trace[1468078784] transaction","detail":"{read_only:false; response_revision:18923; number_of_response:1; }","duration":"126.822057ms","start":"2026-09-02T01:55:23.898975Z","end":"2026-09-02T01:55:24.025797Z","steps":["trace[1468078784] 'process raft request' (duration: 126.57015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:55:24.655546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.430681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T01:55:24.65574Z","caller":"traceutil/trace.go:171","msg":"trace[44772709] linearizableReadLoop","detail":"{readStateIndex:19579; appliedIndex:19578; }","duration":"288.74649ms","start":"2026-09-02T01:55:24.366971Z","end":"2026-09-02T01:55:24.655717Z","steps":["trace[44772709] 'read index received' (duration: 142.158585ms)","trace[44772709] 'applied index is now lower than readState.Index' (duration: 146.585465ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T01:55:24.655912Z","caller":"traceutil/trace.go:171","msg":"trace[1498882729] transaction","detail":"{read_only:false; response_revision:18929; number_of_response:1; }","duration":"290.1162ms","start":"2026-09-02T01:55:24.365745Z","end":"2026-09-02T01:55:24.655861Z","steps":["trace[1498882729] 'process raft request' (duration: 143.484764ms)","trace[1498882729] 'compare' (duration: 144.928236ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T01:55:24.655984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.004697ms","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-09-02T01:55:24.656037Z","caller":"traceutil/trace.go:171","msg":"trace[931501606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18929; }","duration":"289.115071ms","start":"2026-09-02T01:55:24.366905Z","end":"2026-09-02T01:55:24.65602Z","steps":["trace[931501606] 'agreement among raft nodes before linearized reading' (duration: 288.899254ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T01:55:26.055107Z","caller":"traceutil/trace.go:171","msg":"trace[1254361383] transaction","detail":"{read_only:false; response_revision:18932; number_of_response:1; }","duration":"393.547591ms","start":"2026-09-02T01:55:25.661538Z","end":"2026-09-02T01:55:26.055086Z","steps":["trace[1254361383] 'process raft request' (duration: 393.369136ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T01:55:26.055272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T01:55:25.661515Z","time spent":"393.679996ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-02T01:55:59.212091Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16893} {"level":"info","ts":"2026-09-02T01:55:59.319796Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16893,"took":"103.384691ms","hash":2462756606,"current-db-size-bytes":57450496,"current-db-size":"58 MB","current-db-size-in-use-bytes":32043008,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-02T01:55:59.319884Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2462756606,"revision":16893,"compact-revision":14563} {"level":"info","ts":"2026-09-02T01:56:02.283645Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"353030364a385dbf","local-member-applied-index":20006,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-02T01:56:02.300201Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20006} {"level":"info","ts":"2026-09-02T01:56:02.300311Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15006} {"level":"info","ts":"2026-09-02T02:00:59.219626Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19278} {"level":"info","ts":"2026-09-02T02:00:59.332809Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19278,"took":"106.795082ms","hash":2786057490,"current-db-size-bytes":57450496,"current-db-size":"58 MB","current-db-size-in-use-bytes":33845248,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-02T02:00:59.332896Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2786057490,"revision":19278,"compact-revision":16893} {"level":"info","ts":"2026-09-02T02:01:14.696336Z","caller":"traceutil/trace.go:171","msg":"trace[629820421] linearizableReadLoop","detail":"{readStateIndex:22433; appliedIndex:22432; }","duration":"117.200246ms","start":"2026-09-02T02:01:14.579109Z","end":"2026-09-02T02:01:14.69631Z","steps":["trace[629820421] 'read index received' (duration: 117.025091ms)","trace[629820421] 'applied index is now lower than readState.Index' (duration: 173.875µs)"],"step_count":2} {"level":"info","ts":"2026-09-02T02:01:14.696399Z","caller":"traceutil/trace.go:171","msg":"trace[2075178055] transaction","detail":"{read_only:false; response_revision:21692; number_of_response:1; }","duration":"117.834534ms","start":"2026-09-02T02:01:14.578523Z","end":"2026-09-02T02:01:14.696358Z","steps":["trace[2075178055] 'process raft request' (duration: 117.599227ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T02:01:14.696539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.408671ms","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-09-02T02:01:14.696584Z","caller":"traceutil/trace.go:171","msg":"trace[1571504189] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21692; }","duration":"117.471524ms","start":"2026-09-02T02:01:14.579096Z","end":"2026-09-02T02:01:14.696568Z","steps":["trace[1571504189] 'agreement among raft nodes before linearized reading' (duration: 117.31464ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:01:14.888525Z","caller":"traceutil/trace.go:171","msg":"trace[1612857958] linearizableReadLoop","detail":"{readStateIndex:22434; appliedIndex:22433; }","duration":"163.609633ms","start":"2026-09-02T02:01:14.724885Z","end":"2026-09-02T02:01:14.888495Z","steps":["trace[1612857958] 'read index received' (duration: 93.982527ms)","trace[1612857958] 'applied index is now lower than readState.Index' (duration: 69.625656ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T02:01:14.888655Z","caller":"traceutil/trace.go:171","msg":"trace[497676482] transaction","detail":"{read_only:false; response_revision:21693; number_of_response:1; }","duration":"187.253744ms","start":"2026-09-02T02:01:14.701372Z","end":"2026-09-02T02:01:14.888626Z","steps":["trace[497676482] 'process raft request' (duration: 117.489644ms)","trace[497676482] 'compare' (duration: 69.494602ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T02:01:14.888782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.87693ms","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-09-02T02:01:14.888844Z","caller":"traceutil/trace.go:171","msg":"trace[795106605] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21693; }","duration":"164.001375ms","start":"2026-09-02T02:01:14.724828Z","end":"2026-09-02T02:01:14.88883Z","steps":["trace[795106605] 'agreement among raft nodes before linearized reading' (duration: 163.779628ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:01:30.525757Z","caller":"traceutil/trace.go:171","msg":"trace[1843500904] transaction","detail":"{read_only:false; response_revision:21771; number_of_response:1; }","duration":"369.005927ms","start":"2026-09-02T02:01:30.156717Z","end":"2026-09-02T02:01:30.525723Z","steps":["trace[1843500904] 'process raft request' (duration: 368.830772ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T02:01:30.526066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T02:01:30.156691Z","time spent":"369.255704ms","remote":"127.0.0.1:40080","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-09-02T02:01:30.687965Z","caller":"traceutil/trace.go:171","msg":"trace[929548884] linearizableReadLoop","detail":"{readStateIndex:22516; appliedIndex:22514; }","duration":"437.290113ms","start":"2026-09-02T02:01:30.250629Z","end":"2026-09-02T02:01:30.687919Z","steps":["trace[929548884] 'read index received' (duration: 274.915457ms)","trace[929548884] 'applied index is now lower than readState.Index' (duration: 162.373476ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T02:01:30.688044Z","caller":"traceutil/trace.go:171","msg":"trace[1354160715] transaction","detail":"{read_only:false; response_revision:21772; number_of_response:1; }","duration":"448.225862ms","start":"2026-09-02T02:01:30.239756Z","end":"2026-09-02T02:01:30.687982Z","steps":["trace[1354160715] 'process raft request' (duration: 433.153661ms)","trace[1354160715] 'compare' (duration: 14.899116ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T02:01:30.68825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.605132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-09-02T02:01:30.688293Z","caller":"traceutil/trace.go:171","msg":"trace[761292933] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:21772; }","duration":"437.766477ms","start":"2026-09-02T02:01:30.250515Z","end":"2026-09-02T02:01:30.688281Z","steps":["trace[761292933] 'agreement among raft nodes before linearized reading' (duration: 437.593652ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T02:01:30.688327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T02:01:30.250494Z","time spent":"437.825369ms","remote":"127.0.0.1:36216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1872,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" "} {"level":"warn","ts":"2026-09-02T02:01:30.688371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.284361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-02T02:01:30.688475Z","caller":"traceutil/trace.go:171","msg":"trace[1512681334] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21772; }","duration":"414.418305ms","start":"2026-09-02T02:01:30.274031Z","end":"2026-09-02T02:01:30.688449Z","steps":["trace[1512681334] 'agreement among raft nodes before linearized reading' (duration: 414.158007ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T02:01:30.688441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T02:01:30.239725Z","time spent":"448.395867ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-02T02:01:30.688535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-02T02:01:30.274005Z","time spent":"414.516817ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-02T02:01:31.013213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.345387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T02:01:31.013308Z","caller":"traceutil/trace.go:171","msg":"trace[403628136] linearizableReadLoop","detail":"{readStateIndex:22518; appliedIndex:22517; }","duration":"168.553497ms","start":"2026-09-02T02:01:30.844739Z","end":"2026-09-02T02:01:31.013292Z","steps":["trace[403628136] 'read index received' (duration: 6.997224ms)","trace[403628136] 'applied index is now lower than readState.Index' (duration: 161.555182ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T02:01:31.013411Z","caller":"traceutil/trace.go:171","msg":"trace[619294851] transaction","detail":"{read_only:false; response_revision:21774; number_of_response:1; }","duration":"234.634437ms","start":"2026-09-02T02:01:30.778759Z","end":"2026-09-02T02:01:31.013393Z","steps":["trace[619294851] 'process raft request' (duration: 73.033104ms)","trace[619294851] 'compare' (duration: 161.195632ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T02:01:31.013538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.684742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T02:01:31.01361Z","caller":"traceutil/trace.go:171","msg":"trace[1481680559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21774; }","duration":"167.776654ms","start":"2026-09-02T02:01:30.845813Z","end":"2026-09-02T02:01:31.01359Z","steps":["trace[1481680559] 'agreement among raft nodes before linearized reading' (duration: 167.660081ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T02:01:31.013641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.883797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-09-02T02:01:31.013716Z","caller":"traceutil/trace.go:171","msg":"trace[1132018080] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21774; }","duration":"168.99875ms","start":"2026-09-02T02:01:30.8447Z","end":"2026-09-02T02:01:31.013699Z","steps":["trace[1132018080] 'agreement among raft nodes before linearized reading' (duration: 168.798045ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T02:01:31.319654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.220629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-02T02:01:31.31977Z","caller":"traceutil/trace.go:171","msg":"trace[1074921275] linearizableReadLoop","detail":"{readStateIndex:22520; appliedIndex:22519; }","duration":"202.879161ms","start":"2026-09-02T02:01:31.11687Z","end":"2026-09-02T02:01:31.319749Z","steps":["trace[1074921275] 'read index received' (duration: 76.458475ms)","trace[1074921275] 'applied index is now lower than readState.Index' (duration: 126.419176ms)"],"step_count":2} {"level":"info","ts":"2026-09-02T02:01:31.319808Z","caller":"traceutil/trace.go:171","msg":"trace[1191191884] transaction","detail":"{read_only:false; response_revision:21776; number_of_response:1; }","duration":"239.299725ms","start":"2026-09-02T02:01:31.080479Z","end":"2026-09-02T02:01:31.319778Z","steps":["trace[1191191884] 'process raft request' (duration: 112.795997ms)","trace[1191191884] 'compare' (duration: 126.083655ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T02:01:31.319873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.010214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-02T02:01:31.319907Z","caller":"traceutil/trace.go:171","msg":"trace[1349688476] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21776; }","duration":"203.096917ms","start":"2026-09-02T02:01:31.116798Z","end":"2026-09-02T02:01:31.319895Z","steps":["trace[1349688476] 'agreement among raft nodes before linearized reading' (duration: 203.003934ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:01:31.708407Z","caller":"traceutil/trace.go:171","msg":"trace[122268427] transaction","detail":"{read_only:false; response_revision:21777; number_of_response:1; }","duration":"208.615489ms","start":"2026-09-02T02:01:31.499767Z","end":"2026-09-02T02:01:31.708382Z","steps":["trace[122268427] 'process raft request' (duration: 208.479735ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:01:31.733795Z","caller":"traceutil/trace.go:171","msg":"trace[1248976027] transaction","detail":"{read_only:false; response_revision:21778; number_of_response:1; }","duration":"175.117249ms","start":"2026-09-02T02:01:31.558617Z","end":"2026-09-02T02:01:31.733735Z","steps":["trace[1248976027] 'process raft request' (duration: 174.83301ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:01:31.733936Z","caller":"traceutil/trace.go:171","msg":"trace[347104196] linearizableReadLoop","detail":"{readStateIndex:22522; appliedIndex:22520; }","duration":"121.976906ms","start":"2026-09-02T02:01:31.611931Z","end":"2026-09-02T02:01:31.733908Z","steps":["trace[347104196] 'read index received' (duration: 96.336106ms)","trace[347104196] 'applied index is now lower than readState.Index' (duration: 25.63884ms)"],"step_count":2} {"level":"warn","ts":"2026-09-02T02:01:31.734183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.15061ms","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-09-02T02:01:31.734263Z","caller":"traceutil/trace.go:171","msg":"trace[80732351] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21780; }","duration":"122.377978ms","start":"2026-09-02T02:01:31.611866Z","end":"2026-09-02T02:01:31.734244Z","steps":["trace[80732351] 'agreement among raft nodes before linearized reading' (duration: 122.13091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-02T02:05:46.935306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.257237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-02T02:05:46.935396Z","caller":"traceutil/trace.go:171","msg":"trace[232602092] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:24395; }","duration":"209.398581ms","start":"2026-09-02T02:05:46.72598Z","end":"2026-09-02T02:05:46.935378Z","steps":["trace[232602092] 'count revisions from in-memory index tree' (duration: 209.187415ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:05:46.935746Z","caller":"traceutil/trace.go:171","msg":"trace[645463055] transaction","detail":"{read_only:false; response_revision:24396; number_of_response:1; }","duration":"163.123208ms","start":"2026-09-02T02:05:46.772572Z","end":"2026-09-02T02:05:46.935695Z","steps":["trace[645463055] 'process raft request' (duration: 158.854034ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:05:46.93784Z","caller":"traceutil/trace.go:171","msg":"trace[673971904] transaction","detail":"{read_only:false; response_revision:24397; number_of_response:1; }","duration":"135.323215ms","start":"2026-09-02T02:05:46.80249Z","end":"2026-09-02T02:05:46.937813Z","steps":["trace[673971904] 'process raft request' (duration: 135.021207ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:05:46.93787Z","caller":"traceutil/trace.go:171","msg":"trace[1935498589] transaction","detail":"{read_only:false; response_revision:24399; number_of_response:1; }","duration":"108.11035ms","start":"2026-09-02T02:05:46.829723Z","end":"2026-09-02T02:05:46.937834Z","steps":["trace[1935498589] 'process raft request' (duration: 108.005237ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:05:46.938052Z","caller":"traceutil/trace.go:171","msg":"trace[2039793686] transaction","detail":"{read_only:false; response_revision:24398; number_of_response:1; }","duration":"118.255547ms","start":"2026-09-02T02:05:46.819536Z","end":"2026-09-02T02:05:46.937791Z","steps":["trace[2039793686] 'process raft request' (duration: 118.127963ms)"],"step_count":1} {"level":"info","ts":"2026-09-02T02:05:59.227515Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21611} {"level":"info","ts":"2026-09-02T02:05:59.351388Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21611,"took":"116.305059ms","hash":1170431485,"current-db-size-bytes":57450496,"current-db-size":"58 MB","current-db-size-in-use-bytes":42897408,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-02T02:05:59.351466Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1170431485,"revision":21611,"compact-revision":19278} {"level":"info","ts":"2026-09-02T02:07:41.263309Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006709.wal"} {"level":"info","ts":"2026-09-02T02:10:59.236055Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24507} {"level":"info","ts":"2026-09-02T02:10:59.392545Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24507,"took":"150.689595ms","hash":648218498,"current-db-size-bytes":57450496,"current-db-size":"58 MB","current-db-size-in-use-bytes":33333248,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-02T02:10:59.392641Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":648218498,"revision":24507,"compact-revision":21611} {"level":"info","ts":"2026-09-02T02:15:32.494881Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"353030364a385dbf","local-member-applied-index":30007,"local-member-snapshot-index":20006,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-02T02:15:32.501062Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30007} {"level":"info","ts":"2026-09-02T02:15:32.501179Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25007} {"level":"info","ts":"2026-09-02T02:15:59.24447Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27025} {"level":"info","ts":"2026-09-02T02:15:59.370525Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27025,"took":"120.233045ms","hash":652828408,"current-db-size-bytes":57450496,"current-db-size":"58 MB","current-db-size-in-use-bytes":29663232,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-02T02:15:59.370588Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":652828408,"revision":27025,"compact-revision":24507}