{"level":"warn","ts":"2026-10-04T09:55:42.173874Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T09:55:42.173967Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.160: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.160:2380","--initial-cluster=instance=https://162.253.55.160:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.160:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.160: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-10-04T09:55:42.174051Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-04T09:55:42.174069Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.160:2380"]} {"level":"info","ts":"2026-10-04T09:55:42.17409Z","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-10-04T09:55:42.174858Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.160:2379"]} {"level":"info","ts":"2026-10-04T09:55:42.174955Z","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.160:2380"],"listen-peer-urls":["https://162.253.55.160:2380"],"advertise-client-urls":["https://162.253.55.160:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.160:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.160: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-10-04T09:55:42.179848Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.609869ms"} {"level":"info","ts":"2026-10-04T09:55:42.189503Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"596257f6524f82c3","cluster-id":"52bc9097ef2bef78"} {"level":"info","ts":"2026-10-04T09:55:42.18963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"596257f6524f82c3 switched to configuration voters=()"} {"level":"info","ts":"2026-10-04T09:55:42.189678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"596257f6524f82c3 became follower at term 0"} {"level":"info","ts":"2026-10-04T09:55:42.189694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 596257f6524f82c3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-04T09:55:42.189713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"596257f6524f82c3 became follower at term 1"} {"level":"info","ts":"2026-10-04T09:55:42.189769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"596257f6524f82c3 switched to configuration voters=(6440807132547744451)"} {"level":"warn","ts":"2026-10-04T09:55:42.195979Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-04T09:55:42.19805Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-04T09:55:42.199658Z","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-10-04T09:55:42.202901Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"596257f6524f82c3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-04T09:55:42.203341Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"596257f6524f82c3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-04T09:55:42.203399Z","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-10-04T09:55:42.20342Z","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-10-04T09:55:42.203428Z","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-10-04T09:55:42.205442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"596257f6524f82c3 switched to configuration voters=(6440807132547744451)"} {"level":"info","ts":"2026-10-04T09:55:42.205631Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bc9097ef2bef78","local-member-id":"596257f6524f82c3","added-peer-id":"596257f6524f82c3","added-peer-peer-urls":["https://162.253.55.160:2380"]} {"level":"info","ts":"2026-10-04T09:55:42.208479Z","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-10-04T09:55:42.208568Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.160:2380"} {"level":"info","ts":"2026-10-04T09:55:42.20861Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.160:2380"} {"level":"info","ts":"2026-10-04T09:55:42.209449Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"596257f6524f82c3","initial-advertise-peer-urls":["https://162.253.55.160:2380"],"listen-peer-urls":["https://162.253.55.160:2380"],"advertise-client-urls":["https://162.253.55.160:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.160:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-04T09:55:42.209511Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-04T09:55:42.590859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"596257f6524f82c3 is starting a new election at term 1"} {"level":"info","ts":"2026-10-04T09:55:42.590972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"596257f6524f82c3 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-04T09:55:42.591007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"596257f6524f82c3 received MsgPreVoteResp from 596257f6524f82c3 at term 1"} {"level":"info","ts":"2026-10-04T09:55:42.591039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"596257f6524f82c3 became candidate at term 2"} {"level":"info","ts":"2026-10-04T09:55:42.591057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"596257f6524f82c3 received MsgVoteResp from 596257f6524f82c3 at term 2"} {"level":"info","ts":"2026-10-04T09:55:42.591074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"596257f6524f82c3 became leader at term 2"} {"level":"info","ts":"2026-10-04T09:55:42.591095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 596257f6524f82c3 elected leader 596257f6524f82c3 at term 2"} {"level":"info","ts":"2026-10-04T09:55:42.593288Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T09:55:42.594172Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T09:55:42.594157Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"596257f6524f82c3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.160:2379]}","request-path":"/0/members/596257f6524f82c3/attributes","cluster-id":"52bc9097ef2bef78","publish-timeout":"7s"} {"level":"info","ts":"2026-10-04T09:55:42.594328Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-04T09:55:42.594506Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-04T09:55:42.594551Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-04T09:55:42.594899Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bc9097ef2bef78","local-member-id":"596257f6524f82c3","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T09:55:42.595078Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T09:55:42.595129Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-04T09:55:42.598321Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.160:2379"} {"level":"info","ts":"2026-10-04T09:55:42.598444Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-04T09:56:29.28582Z","caller":"traceutil/trace.go:171","msg":"trace[314055785] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"113.914511ms","start":"2026-10-04T09:56:29.171872Z","end":"2026-10-04T09:56:29.285786Z","steps":["trace[314055785] 'process raft request' (duration: 113.741737ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:56:29.478942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.335539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T09:56:29.479081Z","caller":"traceutil/trace.go:171","msg":"trace[699712647] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"168.573762ms","start":"2026-10-04T09:56:29.310487Z","end":"2026-10-04T09:56:29.479061Z","steps":["trace[699712647] 'process raft request' (duration: 57.369551ms)","trace[699712647] 'compare' (duration: 110.172665ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T09:56:29.702884Z","caller":"traceutil/trace.go:171","msg":"trace[662852677] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"236.863116ms","start":"2026-10-04T09:56:29.465996Z","end":"2026-10-04T09:56:29.70286Z","steps":["trace[662852677] 'process raft request' (duration: 236.158828ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:56:30.203503Z","caller":"traceutil/trace.go:171","msg":"trace[1107332985] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"420.884156ms","start":"2026-10-04T09:56:29.782488Z","end":"2026-10-04T09:56:30.203372Z","steps":["trace[1107332985] 'process raft request' (duration: 419.88676ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:56:30.203794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:56:29.782452Z","time spent":"421.194325ms","remote":"127.0.0.1:36238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-04T09:56:45.511068Z","caller":"traceutil/trace.go:171","msg":"trace[539823558] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"133.533028ms","start":"2026-10-04T09:56:45.3775Z","end":"2026-10-04T09:56:45.511033Z","steps":["trace[539823558] 'read index received' (duration: 133.195409ms)","trace[539823558] 'applied index is now lower than readState.Index' (duration: 336.469µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T09:56:45.511169Z","caller":"traceutil/trace.go:171","msg":"trace[669888016] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"150.749282ms","start":"2026-10-04T09:56:45.360383Z","end":"2026-10-04T09:56:45.511132Z","steps":["trace[669888016] 'process raft request' (duration: 150.433004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:56:45.511378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.839026ms","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-10-04T09:56:45.511447Z","caller":"traceutil/trace.go:171","msg":"trace[354418886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:808; }","duration":"133.967109ms","start":"2026-10-04T09:56:45.377464Z","end":"2026-10-04T09:56:45.511431Z","steps":["trace[354418886] 'agreement among raft nodes before linearized reading' (duration: 133.704792ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:58:09.204881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.327123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-04T09:58:09.205433Z","caller":"traceutil/trace.go:171","msg":"trace[997388868] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"353.889171ms","start":"2026-10-04T09:58:08.851507Z","end":"2026-10-04T09:58:09.205396Z","steps":["trace[997388868] 'process raft request' (duration: 46.913001ms)","trace[997388868] 'compare' (duration: 306.127428ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T09:58:09.205571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:58:08.851486Z","time spent":"354.023513ms","remote":"127.0.0.1:35882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T09:58:09.205766Z","caller":"traceutil/trace.go:171","msg":"trace[1406927006] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"346.323505ms","start":"2026-10-04T09:58:08.859421Z","end":"2026-10-04T09:58:09.205745Z","steps":["trace[1406927006] 'process raft request' (duration: 346.142651ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:58:09.205891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:58:08.859406Z","time spent":"346.422729ms","remote":"127.0.0.1:35908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T09:58:09.205665Z","caller":"traceutil/trace.go:171","msg":"trace[471406417] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"353.323516ms","start":"2026-10-04T09:58:08.852325Z","end":"2026-10-04T09:58:09.205649Z","steps":["trace[471406417] 'process raft request' (duration: 353.08525ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:58:09.205804Z","caller":"traceutil/trace.go:171","msg":"trace[2106454386] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"351.55099ms","start":"2026-10-04T09:58:08.854194Z","end":"2026-10-04T09:58:09.205745Z","steps":["trace[2106454386] 'process raft request' (duration: 351.293804ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:58:09.20611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:58:08.8523Z","time spent":"353.760888ms","remote":"127.0.0.1:36014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T09:58:09.206353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:58:08.854186Z","time spent":"352.018532ms","remote":"127.0.0.1:36014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T09:58:09.206819Z","caller":"traceutil/trace.go:171","msg":"trace[43507546] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"354.616079ms","start":"2026-10-04T09:58:08.852177Z","end":"2026-10-04T09:58:09.206794Z","steps":["trace[43507546] 'process raft request' (duration: 353.151372ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:58:09.206997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T09:58:08.852142Z","time spent":"354.788904ms","remote":"127.0.0.1:35882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T09:58:09.392109Z","caller":"traceutil/trace.go:171","msg":"trace[1600683242] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"116.794257ms","start":"2026-10-04T09:58:09.275281Z","end":"2026-10-04T09:58:09.392075Z","steps":["trace[1600683242] 'process raft request' (duration: 45.659789ms)","trace[1600683242] 'compare' (duration: 70.625544ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T09:58:21.838671Z","caller":"traceutil/trace.go:171","msg":"trace[1718315076] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"107.910628ms","start":"2026-10-04T09:58:21.730734Z","end":"2026-10-04T09:58:21.838645Z","steps":["trace[1718315076] 'process raft request' (duration: 107.488706ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:58:58.39335Z","caller":"traceutil/trace.go:171","msg":"trace[2062352742] linearizableReadLoop","detail":"{readStateIndex:2310; appliedIndex:2308; }","duration":"117.859846ms","start":"2026-10-04T09:58:58.275462Z","end":"2026-10-04T09:58:58.393322Z","steps":["trace[2062352742] 'read index received' (duration: 87.942343ms)","trace[2062352742] 'applied index is now lower than readState.Index' (duration: 29.916313ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T09:58:58.393383Z","caller":"traceutil/trace.go:171","msg":"trace[375032950] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"124.470396ms","start":"2026-10-04T09:58:58.268875Z","end":"2026-10-04T09:58:58.393346Z","steps":["trace[375032950] 'process raft request' (duration: 100.899428ms)","trace[375032950] 'compare' (duration: 23.325282ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T09:58:58.393534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.057911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T09:58:58.393565Z","caller":"traceutil/trace.go:171","msg":"trace[34943498] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2224; }","duration":"118.166914ms","start":"2026-10-04T09:58:58.275389Z","end":"2026-10-04T09:58:58.393556Z","steps":["trace[34943498] 'agreement among raft nodes before linearized reading' (duration: 118.055801ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:59:04.393098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.150125ms","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-10-04T09:59:04.393917Z","caller":"traceutil/trace.go:171","msg":"trace[1533247254] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2252; }","duration":"227.025447ms","start":"2026-10-04T09:59:04.16687Z","end":"2026-10-04T09:59:04.393896Z","steps":["trace[1533247254] 'range keys from in-memory index tree' (duration: 225.921079ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:59:04.551902Z","caller":"traceutil/trace.go:171","msg":"trace[1056215534] linearizableReadLoop","detail":"{readStateIndex:2342; appliedIndex:2341; }","duration":"104.85451ms","start":"2026-10-04T09:59:04.447016Z","end":"2026-10-04T09:59:04.55187Z","steps":["trace[1056215534] 'read index received' (duration: 104.550522ms)","trace[1056215534] 'applied index is now lower than readState.Index' (duration: 302.898µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T09:59:04.552151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.114527ms","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-10-04T09:59:04.552256Z","caller":"traceutil/trace.go:171","msg":"trace[1612053345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2254; }","duration":"105.25855ms","start":"2026-10-04T09:59:04.446968Z","end":"2026-10-04T09:59:04.552226Z","steps":["trace[1612053345] 'agreement among raft nodes before linearized reading' (duration: 105.040345ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:59:04.552624Z","caller":"traceutil/trace.go:171","msg":"trace[675985713] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"132.615267ms","start":"2026-10-04T09:59:04.419341Z","end":"2026-10-04T09:59:04.551956Z","steps":["trace[675985713] 'process raft request' (duration: 132.30901ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:59:09.851335Z","caller":"traceutil/trace.go:171","msg":"trace[645567914] linearizableReadLoop","detail":"{readStateIndex:2370; appliedIndex:2369; }","duration":"125.606225ms","start":"2026-10-04T09:59:09.725697Z","end":"2026-10-04T09:59:09.851303Z","steps":["trace[645567914] 'read index received' (duration: 124.684632ms)","trace[645567914] 'applied index is now lower than readState.Index' (duration: 908.623µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T09:59:09.851617Z","caller":"traceutil/trace.go:171","msg":"trace[908321492] transaction","detail":"{read_only:false; response_revision:2281; number_of_response:1; }","duration":"136.947679ms","start":"2026-10-04T09:59:09.714656Z","end":"2026-10-04T09:59:09.851604Z","steps":["trace[908321492] 'process raft request' (duration: 135.824899ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:59:09.852085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.063388ms","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-10-04T09:59:09.852213Z","caller":"traceutil/trace.go:171","msg":"trace[931062350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2281; }","duration":"114.277204ms","start":"2026-10-04T09:59:09.737907Z","end":"2026-10-04T09:59:09.852184Z","steps":["trace[931062350] 'agreement among raft nodes before linearized reading' (duration: 113.878214ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T09:59:09.852721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.978561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T09:59:09.852853Z","caller":"traceutil/trace.go:171","msg":"trace[1697989817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2281; }","duration":"127.170086ms","start":"2026-10-04T09:59:09.725657Z","end":"2026-10-04T09:59:09.852827Z","steps":["trace[1697989817] 'agreement among raft nodes before linearized reading' (duration: 126.195861ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T09:59:40.143511Z","caller":"traceutil/trace.go:171","msg":"trace[1554276383] transaction","detail":"{read_only:false; response_revision:2621; number_of_response:1; }","duration":"117.356701ms","start":"2026-10-04T09:59:40.026124Z","end":"2026-10-04T09:59:40.143481Z","steps":["trace[1554276383] 'process raft request' (duration: 117.095464ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:03:37.576225Z","caller":"traceutil/trace.go:171","msg":"trace[1129953336] transaction","detail":"{read_only:false; response_revision:4095; number_of_response:1; }","duration":"134.965763ms","start":"2026-10-04T10:03:37.441235Z","end":"2026-10-04T10:03:37.576201Z","steps":["trace[1129953336] 'process raft request' (duration: 134.8195ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:03:37.625855Z","caller":"traceutil/trace.go:171","msg":"trace[1736701202] transaction","detail":"{read_only:false; response_revision:4096; number_of_response:1; }","duration":"106.218462ms","start":"2026-10-04T10:03:37.519606Z","end":"2026-10-04T10:03:37.625825Z","steps":["trace[1736701202] 'process raft request' (duration: 106.020507ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:05:08.49203Z","caller":"traceutil/trace.go:171","msg":"trace[1105551151] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"128.052502ms","start":"2026-10-04T10:05:08.363954Z","end":"2026-10-04T10:05:08.492007Z","steps":["trace[1105551151] 'process raft request' (duration: 79.49367ms)","trace[1105551151] 'compare' (duration: 48.367097ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:05:08.491993Z","caller":"traceutil/trace.go:171","msg":"trace[364310267] linearizableReadLoop","detail":"{readStateIndex:5401; appliedIndex:5400; }","duration":"116.828692ms","start":"2026-10-04T10:05:08.375133Z","end":"2026-10-04T10:05:08.491962Z","steps":["trace[364310267] 'read index received' (duration: 68.303531ms)","trace[364310267] 'applied index is now lower than readState.Index' (duration: 48.521371ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:05:08.492212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.609829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-wmz9b\" ","response":"range_response_count:1 size:6531"} {"level":"warn","ts":"2026-10-04T10:05:08.49226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.072601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-10-04T10:05:08.492305Z","caller":"traceutil/trace.go:171","msg":"trace[1124306787] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5202; }","duration":"114.174434ms","start":"2026-10-04T10:05:08.378119Z","end":"2026-10-04T10:05:08.492294Z","steps":["trace[1124306787] 'agreement among raft nodes before linearized reading' (duration: 114.02844ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:05:08.492304Z","caller":"traceutil/trace.go:171","msg":"trace[1133708592] range","detail":"{range_begin:/registry/pods/openstack/keepalived-wmz9b; range_end:; response_count:1; response_revision:5202; }","duration":"118.726492ms","start":"2026-10-04T10:05:08.373561Z","end":"2026-10-04T10:05:08.492287Z","steps":["trace[1133708592] 'agreement among raft nodes before linearized reading' (duration: 118.501006ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:05:12.174741Z","caller":"traceutil/trace.go:171","msg":"trace[429551361] transaction","detail":"{read_only:false; response_revision:5329; number_of_response:1; }","duration":"126.501693ms","start":"2026-10-04T10:05:12.048206Z","end":"2026-10-04T10:05:12.174708Z","steps":["trace[429551361] 'process raft request' (duration: 126.297307ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:05:12.403056Z","caller":"traceutil/trace.go:171","msg":"trace[584071246] transaction","detail":"{read_only:false; response_revision:5330; number_of_response:1; }","duration":"304.349669ms","start":"2026-10-04T10:05:12.098673Z","end":"2026-10-04T10:05:12.403023Z","steps":["trace[584071246] 'process raft request' (duration: 224.644373ms)","trace[584071246] 'compare' (duration: 79.186543ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:05:12.403157Z","caller":"traceutil/trace.go:171","msg":"trace[1757500995] transaction","detail":"{read_only:false; response_revision:5331; number_of_response:1; }","duration":"296.662521ms","start":"2026-10-04T10:05:12.106439Z","end":"2026-10-04T10:05:12.403102Z","steps":["trace[1757500995] 'process raft request' (duration: 296.424985ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:05:12.40321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:05:12.09865Z","time spent":"304.481433ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:05:12.403677Z","caller":"traceutil/trace.go:171","msg":"trace[1898053500] linearizableReadLoop","detail":"{readStateIndex:5534; appliedIndex:5532; }","duration":"253.53849ms","start":"2026-10-04T10:05:12.150119Z","end":"2026-10-04T10:05:12.403657Z","steps":["trace[1898053500] 'read index received' (duration: 24.582025ms)","trace[1898053500] 'applied index is now lower than readState.Index' (duration: 228.955135ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:05:12.403873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.756985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2d2d7d22-cfa3-4d2e-9d29-bebc4e565294\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-04T10:05:12.403934Z","caller":"traceutil/trace.go:171","msg":"trace[604905114] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2d2d7d22-cfa3-4d2e-9d29-bebc4e565294; range_end:; response_count:1; response_revision:5331; }","duration":"253.859098ms","start":"2026-10-04T10:05:12.150064Z","end":"2026-10-04T10:05:12.403923Z","steps":["trace[604905114] 'agreement among raft nodes before linearized reading' (duration: 253.692824ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:05:12.419634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.383148ms","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-10-04T10:05:12.419695Z","caller":"traceutil/trace.go:171","msg":"trace[1909941599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5332; }","duration":"245.500911ms","start":"2026-10-04T10:05:12.17418Z","end":"2026-10-04T10:05:12.419681Z","steps":["trace[1909941599] 'agreement among raft nodes before linearized reading' (duration: 245.326487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:05:12.419767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.475388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2d2d7d22-cfa3-4d2e-9d29-bebc4e565294\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-10-04T10:05:12.419832Z","caller":"traceutil/trace.go:171","msg":"trace[824668457] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2d2d7d22-cfa3-4d2e-9d29-bebc4e565294; range_end:; response_count:1; response_revision:5332; }","duration":"222.610871ms","start":"2026-10-04T10:05:12.197205Z","end":"2026-10-04T10:05:12.419816Z","steps":["trace[824668457] 'agreement among raft nodes before linearized reading' (duration: 222.371125ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:05:14.543155Z","caller":"traceutil/trace.go:171","msg":"trace[1299320155] transaction","detail":"{read_only:false; response_revision:5363; number_of_response:1; }","duration":"133.457711ms","start":"2026-10-04T10:05:14.409651Z","end":"2026-10-04T10:05:14.543109Z","steps":["trace[1299320155] 'process raft request' (duration: 115.671013ms)","trace[1299320155] 'compare' (duration: 17.219284ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:05:14.543206Z","caller":"traceutil/trace.go:171","msg":"trace[1184473297] linearizableReadLoop","detail":"{readStateIndex:5568; appliedIndex:5565; }","duration":"114.079112ms","start":"2026-10-04T10:05:14.429081Z","end":"2026-10-04T10:05:14.543161Z","steps":["trace[1184473297] 'read index received' (duration: 24.534072ms)","trace[1184473297] 'applied index is now lower than readState.Index' (duration: 89.54335ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:05:14.543218Z","caller":"traceutil/trace.go:171","msg":"trace[1700125445] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"118.858025ms","start":"2026-10-04T10:05:14.424314Z","end":"2026-10-04T10:05:14.543172Z","steps":["trace[1700125445] 'process raft request' (duration: 118.67192ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:05:14.543621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.501902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2d2d7d22-cfa3-4d2e-9d29-bebc4e565294\" ","response":"range_response_count:1 size:3907"} {"level":"info","ts":"2026-10-04T10:05:14.543674Z","caller":"traceutil/trace.go:171","msg":"trace[698890932] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2d2d7d22-cfa3-4d2e-9d29-bebc4e565294; range_end:; response_count:1; response_revision:5364; }","duration":"114.630866ms","start":"2026-10-04T10:05:14.429029Z","end":"2026-10-04T10:05:14.54366Z","steps":["trace[698890932] 'agreement among raft nodes before linearized reading' (duration: 114.376069ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:05:14.54373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.508359ms","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-10-04T10:05:14.543811Z","caller":"traceutil/trace.go:171","msg":"trace[2044055065] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5364; }","duration":"108.639343ms","start":"2026-10-04T10:05:14.435152Z","end":"2026-10-04T10:05:14.543792Z","steps":["trace[2044055065] 'agreement among raft nodes before linearized reading' (duration: 108.356164ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:05:36.342457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.574072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3fcad0c0-f1b7-4ce6-92a1-d87f2b7d0056\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-04T10:05:36.342583Z","caller":"traceutil/trace.go:171","msg":"trace[963596186] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3fcad0c0-f1b7-4ce6-92a1-d87f2b7d0056; range_end:; response_count:1; response_revision:5800; }","duration":"141.757576ms","start":"2026-10-04T10:05:36.200804Z","end":"2026-10-04T10:05:36.342561Z","steps":["trace[963596186] 'range keys from in-memory index tree' (duration: 141.257204ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:05:36.718307Z","caller":"traceutil/trace.go:171","msg":"trace[1562163038] linearizableReadLoop","detail":"{readStateIndex:6023; appliedIndex:6022; }","duration":"100.379159ms","start":"2026-10-04T10:05:36.617905Z","end":"2026-10-04T10:05:36.718284Z","steps":["trace[1562163038] 'read index received' (duration: 100.268426ms)","trace[1562163038] 'applied index is now lower than readState.Index' (duration: 109.583µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:05:36.718463Z","caller":"traceutil/trace.go:171","msg":"trace[893561785] transaction","detail":"{read_only:false; response_revision:5804; number_of_response:1; }","duration":"160.134409ms","start":"2026-10-04T10:05:36.558291Z","end":"2026-10-04T10:05:36.718425Z","steps":["trace[893561785] 'process raft request' (duration: 159.836172ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:05:36.718641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.702498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-mlnbv\" ","response":"range_response_count:1 size:8067"} {"level":"info","ts":"2026-10-04T10:05:36.718731Z","caller":"traceutil/trace.go:171","msg":"trace[1277378007] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-mlnbv; range_end:; response_count:1; response_revision:5804; }","duration":"100.861302ms","start":"2026-10-04T10:05:36.617848Z","end":"2026-10-04T10:05:36.71871Z","steps":["trace[1277378007] 'agreement among raft nodes before linearized reading' (duration: 100.580755ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:05:36.728156Z","caller":"traceutil/trace.go:171","msg":"trace[1405826814] transaction","detail":"{read_only:false; response_revision:5805; number_of_response:1; }","duration":"107.655867ms","start":"2026-10-04T10:05:36.620443Z","end":"2026-10-04T10:05:36.728099Z","steps":["trace[1405826814] 'process raft request' (duration: 107.168624ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:05:36.72847Z","caller":"traceutil/trace.go:171","msg":"trace[1604990549] transaction","detail":"{read_only:false; response_revision:5806; number_of_response:1; }","duration":"107.529554ms","start":"2026-10-04T10:05:36.620916Z","end":"2026-10-04T10:05:36.728446Z","steps":["trace[1604990549] 'process raft request' (duration: 107.080073ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:05:36.728511Z","caller":"traceutil/trace.go:171","msg":"trace[2000091394] transaction","detail":"{read_only:false; response_revision:5807; number_of_response:1; }","duration":"101.715484ms","start":"2026-10-04T10:05:36.62675Z","end":"2026-10-04T10:05:36.728465Z","steps":["trace[2000091394] 'process raft request' (duration: 101.537329ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:05:42.635318Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2985} {"level":"info","ts":"2026-10-04T10:05:42.716799Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2985,"took":"79.969621ms","hash":230700249,"current-db-size-bytes":56463360,"current-db-size":"56 MB","current-db-size-in-use-bytes":32292864,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-04T10:05:42.716874Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":230700249,"revision":2985,"compact-revision":-1} {"level":"info","ts":"2026-10-04T10:10:42.642533Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5885} {"level":"info","ts":"2026-10-04T10:10:42.735009Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5885,"took":"90.285425ms","hash":3866238540,"current-db-size-bytes":56463360,"current-db-size":"56 MB","current-db-size-in-use-bytes":26681344,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-04T10:10:42.735082Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3866238540,"revision":5885,"compact-revision":2985} {"level":"info","ts":"2026-10-04T10:12:08.512533Z","caller":"traceutil/trace.go:171","msg":"trace[1445311309] transaction","detail":"{read_only:false; response_revision:8738; number_of_response:1; }","duration":"154.739377ms","start":"2026-10-04T10:12:08.357768Z","end":"2026-10-04T10:12:08.512507Z","steps":["trace[1445311309] 'process raft request' (duration: 154.540262ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:12:08.678836Z","caller":"traceutil/trace.go:171","msg":"trace[526178305] transaction","detail":"{read_only:false; response_revision:8739; number_of_response:1; }","duration":"313.589898ms","start":"2026-10-04T10:12:08.365196Z","end":"2026-10-04T10:12:08.678786Z","steps":["trace[526178305] 'process raft request' (duration: 215.867111ms)","trace[526178305] 'compare' (duration: 97.385638ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:12:08.679008Z","caller":"traceutil/trace.go:171","msg":"trace[1264985604] transaction","detail":"{read_only:false; response_revision:8740; number_of_response:1; }","duration":"312.366216ms","start":"2026-10-04T10:12:08.366614Z","end":"2026-10-04T10:12:08.67898Z","steps":["trace[1264985604] 'process raft request' (duration: 312.040327ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:12:08.679038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:12:08.365158Z","time spent":"313.767912ms","remote":"127.0.0.1:36010","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-10-04T10:12:08.679182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:12:08.366584Z","time spent":"312.493449ms","remote":"127.0.0.1:36010","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-10-04T10:12:09.801144Z","caller":"traceutil/trace.go:171","msg":"trace[570658613] transaction","detail":"{read_only:false; response_revision:8747; number_of_response:1; }","duration":"128.49648ms","start":"2026-10-04T10:12:09.672608Z","end":"2026-10-04T10:12:09.801104Z","steps":["trace[570658613] 'process raft request' (duration: 128.250374ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:12:41.19242Z","caller":"traceutil/trace.go:171","msg":"trace[1406929576] transaction","detail":"{read_only:false; response_revision:8964; number_of_response:1; }","duration":"304.6267ms","start":"2026-10-04T10:12:40.887738Z","end":"2026-10-04T10:12:41.192364Z","steps":["trace[1406929576] 'process raft request' (duration: 304.337823ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:12:41.19266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:12:40.887705Z","time spent":"304.832745ms","remote":"127.0.0.1:36010","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-10-04T10:12:41.241796Z","caller":"traceutil/trace.go:171","msg":"trace[566005232] linearizableReadLoop","detail":"{readStateIndex:9289; appliedIndex:9287; }","duration":"263.232075ms","start":"2026-10-04T10:12:40.978539Z","end":"2026-10-04T10:12:41.241771Z","steps":["trace[566005232] 'read index received' (duration: 213.596555ms)","trace[566005232] 'applied index is now lower than readState.Index' (duration: 49.63396ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:12:41.241935Z","caller":"traceutil/trace.go:171","msg":"trace[584743639] transaction","detail":"{read_only:false; response_revision:8965; number_of_response:1; }","duration":"335.22045ms","start":"2026-10-04T10:12:40.906652Z","end":"2026-10-04T10:12:41.241872Z","steps":["trace[584743639] 'process raft request' (duration: 334.997535ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:12:41.242143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.531522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-04T10:12:41.242183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:12:40.906614Z","time spent":"335.452196ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:12:41.242271Z","caller":"traceutil/trace.go:171","msg":"trace[977450792] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:8965; }","duration":"263.748677ms","start":"2026-10-04T10:12:40.978465Z","end":"2026-10-04T10:12:41.242214Z","steps":["trace[977450792] 'agreement among raft nodes before linearized reading' (duration: 263.434139ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:12:41.242287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.237326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-10-04T10:12:41.242464Z","caller":"traceutil/trace.go:171","msg":"trace[487353736] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8965; }","duration":"137.537744ms","start":"2026-10-04T10:12:41.10489Z","end":"2026-10-04T10:12:41.242428Z","steps":["trace[487353736] 'agreement among raft nodes before linearized reading' (duration: 137.114244ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:12:43.43582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.802564ms","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-10-04T10:12:43.435948Z","caller":"traceutil/trace.go:171","msg":"trace[91253172] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8977; }","duration":"128.003939ms","start":"2026-10-04T10:12:43.307914Z","end":"2026-10-04T10:12:43.435918Z","steps":["trace[91253172] 'range keys from in-memory index tree' (duration: 127.501656ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:12:54.787195Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000249b.wal"} {"level":"info","ts":"2026-10-04T10:13:55.295476Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"596257f6524f82c3","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-04T10:13:55.30719Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-04T10:13:55.307343Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-04T10:15:42.650235Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8057} {"level":"info","ts":"2026-10-04T10:15:42.725137Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8057,"took":"72.19287ms","hash":850169917,"current-db-size-bytes":56463360,"current-db-size":"56 MB","current-db-size-in-use-bytes":27439104,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-04T10:15:42.72522Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":850169917,"revision":8057,"compact-revision":5885} {"level":"info","ts":"2026-10-04T10:20:42.658275Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10426} {"level":"info","ts":"2026-10-04T10:20:42.758924Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10426,"took":"97.409462ms","hash":3043451224,"current-db-size-bytes":56463360,"current-db-size":"56 MB","current-db-size-in-use-bytes":26038272,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-04T10:20:42.759049Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3043451224,"revision":10426,"compact-revision":8057} {"level":"info","ts":"2026-10-04T10:25:42.665986Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12592} {"level":"info","ts":"2026-10-04T10:25:42.754614Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12592,"took":"85.286028ms","hash":4091700287,"current-db-size-bytes":56463360,"current-db-size":"56 MB","current-db-size-in-use-bytes":27623424,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-04T10:25:42.754713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4091700287,"revision":12592,"compact-revision":10426} {"level":"info","ts":"2026-10-04T10:26:54.365129Z","caller":"traceutil/trace.go:171","msg":"trace[1710253120] linearizableReadLoop","detail":"{readStateIndex:15584; appliedIndex:15583; }","duration":"247.838188ms","start":"2026-10-04T10:26:54.117254Z","end":"2026-10-04T10:26:54.365092Z","steps":["trace[1710253120] 'read index received' (duration: 247.507419ms)","trace[1710253120] 'applied index is now lower than readState.Index' (duration: 329.069µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:26:54.365368Z","caller":"traceutil/trace.go:171","msg":"trace[286647591] transaction","detail":"{read_only:false; response_revision:15054; number_of_response:1; }","duration":"414.189965ms","start":"2026-10-04T10:26:53.951074Z","end":"2026-10-04T10:26:54.365264Z","steps":["trace[286647591] 'process raft request' (duration: 413.792154ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:26:54.365622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:26:53.951035Z","time spent":"414.450512ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:26:54.366243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.947346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T10:26:54.366298Z","caller":"traceutil/trace.go:171","msg":"trace[740681414] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:15054; }","duration":"249.080259ms","start":"2026-10-04T10:26:54.117203Z","end":"2026-10-04T10:26:54.366284Z","steps":["trace[740681414] 'agreement among raft nodes before linearized reading' (duration: 248.031993ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:28:47.889408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.092832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T10:28:47.889495Z","caller":"traceutil/trace.go:171","msg":"trace[1185324201] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16207; }","duration":"116.209105ms","start":"2026-10-04T10:28:47.773266Z","end":"2026-10-04T10:28:47.889475Z","steps":["trace[1185324201] 'range keys from in-memory index tree' (duration: 116.018251ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:28:47.889488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.358699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-10-04T10:28:47.889604Z","caller":"traceutil/trace.go:171","msg":"trace[532427328] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16207; }","duration":"116.540765ms","start":"2026-10-04T10:28:47.773036Z","end":"2026-10-04T10:28:47.889577Z","steps":["trace[532427328] 'range keys from in-memory index tree' (duration: 116.076413ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:28:47.890243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.509105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-04T10:28:47.890277Z","caller":"traceutil/trace.go:171","msg":"trace[1180711912] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16207; }","duration":"106.557386ms","start":"2026-10-04T10:28:47.783711Z","end":"2026-10-04T10:28:47.890268Z","steps":["trace[1180711912] 'range keys from in-memory index tree' (duration: 106.106015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:28:48.634623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.916553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T10:28:48.634735Z","caller":"traceutil/trace.go:171","msg":"trace[381050403] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16208; }","duration":"200.098678ms","start":"2026-10-04T10:28:48.434616Z","end":"2026-10-04T10:28:48.634714Z","steps":["trace[381050403] 'agreement among raft nodes before linearized reading' (duration: 199.890422ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:28:48.634457Z","caller":"traceutil/trace.go:171","msg":"trace[1314790105] transaction","detail":"{read_only:false; response_revision:16208; number_of_response:1; }","duration":"229.899396ms","start":"2026-10-04T10:28:48.404484Z","end":"2026-10-04T10:28:48.634383Z","steps":["trace[1314790105] 'process raft request' (duration: 229.531017ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:28:48.634359Z","caller":"traceutil/trace.go:171","msg":"trace[545569283] linearizableReadLoop","detail":"{readStateIndex:16778; appliedIndex:16777; }","duration":"199.631425ms","start":"2026-10-04T10:28:48.434676Z","end":"2026-10-04T10:28:48.634307Z","steps":["trace[545569283] 'read index received' (duration: 199.370689ms)","trace[545569283] 'applied index is now lower than readState.Index' (duration: 259.116µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:28:48.635179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.478307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T10:28:48.635274Z","caller":"traceutil/trace.go:171","msg":"trace[844092936] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16208; }","duration":"200.636891ms","start":"2026-10-04T10:28:48.434616Z","end":"2026-10-04T10:28:48.635253Z","steps":["trace[844092936] 'agreement among raft nodes before linearized reading' (duration: 199.760719ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:28:48.652762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.802023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-04T10:28:48.65286Z","caller":"traceutil/trace.go:171","msg":"trace[1945868611] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16209; }","duration":"217.910956ms","start":"2026-10-04T10:28:48.434926Z","end":"2026-10-04T10:28:48.652837Z","steps":["trace[1945868611] 'agreement among raft nodes before linearized reading' (duration: 217.742252ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:28:48.65301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.00537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-10-04T10:28:48.653041Z","caller":"traceutil/trace.go:171","msg":"trace[1690037081] transaction","detail":"{read_only:false; response_revision:16209; number_of_response:1; }","duration":"107.223794ms","start":"2026-10-04T10:28:48.545776Z","end":"2026-10-04T10:28:48.652999Z","steps":["trace[1690037081] 'process raft request' (duration: 106.742591ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:28:48.653097Z","caller":"traceutil/trace.go:171","msg":"trace[1226586476] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16209; }","duration":"218.119102ms","start":"2026-10-04T10:28:48.434954Z","end":"2026-10-04T10:28:48.653073Z","steps":["trace[1226586476] 'agreement among raft nodes before linearized reading' (duration: 217.857175ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:28:48.65289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.76233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-04T10:28:48.65342Z","caller":"traceutil/trace.go:171","msg":"trace[1673080560] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:16209; }","duration":"188.325445ms","start":"2026-10-04T10:28:48.465075Z","end":"2026-10-04T10:28:48.6534Z","steps":["trace[1673080560] 'agreement among raft nodes before linearized reading' (duration: 187.690088ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:30:42.675383Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14598} {"level":"info","ts":"2026-10-04T10:30:42.771939Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14598,"took":"92.913855ms","hash":3792321673,"current-db-size-bytes":56463360,"current-db-size":"56 MB","current-db-size-in-use-bytes":31023104,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-04T10:30:42.772012Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3792321673,"revision":14598,"compact-revision":12592} {"level":"info","ts":"2026-10-04T10:35:09.340007Z","caller":"traceutil/trace.go:171","msg":"trace[1413070128] linearizableReadLoop","detail":"{readStateIndex:19622; appliedIndex:19621; }","duration":"183.393687ms","start":"2026-10-04T10:35:09.156584Z","end":"2026-10-04T10:35:09.339978Z","steps":["trace[1413070128] 'read index received' (duration: 183.206292ms)","trace[1413070128] 'applied index is now lower than readState.Index' (duration: 185.845µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:35:09.340285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.644513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-gbm5v\" ","response":"range_response_count:1 size:20763"} {"level":"info","ts":"2026-10-04T10:35:09.340233Z","caller":"traceutil/trace.go:171","msg":"trace[1107637464] transaction","detail":"{read_only:false; response_revision:18959; number_of_response:1; }","duration":"214.948591ms","start":"2026-10-04T10:35:09.125211Z","end":"2026-10-04T10:35:09.34016Z","steps":["trace[1107637464] 'process raft request' (duration: 214.584541ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:35:09.340327Z","caller":"traceutil/trace.go:171","msg":"trace[647180184] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-gbm5v; range_end:; response_count:1; response_revision:18959; }","duration":"183.827398ms","start":"2026-10-04T10:35:09.156487Z","end":"2026-10-04T10:35:09.340314Z","steps":["trace[647180184] 'agreement among raft nodes before linearized reading' (duration: 183.600062ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:35:09.56413Z","caller":"traceutil/trace.go:171","msg":"trace[1305026554] transaction","detail":"{read_only:false; response_revision:18960; number_of_response:1; }","duration":"197.273354ms","start":"2026-10-04T10:35:09.366743Z","end":"2026-10-04T10:35:09.564017Z","steps":["trace[1305026554] 'process raft request' (duration: 196.986737ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:35:09.606988Z","caller":"traceutil/trace.go:171","msg":"trace[397199464] linearizableReadLoop","detail":"{readStateIndex:19624; appliedIndex:19622; }","duration":"111.916485ms","start":"2026-10-04T10:35:09.495043Z","end":"2026-10-04T10:35:09.60696Z","steps":["trace[397199464] 'read index received' (duration: 68.789224ms)","trace[397199464] 'applied index is now lower than readState.Index' (duration: 43.125641ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:35:09.607222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.161782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-cfsmc\" ","response":"range_response_count:1 size:22133"} {"level":"info","ts":"2026-10-04T10:35:09.607272Z","caller":"traceutil/trace.go:171","msg":"trace[1969612469] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-cfsmc; range_end:; response_count:1; response_revision:18961; }","duration":"112.266445ms","start":"2026-10-04T10:35:09.494993Z","end":"2026-10-04T10:35:09.60726Z","steps":["trace[1969612469] 'agreement among raft nodes before linearized reading' (duration: 112.079739ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:35:09.607233Z","caller":"traceutil/trace.go:171","msg":"trace[283437213] transaction","detail":"{read_only:false; response_revision:18961; number_of_response:1; }","duration":"194.645368ms","start":"2026-10-04T10:35:09.412542Z","end":"2026-10-04T10:35:09.607187Z","steps":["trace[283437213] 'process raft request' (duration: 176.490269ms)","trace[283437213] 'compare' (duration: 17.782558ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:35:18.270314Z","caller":"traceutil/trace.go:171","msg":"trace[1595709572] transaction","detail":"{read_only:false; response_revision:19133; number_of_response:1; }","duration":"116.791081ms","start":"2026-10-04T10:35:18.153494Z","end":"2026-10-04T10:35:18.270285Z","steps":["trace[1595709572] 'process raft request' (duration: 116.564265ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:35:18.270855Z","caller":"traceutil/trace.go:171","msg":"trace[437175025] linearizableReadLoop","detail":"{readStateIndex:19803; appliedIndex:19802; }","duration":"101.674381ms","start":"2026-10-04T10:35:18.169161Z","end":"2026-10-04T10:35:18.270835Z","steps":["trace[437175025] 'read index received' (duration: 101.064465ms)","trace[437175025] 'applied index is now lower than readState.Index' (duration: 607.626µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:35:18.271041Z","caller":"traceutil/trace.go:171","msg":"trace[797916796] transaction","detail":"{read_only:false; response_revision:19134; number_of_response:1; }","duration":"113.375501ms","start":"2026-10-04T10:35:18.157559Z","end":"2026-10-04T10:35:18.270935Z","steps":["trace[797916796] 'process raft request' (duration: 112.670513ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:35:18.271415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.211184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-10-04T10:35:18.271538Z","caller":"traceutil/trace.go:171","msg":"trace[425070756] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:19134; }","duration":"102.376148ms","start":"2026-10-04T10:35:18.16913Z","end":"2026-10-04T10:35:18.271506Z","steps":["trace[425070756] 'agreement among raft nodes before linearized reading' (duration: 101.850384ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:35:18.275583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.231573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1286"} {"level":"info","ts":"2026-10-04T10:35:18.275652Z","caller":"traceutil/trace.go:171","msg":"trace[1387562281] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:19134; }","duration":"100.332876ms","start":"2026-10-04T10:35:18.175303Z","end":"2026-10-04T10:35:18.275636Z","steps":["trace[1387562281] 'agreement among raft nodes before linearized reading' (duration: 100.165341ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:35:42.682586Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16921} {"level":"info","ts":"2026-10-04T10:35:42.789292Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16921,"took":"103.065066ms","hash":308600449,"current-db-size-bytes":56463360,"current-db-size":"56 MB","current-db-size-in-use-bytes":32342016,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-04T10:35:42.789388Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":308600449,"revision":16921,"compact-revision":14598} {"level":"info","ts":"2026-10-04T10:35:45.509424Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"596257f6524f82c3","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-04T10:35:45.519324Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-04T10:35:45.519544Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-04T10:40:42.691105Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19308} {"level":"info","ts":"2026-10-04T10:40:42.801846Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19308,"took":"106.176866ms","hash":857281953,"current-db-size-bytes":56463360,"current-db-size":"56 MB","current-db-size-in-use-bytes":33710080,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-04T10:40:42.80196Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":857281953,"revision":19308,"compact-revision":16921} {"level":"info","ts":"2026-10-04T10:41:03.153551Z","caller":"traceutil/trace.go:171","msg":"trace[690638895] transaction","detail":"{read_only:false; response_revision:21687; number_of_response:1; }","duration":"125.635328ms","start":"2026-10-04T10:41:03.027885Z","end":"2026-10-04T10:41:03.153521Z","steps":["trace[690638895] 'process raft request' (duration: 124.94168ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:41:03.467168Z","caller":"traceutil/trace.go:171","msg":"trace[1468256832] transaction","detail":"{read_only:false; response_revision:21688; number_of_response:1; }","duration":"243.301ms","start":"2026-10-04T10:41:03.223799Z","end":"2026-10-04T10:41:03.4671Z","steps":["trace[1468256832] 'process raft request' (duration: 243.073864ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:41:19.601184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.006245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:41:19.601669Z","caller":"traceutil/trace.go:171","msg":"trace[1051788071] transaction","detail":"{read_only:false; response_revision:21769; number_of_response:1; }","duration":"191.659969ms","start":"2026-10-04T10:41:19.409986Z","end":"2026-10-04T10:41:19.601646Z","steps":["trace[1051788071] 'process raft request' (duration: 81.0851ms)","trace[1051788071] 'compare' (duration: 109.791439ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:41:20.094993Z","caller":"traceutil/trace.go:171","msg":"trace[1765240637] transaction","detail":"{read_only:false; response_revision:21772; number_of_response:1; }","duration":"138.845538ms","start":"2026-10-04T10:41:19.956114Z","end":"2026-10-04T10:41:20.09496Z","steps":["trace[1765240637] 'process raft request' (duration: 138.606922ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:41:20.106678Z","caller":"traceutil/trace.go:171","msg":"trace[646071708] transaction","detail":"{read_only:false; response_revision:21773; number_of_response:1; }","duration":"150.110907ms","start":"2026-10-04T10:41:19.95648Z","end":"2026-10-04T10:41:20.106591Z","steps":["trace[646071708] 'process raft request' (duration: 149.975624ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:41:20.623932Z","caller":"traceutil/trace.go:171","msg":"trace[790306685] transaction","detail":"{read_only:false; response_revision:21775; number_of_response:1; }","duration":"270.433118ms","start":"2026-10-04T10:41:20.353469Z","end":"2026-10-04T10:41:20.623902Z","steps":["trace[790306685] 'process raft request' (duration: 222.00421ms)","trace[790306685] 'compare' (duration: 47.504094ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:44:29.974684Z","caller":"traceutil/trace.go:171","msg":"trace[325168173] transaction","detail":"{read_only:false; response_revision:23228; number_of_response:1; }","duration":"102.718727ms","start":"2026-10-04T10:44:29.871927Z","end":"2026-10-04T10:44:29.974646Z","steps":["trace[325168173] 'process raft request' (duration: 17.569454ms)","trace[325168173] 'compare' (duration: 85.01817ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:45:30.284268Z","caller":"traceutil/trace.go:171","msg":"trace[1179899337] linearizableReadLoop","detail":"{readStateIndex:24992; appliedIndex:24991; }","duration":"100.473319ms","start":"2026-10-04T10:45:30.183746Z","end":"2026-10-04T10:45:30.28422Z","steps":["trace[1179899337] 'read index received' (duration: 100.253912ms)","trace[1179899337] 'applied index is now lower than readState.Index' (duration: 218.127µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:45:30.284335Z","caller":"traceutil/trace.go:171","msg":"trace[1437668488] transaction","detail":"{read_only:false; response_revision:24157; number_of_response:1; }","duration":"102.658525ms","start":"2026-10-04T10:45:30.181654Z","end":"2026-10-04T10:45:30.284312Z","steps":["trace[1437668488] 'process raft request' (duration: 102.320856ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:45:30.284462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.688344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/capo-manager-role\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:45:30.284514Z","caller":"traceutil/trace.go:171","msg":"trace[927164406] range","detail":"{range_begin:/registry/clusterroles/capo-manager-role; range_end:; response_count:0; response_revision:24157; }","duration":"100.785827ms","start":"2026-10-04T10:45:30.183716Z","end":"2026-10-04T10:45:30.284502Z","steps":["trace[927164406] 'agreement among raft nodes before linearized reading' (duration: 100.659644ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:45:42.704019Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21582} {"level":"info","ts":"2026-10-04T10:45:42.836512Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21582,"took":"116.946353ms","hash":3554543365,"current-db-size-bytes":57401344,"current-db-size":"57 MB","current-db-size-in-use-bytes":42631168,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-04T10:45:42.836593Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3554543365,"revision":21582,"compact-revision":19308} {"level":"info","ts":"2026-10-04T10:47:43.429646Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000677f.wal"} {"level":"warn","ts":"2026-10-04T10:49:32.22805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.749226ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-04T10:49:32.228215Z","caller":"traceutil/trace.go:171","msg":"trace[558713731] linearizableReadLoop","detail":"{readStateIndex:27528; appliedIndex:27527; }","duration":"260.75395ms","start":"2026-10-04T10:49:31.96743Z","end":"2026-10-04T10:49:32.228184Z","steps":["trace[558713731] 'read index received' (duration: 41.817568ms)","trace[558713731] 'applied index is now lower than readState.Index' (duration: 218.934352ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:32.228681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.236092ms","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-10-04T10:49:32.228726Z","caller":"traceutil/trace.go:171","msg":"trace[461582840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26630; }","duration":"261.364465ms","start":"2026-10-04T10:49:31.967352Z","end":"2026-10-04T10:49:32.228716Z","steps":["trace[461582840] 'agreement among raft nodes before linearized reading' (duration: 261.181081ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:32.2763Z","caller":"traceutil/trace.go:171","msg":"trace[844737474] transaction","detail":"{read_only:false; response_revision:26631; number_of_response:1; }","duration":"272.03125ms","start":"2026-10-04T10:49:32.004167Z","end":"2026-10-04T10:49:32.276198Z","steps":["trace[844737474] 'process raft request' (duration: 271.682051ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:32.27652Z","caller":"traceutil/trace.go:171","msg":"trace[1434278829] transaction","detail":"{read_only:false; response_revision:26632; number_of_response:1; }","duration":"270.684885ms","start":"2026-10-04T10:49:32.005802Z","end":"2026-10-04T10:49:32.276487Z","steps":["trace[1434278829] 'process raft request' (duration: 270.266885ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:32.494914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.577426ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:49:32.495158Z","caller":"traceutil/trace.go:171","msg":"trace[2018407948] transaction","detail":"{read_only:false; response_revision:26635; number_of_response:1; }","duration":"196.550564ms","start":"2026-10-04T10:49:32.298585Z","end":"2026-10-04T10:49:32.495135Z","steps":["trace[2018407948] 'process raft request' (duration: 196.492433ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:32.495301Z","caller":"traceutil/trace.go:171","msg":"trace[1901884377] linearizableReadLoop","detail":"{readStateIndex:27531; appliedIndex:27528; }","duration":"266.970309ms","start":"2026-10-04T10:49:32.228288Z","end":"2026-10-04T10:49:32.495259Z","steps":["trace[1901884377] 'read index received' (duration: 47.688779ms)","trace[1901884377] 'applied index is now lower than readState.Index' (duration: 219.277ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:49:32.495257Z","caller":"traceutil/trace.go:171","msg":"trace[1522577979] transaction","detail":"{read_only:false; response_revision:26633; number_of_response:1; }","duration":"353.246021ms","start":"2026-10-04T10:49:32.141901Z","end":"2026-10-04T10:49:32.495147Z","steps":["trace[1522577979] 'process raft request' (duration: 213.313416ms)","trace[1522577979] 'compare' (duration: 139.407192ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:32.495454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.784198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:49:32.495306Z","caller":"traceutil/trace.go:171","msg":"trace[1718962820] transaction","detail":"{read_only:false; response_revision:26634; number_of_response:1; }","duration":"261.405615ms","start":"2026-10-04T10:49:32.233832Z","end":"2026-10-04T10:49:32.495237Z","steps":["trace[1718962820] 'process raft request' (duration: 261.178029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:32.495471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.853131ms","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:6"} {"level":"info","ts":"2026-10-04T10:49:32.495523Z","caller":"traceutil/trace.go:171","msg":"trace[1402163314] range","detail":"{range_begin:/registry/rabbitmq.com/queues/; range_end:/registry/rabbitmq.com/queues0; response_count:0; response_revision:26635; }","duration":"343.86829ms","start":"2026-10-04T10:49:32.15163Z","end":"2026-10-04T10:49:32.495498Z","steps":["trace[1402163314] 'agreement among raft nodes before linearized reading' (duration: 343.706226ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:32.495541Z","caller":"traceutil/trace.go:171","msg":"trace[389119257] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26635; }","duration":"501.976774ms","start":"2026-10-04T10:49:31.993546Z","end":"2026-10-04T10:49:32.495523Z","steps":["trace[389119257] 'agreement among raft nodes before linearized reading' (duration: 501.82158ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:32.495537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:32.14187Z","time spent":"353.56159ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:49:32.495572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:32.15157Z","time spent":"343.987754ms","remote":"127.0.0.1:51228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/queues/\" range_end:\"/registry/rabbitmq.com/queues0\" count_only:true "} {"level":"warn","ts":"2026-10-04T10:49:32.495587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:31.993526Z","time spent":"502.045316ms","remote":"127.0.0.1:42630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-04T10:49:32.495558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.772179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-04T10:49:32.49572Z","caller":"traceutil/trace.go:171","msg":"trace[793514255] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26635; }","duration":"376.927853ms","start":"2026-10-04T10:49:32.118711Z","end":"2026-10-04T10:49:32.495638Z","steps":["trace[793514255] 'agreement among raft nodes before linearized reading' (duration: 376.590844ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:32.49557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-10-04T10:49:32.495794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:32.118683Z","time spent":"377.094596ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-04T10:49:32.495989Z","caller":"traceutil/trace.go:171","msg":"trace[278199054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26635; }","duration":"491.966347ms","start":"2026-10-04T10:49:32.003823Z","end":"2026-10-04T10:49:32.495789Z","steps":["trace[278199054] 'agreement among raft nodes before linearized reading' (duration: 491.504855ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:32.496095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:32.003763Z","time spent":"492.313726ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-04T10:49:32.895564Z","caller":"traceutil/trace.go:171","msg":"trace[312160007] transaction","detail":"{read_only:false; response_revision:26638; number_of_response:1; }","duration":"350.06935ms","start":"2026-10-04T10:49:32.545463Z","end":"2026-10-04T10:49:32.895532Z","steps":["trace[312160007] 'process raft request' (duration: 350.009519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:32.895734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:32.545432Z","time spent":"350.209624ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:32.895721Z","caller":"traceutil/trace.go:171","msg":"trace[485476968] transaction","detail":"{read_only:false; response_revision:26637; number_of_response:1; }","duration":"392.723089ms","start":"2026-10-04T10:49:32.502951Z","end":"2026-10-04T10:49:32.895674Z","steps":["trace[485476968] 'process raft request' (duration: 392.312998ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:32.895936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:32.502925Z","time spent":"392.870703ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:32.915537Z","caller":"traceutil/trace.go:171","msg":"trace[828403269] transaction","detail":"{read_only:false; response_revision:26639; number_of_response:1; }","duration":"287.095418ms","start":"2026-10-04T10:49:32.628407Z","end":"2026-10-04T10:49:32.915502Z","steps":["trace[828403269] 'process raft request' (duration: 286.728218ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:32.915671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.650553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:49:32.915733Z","caller":"traceutil/trace.go:171","msg":"trace[665643250] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:26639; }","duration":"242.789436ms","start":"2026-10-04T10:49:32.672929Z","end":"2026-10-04T10:49:32.915718Z","steps":["trace[665643250] 'agreement among raft nodes before linearized reading' (duration: 242.645443ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:32.91548Z","caller":"traceutil/trace.go:171","msg":"trace[1971575974] linearizableReadLoop","detail":"{readStateIndex:27537; appliedIndex:27534; }","duration":"242.454308ms","start":"2026-10-04T10:49:32.672987Z","end":"2026-10-04T10:49:32.915441Z","steps":["trace[1971575974] 'read index received' (duration: 222.388951ms)","trace[1971575974] 'applied index is now lower than readState.Index' (duration: 20.064067ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:33.166322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.810981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:49:33.16649Z","caller":"traceutil/trace.go:171","msg":"trace[1975049910] transaction","detail":"{read_only:false; response_revision:26640; number_of_response:1; }","duration":"218.778017ms","start":"2026-10-04T10:49:32.947692Z","end":"2026-10-04T10:49:33.16647Z","steps":["trace[1975049910] 'process raft request' (duration: 106.71492ms)","trace[1975049910] 'compare' (duration: 111.650856ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:33.480007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.078621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:49:33.480127Z","caller":"traceutil/trace.go:171","msg":"trace[1000954615] linearizableReadLoop","detail":"{readStateIndex:27539; appliedIndex:27538; }","duration":"353.736305ms","start":"2026-10-04T10:49:33.12637Z","end":"2026-10-04T10:49:33.480106Z","steps":["trace[1000954615] 'read index received' (duration: 145.297914ms)","trace[1000954615] 'applied index is now lower than readState.Index' (duration: 208.43687ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:33.480373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.499816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:49:33.480453Z","caller":"traceutil/trace.go:171","msg":"trace[1929673831] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:26641; }","duration":"330.637349ms","start":"2026-10-04T10:49:33.149802Z","end":"2026-10-04T10:49:33.48044Z","steps":["trace[1929673831] 'agreement among raft nodes before linearized reading' (duration: 330.425713ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:33.480429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.023942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"warn","ts":"2026-10-04T10:49:33.480508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:33.149773Z","time spent":"330.720131ms","remote":"127.0.0.1:35864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-10-04T10:49:33.480534Z","caller":"traceutil/trace.go:171","msg":"trace[2120317345] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:26641; }","duration":"354.202148ms","start":"2026-10-04T10:49:33.126304Z","end":"2026-10-04T10:49:33.480506Z","steps":["trace[2120317345] 'agreement among raft nodes before linearized reading' (duration: 353.909369ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:33.480559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.956079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-04T10:49:33.48058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:33.12627Z","time spent":"354.301179ms","remote":"127.0.0.1:35872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1804,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"info","ts":"2026-10-04T10:49:33.480597Z","caller":"traceutil/trace.go:171","msg":"trace[1430698611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26641; }","duration":"149.038071ms","start":"2026-10-04T10:49:33.331548Z","end":"2026-10-04T10:49:33.480586Z","steps":["trace[1430698611] 'agreement among raft nodes before linearized reading' (duration: 148.886627ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:33.480649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.273847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-04T10:49:33.480726Z","caller":"traceutil/trace.go:171","msg":"trace[529722574] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26641; }","duration":"156.40408ms","start":"2026-10-04T10:49:33.324301Z","end":"2026-10-04T10:49:33.480705Z","steps":["trace[529722574] 'agreement among raft nodes before linearized reading' (duration: 156.187464ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:33.480444Z","caller":"traceutil/trace.go:171","msg":"trace[485181493] transaction","detail":"{read_only:false; response_revision:26641; number_of_response:1; }","duration":"502.688793ms","start":"2026-10-04T10:49:32.977725Z","end":"2026-10-04T10:49:33.480414Z","steps":["trace[485181493] 'process raft request' (duration: 294.062457ms)","trace[485181493] 'compare' (duration: 207.941858ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:33.48085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:32.977693Z","time spent":"503.117264ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:33.736992Z","caller":"traceutil/trace.go:171","msg":"trace[1941840005] linearizableReadLoop","detail":"{readStateIndex:27540; appliedIndex:27539; }","duration":"251.637384ms","start":"2026-10-04T10:49:33.48533Z","end":"2026-10-04T10:49:33.736967Z","steps":["trace[1941840005] 'read index received' (duration: 251.46846ms)","trace[1941840005] 'applied index is now lower than readState.Index' (duration: 167.834µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:49:33.737119Z","caller":"traceutil/trace.go:171","msg":"trace[1934991622] transaction","detail":"{read_only:false; response_revision:26642; number_of_response:1; }","duration":"251.927102ms","start":"2026-10-04T10:49:33.485179Z","end":"2026-10-04T10:49:33.737106Z","steps":["trace[1934991622] 'process raft request' (duration: 251.610514ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:33.737369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.009074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2d2d7d22-cfa3-4d2e-9d29-bebc4e565294\" ","response":"range_response_count:1 size:1276"} {"level":"info","ts":"2026-10-04T10:49:33.737529Z","caller":"traceutil/trace.go:171","msg":"trace[562248117] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2d2d7d22-cfa3-4d2e-9d29-bebc4e565294; range_end:; response_count:1; response_revision:26642; }","duration":"252.212369ms","start":"2026-10-04T10:49:33.485291Z","end":"2026-10-04T10:49:33.737504Z","steps":["trace[562248117] 'agreement among raft nodes before linearized reading' (duration: 251.889891ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:33.792585Z","caller":"traceutil/trace.go:171","msg":"trace[1677835907] transaction","detail":"{read_only:false; response_revision:26643; number_of_response:1; }","duration":"305.906482ms","start":"2026-10-04T10:49:33.486578Z","end":"2026-10-04T10:49:33.792485Z","steps":["trace[1677835907] 'process raft request' (duration: 305.473881ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:33.792855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:33.486554Z","time spent":"306.18653ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:39.64054Z","caller":"traceutil/trace.go:171","msg":"trace[690916139] transaction","detail":"{read_only:false; response_revision:26685; number_of_response:1; }","duration":"136.696501ms","start":"2026-10-04T10:49:39.503809Z","end":"2026-10-04T10:49:39.640506Z","steps":["trace[690916139] 'process raft request' (duration: 136.472045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:39.849052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.259371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:49:39.849133Z","caller":"traceutil/trace.go:171","msg":"trace[566279296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26685; }","duration":"122.402634ms","start":"2026-10-04T10:49:39.726711Z","end":"2026-10-04T10:49:39.849113Z","steps":["trace[566279296] 'range keys from in-memory index tree' (duration: 122.152358ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:40.162657Z","caller":"traceutil/trace.go:171","msg":"trace[1250757673] transaction","detail":"{read_only:false; response_revision:26687; number_of_response:1; }","duration":"307.669988ms","start":"2026-10-04T10:49:39.854956Z","end":"2026-10-04T10:49:40.162626Z","steps":["trace[1250757673] 'process raft request' (duration: 231.394823ms)","trace[1250757673] 'compare' (duration: 75.987178ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:40.162981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:39.854927Z","time spent":"307.824192ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:40.164429Z","caller":"traceutil/trace.go:171","msg":"trace[1369251300] linearizableReadLoop","detail":"{readStateIndex:27587; appliedIndex:27585; }","duration":"161.928302ms","start":"2026-10-04T10:49:40.002482Z","end":"2026-10-04T10:49:40.164411Z","steps":["trace[1369251300] 'read index received' (duration: 83.83546ms)","trace[1369251300] 'applied index is now lower than readState.Index' (duration: 78.091612ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:40.164728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.246741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-04T10:49:40.16471Z","caller":"traceutil/trace.go:171","msg":"trace[1389587961] transaction","detail":"{read_only:false; response_revision:26688; number_of_response:1; }","duration":"309.524197ms","start":"2026-10-04T10:49:39.855146Z","end":"2026-10-04T10:49:40.16467Z","steps":["trace[1389587961] 'process raft request' (duration: 309.150746ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:40.164773Z","caller":"traceutil/trace.go:171","msg":"trace[338929283] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26688; }","duration":"162.345924ms","start":"2026-10-04T10:49:40.002415Z","end":"2026-10-04T10:49:40.164761Z","steps":["trace[338929283] 'agreement among raft nodes before linearized reading' (duration: 162.064017ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:40.164905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:39.8551Z","time spent":"309.695201ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:49:40.402634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.708991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-10-04T10:49:40.402752Z","caller":"traceutil/trace.go:171","msg":"trace[2104877260] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26688; }","duration":"127.888165ms","start":"2026-10-04T10:49:40.274833Z","end":"2026-10-04T10:49:40.402721Z","steps":["trace[2104877260] 'range keys from in-memory index tree' (duration: 127.418903ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:40.768309Z","caller":"traceutil/trace.go:171","msg":"trace[234344191] transaction","detail":"{read_only:false; response_revision:26691; number_of_response:1; }","duration":"295.443232ms","start":"2026-10-04T10:49:40.472831Z","end":"2026-10-04T10:49:40.768274Z","steps":["trace[234344191] 'process raft request' (duration: 295.170775ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:40.76899Z","caller":"traceutil/trace.go:171","msg":"trace[2007054939] linearizableReadLoop","detail":"{readStateIndex:27590; appliedIndex:27590; }","duration":"284.352897ms","start":"2026-10-04T10:49:40.484623Z","end":"2026-10-04T10:49:40.768976Z","steps":["trace[2007054939] 'read index received' (duration: 284.346347ms)","trace[2007054939] 'applied index is now lower than readState.Index' (duration: 5.61µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:40.769149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T10:49:40.769189Z","caller":"traceutil/trace.go:171","msg":"trace[1984995289] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:26691; }","duration":"284.606693ms","start":"2026-10-04T10:49:40.484571Z","end":"2026-10-04T10:49:40.769178Z","steps":["trace[1984995289] 'agreement among raft nodes before linearized reading' (duration: 284.48942ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:40.787787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.245504ms","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-10-04T10:49:40.787893Z","caller":"traceutil/trace.go:171","msg":"trace[147935052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26692; }","duration":"251.390458ms","start":"2026-10-04T10:49:40.536478Z","end":"2026-10-04T10:49:40.787868Z","steps":["trace[147935052] 'agreement among raft nodes before linearized reading' (duration: 251.117301ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:40.788146Z","caller":"traceutil/trace.go:171","msg":"trace[1752303303] transaction","detail":"{read_only:false; response_revision:26692; number_of_response:1; }","duration":"255.550245ms","start":"2026-10-04T10:49:40.532542Z","end":"2026-10-04T10:49:40.788092Z","steps":["trace[1752303303] 'process raft request' (duration: 254.873848ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:40.788582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.290148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-04T10:49:40.788682Z","caller":"traceutil/trace.go:171","msg":"trace[2085340652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26692; }","duration":"203.453592ms","start":"2026-10-04T10:49:40.585205Z","end":"2026-10-04T10:49:40.788658Z","steps":["trace[2085340652] 'agreement among raft nodes before linearized reading' (duration: 203.105043ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:40.968728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.633558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:49:40.968872Z","caller":"traceutil/trace.go:171","msg":"trace[1184812172] linearizableReadLoop","detail":"{readStateIndex:27593; appliedIndex:27592; }","duration":"148.553448ms","start":"2026-10-04T10:49:40.820301Z","end":"2026-10-04T10:49:40.968854Z","steps":["trace[1184812172] 'read index received' (duration: 10.588872ms)","trace[1184812172] 'applied index is now lower than readState.Index' (duration: 137.963336ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:40.969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.711222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T10:49:40.968985Z","caller":"traceutil/trace.go:171","msg":"trace[1556951603] transaction","detail":"{read_only:false; response_revision:26694; number_of_response:1; }","duration":"174.102226ms","start":"2026-10-04T10:49:40.794837Z","end":"2026-10-04T10:49:40.968939Z","steps":["trace[1556951603] 'process raft request' (duration: 36.147641ms)","trace[1556951603] 'compare' (duration: 137.467723ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:49:40.969036Z","caller":"traceutil/trace.go:171","msg":"trace[1709852450] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:26694; }","duration":"148.846795ms","start":"2026-10-04T10:49:40.820176Z","end":"2026-10-04T10:49:40.969023Z","steps":["trace[1709852450] 'agreement among raft nodes before linearized reading' (duration: 148.755873ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:41.012465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.561466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:49:41.012582Z","caller":"traceutil/trace.go:171","msg":"trace[1137850495] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:26696; }","duration":"106.708949ms","start":"2026-10-04T10:49:40.905846Z","end":"2026-10-04T10:49:41.012555Z","steps":["trace[1137850495] 'agreement among raft nodes before linearized reading' (duration: 106.464293ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:41.342925Z","caller":"traceutil/trace.go:171","msg":"trace[1018893178] transaction","detail":"{read_only:false; response_revision:26697; number_of_response:1; }","duration":"324.83218ms","start":"2026-10-04T10:49:41.018054Z","end":"2026-10-04T10:49:41.342886Z","steps":["trace[1018893178] 'process raft request' (duration: 324.598134ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:41.343217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:41.018022Z","time spent":"325.095187ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:49:41.62141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.230157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:49:41.621572Z","caller":"traceutil/trace.go:171","msg":"trace[100877169] linearizableReadLoop","detail":"{readStateIndex:27597; appliedIndex:27596; }","duration":"273.18159ms","start":"2026-10-04T10:49:41.348364Z","end":"2026-10-04T10:49:41.621546Z","steps":["trace[100877169] 'read index received' (duration: 121.567263ms)","trace[100877169] 'applied index is now lower than readState.Index' (duration: 151.611947ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:41.621677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.329094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:49:41.621731Z","caller":"traceutil/trace.go:171","msg":"trace[1347811181] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:26698; }","duration":"273.385095ms","start":"2026-10-04T10:49:41.348328Z","end":"2026-10-04T10:49:41.621713Z","steps":["trace[1347811181] 'agreement among raft nodes before linearized reading' (duration: 273.282892ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:41.621992Z","caller":"traceutil/trace.go:171","msg":"trace[860279228] transaction","detail":"{read_only:false; response_revision:26698; number_of_response:1; }","duration":"413.858244ms","start":"2026-10-04T10:49:41.208037Z","end":"2026-10-04T10:49:41.621895Z","steps":["trace[860279228] 'process raft request' (duration: 261.97643ms)","trace[860279228] 'compare' (duration: 150.909858ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:41.622727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:41.208001Z","time spent":"414.261734ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:49:41.969746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.156231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:49:41.969863Z","caller":"traceutil/trace.go:171","msg":"trace[1662725458] linearizableReadLoop","detail":"{readStateIndex:27598; appliedIndex:27597; }","duration":"243.605317ms","start":"2026-10-04T10:49:41.726237Z","end":"2026-10-04T10:49:41.969842Z","steps":["trace[1662725458] 'read index received' (duration: 44.165318ms)","trace[1662725458] 'applied index is now lower than readState.Index' (duration: 199.438199ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:49:41.970009Z","caller":"traceutil/trace.go:171","msg":"trace[873072283] transaction","detail":"{read_only:false; response_revision:26699; number_of_response:1; }","duration":"322.973972ms","start":"2026-10-04T10:49:41.647012Z","end":"2026-10-04T10:49:41.969986Z","steps":["trace[873072283] 'process raft request' (duration: 123.464961ms)","trace[873072283] 'compare' (duration: 198.678319ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:41.970068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.812112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T10:49:41.970103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:41.64697Z","time spent":"323.087044ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:41.970166Z","caller":"traceutil/trace.go:171","msg":"trace[1772870979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26699; }","duration":"243.976587ms","start":"2026-10-04T10:49:41.726166Z","end":"2026-10-04T10:49:41.970143Z","steps":["trace[1772870979] 'agreement among raft nodes before linearized reading' (duration: 243.810752ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:41.972047Z","caller":"traceutil/trace.go:171","msg":"trace[1050874572] transaction","detail":"{read_only:false; response_revision:26700; number_of_response:1; }","duration":"115.661651ms","start":"2026-10-04T10:49:41.856356Z","end":"2026-10-04T10:49:41.972018Z","steps":["trace[1050874572] 'process raft request' (duration: 115.462376ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:42.001503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.523669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T10:49:42.001705Z","caller":"traceutil/trace.go:171","msg":"trace[1895386570] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:26700; }","duration":"178.758095ms","start":"2026-10-04T10:49:41.822922Z","end":"2026-10-04T10:49:42.00168Z","steps":["trace[1895386570] 'agreement among raft nodes before linearized reading' (duration: 178.445987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:42.510678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.97844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:49:42.511086Z","caller":"traceutil/trace.go:171","msg":"trace[1754355226] transaction","detail":"{read_only:false; response_revision:26704; number_of_response:1; }","duration":"101.533966ms","start":"2026-10-04T10:49:42.409526Z","end":"2026-10-04T10:49:42.51106Z","steps":["trace[1754355226] 'process raft request' (duration: 101.427344ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:42.511149Z","caller":"traceutil/trace.go:171","msg":"trace[453197611] transaction","detail":"{read_only:false; response_revision:26703; number_of_response:1; }","duration":"316.612388ms","start":"2026-10-04T10:49:42.194492Z","end":"2026-10-04T10:49:42.511104Z","steps":["trace[453197611] 'process raft request' (duration: 316.361111ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:42.511343Z","caller":"traceutil/trace.go:171","msg":"trace[1986423118] transaction","detail":"{read_only:false; response_revision:26705; number_of_response:1; }","duration":"101.622899ms","start":"2026-10-04T10:49:42.409681Z","end":"2026-10-04T10:49:42.511304Z","steps":["trace[1986423118] 'process raft request' (duration: 101.328311ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:42.51118Z","caller":"traceutil/trace.go:171","msg":"trace[194340247] linearizableReadLoop","detail":"{readStateIndex:27603; appliedIndex:27601; }","duration":"316.28946ms","start":"2026-10-04T10:49:42.194834Z","end":"2026-10-04T10:49:42.511123Z","steps":["trace[194340247] 'read index received' (duration: 145.764006ms)","trace[194340247] 'applied index is now lower than readState.Index' (duration: 170.520904ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:49:42.511196Z","caller":"traceutil/trace.go:171","msg":"trace[654328002] transaction","detail":"{read_only:false; response_revision:26702; number_of_response:1; }","duration":"317.619644ms","start":"2026-10-04T10:49:42.193542Z","end":"2026-10-04T10:49:42.511161Z","steps":["trace[654328002] 'process raft request' (duration: 147.037528ms)","trace[654328002] 'compare' (duration: 169.729884ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:42.511552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.6877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-3f721110-7d69-496c-b506-aafa3b6570ad\" ","response":"range_response_count:1 size:1436"} {"level":"warn","ts":"2026-10-04T10:49:42.511592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:42.193506Z","time spent":"318.042105ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:42.511612Z","caller":"traceutil/trace.go:171","msg":"trace[1808410237] range","detail":"{range_begin:/registry/persistentvolumes/pvc-3f721110-7d69-496c-b506-aafa3b6570ad; range_end:; response_count:1; response_revision:26705; }","duration":"316.785732ms","start":"2026-10-04T10:49:42.194809Z","end":"2026-10-04T10:49:42.511594Z","steps":["trace[1808410237] 'agreement among raft nodes before linearized reading' (duration: 316.449423ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:42.511653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:42.194798Z","time spent":"316.845464ms","remote":"127.0.0.1:35866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1458,"request content":"key:\"/registry/persistentvolumes/pvc-3f721110-7d69-496c-b506-aafa3b6570ad\" "} {"level":"warn","ts":"2026-10-04T10:49:42.511813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:42.194474Z","time spent":"316.813973ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:43.087764Z","caller":"traceutil/trace.go:171","msg":"trace[187539347] transaction","detail":"{read_only:false; response_revision:26707; number_of_response:1; }","duration":"298.877732ms","start":"2026-10-04T10:49:42.788847Z","end":"2026-10-04T10:49:43.087725Z","steps":["trace[187539347] 'process raft request' (duration: 298.622765ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:43.278944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.380385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:49:43.279243Z","caller":"traceutil/trace.go:171","msg":"trace[257416458] transaction","detail":"{read_only:false; response_revision:26708; number_of_response:1; }","duration":"482.558744ms","start":"2026-10-04T10:49:42.796642Z","end":"2026-10-04T10:49:43.279201Z","steps":["trace[257416458] 'process raft request' (duration: 324.775929ms)","trace[257416458] 'compare' (duration: 157.20247ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:43.279491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:42.796601Z","time spent":"482.745579ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:49:43.279546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.510554ms","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-10-04T10:49:43.279611Z","caller":"traceutil/trace.go:171","msg":"trace[746860136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26710; }","duration":"472.633809ms","start":"2026-10-04T10:49:42.806962Z","end":"2026-10-04T10:49:43.279596Z","steps":["trace[746860136] 'agreement among raft nodes before linearized reading' (duration: 472.354611ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:43.279667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:42.80694Z","time spent":"472.715081ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-04T10:49:43.279276Z","caller":"traceutil/trace.go:171","msg":"trace[1637459816] linearizableReadLoop","detail":"{readStateIndex:27608; appliedIndex:27606; }","duration":"472.207127ms","start":"2026-10-04T10:49:42.807038Z","end":"2026-10-04T10:49:43.279245Z","steps":["trace[1637459816] 'read index received' (duration: 280.464817ms)","trace[1637459816] 'applied index is now lower than readState.Index' (duration: 191.74056ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:49:43.280141Z","caller":"traceutil/trace.go:171","msg":"trace[1729950470] transaction","detail":"{read_only:false; response_revision:26710; number_of_response:1; }","duration":"259.340432ms","start":"2026-10-04T10:49:43.020467Z","end":"2026-10-04T10:49:43.279807Z","steps":["trace[1729950470] 'process raft request' (duration: 258.705465ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:43.280346Z","caller":"traceutil/trace.go:171","msg":"trace[1068152377] transaction","detail":"{read_only:false; response_revision:26709; number_of_response:1; }","duration":"298.330588ms","start":"2026-10-04T10:49:42.982002Z","end":"2026-10-04T10:49:43.280333Z","steps":["trace[1068152377] 'process raft request' (duration: 297.073785ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:43.280176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.836966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-04T10:49:43.280605Z","caller":"traceutil/trace.go:171","msg":"trace[1272067755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26710; }","duration":"307.318899ms","start":"2026-10-04T10:49:42.973272Z","end":"2026-10-04T10:49:43.280591Z","steps":["trace[1272067755] 'agreement among raft nodes before linearized reading' (duration: 306.55544ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:43.280645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:42.973244Z","time spent":"307.38851ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-04T10:49:43.588511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.169146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:49:43.588635Z","caller":"traceutil/trace.go:171","msg":"trace[1789906203] linearizableReadLoop","detail":"{readStateIndex:27612; appliedIndex:27610; }","duration":"276.306209ms","start":"2026-10-04T10:49:43.312306Z","end":"2026-10-04T10:49:43.588612Z","steps":["trace[1789906203] 'read index received' (duration: 17.662164ms)","trace[1789906203] 'applied index is now lower than readState.Index' (duration: 258.642345ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:49:43.588768Z","caller":"traceutil/trace.go:171","msg":"trace[326384806] transaction","detail":"{read_only:false; response_revision:26712; number_of_response:1; }","duration":"291.552922ms","start":"2026-10-04T10:49:43.297174Z","end":"2026-10-04T10:49:43.588727Z","steps":["trace[326384806] 'process raft request' (duration: 152.066208ms)","trace[326384806] 'compare' (duration: 138.964961ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:43.588857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.557296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-10-04T10:49:43.588886Z","caller":"traceutil/trace.go:171","msg":"trace[1513531973] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26712; }","duration":"276.585887ms","start":"2026-10-04T10:49:43.312289Z","end":"2026-10-04T10:49:43.588875Z","steps":["trace[1513531973] 'agreement among raft nodes before linearized reading' (duration: 276.432813ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:43.589157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.62788ms","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-10-04T10:49:43.589206Z","caller":"traceutil/trace.go:171","msg":"trace[1191426200] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26712; }","duration":"240.734743ms","start":"2026-10-04T10:49:43.348458Z","end":"2026-10-04T10:49:43.589193Z","steps":["trace[1191426200] 'agreement among raft nodes before linearized reading' (duration: 240.556969ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:43.907784Z","caller":"traceutil/trace.go:171","msg":"trace[1608579731] transaction","detail":"{read_only:false; response_revision:26713; number_of_response:1; }","duration":"312.009811ms","start":"2026-10-04T10:49:43.595735Z","end":"2026-10-04T10:49:43.907744Z","steps":["trace[1608579731] 'process raft request' (duration: 311.800615ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:43.907977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:43.59571Z","time spent":"312.178704ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:43.919739Z","caller":"traceutil/trace.go:171","msg":"trace[1171916193] linearizableReadLoop","detail":"{readStateIndex:27615; appliedIndex:27612; }","duration":"193.096175ms","start":"2026-10-04T10:49:43.726606Z","end":"2026-10-04T10:49:43.919702Z","steps":["trace[1171916193] 'read index received' (duration: 180.952353ms)","trace[1171916193] 'applied index is now lower than readState.Index' (duration: 12.141822ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:49:43.919814Z","caller":"traceutil/trace.go:171","msg":"trace[837104457] transaction","detail":"{read_only:false; response_revision:26714; number_of_response:1; }","duration":"323.955968ms","start":"2026-10-04T10:49:43.595815Z","end":"2026-10-04T10:49:43.919771Z","steps":["trace[837104457] 'process raft request' (duration: 323.556508ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:43.919924Z","caller":"traceutil/trace.go:171","msg":"trace[1431696118] transaction","detail":"{read_only:false; response_revision:26715; number_of_response:1; }","duration":"290.414383ms","start":"2026-10-04T10:49:43.629485Z","end":"2026-10-04T10:49:43.919899Z","steps":["trace[1431696118] 'process raft request' (duration: 290.136036ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:43.920066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.242343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T10:49:43.92006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.441345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:49:43.920139Z","caller":"traceutil/trace.go:171","msg":"trace[254876927] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:26715; }","duration":"159.379707ms","start":"2026-10-04T10:49:43.760741Z","end":"2026-10-04T10:49:43.920121Z","steps":["trace[254876927] 'agreement among raft nodes before linearized reading' (duration: 159.241963ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:43.920159Z","caller":"traceutil/trace.go:171","msg":"trace[1638922151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26715; }","duration":"193.587518ms","start":"2026-10-04T10:49:43.72655Z","end":"2026-10-04T10:49:43.920138Z","steps":["trace[1638922151] 'agreement among raft nodes before linearized reading' (duration: 193.451585ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:43.92022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:43.595788Z","time spent":"324.118601ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:44.082173Z","caller":"traceutil/trace.go:171","msg":"trace[1623035959] transaction","detail":"{read_only:false; response_revision:26717; number_of_response:1; }","duration":"103.866817ms","start":"2026-10-04T10:49:43.978268Z","end":"2026-10-04T10:49:44.082134Z","steps":["trace[1623035959] 'process raft request' (duration: 103.718833ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:44.082245Z","caller":"traceutil/trace.go:171","msg":"trace[1307236307] transaction","detail":"{read_only:false; response_revision:26716; number_of_response:1; }","duration":"105.239642ms","start":"2026-10-04T10:49:43.976953Z","end":"2026-10-04T10:49:44.082192Z","steps":["trace[1307236307] 'process raft request' (duration: 102.625485ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:44.846041Z","caller":"traceutil/trace.go:171","msg":"trace[1478734239] transaction","detail":"{read_only:false; response_revision:26718; number_of_response:1; }","duration":"328.351092ms","start":"2026-10-04T10:49:44.517659Z","end":"2026-10-04T10:49:44.84601Z","steps":["trace[1478734239] 'process raft request' (duration: 328.161397ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:44.846233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:44.51764Z","time spent":"328.499465ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:44.865605Z","caller":"traceutil/trace.go:171","msg":"trace[734766073] transaction","detail":"{read_only:false; response_revision:26719; number_of_response:1; }","duration":"347.581536ms","start":"2026-10-04T10:49:44.517995Z","end":"2026-10-04T10:49:44.865576Z","steps":["trace[734766073] 'process raft request' (duration: 347.365861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:44.86577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:44.517962Z","time spent":"347.7216ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:44.86589Z","caller":"traceutil/trace.go:171","msg":"trace[1812234322] transaction","detail":"{read_only:false; response_revision:26720; number_of_response:1; }","duration":"344.723222ms","start":"2026-10-04T10:49:44.521129Z","end":"2026-10-04T10:49:44.865852Z","steps":["trace[1812234322] 'process raft request' (duration: 344.409324ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:44.86594Z","caller":"traceutil/trace.go:171","msg":"trace[1137733798] transaction","detail":"{read_only:false; response_revision:26721; number_of_response:1; }","duration":"343.695256ms","start":"2026-10-04T10:49:44.522214Z","end":"2026-10-04T10:49:44.865909Z","steps":["trace[1137733798] 'process raft request' (duration: 343.554333ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:44.866053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:44.521115Z","time spent":"344.843426ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:49:44.866097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:44.522196Z","time spent":"343.812539ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:45.639761Z","caller":"traceutil/trace.go:171","msg":"trace[1117173146] transaction","detail":"{read_only:false; response_revision:26723; number_of_response:1; }","duration":"341.684814ms","start":"2026-10-04T10:49:45.298048Z","end":"2026-10-04T10:49:45.639733Z","steps":["trace[1117173146] 'process raft request' (duration: 341.384927ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:45.640094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:45.298016Z","time spent":"341.93236ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:45.663422Z","caller":"traceutil/trace.go:171","msg":"trace[468172841] linearizableReadLoop","detail":"{readStateIndex:27625; appliedIndex:27623; }","duration":"327.837338ms","start":"2026-10-04T10:49:45.335559Z","end":"2026-10-04T10:49:45.663396Z","steps":["trace[468172841] 'read index received' (duration: 305.00997ms)","trace[468172841] 'applied index is now lower than readState.Index' (duration: 22.826398ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:49:45.66372Z","caller":"traceutil/trace.go:171","msg":"trace[985726992] transaction","detail":"{read_only:false; response_revision:26724; number_of_response:1; }","duration":"364.804191ms","start":"2026-10-04T10:49:45.298905Z","end":"2026-10-04T10:49:45.663709Z","steps":["trace[985726992] 'process raft request' (duration: 364.305347ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:45.663823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:45.298891Z","time spent":"364.854702ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:45.663977Z","caller":"traceutil/trace.go:171","msg":"trace[2135497719] transaction","detail":"{read_only:false; response_revision:26725; number_of_response:1; }","duration":"365.016466ms","start":"2026-10-04T10:49:45.298952Z","end":"2026-10-04T10:49:45.663969Z","steps":["trace[2135497719] 'process raft request' (duration: 364.396029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:45.664016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:45.298914Z","time spent":"365.081618ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:49:45.664188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.642369ms","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-10-04T10:49:45.664214Z","caller":"traceutil/trace.go:171","msg":"trace[781362508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26725; }","duration":"328.725741ms","start":"2026-10-04T10:49:45.335482Z","end":"2026-10-04T10:49:45.664208Z","steps":["trace[781362508] 'agreement among raft nodes before linearized reading' (duration: 328.608578ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:45.664254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:45.335454Z","time spent":"328.773492ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-04T10:49:45.664413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.962332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-10-04T10:49:45.66444Z","caller":"traceutil/trace.go:171","msg":"trace[484706045] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26725; }","duration":"251.991203ms","start":"2026-10-04T10:49:45.412442Z","end":"2026-10-04T10:49:45.664433Z","steps":["trace[484706045] 'agreement among raft nodes before linearized reading' (duration: 251.897861ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:45.865752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.506187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:49:45.866125Z","caller":"traceutil/trace.go:171","msg":"trace[846410378] linearizableReadLoop","detail":"{readStateIndex:27627; appliedIndex:27625; }","duration":"194.681237ms","start":"2026-10-04T10:49:45.671417Z","end":"2026-10-04T10:49:45.866098Z","steps":["trace[846410378] 'read index received' (duration: 71.678607ms)","trace[846410378] 'applied index is now lower than readState.Index' (duration: 123.001309ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:49:45.866271Z","caller":"traceutil/trace.go:171","msg":"trace[1890445292] transaction","detail":"{read_only:false; response_revision:26727; number_of_response:1; }","duration":"195.085557ms","start":"2026-10-04T10:49:45.671173Z","end":"2026-10-04T10:49:45.866258Z","steps":["trace[1890445292] 'process raft request' (duration: 194.782669ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:45.86631Z","caller":"traceutil/trace.go:171","msg":"trace[1441022975] transaction","detail":"{read_only:false; response_revision:26726; number_of_response:1; }","duration":"195.639721ms","start":"2026-10-04T10:49:45.670624Z","end":"2026-10-04T10:49:45.866263Z","steps":["trace[1441022975] 'process raft request' (duration: 72.481607ms)","trace[1441022975] 'compare' (duration: 122.21438ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:45.866403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.543591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:49:45.866484Z","caller":"traceutil/trace.go:171","msg":"trace[1832687481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26727; }","duration":"140.728856ms","start":"2026-10-04T10:49:45.725736Z","end":"2026-10-04T10:49:45.866465Z","steps":["trace[1832687481] 'agreement among raft nodes before linearized reading' (duration: 140.553281ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:45.8665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.115957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19030"} {"level":"info","ts":"2026-10-04T10:49:45.866543Z","caller":"traceutil/trace.go:171","msg":"trace[1837133338] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26727; }","duration":"195.210961ms","start":"2026-10-04T10:49:45.671321Z","end":"2026-10-04T10:49:45.866531Z","steps":["trace[1837133338] 'agreement among raft nodes before linearized reading' (duration: 195.008845ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:50.440542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9422551893994573772,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-04T10:49:50.656002Z","caller":"traceutil/trace.go:171","msg":"trace[829383961] transaction","detail":"{read_only:false; response_revision:26759; number_of_response:1; }","duration":"765.948667ms","start":"2026-10-04T10:49:49.890028Z","end":"2026-10-04T10:49:50.655977Z","steps":["trace[829383961] 'process raft request' (duration: 765.738902ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:50.656307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:49.890011Z","time spent":"766.153522ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:50.678067Z","caller":"traceutil/trace.go:171","msg":"trace[2101224255] linearizableReadLoop","detail":"{readStateIndex:27665; appliedIndex:27660; }","duration":"738.51594ms","start":"2026-10-04T10:49:49.939538Z","end":"2026-10-04T10:49:50.678054Z","steps":["trace[2101224255] 'read index received' (duration: 716.294778ms)","trace[2101224255] 'applied index is now lower than readState.Index' (duration: 22.220022ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:50.678357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.162151ms","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:6"} {"level":"info","ts":"2026-10-04T10:49:50.678458Z","caller":"traceutil/trace.go:171","msg":"trace[1753067400] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26764; }","duration":"575.282014ms","start":"2026-10-04T10:49:50.103158Z","end":"2026-10-04T10:49:50.67844Z","steps":["trace[1753067400] 'agreement among raft nodes before linearized reading' (duration: 575.1247ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:50.678508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:50.103069Z","time spent":"575.423997ms","remote":"127.0.0.1:42636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-10-04T10:49:50.677812Z","caller":"traceutil/trace.go:171","msg":"trace[1558208836] transaction","detail":"{read_only:false; response_revision:26760; number_of_response:1; }","duration":"787.667546ms","start":"2026-10-04T10:49:49.890092Z","end":"2026-10-04T10:49:50.677759Z","steps":["trace[1558208836] 'process raft request' (duration: 787.377968ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:50.67818Z","caller":"traceutil/trace.go:171","msg":"trace[915435513] transaction","detail":"{read_only:false; response_revision:26763; number_of_response:1; }","duration":"567.35224ms","start":"2026-10-04T10:49:50.110798Z","end":"2026-10-04T10:49:50.67815Z","steps":["trace[915435513] 'process raft request' (duration: 567.168694ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:50.678604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.026583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-04T10:49:50.678685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:50.110788Z","time spent":"567.825292ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:50.678712Z","caller":"traceutil/trace.go:171","msg":"trace[2010433382] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26764; }","duration":"739.206817ms","start":"2026-10-04T10:49:49.939478Z","end":"2026-10-04T10:49:50.678685Z","steps":["trace[2010433382] 'agreement among raft nodes before linearized reading' (duration: 738.847918ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:50.678059Z","caller":"traceutil/trace.go:171","msg":"trace[1738441030] transaction","detail":"{read_only:false; response_revision:26761; number_of_response:1; }","duration":"724.989912ms","start":"2026-10-04T10:49:49.95304Z","end":"2026-10-04T10:49:50.67803Z","steps":["trace[1738441030] 'process raft request' (duration: 724.613701ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:50.678766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:49.939449Z","time spent":"739.3064ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-04T10:49:50.678946Z","caller":"traceutil/trace.go:171","msg":"trace[552452593] transaction","detail":"{read_only:false; response_revision:26762; number_of_response:1; }","duration":"567.689778ms","start":"2026-10-04T10:49:50.11056Z","end":"2026-10-04T10:49:50.678249Z","steps":["trace[552452593] 'process raft request' (duration: 567.346479ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:50.67915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:50.110526Z","time spent":"568.518449ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:49:50.679222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:49.953021Z","time spent":"725.841503ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:49:50.678682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:49.89007Z","time spent":"788.484498ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:51.054446Z","caller":"traceutil/trace.go:171","msg":"trace[1049709703] transaction","detail":"{read_only:false; response_revision:26765; number_of_response:1; }","duration":"368.555327ms","start":"2026-10-04T10:49:50.685855Z","end":"2026-10-04T10:49:51.054411Z","steps":["trace[1049709703] 'process raft request' (duration: 368.344112ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:51.054635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:50.685825Z","time spent":"368.712271ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:49:51.054915Z","caller":"traceutil/trace.go:171","msg":"trace[1469357426] linearizableReadLoop","detail":"{readStateIndex:27667; appliedIndex:27667; }","duration":"328.762191ms","start":"2026-10-04T10:49:50.726109Z","end":"2026-10-04T10:49:51.054871Z","steps":["trace[1469357426] 'read index received' (duration: 328.742191ms)","trace[1469357426] 'applied index is now lower than readState.Index' (duration: 17.23µs)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:51.055087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.968826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:49:51.055174Z","caller":"traceutil/trace.go:171","msg":"trace[891119585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26765; }","duration":"329.12703ms","start":"2026-10-04T10:49:50.726027Z","end":"2026-10-04T10:49:51.055154Z","steps":["trace[891119585] 'agreement among raft nodes before linearized reading' (duration: 328.980287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:51.055217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:50.725994Z","time spent":"329.213813ms","remote":"127.0.0.1:35698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-04T10:49:51.056092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.888746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:49:51.056205Z","caller":"traceutil/trace.go:171","msg":"trace[1517749258] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:26767; }","duration":"260.06944ms","start":"2026-10-04T10:49:50.796111Z","end":"2026-10-04T10:49:51.05618Z","steps":["trace[1517749258] 'agreement among raft nodes before linearized reading' (duration: 259.844295ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:51.056371Z","caller":"traceutil/trace.go:171","msg":"trace[1060560913] transaction","detail":"{read_only:false; response_revision:26767; number_of_response:1; }","duration":"147.578132ms","start":"2026-10-04T10:49:50.908723Z","end":"2026-10-04T10:49:51.056301Z","steps":["trace[1060560913] 'process raft request' (duration: 147.182341ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:51.056624Z","caller":"traceutil/trace.go:171","msg":"trace[743091507] transaction","detail":"{read_only:false; response_revision:26766; number_of_response:1; }","duration":"186.005763ms","start":"2026-10-04T10:49:50.870604Z","end":"2026-10-04T10:49:51.05661Z","steps":["trace[743091507] 'process raft request' (duration: 185.097639ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:51.056986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.280655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-04T10:49:51.05702Z","caller":"traceutil/trace.go:171","msg":"trace[1883147728] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26767; }","duration":"147.366698ms","start":"2026-10-04T10:49:50.909646Z","end":"2026-10-04T10:49:51.057012Z","steps":["trace[1883147728] 'agreement among raft nodes before linearized reading' (duration: 147.180223ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:51.058074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.459419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-04T10:49:51.058134Z","caller":"traceutil/trace.go:171","msg":"trace[425176841] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26767; }","duration":"147.578352ms","start":"2026-10-04T10:49:50.910541Z","end":"2026-10-04T10:49:51.058119Z","steps":["trace[425176841] 'agreement among raft nodes before linearized reading' (duration: 146.002612ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:56.872976Z","caller":"traceutil/trace.go:171","msg":"trace[1277686531] transaction","detail":"{read_only:false; response_revision:26863; number_of_response:1; }","duration":"169.2163ms","start":"2026-10-04T10:49:56.703727Z","end":"2026-10-04T10:49:56.872944Z","steps":["trace[1277686531] 'process raft request' (duration: 168.988264ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:56.969631Z","caller":"traceutil/trace.go:171","msg":"trace[775285511] transaction","detail":"{read_only:false; response_revision:26864; number_of_response:1; }","duration":"264.019723ms","start":"2026-10-04T10:49:56.70556Z","end":"2026-10-04T10:49:56.96958Z","steps":["trace[775285511] 'process raft request' (duration: 234.939364ms)","trace[775285511] 'compare' (duration: 28.75454ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:49:56.969635Z","caller":"traceutil/trace.go:171","msg":"trace[2043332402] linearizableReadLoop","detail":"{readStateIndex:27769; appliedIndex:27766; }","duration":"260.42568ms","start":"2026-10-04T10:49:56.709174Z","end":"2026-10-04T10:49:56.9696Z","steps":["trace[2043332402] 'read index received' (duration: 163.539234ms)","trace[2043332402] 'applied index is now lower than readState.Index' (duration: 96.884876ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:49:56.969798Z","caller":"traceutil/trace.go:171","msg":"trace[920326862] transaction","detail":"{read_only:false; response_revision:26865; number_of_response:1; }","duration":"263.121619ms","start":"2026-10-04T10:49:56.706661Z","end":"2026-10-04T10:49:56.969783Z","steps":["trace[920326862] 'process raft request' (duration: 262.819261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:56.969973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.786761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-04T10:49:56.970004Z","caller":"traceutil/trace.go:171","msg":"trace[724257257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26866; }","duration":"260.880883ms","start":"2026-10-04T10:49:56.709115Z","end":"2026-10-04T10:49:56.969996Z","steps":["trace[724257257] 'agreement among raft nodes before linearized reading' (duration: 260.704518ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:56.970078Z","caller":"traceutil/trace.go:171","msg":"trace[356815501] transaction","detail":"{read_only:false; response_revision:26866; number_of_response:1; }","duration":"181.076116ms","start":"2026-10-04T10:49:56.788994Z","end":"2026-10-04T10:49:56.970071Z","steps":["trace[356815501] 'process raft request' (duration: 180.542182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:56.970208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.358551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:49:56.970293Z","caller":"traceutil/trace.go:171","msg":"trace[869571277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26866; }","duration":"243.518294ms","start":"2026-10-04T10:49:56.726756Z","end":"2026-10-04T10:49:56.970274Z","steps":["trace[869571277] 'agreement among raft nodes before linearized reading' (duration: 243.375341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:57.213992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.657156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:49:57.214128Z","caller":"traceutil/trace.go:171","msg":"trace[887764440] transaction","detail":"{read_only:false; response_revision:26867; number_of_response:1; }","duration":"239.342038ms","start":"2026-10-04T10:49:56.974756Z","end":"2026-10-04T10:49:57.214098Z","steps":["trace[887764440] 'process raft request' (duration: 108.483486ms)","trace[887764440] 'compare' (duration: 130.079671ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:49:57.235016Z","caller":"traceutil/trace.go:171","msg":"trace[965398382] transaction","detail":"{read_only:false; response_revision:26868; number_of_response:1; }","duration":"256.493889ms","start":"2026-10-04T10:49:56.978487Z","end":"2026-10-04T10:49:57.23498Z","steps":["trace[965398382] 'process raft request' (duration: 256.218972ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:57.235114Z","caller":"traceutil/trace.go:171","msg":"trace[921915501] transaction","detail":"{read_only:false; response_revision:26869; number_of_response:1; }","duration":"156.028821ms","start":"2026-10-04T10:49:57.079068Z","end":"2026-10-04T10:49:57.235096Z","steps":["trace[921915501] 'process raft request' (duration: 155.841606ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:57.51096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.633311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:49:57.51107Z","caller":"traceutil/trace.go:171","msg":"trace[953890665] linearizableReadLoop","detail":"{readStateIndex:27774; appliedIndex:27771; }","duration":"410.596691ms","start":"2026-10-04T10:49:57.100455Z","end":"2026-10-04T10:49:57.511052Z","steps":["trace[953890665] 'read index received' (duration: 134.27177ms)","trace[953890665] 'applied index is now lower than readState.Index' (duration: 276.32308ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:49:57.511178Z","caller":"traceutil/trace.go:171","msg":"trace[1508168498] transaction","detail":"{read_only:false; response_revision:26870; number_of_response:1; }","duration":"424.97163ms","start":"2026-10-04T10:49:57.086189Z","end":"2026-10-04T10:49:57.51116Z","steps":["trace[1508168498] 'process raft request' (duration: 274.00234ms)","trace[1508168498] 'compare' (duration: 150.143888ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:49:57.511258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:57.086138Z","time spent":"425.086724ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:49:57.511426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.949576ms","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-10-04T10:49:57.511527Z","caller":"traceutil/trace.go:171","msg":"trace[1687342263] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26870; }","duration":"407.098039ms","start":"2026-10-04T10:49:57.104401Z","end":"2026-10-04T10:49:57.511499Z","steps":["trace[1687342263] 'agreement among raft nodes before linearized reading' (duration: 406.845062ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:57.511576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:57.104389Z","time spent":"407.174631ms","remote":"127.0.0.1:36010","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-10-04T10:49:57.511605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.802112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-04T10:49:57.511683Z","caller":"traceutil/trace.go:171","msg":"trace[60291624] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26870; }","duration":"399.937715ms","start":"2026-10-04T10:49:57.111723Z","end":"2026-10-04T10:49:57.511661Z","steps":["trace[60291624] 'agreement among raft nodes before linearized reading' (duration: 399.705029ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:57.511724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:57.111701Z","time spent":"400.014377ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-04T10:49:57.511693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.224036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-04T10:49:57.511779Z","caller":"traceutil/trace.go:171","msg":"trace[1186806433] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26870; }","duration":"411.389771ms","start":"2026-10-04T10:49:57.100371Z","end":"2026-10-04T10:49:57.51176Z","steps":["trace[1186806433] 'agreement among raft nodes before linearized reading' (duration: 411.053182ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:57.51182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:49:57.100337Z","time spent":"411.476383ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-04T10:49:57.804924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.164592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:49:57.805255Z","caller":"traceutil/trace.go:171","msg":"trace[1261493087] transaction","detail":"{read_only:false; response_revision:26871; number_of_response:1; }","duration":"288.73615ms","start":"2026-10-04T10:49:57.516399Z","end":"2026-10-04T10:49:57.805135Z","steps":["trace[1261493087] 'process raft request' (duration: 136.248681ms)","trace[1261493087] 'compare' (duration: 151.935534ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:49:57.805555Z","caller":"traceutil/trace.go:171","msg":"trace[796047622] transaction","detail":"{read_only:false; response_revision:26872; number_of_response:1; }","duration":"288.127575ms","start":"2026-10-04T10:49:57.517374Z","end":"2026-10-04T10:49:57.805502Z","steps":["trace[796047622] 'process raft request' (duration: 287.695254ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:57.805669Z","caller":"traceutil/trace.go:171","msg":"trace[923375496] transaction","detail":"{read_only:false; response_revision:26873; number_of_response:1; }","duration":"287.92893ms","start":"2026-10-04T10:49:57.517695Z","end":"2026-10-04T10:49:57.805624Z","steps":["trace[923375496] 'process raft request' (duration: 287.436357ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:57.806073Z","caller":"traceutil/trace.go:171","msg":"trace[71446687] linearizableReadLoop","detail":"{readStateIndex:27777; appliedIndex:27774; }","duration":"241.088713ms","start":"2026-10-04T10:49:57.564962Z","end":"2026-10-04T10:49:57.806051Z","steps":["trace[71446687] 'read index received' (duration: 87.649929ms)","trace[71446687] 'applied index is now lower than readState.Index' (duration: 153.437254ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:49:57.806073Z","caller":"traceutil/trace.go:171","msg":"trace[1505258974] transaction","detail":"{read_only:false; response_revision:26874; number_of_response:1; }","duration":"226.474626ms","start":"2026-10-04T10:49:57.579584Z","end":"2026-10-04T10:49:57.806059Z","steps":["trace[1505258974] 'process raft request' (duration: 225.920882ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:49:57.806272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.309918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4040475a-3707-4f9c-b6f1-7e77b19beacc\" ","response":"range_response_count:1 size:3764"} {"level":"info","ts":"2026-10-04T10:49:57.806314Z","caller":"traceutil/trace.go:171","msg":"trace[1213672103] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4040475a-3707-4f9c-b6f1-7e77b19beacc; range_end:; response_count:1; response_revision:26874; }","duration":"241.39509ms","start":"2026-10-04T10:49:57.564906Z","end":"2026-10-04T10:49:57.806301Z","steps":["trace[1213672103] 'agreement among raft nodes before linearized reading' (duration: 241.212255ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:49:57.812696Z","caller":"traceutil/trace.go:171","msg":"trace[908756299] transaction","detail":"{read_only:false; response_revision:26875; number_of_response:1; }","duration":"114.260883ms","start":"2026-10-04T10:49:57.698409Z","end":"2026-10-04T10:49:57.81267Z","steps":["trace[908756299] 'process raft request' (duration: 114.086018ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:03.512755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.927704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:50:03.512992Z","caller":"traceutil/trace.go:171","msg":"trace[1331806533] transaction","detail":"{read_only:false; response_revision:26949; number_of_response:1; }","duration":"162.63567ms","start":"2026-10-04T10:50:03.350333Z","end":"2026-10-04T10:50:03.512969Z","steps":["trace[1331806533] 'process raft request' (duration: 31.380898ms)","trace[1331806533] 'compare' (duration: 130.7717ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:03.815771Z","caller":"traceutil/trace.go:171","msg":"trace[937324140] linearizableReadLoop","detail":"{readStateIndex:27855; appliedIndex:27854; }","duration":"173.042059ms","start":"2026-10-04T10:50:03.642699Z","end":"2026-10-04T10:50:03.815741Z","steps":["trace[937324140] 'read index received' (duration: 172.836644ms)","trace[937324140] 'applied index is now lower than readState.Index' (duration: 204.095µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:03.815976Z","caller":"traceutil/trace.go:171","msg":"trace[255319004] transaction","detail":"{read_only:false; response_revision:26950; number_of_response:1; }","duration":"281.728449ms","start":"2026-10-04T10:50:03.534191Z","end":"2026-10-04T10:50:03.81592Z","steps":["trace[255319004] 'process raft request' (duration: 281.354849ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:03.816186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.43774ms","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-10-04T10:50:03.816315Z","caller":"traceutil/trace.go:171","msg":"trace[1592616294] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26950; }","duration":"173.653225ms","start":"2026-10-04T10:50:03.642638Z","end":"2026-10-04T10:50:03.816291Z","steps":["trace[1592616294] 'agreement among raft nodes before linearized reading' (duration: 173.386918ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:05.252911Z","caller":"traceutil/trace.go:171","msg":"trace[1719575939] transaction","detail":"{read_only:false; response_revision:26971; number_of_response:1; }","duration":"111.452063ms","start":"2026-10-04T10:50:05.141374Z","end":"2026-10-04T10:50:05.252826Z","steps":["trace[1719575939] 'process raft request' (duration: 111.181166ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:27.808476Z","caller":"traceutil/trace.go:171","msg":"trace[1069308895] transaction","detail":"{read_only:false; response_revision:27163; number_of_response:1; }","duration":"125.268018ms","start":"2026-10-04T10:50:27.683174Z","end":"2026-10-04T10:50:27.808442Z","steps":["trace[1069308895] 'process raft request' (duration: 125.014272ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:28.018254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.317617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:50:28.018539Z","caller":"traceutil/trace.go:171","msg":"trace[377109502] transaction","detail":"{read_only:false; response_revision:27165; number_of_response:1; }","duration":"111.108893ms","start":"2026-10-04T10:50:27.907404Z","end":"2026-10-04T10:50:28.018513Z","steps":["trace[377109502] 'process raft request' (duration: 111.010631ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:28.018535Z","caller":"traceutil/trace.go:171","msg":"trace[1345596516] transaction","detail":"{read_only:false; response_revision:27164; number_of_response:1; }","duration":"162.99119ms","start":"2026-10-04T10:50:27.855512Z","end":"2026-10-04T10:50:28.018504Z","steps":["trace[1345596516] 'process raft request' (duration: 53.297884ms)","trace[1345596516] 'compare' (duration: 109.178213ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:28.085815Z","caller":"traceutil/trace.go:171","msg":"trace[791538840] transaction","detail":"{read_only:false; response_revision:27166; number_of_response:1; }","duration":"127.299121ms","start":"2026-10-04T10:50:27.958483Z","end":"2026-10-04T10:50:28.085782Z","steps":["trace[791538840] 'process raft request' (duration: 127.133126ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:28.2895Z","caller":"traceutil/trace.go:171","msg":"trace[329172716] linearizableReadLoop","detail":"{readStateIndex:28078; appliedIndex:28075; }","duration":"306.237592ms","start":"2026-10-04T10:50:27.983233Z","end":"2026-10-04T10:50:28.28947Z","steps":["trace[329172716] 'read index received' (duration: 102.481871ms)","trace[329172716] 'applied index is now lower than readState.Index' (duration: 203.754121ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:28.28949Z","caller":"traceutil/trace.go:171","msg":"trace[528500027] transaction","detail":"{read_only:false; response_revision:27167; number_of_response:1; }","duration":"308.543332ms","start":"2026-10-04T10:50:27.980895Z","end":"2026-10-04T10:50:28.289438Z","steps":["trace[528500027] 'process raft request' (duration: 272.855282ms)","trace[528500027] 'compare' (duration: 35.144965ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:28.289567Z","caller":"traceutil/trace.go:171","msg":"trace[1587678052] transaction","detail":"{read_only:false; response_revision:27168; number_of_response:1; }","duration":"307.412342ms","start":"2026-10-04T10:50:27.982119Z","end":"2026-10-04T10:50:28.289531Z","steps":["trace[1587678052] 'process raft request' (duration: 307.212696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:28.289688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.432787ms","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-10-04T10:50:28.289743Z","caller":"traceutil/trace.go:171","msg":"trace[92003355] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:27168; }","duration":"306.54659ms","start":"2026-10-04T10:50:27.983184Z","end":"2026-10-04T10:50:28.289731Z","steps":["trace[92003355] 'agreement among raft nodes before linearized reading' (duration: 306.391846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:28.289732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:50:27.980851Z","time spent":"308.776478ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:50:28.289752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:50:27.982093Z","time spent":"307.557215ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:50:28.289784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:50:27.983173Z","time spent":"306.599261ms","remote":"127.0.0.1:39976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-04T10:50:28.290951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.201552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-04T10:50:28.291063Z","caller":"traceutil/trace.go:171","msg":"trace[473636030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27169; }","duration":"282.352435ms","start":"2026-10-04T10:50:28.008686Z","end":"2026-10-04T10:50:28.291039Z","steps":["trace[473636030] 'agreement among raft nodes before linearized reading' (duration: 282.069008ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:28.291925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.858548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-04T10:50:28.292037Z","caller":"traceutil/trace.go:171","msg":"trace[1741219741] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27169; }","duration":"276.025362ms","start":"2026-10-04T10:50:28.015985Z","end":"2026-10-04T10:50:28.29201Z","steps":["trace[1741219741] 'agreement among raft nodes before linearized reading' (duration: 275.654983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:28.292083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.335454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-04T10:50:28.29213Z","caller":"traceutil/trace.go:171","msg":"trace[1686188697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27169; }","duration":"280.401095ms","start":"2026-10-04T10:50:28.011717Z","end":"2026-10-04T10:50:28.292119Z","steps":["trace[1686188697] 'agreement among raft nodes before linearized reading' (duration: 280.286292ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:28.499184Z","caller":"traceutil/trace.go:171","msg":"trace[1132710908] transaction","detail":"{read_only:false; response_revision:27172; number_of_response:1; }","duration":"197.079477ms","start":"2026-10-04T10:50:28.302075Z","end":"2026-10-04T10:50:28.499155Z","steps":["trace[1132710908] 'process raft request' (duration: 196.937244ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:28.499507Z","caller":"traceutil/trace.go:171","msg":"trace[962445388] transaction","detail":"{read_only:false; response_revision:27171; number_of_response:1; }","duration":"201.537103ms","start":"2026-10-04T10:50:28.297931Z","end":"2026-10-04T10:50:28.499468Z","steps":["trace[962445388] 'process raft request' (duration: 153.822033ms)","trace[962445388] 'compare' (duration: 46.824277ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:29.130437Z","caller":"traceutil/trace.go:171","msg":"trace[155949949] transaction","detail":"{read_only:false; response_revision:27174; number_of_response:1; }","duration":"137.630797ms","start":"2026-10-04T10:50:28.992772Z","end":"2026-10-04T10:50:29.130403Z","steps":["trace[155949949] 'process raft request' (duration: 121.724456ms)","trace[155949949] 'compare' (duration: 15.231143ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:50:29.476018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.820092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:50:29.476555Z","caller":"traceutil/trace.go:171","msg":"trace[256937081] transaction","detail":"{read_only:false; response_revision:27177; number_of_response:1; }","duration":"314.821112ms","start":"2026-10-04T10:50:29.161662Z","end":"2026-10-04T10:50:29.476483Z","steps":["trace[256937081] 'process raft request' (duration: 314.563985ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:29.47669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:50:29.16164Z","time spent":"314.965776ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:50:29.477055Z","caller":"traceutil/trace.go:171","msg":"trace[825800090] transaction","detail":"{read_only:false; response_revision:27176; number_of_response:1; }","duration":"315.782436ms","start":"2026-10-04T10:50:29.161255Z","end":"2026-10-04T10:50:29.477038Z","steps":["trace[825800090] 'process raft request' (duration: 105.543448ms)","trace[825800090] 'compare' (duration: 208.563085ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:50:29.477262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:50:29.161215Z","time spent":"316.007343ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:50:29.920788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.914021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T10:50:29.920807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.001347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-10-04T10:50:29.920892Z","caller":"traceutil/trace.go:171","msg":"trace[634456568] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:27180; }","duration":"153.088736ms","start":"2026-10-04T10:50:29.767779Z","end":"2026-10-04T10:50:29.920868Z","steps":["trace[634456568] 'count revisions from in-memory index tree' (duration: 152.810078ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:29.920934Z","caller":"traceutil/trace.go:171","msg":"trace[1804860839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27180; }","duration":"107.176492ms","start":"2026-10-04T10:50:29.813729Z","end":"2026-10-04T10:50:29.920906Z","steps":["trace[1804860839] 'range keys from in-memory index tree' (duration: 106.752091ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:30.063985Z","caller":"traceutil/trace.go:171","msg":"trace[276848953] transaction","detail":"{read_only:false; response_revision:27181; number_of_response:1; }","duration":"137.243396ms","start":"2026-10-04T10:50:29.926715Z","end":"2026-10-04T10:50:30.063958Z","steps":["trace[276848953] 'process raft request' (duration: 137.089332ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:30.295592Z","caller":"traceutil/trace.go:171","msg":"trace[522479794] transaction","detail":"{read_only:false; response_revision:27182; number_of_response:1; }","duration":"268.388176ms","start":"2026-10-04T10:50:30.027169Z","end":"2026-10-04T10:50:30.295557Z","steps":["trace[522479794] 'process raft request' (duration: 188.825305ms)","trace[522479794] 'compare' (duration: 79.433747ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:30.318317Z","caller":"traceutil/trace.go:171","msg":"trace[42427983] transaction","detail":"{read_only:false; response_revision:27183; number_of_response:1; }","duration":"227.117652ms","start":"2026-10-04T10:50:30.091181Z","end":"2026-10-04T10:50:30.318298Z","steps":["trace[42427983] 'process raft request' (duration: 227.03173ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:30.564611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.913836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:50:30.564785Z","caller":"traceutil/trace.go:171","msg":"trace[1873780338] transaction","detail":"{read_only:false; response_revision:27184; number_of_response:1; }","duration":"269.114644ms","start":"2026-10-04T10:50:30.295648Z","end":"2026-10-04T10:50:30.564763Z","steps":["trace[1873780338] 'process raft request' (duration: 121.968062ms)","trace[1873780338] 'compare' (duration: 146.456683ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:30.564964Z","caller":"traceutil/trace.go:171","msg":"trace[109810986] transaction","detail":"{read_only:false; response_revision:27185; number_of_response:1; }","duration":"269.238637ms","start":"2026-10-04T10:50:30.295718Z","end":"2026-10-04T10:50:30.564957Z","steps":["trace[109810986] 'process raft request' (duration: 268.994331ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:30.565128Z","caller":"traceutil/trace.go:171","msg":"trace[548708684] linearizableReadLoop","detail":"{readStateIndex:28096; appliedIndex:28093; }","duration":"233.163868ms","start":"2026-10-04T10:50:30.331946Z","end":"2026-10-04T10:50:30.565109Z","steps":["trace[548708684] 'read index received' (duration: 85.901833ms)","trace[548708684] 'applied index is now lower than readState.Index' (duration: 147.259815ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:30.565208Z","caller":"traceutil/trace.go:171","msg":"trace[1212161149] transaction","detail":"{read_only:false; response_revision:27186; number_of_response:1; }","duration":"241.006229ms","start":"2026-10-04T10:50:30.324167Z","end":"2026-10-04T10:50:30.565173Z","steps":["trace[1212161149] 'process raft request' (duration: 240.756743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:30.565406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.455115ms","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-10-04T10:50:30.565439Z","caller":"traceutil/trace.go:171","msg":"trace[1251642240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27186; }","duration":"233.516767ms","start":"2026-10-04T10:50:30.331909Z","end":"2026-10-04T10:50:30.565426Z","steps":["trace[1251642240] 'agreement among raft nodes before linearized reading' (duration: 233.404034ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:30.78802Z","caller":"traceutil/trace.go:171","msg":"trace[1224618641] transaction","detail":"{read_only:false; response_revision:27189; number_of_response:1; }","duration":"215.069151ms","start":"2026-10-04T10:50:30.572914Z","end":"2026-10-04T10:50:30.787983Z","steps":["trace[1224618641] 'process raft request' (duration: 214.971509ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:30.788362Z","caller":"traceutil/trace.go:171","msg":"trace[1162574201] transaction","detail":"{read_only:false; response_revision:27188; number_of_response:1; }","duration":"217.404282ms","start":"2026-10-04T10:50:30.570938Z","end":"2026-10-04T10:50:30.788343Z","steps":["trace[1162574201] 'process raft request' (duration: 119.208492ms)","trace[1162574201] 'compare' (duration: 97.459831ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:32.763483Z","caller":"traceutil/trace.go:171","msg":"trace[1319411781] linearizableReadLoop","detail":"{readStateIndex:28110; appliedIndex:28109; }","duration":"148.744123ms","start":"2026-10-04T10:50:32.614707Z","end":"2026-10-04T10:50:32.763452Z","steps":["trace[1319411781] 'read index received' (duration: 144.573555ms)","trace[1319411781] 'applied index is now lower than readState.Index' (duration: 4.169438ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:32.763527Z","caller":"traceutil/trace.go:171","msg":"trace[1336244266] transaction","detail":"{read_only:false; response_revision:27199; number_of_response:1; }","duration":"191.788243ms","start":"2026-10-04T10:50:32.571689Z","end":"2026-10-04T10:50:32.763477Z","steps":["trace[1336244266] 'process raft request' (duration: 187.591684ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:32.76375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.01654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-04T10:50:32.763842Z","caller":"traceutil/trace.go:171","msg":"trace[1925701086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27199; }","duration":"149.166644ms","start":"2026-10-04T10:50:32.614651Z","end":"2026-10-04T10:50:32.763817Z","steps":["trace[1925701086] 'agreement among raft nodes before linearized reading' (duration: 148.907678ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:33.015826Z","caller":"traceutil/trace.go:171","msg":"trace[312346388] transaction","detail":"{read_only:false; response_revision:27201; number_of_response:1; }","duration":"173.956683ms","start":"2026-10-04T10:50:32.841828Z","end":"2026-10-04T10:50:33.015784Z","steps":["trace[312346388] 'process raft request' (duration: 84.149709ms)","trace[312346388] 'compare' (duration: 89.357742ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:33.015907Z","caller":"traceutil/trace.go:171","msg":"trace[1029073667] transaction","detail":"{read_only:false; response_revision:27202; number_of_response:1; }","duration":"173.650145ms","start":"2026-10-04T10:50:32.842215Z","end":"2026-10-04T10:50:33.015865Z","steps":["trace[1029073667] 'process raft request' (duration: 173.307886ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:33.104029Z","caller":"traceutil/trace.go:171","msg":"trace[546924411] transaction","detail":"{read_only:false; response_revision:27203; number_of_response:1; }","duration":"116.356027ms","start":"2026-10-04T10:50:32.987639Z","end":"2026-10-04T10:50:33.103995Z","steps":["trace[546924411] 'process raft request' (duration: 116.191173ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:33.257851Z","caller":"traceutil/trace.go:171","msg":"trace[1875704454] transaction","detail":"{read_only:false; response_revision:27204; number_of_response:1; }","duration":"232.918181ms","start":"2026-10-04T10:50:33.024904Z","end":"2026-10-04T10:50:33.257823Z","steps":["trace[1875704454] 'process raft request' (duration: 232.703256ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:33.323821Z","caller":"traceutil/trace.go:171","msg":"trace[2088457176] linearizableReadLoop","detail":"{readStateIndex:28117; appliedIndex:28115; }","duration":"167.832734ms","start":"2026-10-04T10:50:33.155956Z","end":"2026-10-04T10:50:33.323789Z","steps":["trace[2088457176] 'read index received' (duration: 102.50742ms)","trace[2088457176] 'applied index is now lower than readState.Index' (duration: 65.324264ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:33.323948Z","caller":"traceutil/trace.go:171","msg":"trace[1316731534] transaction","detail":"{read_only:false; response_revision:27205; number_of_response:1; }","duration":"178.11358ms","start":"2026-10-04T10:50:33.145823Z","end":"2026-10-04T10:50:33.323937Z","steps":["trace[1316731534] 'process raft request' (duration: 177.762111ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:33.324145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.164404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T10:50:33.324209Z","caller":"traceutil/trace.go:171","msg":"trace[461809201] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:27206; }","duration":"168.282877ms","start":"2026-10-04T10:50:33.155909Z","end":"2026-10-04T10:50:33.324192Z","steps":["trace[461809201] 'agreement among raft nodes before linearized reading' (duration: 168.156933ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:33.324288Z","caller":"traceutil/trace.go:171","msg":"trace[528646642] transaction","detail":"{read_only:false; response_revision:27206; number_of_response:1; }","duration":"177.988577ms","start":"2026-10-04T10:50:33.14622Z","end":"2026-10-04T10:50:33.324208Z","steps":["trace[528646642] 'process raft request' (duration: 177.530665ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:33.324335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.145498ms","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-10-04T10:50:33.324421Z","caller":"traceutil/trace.go:171","msg":"trace[1728857546] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:27206; }","duration":"143.270752ms","start":"2026-10-04T10:50:33.181128Z","end":"2026-10-04T10:50:33.324398Z","steps":["trace[1728857546] 'agreement among raft nodes before linearized reading' (duration: 143.009305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:33.434261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.881962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-c5992732-6aba-4247-93aa-8d9dda08e8c0\" ","response":"range_response_count:1 size:1316"} {"level":"info","ts":"2026-10-04T10:50:33.434975Z","caller":"traceutil/trace.go:171","msg":"trace[461723811] range","detail":"{range_begin:/registry/persistentvolumes/pvc-c5992732-6aba-4247-93aa-8d9dda08e8c0; range_end:; response_count:1; response_revision:27206; }","duration":"105.042576ms","start":"2026-10-04T10:50:33.329303Z","end":"2026-10-04T10:50:33.434346Z","steps":["trace[461723811] 'range keys from in-memory index tree' (duration: 104.654736ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:39.041125Z","caller":"traceutil/trace.go:171","msg":"trace[1897251317] transaction","detail":"{read_only:false; response_revision:27243; number_of_response:1; }","duration":"254.223201ms","start":"2026-10-04T10:50:38.786872Z","end":"2026-10-04T10:50:39.041095Z","steps":["trace[1897251317] 'process raft request' (duration: 244.356186ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:39.254568Z","caller":"traceutil/trace.go:171","msg":"trace[853331475] linearizableReadLoop","detail":"{readStateIndex:28156; appliedIndex:28154; }","duration":"388.822899ms","start":"2026-10-04T10:50:38.865706Z","end":"2026-10-04T10:50:39.254529Z","steps":["trace[853331475] 'read index received' (duration: 165.537056ms)","trace[853331475] 'applied index is now lower than readState.Index' (duration: 223.283553ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:39.254928Z","caller":"traceutil/trace.go:171","msg":"trace[696344929] transaction","detail":"{read_only:false; response_revision:27244; number_of_response:1; }","duration":"467.227929ms","start":"2026-10-04T10:50:38.787497Z","end":"2026-10-04T10:50:39.254725Z","steps":["trace[696344929] 'process raft request' (duration: 434.496226ms)","trace[696344929] 'compare' (duration: 32.144218ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:50:39.255058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.128986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:50:39.255171Z","caller":"traceutil/trace.go:171","msg":"trace[1667427515] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:27244; }","duration":"120.30211ms","start":"2026-10-04T10:50:39.134842Z","end":"2026-10-04T10:50:39.255144Z","steps":["trace[1667427515] 'agreement among raft nodes before linearized reading' (duration: 120.096035ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:39.255242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.217466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-10-04T10:50:39.255372Z","caller":"traceutil/trace.go:171","msg":"trace[1604776073] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27244; }","duration":"215.445661ms","start":"2026-10-04T10:50:39.039902Z","end":"2026-10-04T10:50:39.255348Z","steps":["trace[1604776073] 'agreement among raft nodes before linearized reading' (duration: 215.01657ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:39.255341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.604269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-10-04T10:50:39.255439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.538412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11795"} {"level":"info","ts":"2026-10-04T10:50:39.255512Z","caller":"traceutil/trace.go:171","msg":"trace[1794449158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27244; }","duration":"389.839855ms","start":"2026-10-04T10:50:38.865644Z","end":"2026-10-04T10:50:39.255484Z","steps":["trace[1794449158] 'agreement among raft nodes before linearized reading' (duration: 389.412604ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:39.25553Z","caller":"traceutil/trace.go:171","msg":"trace[1461656792] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:27244; }","duration":"192.670455ms","start":"2026-10-04T10:50:39.062842Z","end":"2026-10-04T10:50:39.255512Z","steps":["trace[1461656792] 'agreement among raft nodes before linearized reading' (duration: 192.202233ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:39.255585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:50:38.86562Z","time spent":"389.946628ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-04T10:50:39.255676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.819961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-10-04T10:50:39.255849Z","caller":"traceutil/trace.go:171","msg":"trace[1605078998] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27244; }","duration":"211.086979ms","start":"2026-10-04T10:50:39.044711Z","end":"2026-10-04T10:50:39.255798Z","steps":["trace[1605078998] 'agreement among raft nodes before linearized reading' (duration: 210.377911ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:39.255427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:50:38.787463Z","time spent":"467.514747ms","remote":"127.0.0.1:40342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7681,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-04T10:50:39.527895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.502685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:50:39.52801Z","caller":"traceutil/trace.go:171","msg":"trace[262970292] transaction","detail":"{read_only:false; response_revision:27248; number_of_response:1; }","duration":"181.882176ms","start":"2026-10-04T10:50:39.34611Z","end":"2026-10-04T10:50:39.527992Z","steps":["trace[262970292] 'process raft request' (duration: 16.171896ms)","trace[262970292] 'compare' (duration: 165.275679ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:39.54172Z","caller":"traceutil/trace.go:171","msg":"trace[1965251842] transaction","detail":"{read_only:false; response_revision:27249; number_of_response:1; }","duration":"194.84355ms","start":"2026-10-04T10:50:39.34684Z","end":"2026-10-04T10:50:39.541684Z","steps":["trace[1965251842] 'process raft request' (duration: 194.682826ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:42.712271Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24421} {"level":"info","ts":"2026-10-04T10:50:42.870216Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24421,"took":"152.911609ms","hash":4037909271,"current-db-size-bytes":59510784,"current-db-size":"60 MB","current-db-size-in-use-bytes":35966976,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-04T10:50:42.870302Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4037909271,"revision":24421,"compact-revision":21582} {"level":"info","ts":"2026-10-04T10:50:49.570578Z","caller":"traceutil/trace.go:171","msg":"trace[441482383] transaction","detail":"{read_only:false; response_revision:27319; number_of_response:1; }","duration":"176.014315ms","start":"2026-10-04T10:50:49.394537Z","end":"2026-10-04T10:50:49.570551Z","steps":["trace[441482383] 'process raft request' (duration: 175.81596ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:49.667604Z","caller":"traceutil/trace.go:171","msg":"trace[1668372574] linearizableReadLoop","detail":"{readStateIndex:28235; appliedIndex:28233; }","duration":"261.494528ms","start":"2026-10-04T10:50:49.406075Z","end":"2026-10-04T10:50:49.66757Z","steps":["trace[1668372574] 'read index received' (duration: 164.270143ms)","trace[1668372574] 'applied index is now lower than readState.Index' (duration: 97.223415ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:49.667719Z","caller":"traceutil/trace.go:171","msg":"trace[1492439881] transaction","detail":"{read_only:false; response_revision:27321; number_of_response:1; }","duration":"105.484878ms","start":"2026-10-04T10:50:49.562195Z","end":"2026-10-04T10:50:49.66768Z","steps":["trace[1492439881] 'process raft request' (duration: 105.328364ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:49.667678Z","caller":"traceutil/trace.go:171","msg":"trace[1389674251] transaction","detail":"{read_only:false; response_revision:27320; number_of_response:1; }","duration":"271.587678ms","start":"2026-10-04T10:50:49.396043Z","end":"2026-10-04T10:50:49.667631Z","steps":["trace[1389674251] 'process raft request' (duration: 271.26028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:49.668029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.927389ms","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-10-04T10:50:49.668126Z","caller":"traceutil/trace.go:171","msg":"trace[1654454858] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27321; }","duration":"262.100164ms","start":"2026-10-04T10:50:49.406001Z","end":"2026-10-04T10:50:49.668101Z","steps":["trace[1654454858] 'agreement among raft nodes before linearized reading' (duration: 261.771906ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:49.810217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.03864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:50:49.810475Z","caller":"traceutil/trace.go:171","msg":"trace[1341885115] linearizableReadLoop","detail":"{readStateIndex:28239; appliedIndex:28236; }","duration":"142.691667ms","start":"2026-10-04T10:50:49.667764Z","end":"2026-10-04T10:50:49.810456Z","steps":["trace[1341885115] 'read index received' (duration: 38.319268ms)","trace[1341885115] 'applied index is now lower than readState.Index' (duration: 104.370989ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:49.810698Z","caller":"traceutil/trace.go:171","msg":"trace[389483421] transaction","detail":"{read_only:false; response_revision:27322; number_of_response:1; }","duration":"234.136904ms","start":"2026-10-04T10:50:49.576548Z","end":"2026-10-04T10:50:49.810685Z","steps":["trace[389483421] 'process raft request' (duration: 129.523209ms)","trace[389483421] 'compare' (duration: 103.671941ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:50:49.810969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.721731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-10-04T10:50:49.811056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.286379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:50:49.811097Z","caller":"traceutil/trace.go:171","msg":"trace[480940450] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:27324; }","duration":"401.927686ms","start":"2026-10-04T10:50:49.40914Z","end":"2026-10-04T10:50:49.811068Z","steps":["trace[480940450] 'agreement among raft nodes before linearized reading' (duration: 401.653979ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:49.81116Z","caller":"traceutil/trace.go:171","msg":"trace[112687152] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:27324; }","duration":"191.446673ms","start":"2026-10-04T10:50:49.619691Z","end":"2026-10-04T10:50:49.811137Z","steps":["trace[112687152] 'agreement among raft nodes before linearized reading' (duration: 191.229287ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:49.811174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.867208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-10-04T10:50:49.811174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:50:49.409112Z","time spent":"402.046559ms","remote":"127.0.0.1:35866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":15,"response size":30,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-10-04T10:50:49.811213Z","caller":"traceutil/trace.go:171","msg":"trace[777534578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27324; }","duration":"209.95265ms","start":"2026-10-04T10:50:49.601247Z","end":"2026-10-04T10:50:49.8112Z","steps":["trace[777534578] 'agreement among raft nodes before linearized reading' (duration: 209.819997ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:49.811359Z","caller":"traceutil/trace.go:171","msg":"trace[353283941] transaction","detail":"{read_only:false; response_revision:27324; number_of_response:1; }","duration":"231.881555ms","start":"2026-10-04T10:50:49.579467Z","end":"2026-10-04T10:50:49.811349Z","steps":["trace[353283941] 'process raft request' (duration: 230.940681ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:49.811461Z","caller":"traceutil/trace.go:171","msg":"trace[777525108] transaction","detail":"{read_only:false; response_revision:27323; number_of_response:1; }","duration":"234.828462ms","start":"2026-10-04T10:50:49.576624Z","end":"2026-10-04T10:50:49.811453Z","steps":["trace[777525108] 'process raft request' (duration: 233.693443ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:49.8115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.374853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-04T10:50:49.811559Z","caller":"traceutil/trace.go:171","msg":"trace[1499965038] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:27324; }","duration":"188.480097ms","start":"2026-10-04T10:50:49.623061Z","end":"2026-10-04T10:50:49.811542Z","steps":["trace[1499965038] 'agreement among raft nodes before linearized reading' (duration: 188.379954ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:49.98725Z","caller":"traceutil/trace.go:171","msg":"trace[523783509] linearizableReadLoop","detail":"{readStateIndex:28240; appliedIndex:28239; }","duration":"176.718923ms","start":"2026-10-04T10:50:49.810504Z","end":"2026-10-04T10:50:49.987223Z","steps":["trace[523783509] 'read index received' (duration: 141.995969ms)","trace[523783509] 'applied index is now lower than readState.Index' (duration: 34.721004ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:49.987249Z","caller":"traceutil/trace.go:171","msg":"trace[1358220701] transaction","detail":"{read_only:false; response_revision:27325; number_of_response:1; }","duration":"313.340454ms","start":"2026-10-04T10:50:49.673859Z","end":"2026-10-04T10:50:49.987199Z","steps":["trace[1358220701] 'process raft request' (duration: 278.62814ms)","trace[1358220701] 'compare' (duration: 34.52566ms)"],"step_count":2} {"level":"warn","ts":"2026-10-04T10:50:49.987434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.876388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:50:49.987516Z","caller":"traceutil/trace.go:171","msg":"trace[1861184208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27325; }","duration":"262.001462ms","start":"2026-10-04T10:50:49.725496Z","end":"2026-10-04T10:50:49.987497Z","steps":["trace[1861184208] 'agreement among raft nodes before linearized reading' (duration: 261.847568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:49.98751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.828506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:50:49.987568Z","caller":"traceutil/trace.go:171","msg":"trace[810660434] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:27325; }","duration":"221.918228ms","start":"2026-10-04T10:50:49.765633Z","end":"2026-10-04T10:50:49.987552Z","steps":["trace[810660434] 'agreement among raft nodes before linearized reading' (duration: 221.60751ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:50:49.987459Z","caller":"traceutil/trace.go:171","msg":"trace[1023840425] transaction","detail":"{read_only:false; response_revision:27326; number_of_response:1; }","duration":"170.944445ms","start":"2026-10-04T10:50:49.816501Z","end":"2026-10-04T10:50:49.987445Z","steps":["trace[1023840425] 'process raft request' (duration: 170.687598ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:49.987807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-04T10:50:49.673831Z","time spent":"313.826507ms","remote":"127.0.0.1:36010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-04T10:50:50.292279Z","caller":"traceutil/trace.go:171","msg":"trace[1619218376] transaction","detail":"{read_only:false; response_revision:27327; number_of_response:1; }","duration":"127.64906ms","start":"2026-10-04T10:50:50.164571Z","end":"2026-10-04T10:50:50.29222Z","steps":["trace[1619218376] 'process raft request' (duration: 126.987083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:50.48309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.815803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-04T10:50:50.483363Z","caller":"traceutil/trace.go:171","msg":"trace[1244615463] transaction","detail":"{read_only:false; response_revision:27328; number_of_response:1; }","duration":"185.88454ms","start":"2026-10-04T10:50:50.297427Z","end":"2026-10-04T10:50:50.483312Z","steps":["trace[1244615463] 'process raft request' (duration: 31.743608ms)","trace[1244615463] 'compare' (duration: 153.642129ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:50.646771Z","caller":"traceutil/trace.go:171","msg":"trace[836505626] linearizableReadLoop","detail":"{readStateIndex:28245; appliedIndex:28242; }","duration":"206.443769ms","start":"2026-10-04T10:50:50.440298Z","end":"2026-10-04T10:50:50.646741Z","steps":["trace[836505626] 'read index received' (duration: 20.590951ms)","trace[836505626] 'applied index is now lower than readState.Index' (duration: 185.851458ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:50:50.646893Z","caller":"traceutil/trace.go:171","msg":"trace[570422014] transaction","detail":"{read_only:false; response_revision:27330; number_of_response:1; }","duration":"229.556315ms","start":"2026-10-04T10:50:50.417283Z","end":"2026-10-04T10:50:50.646839Z","steps":["trace[570422014] 'process raft request' (duration: 229.247417ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:50:50.646941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.609053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:50:50.646982Z","caller":"traceutil/trace.go:171","msg":"trace[1069924902] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:27330; }","duration":"206.991873ms","start":"2026-10-04T10:50:50.439975Z","end":"2026-10-04T10:50:50.646967Z","steps":["trace[1069924902] 'agreement among raft nodes before linearized reading' (duration: 206.88527ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:51:16.372161Z","caller":"traceutil/trace.go:171","msg":"trace[1641483444] linearizableReadLoop","detail":"{readStateIndex:28597; appliedIndex:28596; }","duration":"131.337433ms","start":"2026-10-04T10:51:16.240796Z","end":"2026-10-04T10:51:16.372134Z","steps":["trace[1641483444] 'read index received' (duration: 131.067166ms)","trace[1641483444] 'applied index is now lower than readState.Index' (duration: 269.147µs)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:51:16.372219Z","caller":"traceutil/trace.go:171","msg":"trace[1100232476] transaction","detail":"{read_only:false; response_revision:27675; number_of_response:1; }","duration":"188.025665ms","start":"2026-10-04T10:51:16.184157Z","end":"2026-10-04T10:51:16.372182Z","steps":["trace[1100232476] 'process raft request' (duration: 187.805699ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:51:16.372427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.58957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-04T10:51:16.372526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.717966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:51:16.373461Z","caller":"traceutil/trace.go:171","msg":"trace[175374032] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:27675; }","duration":"132.670628ms","start":"2026-10-04T10:51:16.24073Z","end":"2026-10-04T10:51:16.3734Z","steps":["trace[175374032] 'agreement among raft nodes before linearized reading' (duration: 131.587211ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:51:16.373553Z","caller":"traceutil/trace.go:171","msg":"trace[1853096677] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:27675; }","duration":"108.751693ms","start":"2026-10-04T10:51:16.264773Z","end":"2026-10-04T10:51:16.373525Z","steps":["trace[1853096677] 'agreement among raft nodes before linearized reading' (duration: 107.725227ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:51:16.372541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.317396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:51:16.373733Z","caller":"traceutil/trace.go:171","msg":"trace[586614665] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27675; }","duration":"108.527216ms","start":"2026-10-04T10:51:16.265179Z","end":"2026-10-04T10:51:16.373706Z","steps":["trace[586614665] 'agreement among raft nodes before linearized reading' (duration: 107.289645ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:51:16.372492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.993329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:51:16.373848Z","caller":"traceutil/trace.go:171","msg":"trace[1737525645] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27675; }","duration":"103.372583ms","start":"2026-10-04T10:51:16.270454Z","end":"2026-10-04T10:51:16.373827Z","steps":["trace[1737525645] 'agreement among raft nodes before linearized reading' (duration: 101.991698ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:51:16.372584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.94107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:51:16.37397Z","caller":"traceutil/trace.go:171","msg":"trace[853392768] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:27675; }","duration":"126.354536ms","start":"2026-10-04T10:51:16.247575Z","end":"2026-10-04T10:51:16.37393Z","steps":["trace[853392768] 'agreement among raft nodes before linearized reading' (duration: 124.909519ms)"],"step_count":1} {"level":"warn","ts":"2026-10-04T10:51:16.37262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.000284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-04T10:51:16.374051Z","caller":"traceutil/trace.go:171","msg":"trace[249379766] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27675; }","duration":"104.489392ms","start":"2026-10-04T10:51:16.269541Z","end":"2026-10-04T10:51:16.37403Z","steps":["trace[249379766] 'agreement among raft nodes before linearized reading' (duration: 102.901882ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:51:17.004719Z","caller":"traceutil/trace.go:171","msg":"trace[1458428460] transaction","detail":"{read_only:false; response_revision:27680; number_of_response:1; }","duration":"157.768255ms","start":"2026-10-04T10:51:16.846899Z","end":"2026-10-04T10:51:17.004667Z","steps":["trace[1458428460] 'process raft request' (duration: 132.684739ms)","trace[1458428460] 'compare' (duration: 24.892581ms)"],"step_count":2} {"level":"info","ts":"2026-10-04T10:51:17.348598Z","caller":"traceutil/trace.go:171","msg":"trace[519407002] transaction","detail":"{read_only:false; response_revision:27681; number_of_response:1; }","duration":"127.881315ms","start":"2026-10-04T10:51:17.220681Z","end":"2026-10-04T10:51:17.348563Z","steps":["trace[519407002] 'process raft request' (duration: 127.649029ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:51:17.894022Z","caller":"traceutil/trace.go:171","msg":"trace[644808998] transaction","detail":"{read_only:false; response_revision:27683; number_of_response:1; }","duration":"105.662383ms","start":"2026-10-04T10:51:17.788332Z","end":"2026-10-04T10:51:17.893994Z","steps":["trace[644808998] 'process raft request' (duration: 105.222812ms)"],"step_count":1} {"level":"info","ts":"2026-10-04T10:53:52.917867Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"596257f6524f82c3","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-04T10:53:52.936937Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-04T10:53:52.937095Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-04T10:55:42.720533Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27271} {"level":"info","ts":"2026-10-04T10:55:42.858833Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27271,"took":"131.95573ms","hash":3549277621,"current-db-size-bytes":59510784,"current-db-size":"60 MB","current-db-size-in-use-bytes":37822464,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-10-04T10:55:42.858951Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3549277621,"revision":27271,"compact-revision":24421} {"level":"info","ts":"2026-10-04T11:00:42.733154Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30157} {"level":"info","ts":"2026-10-04T11:00:42.890235Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30157,"took":"151.220767ms","hash":3517357458,"current-db-size-bytes":59510784,"current-db-size":"60 MB","current-db-size-in-use-bytes":32407552,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-04T11:00:42.890331Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3517357458,"revision":30157,"compact-revision":27271} {"level":"info","ts":"2026-10-04T11:05:42.742546Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32481} {"level":"info","ts":"2026-10-04T11:05:42.911588Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32481,"took":"162.689342ms","hash":738178270,"current-db-size-bytes":60555264,"current-db-size":"61 MB","current-db-size-in-use-bytes":31989760,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-04T11:05:42.911692Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":738178270,"revision":32481,"compact-revision":30157} {"level":"info","ts":"2026-10-04T11:10:42.750688Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34739} {"level":"info","ts":"2026-10-04T11:10:42.899554Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34739,"took":"142.289837ms","hash":2006527768,"current-db-size-bytes":60555264,"current-db-size":"61 MB","current-db-size-in-use-bytes":31911936,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-04T11:10:42.899636Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2006527768,"revision":34739,"compact-revision":32481} {"level":"info","ts":"2026-10-04T11:14:33.38049Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"596257f6524f82c3","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-04T11:14:33.387165Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-04T11:14:33.387322Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-04T11:15:42.761303Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36971} {"level":"info","ts":"2026-10-04T11:15:42.893705Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36971,"took":"127.806064ms","hash":3587739887,"current-db-size-bytes":60981248,"current-db-size":"61 MB","current-db-size-in-use-bytes":33071104,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-04T11:15:42.893785Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3587739887,"revision":36971,"compact-revision":34739} {"level":"info","ts":"2026-10-04T11:20:42.771213Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39310} {"level":"info","ts":"2026-10-04T11:20:42.917383Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39310,"took":"141.25947ms","hash":4248951155,"current-db-size-bytes":63111168,"current-db-size":"63 MB","current-db-size-in-use-bytes":32100352,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-04T11:20:42.917541Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4248951155,"revision":39310,"compact-revision":36971} {"level":"info","ts":"2026-10-04T11:25:42.780068Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41543} {"level":"info","ts":"2026-10-04T11:25:42.932422Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41543,"took":"139.607397ms","hash":3018557334,"current-db-size-bytes":63111168,"current-db-size":"63 MB","current-db-size-in-use-bytes":31301632,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-04T11:25:42.932491Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3018557334,"revision":41543,"compact-revision":39310}