{"level":"warn","ts":"2026-08-26T00:23:26.245576Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-26T00:23:26.245652Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.105: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.105:2380","--initial-cluster=instance=https://162.253.55.105:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.105:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.105: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-08-26T00:23:26.24571Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-26T00:23:26.245722Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.105:2380"]} {"level":"info","ts":"2026-08-26T00:23:26.245753Z","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-08-26T00:23:26.246278Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.105:2379"]} {"level":"info","ts":"2026-08-26T00:23:26.24635Z","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.105:2380"],"listen-peer-urls":["https://162.253.55.105:2380"],"advertise-client-urls":["https://162.253.55.105:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.105:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.105: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-08-26T00:23:26.250228Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.678699ms"} {"level":"info","ts":"2026-08-26T00:23:26.261874Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"15a19459cc268924","cluster-id":"9aaad1c0fc15509e"} {"level":"info","ts":"2026-08-26T00:23:26.261959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 switched to configuration voters=()"} {"level":"info","ts":"2026-08-26T00:23:26.261981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 became follower at term 0"} {"level":"info","ts":"2026-08-26T00:23:26.261996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 15a19459cc268924 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-26T00:23:26.262008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 became follower at term 1"} {"level":"info","ts":"2026-08-26T00:23:26.26205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 switched to configuration voters=(1558690059444980004)"} {"level":"warn","ts":"2026-08-26T00:23:26.266778Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-26T00:23:26.269259Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-26T00:23:26.270801Z","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-08-26T00:23:26.273435Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"15a19459cc268924","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-26T00:23:26.273618Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"15a19459cc268924","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-26T00:23:26.273693Z","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-08-26T00:23:26.273869Z","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-08-26T00:23:26.273886Z","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-08-26T00:23:26.274439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 switched to configuration voters=(1558690059444980004)"} {"level":"info","ts":"2026-08-26T00:23:26.274592Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9aaad1c0fc15509e","local-member-id":"15a19459cc268924","added-peer-id":"15a19459cc268924","added-peer-peer-urls":["https://162.253.55.105:2380"]} {"level":"info","ts":"2026-08-26T00:23:26.277738Z","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-08-26T00:23:26.277839Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.105:2380"} {"level":"info","ts":"2026-08-26T00:23:26.277902Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.105:2380"} {"level":"info","ts":"2026-08-26T00:23:26.278141Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"15a19459cc268924","initial-advertise-peer-urls":["https://162.253.55.105:2380"],"listen-peer-urls":["https://162.253.55.105:2380"],"advertise-client-urls":["https://162.253.55.105:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.105:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-26T00:23:26.278227Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-26T00:23:26.462753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 is starting a new election at term 1"} {"level":"info","ts":"2026-08-26T00:23:26.4628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-26T00:23:26.462832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 received MsgPreVoteResp from 15a19459cc268924 at term 1"} {"level":"info","ts":"2026-08-26T00:23:26.462849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 became candidate at term 2"} {"level":"info","ts":"2026-08-26T00:23:26.462856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 received MsgVoteResp from 15a19459cc268924 at term 2"} {"level":"info","ts":"2026-08-26T00:23:26.462867Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 became leader at term 2"} {"level":"info","ts":"2026-08-26T00:23:26.462888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 15a19459cc268924 elected leader 15a19459cc268924 at term 2"} {"level":"info","ts":"2026-08-26T00:23:26.464281Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"15a19459cc268924","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.105:2379]}","request-path":"/0/members/15a19459cc268924/attributes","cluster-id":"9aaad1c0fc15509e","publish-timeout":"7s"} {"level":"info","ts":"2026-08-26T00:23:26.464333Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T00:23:26.464398Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:23:26.464402Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T00:23:26.464793Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-26T00:23:26.464829Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-26T00:23:26.465539Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9aaad1c0fc15509e","local-member-id":"15a19459cc268924","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:23:26.46564Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:23:26.46568Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:23:26.467761Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-26T00:23:26.47221Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.105:2379"} {"level":"info","ts":"2026-08-26T00:24:02.336651Z","caller":"traceutil/trace.go:171","msg":"trace[1331496120] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"112.157866ms","start":"2026-08-26T00:24:02.224451Z","end":"2026-08-26T00:24:02.336609Z","steps":["trace[1331496120] 'process raft request' (duration: 100.475039ms)","trace[1331496120] 'compare' (duration: 11.474122ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:24:02.336744Z","caller":"traceutil/trace.go:171","msg":"trace[2068505488] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"112.128806ms","start":"2026-08-26T00:24:02.224587Z","end":"2026-08-26T00:24:02.336715Z","steps":["trace[2068505488] 'process raft request' (duration: 111.921961ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:24:02.336959Z","caller":"traceutil/trace.go:171","msg":"trace[462600607] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"111.642982ms","start":"2026-08-26T00:24:02.225305Z","end":"2026-08-26T00:24:02.336948Z","steps":["trace[462600607] 'process raft request' (duration: 111.326794ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:24:02.337039Z","caller":"traceutil/trace.go:171","msg":"trace[735654017] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"111.782986ms","start":"2026-08-26T00:24:02.225247Z","end":"2026-08-26T00:24:02.33703Z","steps":["trace[735654017] 'process raft request' (duration: 111.298122ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:24:02.342894Z","caller":"traceutil/trace.go:171","msg":"trace[896155791] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"113.014529ms","start":"2026-08-26T00:24:02.229867Z","end":"2026-08-26T00:24:02.342881Z","steps":["trace[896155791] 'process raft request' (duration: 112.820183ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:24:02.343145Z","caller":"traceutil/trace.go:171","msg":"trace[276990647] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"111.059136ms","start":"2026-08-26T00:24:02.232057Z","end":"2026-08-26T00:24:02.343116Z","steps":["trace[276990647] 'process raft request' (duration: 110.714237ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:24:02.343486Z","caller":"traceutil/trace.go:171","msg":"trace[366908111] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"105.205837ms","start":"2026-08-26T00:24:02.238269Z","end":"2026-08-26T00:24:02.343475Z","steps":["trace[366908111] 'process raft request' (duration: 104.843938ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:24:02.344018Z","caller":"traceutil/trace.go:171","msg":"trace[884074505] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"105.380031ms","start":"2026-08-26T00:24:02.238603Z","end":"2026-08-26T00:24:02.343983Z","steps":["trace[884074505] 'process raft request' (duration: 104.717274ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:24:08.466983Z","caller":"traceutil/trace.go:171","msg":"trace[481116956] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"263.42048ms","start":"2026-08-26T00:24:08.20352Z","end":"2026-08-26T00:24:08.46694Z","steps":["trace[481116956] 'process raft request' (duration: 262.639549ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:24:08.644044Z","caller":"traceutil/trace.go:171","msg":"trace[8345495] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"158.935702ms","start":"2026-08-26T00:24:08.485072Z","end":"2026-08-26T00:24:08.644008Z","steps":["trace[8345495] 'process raft request' (duration: 137.427879ms)","trace[8345495] 'compare' (duration: 21.286667ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:24:08.644164Z","caller":"traceutil/trace.go:171","msg":"trace[1057288315] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"143.463463ms","start":"2026-08-26T00:24:08.500665Z","end":"2026-08-26T00:24:08.644128Z","steps":["trace[1057288315] 'read index received' (duration: 121.824977ms)","trace[1057288315] 'applied index is now lower than readState.Index' (duration: 21.635786ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:24:08.64429Z","caller":"traceutil/trace.go:171","msg":"trace[1353588177] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"120.06179ms","start":"2026-08-26T00:24:08.524184Z","end":"2026-08-26T00:24:08.644246Z","steps":["trace[1353588177] 'process raft request' (duration: 119.778143ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:24:08.644586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.843544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T00:24:08.644803Z","caller":"traceutil/trace.go:171","msg":"trace[882630158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"144.168572ms","start":"2026-08-26T00:24:08.500618Z","end":"2026-08-26T00:24:08.644786Z","steps":["trace[882630158] 'agreement among raft nodes before linearized reading' (duration: 143.678229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:24:09.239922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.953994ms","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-08-26T00:24:09.240018Z","caller":"traceutil/trace.go:171","msg":"trace[1618578700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"404.095317ms","start":"2026-08-26T00:24:08.835892Z","end":"2026-08-26T00:24:09.239987Z","steps":["trace[1618578700] 'range keys from in-memory index tree' (duration: 403.775928ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:24:09.240149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:24:08.835865Z","time spent":"404.266502ms","remote":"127.0.0.1:56866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-26T00:24:09.240192Z","caller":"traceutil/trace.go:171","msg":"trace[2143780643] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"207.085745ms","start":"2026-08-26T00:24:09.032998Z","end":"2026-08-26T00:24:09.240084Z","steps":["trace[2143780643] 'process raft request' (duration: 138.881259ms)","trace[2143780643] 'compare' (duration: 67.732313ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:24:09.251443Z","caller":"traceutil/trace.go:171","msg":"trace[2041974997] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"151.673565ms","start":"2026-08-26T00:24:09.099743Z","end":"2026-08-26T00:24:09.251417Z","steps":["trace[2041974997] 'process raft request' (duration: 151.178991ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:24:22.90553Z","caller":"traceutil/trace.go:171","msg":"trace[1922493245] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"138.520259ms","start":"2026-08-26T00:24:22.766986Z","end":"2026-08-26T00:24:22.905506Z","steps":["trace[1922493245] 'read index received' (duration: 138.251082ms)","trace[1922493245] 'applied index is now lower than readState.Index' (duration: 267.767µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:24:22.90565Z","caller":"traceutil/trace.go:171","msg":"trace[1759453152] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"182.636358ms","start":"2026-08-26T00:24:22.722952Z","end":"2026-08-26T00:24:22.905588Z","steps":["trace[1759453152] 'process raft request' (duration: 182.409432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:24:22.905729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.728956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-26T00:24:22.905768Z","caller":"traceutil/trace.go:171","msg":"trace[1504388531] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:748; }","duration":"138.824388ms","start":"2026-08-26T00:24:22.766934Z","end":"2026-08-26T00:24:22.905758Z","steps":["trace[1504388531] 'agreement among raft nodes before linearized reading' (duration: 138.676644ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:24:49.621344Z","caller":"traceutil/trace.go:171","msg":"trace[1932901384] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"173.88608ms","start":"2026-08-26T00:24:49.447406Z","end":"2026-08-26T00:24:49.621292Z","steps":["trace[1932901384] 'process raft request' (duration: 173.607802ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:24:49.671109Z","caller":"traceutil/trace.go:171","msg":"trace[1141943522] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"221.766759ms","start":"2026-08-26T00:24:49.449309Z","end":"2026-08-26T00:24:49.671075Z","steps":["trace[1141943522] 'process raft request' (duration: 221.534833ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:25:34.03556Z","caller":"traceutil/trace.go:171","msg":"trace[2138034849] linearizableReadLoop","detail":"{readStateIndex:1858; appliedIndex:1857; }","duration":"206.268994ms","start":"2026-08-26T00:25:33.829263Z","end":"2026-08-26T00:25:34.035532Z","steps":["trace[2138034849] 'read index received' (duration: 206.066799ms)","trace[2138034849] 'applied index is now lower than readState.Index' (duration: 200.695µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:25:34.035959Z","caller":"traceutil/trace.go:171","msg":"trace[327045382] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"414.844042ms","start":"2026-08-26T00:25:33.621082Z","end":"2026-08-26T00:25:34.035926Z","steps":["trace[327045382] 'process raft request' (duration: 414.298077ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:25:34.036071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.85265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-26T00:25:34.036164Z","caller":"traceutil/trace.go:171","msg":"trace[1399038110] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1792; }","duration":"164.982413ms","start":"2026-08-26T00:25:33.871168Z","end":"2026-08-26T00:25:34.03615Z","steps":["trace[1399038110] 'agreement among raft nodes before linearized reading' (duration: 164.779677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:25:34.036171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.899563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b34abaca-0093-4899-98ec-95d9b011ad53\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-08-26T00:25:34.036176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.832728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-26T00:25:34.03623Z","caller":"traceutil/trace.go:171","msg":"trace[1707389046] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b34abaca-0093-4899-98ec-95d9b011ad53; range_end:; response_count:1; response_revision:1792; }","duration":"206.972374ms","start":"2026-08-26T00:25:33.829242Z","end":"2026-08-26T00:25:34.036214Z","steps":["trace[1707389046] 'agreement among raft nodes before linearized reading' (duration: 206.740588ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:25:34.036283Z","caller":"traceutil/trace.go:171","msg":"trace[838213563] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1792; }","duration":"197.991073ms","start":"2026-08-26T00:25:33.838263Z","end":"2026-08-26T00:25:34.036255Z","steps":["trace[838213563] 'agreement among raft nodes before linearized reading' (duration: 197.669364ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:25:34.03619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.225852ms","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-08-26T00:25:34.036392Z","caller":"traceutil/trace.go:171","msg":"trace[1813653928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1792; }","duration":"187.477099ms","start":"2026-08-26T00:25:33.848893Z","end":"2026-08-26T00:25:34.03637Z","steps":["trace[1813653928] 'agreement among raft nodes before linearized reading' (duration: 187.088488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:25:34.036622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:25:33.621043Z","time spent":"415.000157ms","remote":"127.0.0.1:56866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:25:34.18835Z","caller":"traceutil/trace.go:171","msg":"trace[264956301] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"143.626158ms","start":"2026-08-26T00:25:34.04469Z","end":"2026-08-26T00:25:34.188316Z","steps":["trace[264956301] 'process raft request' (duration: 143.483054ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:25:34.188401Z","caller":"traceutil/trace.go:171","msg":"trace[301303061] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"146.996349ms","start":"2026-08-26T00:25:34.04136Z","end":"2026-08-26T00:25:34.188356Z","steps":["trace[301303061] 'process raft request' (duration: 146.63997ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:25:34.511478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.399649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:25:34.511566Z","caller":"traceutil/trace.go:171","msg":"trace[1815500297] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1795; }","duration":"121.728367ms","start":"2026-08-26T00:25:34.389818Z","end":"2026-08-26T00:25:34.511546Z","steps":["trace[1815500297] 'count revisions from in-memory index tree' (duration: 121.315808ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:26:24.325477Z","caller":"traceutil/trace.go:171","msg":"trace[1743030291] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"111.212225ms","start":"2026-08-26T00:26:24.214244Z","end":"2026-08-26T00:26:24.325457Z","steps":["trace[1743030291] 'process raft request' (duration: 111.094781ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:27:14.63736Z","caller":"traceutil/trace.go:171","msg":"trace[591070033] transaction","detail":"{read_only:false; response_revision:2566; number_of_response:1; }","duration":"145.540191ms","start":"2026-08-26T00:27:14.491788Z","end":"2026-08-26T00:27:14.637328Z","steps":["trace[591070033] 'process raft request' (duration: 145.355466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:27:48.349053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.730462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:27:48.349155Z","caller":"traceutil/trace.go:171","msg":"trace[343449314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2770; }","duration":"170.859506ms","start":"2026-08-26T00:27:48.178273Z","end":"2026-08-26T00:27:48.349132Z","steps":["trace[343449314] 'range keys from in-memory index tree' (duration: 170.666952ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:29:41.890725Z","caller":"traceutil/trace.go:171","msg":"trace[1843843737] transaction","detail":"{read_only:false; response_revision:3535; number_of_response:1; }","duration":"106.704574ms","start":"2026-08-26T00:29:41.783986Z","end":"2026-08-26T00:29:41.89069Z","steps":["trace[1843843737] 'process raft request' (duration: 71.39443ms)","trace[1843843737] 'compare' (duration: 35.173951ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:31:30.689828Z","caller":"traceutil/trace.go:171","msg":"trace[756811640] transaction","detail":"{read_only:false; response_revision:4611; number_of_response:1; }","duration":"112.403037ms","start":"2026-08-26T00:31:30.577394Z","end":"2026-08-26T00:31:30.689797Z","steps":["trace[756811640] 'process raft request' (duration: 112.155621ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:31:39.828753Z","caller":"traceutil/trace.go:171","msg":"trace[1729000151] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"313.968916ms","start":"2026-08-26T00:31:39.514746Z","end":"2026-08-26T00:31:39.828715Z","steps":["trace[1729000151] 'process raft request' (duration: 301.036786ms)","trace[1729000151] 'compare' (duration: 12.780345ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:31:39.828923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:31:39.51472Z","time spent":"314.13344ms","remote":"127.0.0.1:53384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2500,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-26T00:31:39.891368Z","caller":"traceutil/trace.go:171","msg":"trace[1318524991] linearizableReadLoop","detail":"{readStateIndex:5073; appliedIndex:5071; }","duration":"270.287625ms","start":"2026-08-26T00:31:39.621055Z","end":"2026-08-26T00:31:39.891342Z","steps":["trace[1318524991] 'read index received' (duration: 194.60223ms)","trace[1318524991] 'applied index is now lower than readState.Index' (duration: 75.684225ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:31:39.891448Z","caller":"traceutil/trace.go:171","msg":"trace[486052582] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"327.584424ms","start":"2026-08-26T00:31:39.563817Z","end":"2026-08-26T00:31:39.891402Z","steps":["trace[486052582] 'process raft request' (duration: 327.331657ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:31:39.891464Z","caller":"traceutil/trace.go:171","msg":"trace[451478534] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"264.21391ms","start":"2026-08-26T00:31:39.627217Z","end":"2026-08-26T00:31:39.891431Z","steps":["trace[451478534] 'process raft request' (duration: 264.065237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:31:39.891628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.558262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d378ee5-5f57-4452-a066-c706be9b69b8\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-08-26T00:31:39.89163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:31:39.563789Z","time spent":"327.745428ms","remote":"127.0.0.1:56866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:31:39.891671Z","caller":"traceutil/trace.go:171","msg":"trace[1665847843] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7d378ee5-5f57-4452-a066-c706be9b69b8; range_end:; response_count:1; response_revision:4896; }","duration":"270.650075ms","start":"2026-08-26T00:31:39.62101Z","end":"2026-08-26T00:31:39.891661Z","steps":["trace[1665847843] 'agreement among raft nodes before linearized reading' (duration: 270.473491ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:31:39.89174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.761023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.105\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-26T00:31:39.8918Z","caller":"traceutil/trace.go:171","msg":"trace[1917204123] range","detail":"{range_begin:/registry/masterleases/162.253.55.105; range_end:; response_count:1; response_revision:4896; }","duration":"201.814584ms","start":"2026-08-26T00:31:39.68997Z","end":"2026-08-26T00:31:39.891785Z","steps":["trace[1917204123] 'agreement among raft nodes before linearized reading' (duration: 201.69499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:31:39.891628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.065839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7d378ee5-5f57-4452-a066-c706be9b69b8\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-08-26T00:31:39.891866Z","caller":"traceutil/trace.go:171","msg":"trace[1601819335] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7d378ee5-5f57-4452-a066-c706be9b69b8; range_end:; response_count:1; response_revision:4896; }","duration":"226.348118ms","start":"2026-08-26T00:31:39.665502Z","end":"2026-08-26T00:31:39.89185Z","steps":["trace[1601819335] 'agreement among raft nodes before linearized reading' (duration: 226.005399ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:31:40.343371Z","caller":"traceutil/trace.go:171","msg":"trace[850543197] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"177.495087ms","start":"2026-08-26T00:31:40.165849Z","end":"2026-08-26T00:31:40.343344Z","steps":["trace[850543197] 'process raft request' (duration: 177.435036ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:31:40.343479Z","caller":"traceutil/trace.go:171","msg":"trace[304068310] linearizableReadLoop","detail":"{readStateIndex:5082; appliedIndex:5081; }","duration":"193.273543ms","start":"2026-08-26T00:31:40.150178Z","end":"2026-08-26T00:31:40.343451Z","steps":["trace[304068310] 'read index received' (duration: 164.887367ms)","trace[304068310] 'applied index is now lower than readState.Index' (duration: 28.383526ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:31:40.343513Z","caller":"traceutil/trace.go:171","msg":"trace[1232260417] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"185.786192ms","start":"2026-08-26T00:31:40.157702Z","end":"2026-08-26T00:31:40.343488Z","steps":["trace[1232260417] 'process raft request' (duration: 185.539585ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:31:40.343523Z","caller":"traceutil/trace.go:171","msg":"trace[1059797501] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"194.092436ms","start":"2026-08-26T00:31:40.149373Z","end":"2026-08-26T00:31:40.343465Z","steps":["trace[1059797501] 'process raft request' (duration: 165.677928ms)","trace[1059797501] 'compare' (duration: 28.039068ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:31:40.343668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.468978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:31:40.343718Z","caller":"traceutil/trace.go:171","msg":"trace[691962667] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4905; }","duration":"193.565342ms","start":"2026-08-26T00:31:40.150139Z","end":"2026-08-26T00:31:40.343704Z","steps":["trace[691962667] 'agreement among raft nodes before linearized reading' (duration: 193.46275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:31:40.343784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.868882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:31:40.343834Z","caller":"traceutil/trace.go:171","msg":"trace[903791745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4905; }","duration":"165.952866ms","start":"2026-08-26T00:31:40.17787Z","end":"2026-08-26T00:31:40.343823Z","steps":["trace[903791745] 'agreement among raft nodes before linearized reading' (duration: 165.865393ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:31:42.952754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.893025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:31:42.952854Z","caller":"traceutil/trace.go:171","msg":"trace[2075878766] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4965; }","duration":"115.014508ms","start":"2026-08-26T00:31:42.837815Z","end":"2026-08-26T00:31:42.952829Z","steps":["trace[2075878766] 'count revisions from in-memory index tree' (duration: 114.812244ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:31:42.952848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.298728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-08-26T00:31:42.95297Z","caller":"traceutil/trace.go:171","msg":"trace[656901328] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4965; }","duration":"108.484532ms","start":"2026-08-26T00:31:42.844456Z","end":"2026-08-26T00:31:42.952941Z","steps":["trace[656901328] 'range keys from in-memory index tree' (duration: 108.096752ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:31:51.281419Z","caller":"traceutil/trace.go:171","msg":"trace[1447985713] transaction","detail":"{read_only:false; response_revision:5149; number_of_response:1; }","duration":"139.525492ms","start":"2026-08-26T00:31:51.141868Z","end":"2026-08-26T00:31:51.281393Z","steps":["trace[1447985713] 'process raft request' (duration: 139.295396ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:31:51.292574Z","caller":"traceutil/trace.go:171","msg":"trace[43318076] linearizableReadLoop","detail":"{readStateIndex:5340; appliedIndex:5338; }","duration":"129.990253ms","start":"2026-08-26T00:31:51.162574Z","end":"2026-08-26T00:31:51.292564Z","steps":["trace[43318076] 'read index received' (duration: 118.523224ms)","trace[43318076] 'applied index is now lower than readState.Index' (duration: 11.466379ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:31:51.292666Z","caller":"traceutil/trace.go:171","msg":"trace[359426177] transaction","detail":"{read_only:false; response_revision:5150; number_of_response:1; }","duration":"130.409875ms","start":"2026-08-26T00:31:51.162222Z","end":"2026-08-26T00:31:51.292632Z","steps":["trace[359426177] 'process raft request' (duration: 130.280182ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:31:51.292704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.123828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-26T00:31:51.29281Z","caller":"traceutil/trace.go:171","msg":"trace[496403485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5150; }","duration":"130.261551ms","start":"2026-08-26T00:31:51.16254Z","end":"2026-08-26T00:31:51.292801Z","steps":["trace[496403485] 'agreement among raft nodes before linearized reading' (duration: 130.077906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:31:51.308676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.131708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:31:51.308768Z","caller":"traceutil/trace.go:171","msg":"trace[1676424028] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5151; }","duration":"133.249632ms","start":"2026-08-26T00:31:51.175497Z","end":"2026-08-26T00:31:51.308747Z","steps":["trace[1676424028] 'agreement among raft nodes before linearized reading' (duration: 133.115618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:31:59.062335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.577156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:31:59.062455Z","caller":"traceutil/trace.go:171","msg":"trace[457576611] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:5284; }","duration":"101.72767ms","start":"2026-08-26T00:31:58.960699Z","end":"2026-08-26T00:31:59.062426Z","steps":["trace[457576611] 'count revisions from in-memory index tree' (duration: 101.432282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:31:59.705918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.734884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:31:59.706163Z","caller":"traceutil/trace.go:171","msg":"trace[1933621782] linearizableReadLoop","detail":"{readStateIndex:5478; appliedIndex:5477; }","duration":"252.967047ms","start":"2026-08-26T00:31:59.45317Z","end":"2026-08-26T00:31:59.706137Z","steps":["trace[1933621782] 'read index received' (duration: 71.513693ms)","trace[1933621782] 'applied index is now lower than readState.Index' (duration: 181.451824ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:31:59.706193Z","caller":"traceutil/trace.go:171","msg":"trace[1383069516] transaction","detail":"{read_only:false; response_revision:5287; number_of_response:1; }","duration":"310.237735ms","start":"2026-08-26T00:31:59.395896Z","end":"2026-08-26T00:31:59.706134Z","steps":["trace[1383069516] 'process raft request' (duration: 128.797471ms)","trace[1383069516] 'compare' (duration: 180.363475ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:31:59.706514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:31:59.395868Z","time spent":"310.4377ms","remote":"127.0.0.1:56866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:31:59.706566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.384118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80099"} {"level":"info","ts":"2026-08-26T00:31:59.706622Z","caller":"traceutil/trace.go:171","msg":"trace[955594769] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5287; }","duration":"253.488121ms","start":"2026-08-26T00:31:59.453118Z","end":"2026-08-26T00:31:59.706606Z","steps":["trace[955594769] 'agreement among raft nodes before linearized reading' (duration: 253.153092ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:33:26.774118Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2979} {"level":"info","ts":"2026-08-26T00:33:26.844549Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2979,"took":"68.935473ms","hash":1626672326,"current-db-size-bytes":57503744,"current-db-size":"58 MB","current-db-size-in-use-bytes":34238464,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-26T00:33:26.844641Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1626672326,"revision":2979,"compact-revision":-1} {"level":"info","ts":"2026-08-26T00:37:55.000208Z","caller":"traceutil/trace.go:171","msg":"trace[2126256392] transaction","detail":"{read_only:false; response_revision:8102; number_of_response:1; }","duration":"136.297892ms","start":"2026-08-26T00:37:54.863884Z","end":"2026-08-26T00:37:55.000181Z","steps":["trace[2126256392] 'process raft request' (duration: 136.077616ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:37:57.215908Z","caller":"traceutil/trace.go:171","msg":"trace[956534113] transaction","detail":"{read_only:false; response_revision:8112; number_of_response:1; }","duration":"204.194725ms","start":"2026-08-26T00:37:57.011681Z","end":"2026-08-26T00:37:57.215876Z","steps":["trace[956534113] 'process raft request' (duration: 204.041311ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:37:57.488163Z","caller":"traceutil/trace.go:171","msg":"trace[1862263097] transaction","detail":"{read_only:false; response_revision:8113; number_of_response:1; }","duration":"237.802332ms","start":"2026-08-26T00:37:57.250337Z","end":"2026-08-26T00:37:57.488139Z","steps":["trace[1862263097] 'process raft request' (duration: 237.589726ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:37:57.489841Z","caller":"traceutil/trace.go:171","msg":"trace[2107526079] linearizableReadLoop","detail":"{readStateIndex:8393; appliedIndex:8391; }","duration":"191.656705ms","start":"2026-08-26T00:37:57.298167Z","end":"2026-08-26T00:37:57.489824Z","steps":["trace[2107526079] 'read index received' (duration: 189.822806ms)","trace[2107526079] 'applied index is now lower than readState.Index' (duration: 1.832359ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:37:57.48993Z","caller":"traceutil/trace.go:171","msg":"trace[1153079417] transaction","detail":"{read_only:false; response_revision:8114; number_of_response:1; }","duration":"214.704769ms","start":"2026-08-26T00:37:57.275174Z","end":"2026-08-26T00:37:57.489879Z","steps":["trace[1153079417] 'process raft request' (duration: 214.543474ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:37:57.490015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.835691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-08-26T00:37:57.490064Z","caller":"traceutil/trace.go:171","msg":"trace[977057542] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8114; }","duration":"191.898943ms","start":"2026-08-26T00:37:57.298151Z","end":"2026-08-26T00:37:57.49005Z","steps":["trace[977057542] 'agreement among raft nodes before linearized reading' (duration: 191.737878ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:38:09.190809Z","caller":"traceutil/trace.go:171","msg":"trace[1589959871] transaction","detail":"{read_only:false; response_revision:8173; number_of_response:1; }","duration":"489.926632ms","start":"2026-08-26T00:38:08.700842Z","end":"2026-08-26T00:38:09.190768Z","steps":["trace[1589959871] 'process raft request' (duration: 489.502371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:09.191003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:38:08.70082Z","time spent":"490.080825ms","remote":"127.0.0.1:56866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:38:10.281632Z","caller":"traceutil/trace.go:171","msg":"trace[1734441766] linearizableReadLoop","detail":"{readStateIndex:8462; appliedIndex:8461; }","duration":"194.436892ms","start":"2026-08-26T00:38:10.08717Z","end":"2026-08-26T00:38:10.281606Z","steps":["trace[1734441766] 'read index received' (duration: 180.048733ms)","trace[1734441766] 'applied index is now lower than readState.Index' (duration: 14.386839ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:38:10.281679Z","caller":"traceutil/trace.go:171","msg":"trace[1740198389] transaction","detail":"{read_only:false; response_revision:8180; number_of_response:1; }","duration":"386.088627ms","start":"2026-08-26T00:38:09.89556Z","end":"2026-08-26T00:38:10.281649Z","steps":["trace[1740198389] 'process raft request' (duration: 371.702278ms)","trace[1740198389] 'compare' (duration: 14.189254ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:38:10.281833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:38:09.895533Z","time spent":"386.224741ms","remote":"127.0.0.1:56866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:38:10.281836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.647217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:38:10.281905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.781517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-26T00:38:10.281919Z","caller":"traceutil/trace.go:171","msg":"trace[1016478224] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8180; }","duration":"194.77665ms","start":"2026-08-26T00:38:10.087122Z","end":"2026-08-26T00:38:10.281898Z","steps":["trace[1016478224] 'agreement among raft nodes before linearized reading' (duration: 194.616767ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:38:10.281952Z","caller":"traceutil/trace.go:171","msg":"trace[40081562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8180; }","duration":"155.86426ms","start":"2026-08-26T00:38:10.126077Z","end":"2026-08-26T00:38:10.281942Z","steps":["trace[40081562] 'agreement among raft nodes before linearized reading' (duration: 155.666035ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:38:11.453343Z","caller":"traceutil/trace.go:171","msg":"trace[1613524621] linearizableReadLoop","detail":"{readStateIndex:8469; appliedIndex:8468; }","duration":"189.265312ms","start":"2026-08-26T00:38:11.264015Z","end":"2026-08-26T00:38:11.453281Z","steps":["trace[1613524621] 'read index received' (duration: 189.035536ms)","trace[1613524621] 'applied index is now lower than readState.Index' (duration: 228.996µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:38:11.453404Z","caller":"traceutil/trace.go:171","msg":"trace[2040462031] transaction","detail":"{read_only:false; response_revision:8186; number_of_response:1; }","duration":"222.436998ms","start":"2026-08-26T00:38:11.23095Z","end":"2026-08-26T00:38:11.453387Z","steps":["trace[2040462031] 'process raft request' (duration: 222.166141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:11.453492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.435677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:38:11.453543Z","caller":"traceutil/trace.go:171","msg":"trace[638266334] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:8186; }","duration":"189.546649ms","start":"2026-08-26T00:38:11.263984Z","end":"2026-08-26T00:38:11.453531Z","steps":["trace[638266334] 'agreement among raft nodes before linearized reading' (duration: 189.428916ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:11.453555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.666581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-26T00:38:11.453601Z","caller":"traceutil/trace.go:171","msg":"trace[908857116] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8186; }","duration":"116.744643ms","start":"2026-08-26T00:38:11.336832Z","end":"2026-08-26T00:38:11.453576Z","steps":["trace[908857116] 'agreement among raft nodes before linearized reading' (duration: 116.62464ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:38:11.715101Z","caller":"traceutil/trace.go:171","msg":"trace[1980483406] linearizableReadLoop","detail":"{readStateIndex:8472; appliedIndex:8471; }","duration":"121.392088ms","start":"2026-08-26T00:38:11.593684Z","end":"2026-08-26T00:38:11.715077Z","steps":["trace[1980483406] 'read index received' (duration: 99.768364ms)","trace[1980483406] 'applied index is now lower than readState.Index' (duration: 21.622764ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:38:11.715181Z","caller":"traceutil/trace.go:171","msg":"trace[1884915345] transaction","detail":"{read_only:false; response_revision:8189; number_of_response:1; }","duration":"123.584698ms","start":"2026-08-26T00:38:11.591568Z","end":"2026-08-26T00:38:11.715153Z","steps":["trace[1884915345] 'process raft request' (duration: 101.85227ms)","trace[1884915345] 'compare' (duration: 21.525622ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:38:11.7164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.691483ms","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-08-26T00:38:11.716457Z","caller":"traceutil/trace.go:171","msg":"trace[942559885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8189; }","duration":"122.779695ms","start":"2026-08-26T00:38:11.59366Z","end":"2026-08-26T00:38:11.716439Z","steps":["trace[942559885] 'agreement among raft nodes before linearized reading' (duration: 121.508881ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:38:26.780584Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6105} {"level":"info","ts":"2026-08-26T00:38:26.880586Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6105,"took":"98.404067ms","hash":3837961833,"current-db-size-bytes":57503744,"current-db-size":"58 MB","current-db-size-in-use-bytes":23183360,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-26T00:38:26.880656Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3837961833,"revision":6105,"compact-revision":2979} {"level":"info","ts":"2026-08-26T00:38:35.938856Z","caller":"traceutil/trace.go:171","msg":"trace[26516720] transaction","detail":"{read_only:false; response_revision:8372; number_of_response:1; }","duration":"105.855329ms","start":"2026-08-26T00:38:35.832976Z","end":"2026-08-26T00:38:35.938831Z","steps":["trace[26516720] 'process raft request' (duration: 105.682904ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:38:35.940908Z","caller":"traceutil/trace.go:171","msg":"trace[1948960946] transaction","detail":"{read_only:false; response_revision:8373; number_of_response:1; }","duration":"107.221136ms","start":"2026-08-26T00:38:35.833666Z","end":"2026-08-26T00:38:35.940888Z","steps":["trace[1948960946] 'process raft request' (duration: 107.113793ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:38:39.852563Z","caller":"traceutil/trace.go:171","msg":"trace[1328962031] linearizableReadLoop","detail":"{readStateIndex:8683; appliedIndex:8682; }","duration":"181.286887ms","start":"2026-08-26T00:38:39.671258Z","end":"2026-08-26T00:38:39.852544Z","steps":["trace[1328962031] 'read index received' (duration: 181.126292ms)","trace[1328962031] 'applied index is now lower than readState.Index' (duration: 159.975µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:38:39.852789Z","caller":"traceutil/trace.go:171","msg":"trace[1270841003] transaction","detail":"{read_only:false; response_revision:8392; number_of_response:1; }","duration":"188.368897ms","start":"2026-08-26T00:38:39.664411Z","end":"2026-08-26T00:38:39.85278Z","steps":["trace[1270841003] 'process raft request' (duration: 188.038458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:39.852993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.721458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-26T00:38:39.853022Z","caller":"traceutil/trace.go:171","msg":"trace[2111396979] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8392; }","duration":"181.78326ms","start":"2026-08-26T00:38:39.671231Z","end":"2026-08-26T00:38:39.853014Z","steps":["trace[2111396979] 'agreement among raft nodes before linearized reading' (duration: 181.682807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:39.853274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.857334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.105\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-26T00:38:39.853335Z","caller":"traceutil/trace.go:171","msg":"trace[1500836455] range","detail":"{range_begin:/registry/masterleases/162.253.55.105; range_end:; response_count:1; response_revision:8392; }","duration":"140.954516ms","start":"2026-08-26T00:38:39.712363Z","end":"2026-08-26T00:38:39.853317Z","steps":["trace[1500836455] 'agreement among raft nodes before linearized reading' (duration: 140.72007ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:40.342243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.926469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:38:40.342582Z","caller":"traceutil/trace.go:171","msg":"trace[28169659] linearizableReadLoop","detail":"{readStateIndex:8686; appliedIndex:8684; }","duration":"390.095966ms","start":"2026-08-26T00:38:39.952466Z","end":"2026-08-26T00:38:40.342562Z","steps":["trace[28169659] 'read index received' (duration: 142.783346ms)","trace[28169659] 'applied index is now lower than readState.Index' (duration: 247.31137ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:38:40.342713Z","caller":"traceutil/trace.go:171","msg":"trace[2120920708] transaction","detail":"{read_only:false; response_revision:8395; number_of_response:1; }","duration":"276.19904ms","start":"2026-08-26T00:38:40.066494Z","end":"2026-08-26T00:38:40.342693Z","steps":["trace[2120920708] 'process raft request' (duration: 276.000674ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:38:40.34273Z","caller":"traceutil/trace.go:171","msg":"trace[1498486264] transaction","detail":"{read_only:false; response_revision:8394; number_of_response:1; }","duration":"478.796751ms","start":"2026-08-26T00:38:39.863882Z","end":"2026-08-26T00:38:40.342679Z","steps":["trace[1498486264] 'process raft request' (duration: 478.486103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:40.342891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:38:39.863853Z","time spent":"478.955806ms","remote":"127.0.0.1:56618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:38:40.342996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.657647ms","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-08-26T00:38:40.343095Z","caller":"traceutil/trace.go:171","msg":"trace[1998794184] transaction","detail":"{read_only:false; response_revision:8393; number_of_response:1; }","duration":"485.426271ms","start":"2026-08-26T00:38:39.857654Z","end":"2026-08-26T00:38:40.34308Z","steps":["trace[1998794184] 'process raft request' (duration: 237.579837ms)","trace[1998794184] 'compare' (duration: 246.639131ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:38:40.343165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:38:39.857633Z","time spent":"485.497942ms","remote":"127.0.0.1:56866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:38:40.343096Z","caller":"traceutil/trace.go:171","msg":"trace[1412188853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8395; }","duration":"378.77133ms","start":"2026-08-26T00:38:39.964302Z","end":"2026-08-26T00:38:40.343074Z","steps":["trace[1412188853] 'agreement among raft nodes before linearized reading' (duration: 378.471393ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:40.3432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.53989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pdzct\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-26T00:38:40.343237Z","caller":"traceutil/trace.go:171","msg":"trace[384112436] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pdzct; range_end:; response_count:1; response_revision:8395; }","duration":"192.611173ms","start":"2026-08-26T00:38:40.150617Z","end":"2026-08-26T00:38:40.343228Z","steps":["trace[384112436] 'agreement among raft nodes before linearized reading' (duration: 192.50904ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:40.343268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:38:39.964255Z","time spent":"378.982665ms","remote":"127.0.0.1:56866","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-08-26T00:38:40.343373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.895668ms","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-08-26T00:38:40.343478Z","caller":"traceutil/trace.go:171","msg":"trace[2006157772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8395; }","duration":"391.055351ms","start":"2026-08-26T00:38:39.9524Z","end":"2026-08-26T00:38:40.343455Z","steps":["trace[2006157772] 'agreement among raft nodes before linearized reading' (duration: 390.725242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:40.34353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:38:39.952376Z","time spent":"391.141933ms","remote":"127.0.0.1:56866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-26T00:38:40.487821Z","caller":"traceutil/trace.go:171","msg":"trace[1607576271] transaction","detail":"{read_only:false; response_revision:8398; number_of_response:1; }","duration":"107.845982ms","start":"2026-08-26T00:38:40.379945Z","end":"2026-08-26T00:38:40.487791Z","steps":["trace[1607576271] 'process raft request' (duration: 94.895663ms)","trace[1607576271] 'compare' (duration: 12.812306ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:38:40.678263Z","caller":"traceutil/trace.go:171","msg":"trace[751314006] transaction","detail":"{read_only:false; response_revision:8401; number_of_response:1; }","duration":"181.584364ms","start":"2026-08-26T00:38:40.49665Z","end":"2026-08-26T00:38:40.678235Z","steps":["trace[751314006] 'process raft request' (duration: 169.694123ms)","trace[751314006] 'compare' (duration: 11.758518ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:38:46.496917Z","caller":"traceutil/trace.go:171","msg":"trace[1081853848] linearizableReadLoop","detail":"{readStateIndex:8723; appliedIndex:8722; }","duration":"126.273561ms","start":"2026-08-26T00:38:46.370626Z","end":"2026-08-26T00:38:46.4969Z","steps":["trace[1081853848] 'read index received' (duration: 126.108696ms)","trace[1081853848] 'applied index is now lower than readState.Index' (duration: 164.315µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:38:46.497016Z","caller":"traceutil/trace.go:171","msg":"trace[1361296149] transaction","detail":"{read_only:false; response_revision:8430; number_of_response:1; }","duration":"130.440103ms","start":"2026-08-26T00:38:46.366548Z","end":"2026-08-26T00:38:46.496988Z","steps":["trace[1361296149] 'process raft request' (duration: 130.242678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:46.49726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.61775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-26T00:38:46.49731Z","caller":"traceutil/trace.go:171","msg":"trace[1666837409] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8430; }","duration":"126.714712ms","start":"2026-08-26T00:38:46.370583Z","end":"2026-08-26T00:38:46.497298Z","steps":["trace[1666837409] 'agreement among raft nodes before linearized reading' (duration: 126.552428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:46.497321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.206373ms","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-08-26T00:38:46.497345Z","caller":"traceutil/trace.go:171","msg":"trace[990008547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8430; }","duration":"121.266045ms","start":"2026-08-26T00:38:46.37607Z","end":"2026-08-26T00:38:46.497336Z","steps":["trace[990008547] 'agreement among raft nodes before linearized reading' (duration: 121.180353ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:46.497442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.745439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pdzct\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-26T00:38:46.497461Z","caller":"traceutil/trace.go:171","msg":"trace[909924160] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pdzct; range_end:; response_count:1; response_revision:8430; }","duration":"104.794751ms","start":"2026-08-26T00:38:46.39266Z","end":"2026-08-26T00:38:46.497455Z","steps":["trace[909924160] 'agreement among raft nodes before linearized reading' (duration: 104.731328ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:38:46.624073Z","caller":"traceutil/trace.go:171","msg":"trace[2090325146] transaction","detail":"{read_only:false; response_revision:8433; number_of_response:1; }","duration":"121.986924ms","start":"2026-08-26T00:38:46.502062Z","end":"2026-08-26T00:38:46.624049Z","steps":["trace[2090325146] 'process raft request' (duration: 74.998485ms)","trace[2090325146] 'compare' (duration: 46.823705ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:38:46.62424Z","caller":"traceutil/trace.go:171","msg":"trace[1107094486] linearizableReadLoop","detail":"{readStateIndex:8726; appliedIndex:8723; }","duration":"121.486423ms","start":"2026-08-26T00:38:46.502694Z","end":"2026-08-26T00:38:46.62418Z","steps":["trace[1107094486] 'read index received' (duration: 58.004287ms)","trace[1107094486] 'applied index is now lower than readState.Index' (duration: 63.479266ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:38:46.624317Z","caller":"traceutil/trace.go:171","msg":"trace[345977153] transaction","detail":"{read_only:false; response_revision:8434; number_of_response:1; }","duration":"110.580796ms","start":"2026-08-26T00:38:46.513697Z","end":"2026-08-26T00:38:46.624278Z","steps":["trace[345977153] 'process raft request' (duration: 110.31054ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:46.624752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.036257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228544"} {"level":"info","ts":"2026-08-26T00:38:46.624796Z","caller":"traceutil/trace.go:171","msg":"trace[1520753841] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8434; }","duration":"122.097858ms","start":"2026-08-26T00:38:46.502686Z","end":"2026-08-26T00:38:46.624784Z","steps":["trace[1520753841] 'agreement among raft nodes before linearized reading' (duration: 121.673817ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:54.844319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.016962ms","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-08-26T00:38:54.844394Z","caller":"traceutil/trace.go:171","msg":"trace[1567310793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8512; }","duration":"250.155246ms","start":"2026-08-26T00:38:54.594225Z","end":"2026-08-26T00:38:54.84438Z","steps":["trace[1567310793] 'range keys from in-memory index tree' (duration: 249.834466ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:54.844398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.934212ms","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-08-26T00:38:54.844622Z","caller":"traceutil/trace.go:171","msg":"trace[596527036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8512; }","duration":"256.212629ms","start":"2026-08-26T00:38:54.588389Z","end":"2026-08-26T00:38:54.844601Z","steps":["trace[596527036] 'range keys from in-memory index tree' (duration: 255.670705ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:38:54.84682Z","caller":"traceutil/trace.go:171","msg":"trace[921216614] linearizableReadLoop","detail":"{readStateIndex:8809; appliedIndex:8808; }","duration":"182.575211ms","start":"2026-08-26T00:38:54.664229Z","end":"2026-08-26T00:38:54.846804Z","steps":["trace[921216614] 'read index received' (duration: 182.403986ms)","trace[921216614] 'applied index is now lower than readState.Index' (duration: 170.475µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:38:54.847058Z","caller":"traceutil/trace.go:171","msg":"trace[440708338] transaction","detail":"{read_only:false; response_revision:8513; number_of_response:1; }","duration":"194.508983ms","start":"2026-08-26T00:38:54.652534Z","end":"2026-08-26T00:38:54.847043Z","steps":["trace[440708338] 'process raft request' (duration: 194.143553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:54.847237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.198571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3672"} {"level":"warn","ts":"2026-08-26T00:38:54.847308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.081974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pdzct\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-26T00:38:54.847323Z","caller":"traceutil/trace.go:171","msg":"trace[87391291] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:8513; }","duration":"159.312812ms","start":"2026-08-26T00:38:54.687991Z","end":"2026-08-26T00:38:54.847303Z","steps":["trace[87391291] 'agreement among raft nodes before linearized reading' (duration: 159.057216ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:38:54.847342Z","caller":"traceutil/trace.go:171","msg":"trace[856101719] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pdzct; range_end:; response_count:1; response_revision:8513; }","duration":"183.150997ms","start":"2026-08-26T00:38:54.664181Z","end":"2026-08-26T00:38:54.847332Z","steps":["trace[856101719] 'agreement among raft nodes before linearized reading' (duration: 183.032114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:54.847626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.089136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-26T00:38:54.847686Z","caller":"traceutil/trace.go:171","msg":"trace[300857506] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8513; }","duration":"166.166188ms","start":"2026-08-26T00:38:54.681503Z","end":"2026-08-26T00:38:54.847669Z","steps":["trace[300857506] 'agreement among raft nodes before linearized reading' (duration: 166.052795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:54.847833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.057757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-26T00:38:54.847968Z","caller":"traceutil/trace.go:171","msg":"trace[564868288] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8513; }","duration":"182.254462ms","start":"2026-08-26T00:38:54.665685Z","end":"2026-08-26T00:38:54.84794Z","steps":["trace[564868288] 'agreement among raft nodes before linearized reading' (duration: 181.282235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:38:55.067331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.330982ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-26T00:38:55.067494Z","caller":"traceutil/trace.go:171","msg":"trace[377856313] linearizableReadLoop","detail":"{readStateIndex:8815; appliedIndex:8814; }","duration":"141.957705ms","start":"2026-08-26T00:38:54.92549Z","end":"2026-08-26T00:38:55.067447Z","steps":["trace[377856313] 'read index received' (duration: 25.451188ms)","trace[377856313] 'applied index is now lower than readState.Index' (duration: 116.479715ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:38:55.067571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.939571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ceph.18cf33f760d39383\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-08-26T00:38:55.067789Z","caller":"traceutil/trace.go:171","msg":"trace[454177925] range","detail":"{range_begin:/registry/events/openstack/ceph.18cf33f760d39383; range_end:; response_count:1; response_revision:8518; }","duration":"210.167165ms","start":"2026-08-26T00:38:54.857599Z","end":"2026-08-26T00:38:55.067766Z","steps":["trace[454177925] 'agreement among raft nodes before linearized reading' (duration: 209.827866ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:38:55.260537Z","caller":"traceutil/trace.go:171","msg":"trace[2060146436] transaction","detail":"{read_only:false; response_revision:8519; number_of_response:1; }","duration":"238.329157ms","start":"2026-08-26T00:38:55.022178Z","end":"2026-08-26T00:38:55.260507Z","steps":["trace[2060146436] 'process raft request' (duration: 238.080561ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:38:55.264948Z","caller":"traceutil/trace.go:171","msg":"trace[239890225] transaction","detail":"{read_only:false; response_revision:8520; number_of_response:1; }","duration":"193.941937ms","start":"2026-08-26T00:38:55.070905Z","end":"2026-08-26T00:38:55.264847Z","steps":["trace[239890225] 'process raft request' (duration: 193.767093ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:39:44.750648Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002429.wal"} {"level":"info","ts":"2026-08-26T00:40:13.436807Z","caller":"traceutil/trace.go:171","msg":"trace[1820504101] linearizableReadLoop","detail":"{readStateIndex:9591; appliedIndex:9590; }","duration":"133.422102ms","start":"2026-08-26T00:40:13.303365Z","end":"2026-08-26T00:40:13.436787Z","steps":["trace[1820504101] 'read index received' (duration: 112.913318ms)","trace[1820504101] 'applied index is now lower than readState.Index' (duration: 20.507764ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:40:13.437099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.960824ms","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-08-26T00:40:13.437094Z","caller":"traceutil/trace.go:171","msg":"trace[1145348775] transaction","detail":"{read_only:false; response_revision:9273; number_of_response:1; }","duration":"172.99503ms","start":"2026-08-26T00:40:13.263931Z","end":"2026-08-26T00:40:13.436926Z","steps":["trace[1145348775] 'process raft request' (duration: 152.398884ms)","trace[1145348775] 'compare' (duration: 20.125674ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:40:13.437161Z","caller":"traceutil/trace.go:171","msg":"trace[1236795648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9273; }","duration":"128.053497ms","start":"2026-08-26T00:40:13.309088Z","end":"2026-08-26T00:40:13.437142Z","steps":["trace[1236795648] 'agreement among raft nodes before linearized reading' (duration: 127.859593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:40:13.437217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.263206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-26T00:40:13.43733Z","caller":"traceutil/trace.go:171","msg":"trace[1556302867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9273; }","duration":"127.407159ms","start":"2026-08-26T00:40:13.309902Z","end":"2026-08-26T00:40:13.437309Z","steps":["trace[1556302867] 'agreement among raft nodes before linearized reading' (duration: 127.128711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:40:13.437553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.180663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-26T00:40:13.437598Z","caller":"traceutil/trace.go:171","msg":"trace[1263601763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9273; }","duration":"134.262635ms","start":"2026-08-26T00:40:13.303322Z","end":"2026-08-26T00:40:13.437585Z","steps":["trace[1263601763] 'agreement among raft nodes before linearized reading' (duration: 133.578286ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:40:13.632696Z","caller":"traceutil/trace.go:171","msg":"trace[2090226391] transaction","detail":"{read_only:false; response_revision:9275; number_of_response:1; }","duration":"191.50253ms","start":"2026-08-26T00:40:13.441142Z","end":"2026-08-26T00:40:13.632645Z","steps":["trace[2090226391] 'process raft request' (duration: 156.844744ms)","trace[2090226391] 'compare' (duration: 34.303065ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:40:13.632974Z","caller":"traceutil/trace.go:171","msg":"trace[1773948586] transaction","detail":"{read_only:false; response_revision:9276; number_of_response:1; }","duration":"191.50858ms","start":"2026-08-26T00:40:13.441454Z","end":"2026-08-26T00:40:13.632962Z","steps":["trace[1773948586] 'process raft request' (duration: 191.119849ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:40:13.633093Z","caller":"traceutil/trace.go:171","msg":"trace[1562574195] transaction","detail":"{read_only:false; response_revision:9277; number_of_response:1; }","duration":"189.533337ms","start":"2026-08-26T00:40:13.443542Z","end":"2026-08-26T00:40:13.633075Z","steps":["trace[1562574195] 'process raft request' (duration: 189.365412ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:40:13.633144Z","caller":"traceutil/trace.go:171","msg":"trace[1884070515] linearizableReadLoop","detail":"{readStateIndex:9594; appliedIndex:9591; }","duration":"191.177141ms","start":"2026-08-26T00:40:13.441903Z","end":"2026-08-26T00:40:13.63308Z","steps":["trace[1884070515] 'read index received' (duration: 28.387146ms)","trace[1884070515] 'applied index is now lower than readState.Index' (duration: 162.781515ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:40:13.633377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.742323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:40:13.633482Z","caller":"traceutil/trace.go:171","msg":"trace[1210601618] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9277; }","duration":"189.831575ms","start":"2026-08-26T00:40:13.443588Z","end":"2026-08-26T00:40:13.633419Z","steps":["trace[1210601618] 'agreement among raft nodes before linearized reading' (duration: 189.718652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:40:13.633827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.916312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:31 size:291241"} {"level":"info","ts":"2026-08-26T00:40:13.633898Z","caller":"traceutil/trace.go:171","msg":"trace[258031879] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:31; response_revision:9277; }","duration":"192.012864ms","start":"2026-08-26T00:40:13.44187Z","end":"2026-08-26T00:40:13.633883Z","steps":["trace[258031879] 'agreement among raft nodes before linearized reading' (duration: 191.336186ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:41:08.110775Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"15a19459cc268924","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-26T00:41:08.117332Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-26T00:41:08.11755Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-08-26T00:41:13.675105Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9882199659555427364,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:41:14.176183Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9882199659555427364,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:41:14.429927Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.391865135s","expected-duration":"1s"} {"level":"info","ts":"2026-08-26T00:41:14.430462Z","caller":"traceutil/trace.go:171","msg":"trace[761542923] transaction","detail":"{read_only:false; response_revision:9701; number_of_response:1; }","duration":"1.392494263s","start":"2026-08-26T00:41:13.037938Z","end":"2026-08-26T00:41:14.430432Z","steps":["trace[761542923] 'process raft request' (duration: 1.392260775s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:14.430631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:41:13.037911Z","time spent":"1.392626247s","remote":"127.0.0.1:56866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:41:14.440822Z","caller":"traceutil/trace.go:171","msg":"trace[1577258408] transaction","detail":"{read_only:false; response_revision:9702; number_of_response:1; }","duration":"1.028589668s","start":"2026-08-26T00:41:13.412209Z","end":"2026-08-26T00:41:14.440798Z","steps":["trace[1577258408] 'process raft request' (duration: 1.028423463s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:14.440979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:41:13.412189Z","time spent":"1.028704432s","remote":"127.0.0.1:56866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:41:14.440977Z","caller":"traceutil/trace.go:171","msg":"trace[372228850] linearizableReadLoop","detail":"{readStateIndex:10033; appliedIndex:10031; }","duration":"1.266681777s","start":"2026-08-26T00:41:13.174264Z","end":"2026-08-26T00:41:14.440945Z","steps":["trace[372228850] 'read index received' (duration: 1.256032919s)","trace[372228850] 'applied index is now lower than readState.Index' (duration: 10.646588ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:41:14.441157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266863592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:41:14.441254Z","caller":"traceutil/trace.go:171","msg":"trace[253795622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9703; }","duration":"1.267000045s","start":"2026-08-26T00:41:13.174228Z","end":"2026-08-26T00:41:14.441228Z","steps":["trace[253795622] 'agreement among raft nodes before linearized reading' (duration: 1.2668403s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:14.441302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:41:13.174213Z","time spent":"1.267080037s","remote":"127.0.0.1:56610","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-08-26T00:41:14.442846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.969552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-26T00:41:14.442892Z","caller":"traceutil/trace.go:171","msg":"trace[575672687] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9704; }","duration":"715.064914ms","start":"2026-08-26T00:41:13.727812Z","end":"2026-08-26T00:41:14.442877Z","steps":["trace[575672687] 'agreement among raft nodes before linearized reading' (duration: 714.936681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:14.442932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:41:13.727767Z","time spent":"715.158657ms","remote":"127.0.0.1:56866","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-08-26T00:41:14.442971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.204023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:41:14.443017Z","caller":"traceutil/trace.go:171","msg":"trace[1072237142] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9704; }","duration":"304.304614ms","start":"2026-08-26T00:41:14.138702Z","end":"2026-08-26T00:41:14.443007Z","steps":["trace[1072237142] 'agreement among raft nodes before linearized reading' (duration: 304.204991ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:14.443046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:41:14.13868Z","time spent":"304.359887ms","remote":"127.0.0.1:55728","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-08-26T00:41:14.443127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.303932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-26T00:41:14.443164Z","caller":"traceutil/trace.go:171","msg":"trace[1896170620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9704; }","duration":"511.374916ms","start":"2026-08-26T00:41:13.931779Z","end":"2026-08-26T00:41:14.443154Z","steps":["trace[1896170620] 'agreement among raft nodes before linearized reading' (duration: 511.308844ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:14.44319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:41:13.931758Z","time spent":"511.425597ms","remote":"127.0.0.1:56866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-26T00:41:14.443215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.155912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-26T00:41:14.443289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.047733ms","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-08-26T00:41:14.443297Z","caller":"traceutil/trace.go:171","msg":"trace[1303916793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9704; }","duration":"510.267415ms","start":"2026-08-26T00:41:13.933009Z","end":"2026-08-26T00:41:14.443276Z","steps":["trace[1303916793] 'agreement among raft nodes before linearized reading' (duration: 510.010378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:14.443369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:41:13.932995Z","time spent":"510.361138ms","remote":"127.0.0.1:56866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-26T00:41:14.443391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.725821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-26T00:41:14.443417Z","caller":"traceutil/trace.go:171","msg":"trace[1918666955] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9704; }","duration":"196.770452ms","start":"2026-08-26T00:41:14.246639Z","end":"2026-08-26T00:41:14.44341Z","steps":["trace[1918666955] 'agreement among raft nodes before linearized reading' (duration: 196.706541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:14.443508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.969964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:41:14.443531Z","caller":"traceutil/trace.go:171","msg":"trace[491547379] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:9704; }","duration":"254.995374ms","start":"2026-08-26T00:41:14.188531Z","end":"2026-08-26T00:41:14.443526Z","steps":["trace[491547379] 'agreement among raft nodes before linearized reading' (duration: 254.950643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:14.443681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.386778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pdzct\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-26T00:41:14.443735Z","caller":"traceutil/trace.go:171","msg":"trace[1837054977] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pdzct; range_end:; response_count:1; response_revision:9704; }","duration":"304.453949ms","start":"2026-08-26T00:41:14.139258Z","end":"2026-08-26T00:41:14.443712Z","steps":["trace[1837054977] 'agreement among raft nodes before linearized reading' (duration: 304.321455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:14.443754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.126732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-26T00:41:14.443319Z","caller":"traceutil/trace.go:171","msg":"trace[471844272] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9704; }","duration":"712.095773ms","start":"2026-08-26T00:41:13.731214Z","end":"2026-08-26T00:41:14.44331Z","steps":["trace[471844272] 'agreement among raft nodes before linearized reading' (duration: 712.039122ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:41:14.443785Z","caller":"traceutil/trace.go:171","msg":"trace[1485343662] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:9704; }","duration":"510.196874ms","start":"2026-08-26T00:41:13.933581Z","end":"2026-08-26T00:41:14.443778Z","steps":["trace[1485343662] 'agreement among raft nodes before linearized reading' (duration: 510.123281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:14.443815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:41:13.933553Z","time spent":"510.256465ms","remote":"127.0.0.1:56866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-26T00:41:14.443683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.142366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-08-26T00:41:14.443877Z","caller":"traceutil/trace.go:171","msg":"trace[536011251] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:9704; }","duration":"469.372281ms","start":"2026-08-26T00:41:13.974499Z","end":"2026-08-26T00:41:14.443871Z","steps":["trace[536011251] 'agreement among raft nodes before linearized reading' (duration: 469.132215ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:41:14.443786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:41:14.139244Z","time spent":"304.534452ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-pdzct\" "} {"level":"warn","ts":"2026-08-26T00:41:14.443859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:41:13.731205Z","time spent":"712.617529ms","remote":"127.0.0.1:56866","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-08-26T00:41:14.443901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:41:13.974483Z","time spent":"469.412933ms","remote":"127.0.0.1:56782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2011,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-08-26T00:43:26.787152Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8276} {"level":"info","ts":"2026-08-26T00:43:26.873877Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8276,"took":"84.776739ms","hash":3722616745,"current-db-size-bytes":57503744,"current-db-size":"58 MB","current-db-size-in-use-bytes":25231360,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-08-26T00:43:26.874045Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3722616745,"revision":8276,"compact-revision":6105} {"level":"info","ts":"2026-08-26T00:43:57.010576Z","caller":"traceutil/trace.go:171","msg":"trace[1879636807] transaction","detail":"{read_only:false; response_revision:11108; number_of_response:1; }","duration":"154.871113ms","start":"2026-08-26T00:43:56.855672Z","end":"2026-08-26T00:43:57.010543Z","steps":["trace[1879636807] 'process raft request' (duration: 94.299024ms)","trace[1879636807] 'compare' (duration: 60.406854ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:48:26.793659Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10756} {"level":"info","ts":"2026-08-26T00:48:26.874452Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10756,"took":"78.284615ms","hash":2770571241,"current-db-size-bytes":57503744,"current-db-size":"58 MB","current-db-size-in-use-bytes":27721728,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-26T00:48:26.874507Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2770571241,"revision":10756,"compact-revision":8276} {"level":"info","ts":"2026-08-26T00:50:53.493061Z","caller":"traceutil/trace.go:171","msg":"trace[1110881265] transaction","detail":"{read_only:false; response_revision:13845; number_of_response:1; }","duration":"128.27169ms","start":"2026-08-26T00:50:53.364764Z","end":"2026-08-26T00:50:53.493036Z","steps":["trace[1110881265] 'process raft request' (duration: 127.737885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:51:13.674785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.16535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:51:13.674878Z","caller":"traceutil/trace.go:171","msg":"trace[632553320] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:13947; }","duration":"175.309664ms","start":"2026-08-26T00:51:13.49955Z","end":"2026-08-26T00:51:13.67486Z","steps":["trace[632553320] 'count revisions from in-memory index tree' (duration: 175.008746ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:51:33.277368Z","caller":"traceutil/trace.go:171","msg":"trace[771980192] transaction","detail":"{read_only:false; response_revision:14070; number_of_response:1; }","duration":"446.63716ms","start":"2026-08-26T00:51:32.830671Z","end":"2026-08-26T00:51:33.277308Z","steps":["trace[771980192] 'process raft request' (duration: 376.121921ms)","trace[771980192] 'compare' (duration: 70.223392ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:51:33.277792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:51:32.83065Z","time spent":"446.862977ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12713,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:51:33.760829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.942266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:51:33.760876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.918749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:51:33.760947Z","caller":"traceutil/trace.go:171","msg":"trace[921800883] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:14070; }","duration":"102.089989ms","start":"2026-08-26T00:51:33.658833Z","end":"2026-08-26T00:51:33.760923Z","steps":["trace[921800883] 'count revisions from in-memory index tree' (duration: 101.847002ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:51:33.760961Z","caller":"traceutil/trace.go:171","msg":"trace[666424468] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:14070; }","duration":"366.040783ms","start":"2026-08-26T00:51:33.394896Z","end":"2026-08-26T00:51:33.760937Z","steps":["trace[666424468] 'range keys from in-memory index tree' (duration: 365.831168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:51:33.761002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:51:33.394878Z","time spent":"366.115746ms","remote":"127.0.0.1:57082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-08-26T00:51:33.761408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.375751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-26T00:51:33.76145Z","caller":"traceutil/trace.go:171","msg":"trace[2041443017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14070; }","duration":"314.444444ms","start":"2026-08-26T00:51:33.446995Z","end":"2026-08-26T00:51:33.76144Z","steps":["trace[2041443017] 'range keys from in-memory index tree' (duration: 314.220528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:51:33.761477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:51:33.446978Z","time spent":"314.492154ms","remote":"127.0.0.1:56866","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-08-26T00:51:33.761583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.039702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:51:33.761675Z","caller":"traceutil/trace.go:171","msg":"trace[1929534532] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14070; }","duration":"173.214437ms","start":"2026-08-26T00:51:33.588439Z","end":"2026-08-26T00:51:33.761653Z","steps":["trace[1929534532] 'count revisions from in-memory index tree' (duration: 172.921978ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:52:43.286784Z","caller":"traceutil/trace.go:171","msg":"trace[298141071] linearizableReadLoop","detail":"{readStateIndex:15623; appliedIndex:15622; }","duration":"135.805488ms","start":"2026-08-26T00:52:43.150952Z","end":"2026-08-26T00:52:43.286757Z","steps":["trace[298141071] 'read index received' (duration: 135.633193ms)","trace[298141071] 'applied index is now lower than readState.Index' (duration: 171.315µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:52:43.286891Z","caller":"traceutil/trace.go:171","msg":"trace[2127108465] transaction","detail":"{read_only:false; response_revision:15112; number_of_response:1; }","duration":"164.551218ms","start":"2026-08-26T00:52:43.122305Z","end":"2026-08-26T00:52:43.286856Z","steps":["trace[2127108465] 'process raft request' (duration: 164.27768ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:52:43.287053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.075194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-zfttk\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-08-26T00:52:43.2871Z","caller":"traceutil/trace.go:171","msg":"trace[1114040456] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-zfttk; range_end:; response_count:1; response_revision:15112; }","duration":"136.194089ms","start":"2026-08-26T00:52:43.150893Z","end":"2026-08-26T00:52:43.287087Z","steps":["trace[1114040456] 'agreement among raft nodes before linearized reading' (duration: 136.008273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:52:43.287193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.661284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-zfttk\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-08-26T00:52:43.287297Z","caller":"traceutil/trace.go:171","msg":"trace[1113481994] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-zfttk; range_end:; response_count:1; response_revision:15112; }","duration":"131.802047ms","start":"2026-08-26T00:52:43.155466Z","end":"2026-08-26T00:52:43.287268Z","steps":["trace[1113481994] 'agreement among raft nodes before linearized reading' (duration: 131.578701ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:53:26.801756Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12955} {"level":"info","ts":"2026-08-26T00:53:26.879964Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12955,"took":"75.358353ms","hash":2414226195,"current-db-size-bytes":57503744,"current-db-size":"58 MB","current-db-size-in-use-bytes":29519872,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-26T00:53:26.880025Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2414226195,"revision":12955,"compact-revision":10756} {"level":"info","ts":"2026-08-26T00:56:22.071892Z","caller":"traceutil/trace.go:171","msg":"trace[1484675703] linearizableReadLoop","detail":"{readStateIndex:17281; appliedIndex:17279; }","duration":"219.121772ms","start":"2026-08-26T00:56:21.85275Z","end":"2026-08-26T00:56:22.071872Z","steps":["trace[1484675703] 'read index received' (duration: 218.969557ms)","trace[1484675703] 'applied index is now lower than readState.Index' (duration: 150.995µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:56:22.071946Z","caller":"traceutil/trace.go:171","msg":"trace[1864473090] transaction","detail":"{read_only:false; response_revision:16714; number_of_response:1; }","duration":"252.816422ms","start":"2026-08-26T00:56:21.819102Z","end":"2026-08-26T00:56:22.071918Z","steps":["trace[1864473090] 'process raft request' (duration: 252.613417ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:56:22.071983Z","caller":"traceutil/trace.go:171","msg":"trace[442852299] transaction","detail":"{read_only:false; response_revision:16715; number_of_response:1; }","duration":"227.444487ms","start":"2026-08-26T00:56:21.844532Z","end":"2026-08-26T00:56:22.071977Z","steps":["trace[442852299] 'process raft request' (duration: 227.309942ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:56:22.072245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.480651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-zfttk\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-08-26T00:56:22.072276Z","caller":"traceutil/trace.go:171","msg":"trace[1192369789] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-zfttk; range_end:; response_count:1; response_revision:16715; }","duration":"219.553512ms","start":"2026-08-26T00:56:21.852715Z","end":"2026-08-26T00:56:22.072268Z","steps":["trace[1192369789] 'agreement among raft nodes before linearized reading' (duration: 219.407008ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:56:22.104616Z","caller":"traceutil/trace.go:171","msg":"trace[454390583] transaction","detail":"{read_only:false; response_revision:16716; number_of_response:1; }","duration":"126.162706ms","start":"2026-08-26T00:56:21.978429Z","end":"2026-08-26T00:56:22.104592Z","steps":["trace[454390583] 'process raft request' (duration: 121.088607ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:56:22.104794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.819655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-zfttk\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-08-26T00:56:22.104896Z","caller":"traceutil/trace.go:171","msg":"trace[633593894] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-zfttk; range_end:; response_count:1; response_revision:16716; }","duration":"197.957469ms","start":"2026-08-26T00:56:21.906915Z","end":"2026-08-26T00:56:22.104872Z","steps":["trace[633593894] 'agreement among raft nodes before linearized reading' (duration: 197.670852ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:58:26.80998Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15338} {"level":"info","ts":"2026-08-26T00:58:26.908612Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15338,"took":"95.094034ms","hash":3885403665,"current-db-size-bytes":57503744,"current-db-size":"58 MB","current-db-size-in-use-bytes":30552064,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-26T00:58:26.908665Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3885403665,"revision":15338,"compact-revision":12955} {"level":"info","ts":"2026-08-26T00:58:59.76446Z","caller":"traceutil/trace.go:171","msg":"trace[1908587962] linearizableReadLoop","detail":"{readStateIndex:18395; appliedIndex:18394; }","duration":"135.639882ms","start":"2026-08-26T00:58:59.628796Z","end":"2026-08-26T00:58:59.764435Z","steps":["trace[1908587962] 'read index received' (duration: 135.411596ms)","trace[1908587962] 'applied index is now lower than readState.Index' (duration: 227.376µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:58:59.764545Z","caller":"traceutil/trace.go:171","msg":"trace[1742928858] transaction","detail":"{read_only:false; response_revision:17793; number_of_response:1; }","duration":"432.028101ms","start":"2026-08-26T00:58:59.332477Z","end":"2026-08-26T00:58:59.764505Z","steps":["trace[1742928858] 'process raft request' (duration: 431.802505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:58:59.764751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.90231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-26T00:58:59.764906Z","caller":"traceutil/trace.go:171","msg":"trace[1636381202] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:17793; }","duration":"136.132286ms","start":"2026-08-26T00:58:59.62874Z","end":"2026-08-26T00:58:59.764872Z","steps":["trace[1636381202] 'agreement among raft nodes before linearized reading' (duration: 135.827428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:58:59.765119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:58:59.332452Z","time spent":"432.40247ms","remote":"127.0.0.1:56772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20871,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:59:00.073733Z","caller":"traceutil/trace.go:171","msg":"trace[1399250105] linearizableReadLoop","detail":"{readStateIndex:18397; appliedIndex:18395; }","duration":"217.1208ms","start":"2026-08-26T00:58:59.856576Z","end":"2026-08-26T00:59:00.073696Z","steps":["trace[1399250105] 'read index received' (duration: 132.112018ms)","trace[1399250105] 'applied index is now lower than readState.Index' (duration: 85.007602ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:59:00.073854Z","caller":"traceutil/trace.go:171","msg":"trace[2132721974] transaction","detail":"{read_only:false; response_revision:17794; number_of_response:1; }","duration":"264.121323ms","start":"2026-08-26T00:58:59.809695Z","end":"2026-08-26T00:59:00.073816Z","steps":["trace[2132721974] 'process raft request' (duration: 263.465096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:59:00.074007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.430128ms","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-08-26T00:59:00.07405Z","caller":"traceutil/trace.go:171","msg":"trace[1201755348] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17794; }","duration":"217.536321ms","start":"2026-08-26T00:58:59.856503Z","end":"2026-08-26T00:59:00.07404Z","steps":["trace[1201755348] 'agreement among raft nodes before linearized reading' (duration: 217.299965ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:59:09.975689Z","caller":"traceutil/trace.go:171","msg":"trace[1260954889] transaction","detail":"{read_only:false; response_revision:17941; number_of_response:1; }","duration":"113.249127ms","start":"2026-08-26T00:59:09.862408Z","end":"2026-08-26T00:59:09.975657Z","steps":["trace[1260954889] 'process raft request' (duration: 14.457952ms)","trace[1260954889] 'compare' (duration: 98.516258ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:59:09.978164Z","caller":"traceutil/trace.go:171","msg":"trace[981968532] linearizableReadLoop","detail":"{readStateIndex:18553; appliedIndex:18547; }","duration":"113.009001ms","start":"2026-08-26T00:59:09.865134Z","end":"2026-08-26T00:59:09.978143Z","steps":["trace[981968532] 'read index received' (duration: 11.743248ms)","trace[981968532] 'applied index is now lower than readState.Index' (duration: 101.265042ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:59:09.978452Z","caller":"traceutil/trace.go:171","msg":"trace[1113577945] transaction","detail":"{read_only:false; response_revision:17942; number_of_response:1; }","duration":"116.009742ms","start":"2026-08-26T00:59:09.862433Z","end":"2026-08-26T00:59:09.978443Z","steps":["trace[1113577945] 'process raft request' (duration: 115.394676ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:59:09.978557Z","caller":"traceutil/trace.go:171","msg":"trace[339935920] transaction","detail":"{read_only:false; response_revision:17947; number_of_response:1; }","duration":"112.683461ms","start":"2026-08-26T00:59:09.865837Z","end":"2026-08-26T00:59:09.97852Z","steps":["trace[339935920] 'process raft request' (duration: 112.234459ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:59:09.978605Z","caller":"traceutil/trace.go:171","msg":"trace[837562226] transaction","detail":"{read_only:false; response_revision:17948; number_of_response:1; }","duration":"108.841818ms","start":"2026-08-26T00:59:09.869756Z","end":"2026-08-26T00:59:09.978598Z","steps":["trace[837562226] 'process raft request' (duration: 108.348265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:59:09.978751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.605487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-26T00:59:09.97878Z","caller":"traceutil/trace.go:171","msg":"trace[791728338] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:17948; }","duration":"113.663158ms","start":"2026-08-26T00:59:09.86511Z","end":"2026-08-26T00:59:09.978773Z","steps":["trace[791728338] 'agreement among raft nodes before linearized reading' (duration: 113.574766ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:59:09.978841Z","caller":"traceutil/trace.go:171","msg":"trace[1165130564] transaction","detail":"{read_only:false; response_revision:17943; number_of_response:1; }","duration":"116.197888ms","start":"2026-08-26T00:59:09.862634Z","end":"2026-08-26T00:59:09.978832Z","steps":["trace[1165130564] 'process raft request' (duration: 115.319734ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:59:09.978949Z","caller":"traceutil/trace.go:171","msg":"trace[221242474] transaction","detail":"{read_only:false; response_revision:17944; number_of_response:1; }","duration":"116.209718ms","start":"2026-08-26T00:59:09.862732Z","end":"2026-08-26T00:59:09.978941Z","steps":["trace[221242474] 'process raft request' (duration: 115.272452ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:59:09.979051Z","caller":"traceutil/trace.go:171","msg":"trace[2132594909] transaction","detail":"{read_only:false; response_revision:17945; number_of_response:1; }","duration":"116.289889ms","start":"2026-08-26T00:59:09.862755Z","end":"2026-08-26T00:59:09.979045Z","steps":["trace[2132594909] 'process raft request' (duration: 115.271611ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:59:09.979133Z","caller":"traceutil/trace.go:171","msg":"trace[526549045] transaction","detail":"{read_only:false; response_revision:17946; number_of_response:1; }","duration":"116.334562ms","start":"2026-08-26T00:59:09.862792Z","end":"2026-08-26T00:59:09.979127Z","steps":["trace[526549045] 'process raft request' (duration: 115.259923ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:59:10.148215Z","caller":"traceutil/trace.go:171","msg":"trace[1225169024] linearizableReadLoop","detail":"{readStateIndex:18566; appliedIndex:18565; }","duration":"120.609885ms","start":"2026-08-26T00:59:10.027579Z","end":"2026-08-26T00:59:10.148189Z","steps":["trace[1225169024] 'read index received' (duration: 120.441411ms)","trace[1225169024] 'applied index is now lower than readState.Index' (duration: 167.124µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:59:10.148258Z","caller":"traceutil/trace.go:171","msg":"trace[1451622007] transaction","detail":"{read_only:false; response_revision:17959; number_of_response:1; }","duration":"124.782179ms","start":"2026-08-26T00:59:10.023449Z","end":"2026-08-26T00:59:10.148231Z","steps":["trace[1451622007] 'process raft request' (duration: 124.565293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:59:10.148533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.257214ms","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-08-26T00:59:10.148637Z","caller":"traceutil/trace.go:171","msg":"trace[1452327905] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:17959; }","duration":"124.387499ms","start":"2026-08-26T00:59:10.02423Z","end":"2026-08-26T00:59:10.148617Z","steps":["trace[1452327905] 'agreement among raft nodes before linearized reading' (duration: 124.0678ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:59:10.173349Z","caller":"traceutil/trace.go:171","msg":"trace[1129562915] transaction","detail":"{read_only:false; response_revision:17960; number_of_response:1; }","duration":"143.693961ms","start":"2026-08-26T00:59:10.029634Z","end":"2026-08-26T00:59:10.173328Z","steps":["trace[1129562915] 'process raft request' (duration: 143.407683ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:59:10.173472Z","caller":"traceutil/trace.go:171","msg":"trace[390231808] transaction","detail":"{read_only:false; response_revision:17962; number_of_response:1; }","duration":"140.797363ms","start":"2026-08-26T00:59:10.032636Z","end":"2026-08-26T00:59:10.173434Z","steps":["trace[390231808] 'process raft request' (duration: 140.583497ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:59:10.173877Z","caller":"traceutil/trace.go:171","msg":"trace[1659834541] transaction","detail":"{read_only:false; response_revision:17961; number_of_response:1; }","duration":"143.892386ms","start":"2026-08-26T00:59:10.029969Z","end":"2026-08-26T00:59:10.173862Z","steps":["trace[1659834541] 'process raft request' (duration: 143.189527ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:59:10.174623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.338106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225409"} {"level":"info","ts":"2026-08-26T00:59:10.174679Z","caller":"traceutil/trace.go:171","msg":"trace[189089842] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:17962; }","duration":"121.444249ms","start":"2026-08-26T00:59:10.05322Z","end":"2026-08-26T00:59:10.174664Z","steps":["trace[189089842] 'agreement among raft nodes before linearized reading' (duration: 120.37123ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:59:10.298752Z","caller":"traceutil/trace.go:171","msg":"trace[636868664] linearizableReadLoop","detail":"{readStateIndex:18573; appliedIndex:18570; }","duration":"105.944979ms","start":"2026-08-26T00:59:10.192783Z","end":"2026-08-26T00:59:10.298728Z","steps":["trace[636868664] 'read index received' (duration: 16.528757ms)","trace[636868664] 'applied index is now lower than readState.Index' (duration: 89.415222ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:59:10.298849Z","caller":"traceutil/trace.go:171","msg":"trace[263790403] transaction","detail":"{read_only:false; response_revision:17965; number_of_response:1; }","duration":"107.048159ms","start":"2026-08-26T00:59:10.191767Z","end":"2026-08-26T00:59:10.298815Z","steps":["trace[263790403] 'process raft request' (duration: 106.72651ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:59:10.298875Z","caller":"traceutil/trace.go:171","msg":"trace[1591868843] transaction","detail":"{read_only:false; number_of_response:1; response_revision:17965; }","duration":"106.830643ms","start":"2026-08-26T00:59:10.191999Z","end":"2026-08-26T00:59:10.29883Z","steps":["trace[1591868843] 'process raft request' (duration: 106.667939ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:59:10.299036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.230647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1344"} {"level":"info","ts":"2026-08-26T00:59:10.29912Z","caller":"traceutil/trace.go:171","msg":"trace[1039546871] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:17965; }","duration":"106.33929ms","start":"2026-08-26T00:59:10.192761Z","end":"2026-08-26T00:59:10.2991Z","steps":["trace[1039546871] 'agreement among raft nodes before linearized reading' (duration: 106.083813ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:59:10.307704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.690516ms","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-08-26T00:59:10.307773Z","caller":"traceutil/trace.go:171","msg":"trace[2052470495] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17967; }","duration":"107.789569ms","start":"2026-08-26T00:59:10.199967Z","end":"2026-08-26T00:59:10.307756Z","steps":["trace[2052470495] 'agreement among raft nodes before linearized reading' (duration: 107.587543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:59:10.307876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.837093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1822"} {"level":"info","ts":"2026-08-26T00:59:10.307908Z","caller":"traceutil/trace.go:171","msg":"trace[468949529] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:17967; }","duration":"106.900644ms","start":"2026-08-26T00:59:10.200998Z","end":"2026-08-26T00:59:10.307899Z","steps":["trace[468949529] 'agreement among raft nodes before linearized reading' (duration: 106.782881ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:01:49.290649Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"15a19459cc268924","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-26T01:01:49.307309Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-26T01:01:49.307436Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-26T01:03:26.817967Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17530} {"level":"info","ts":"2026-08-26T01:03:26.916561Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17530,"took":"94.857939ms","hash":4104975995,"current-db-size-bytes":57503744,"current-db-size":"58 MB","current-db-size-in-use-bytes":34537472,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-26T01:03:26.916618Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4104975995,"revision":17530,"compact-revision":15338} {"level":"warn","ts":"2026-08-26T01:04:39.312403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.399703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-26T01:04:39.312512Z","caller":"traceutil/trace.go:171","msg":"trace[1954997146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20501; }","duration":"136.565658ms","start":"2026-08-26T01:04:39.175925Z","end":"2026-08-26T01:04:39.312491Z","steps":["trace[1954997146] 'range keys from in-memory index tree' (duration: 136.320021ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:04:39.312558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.694924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-26T01:04:39.312664Z","caller":"traceutil/trace.go:171","msg":"trace[1333991658] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:20501; }","duration":"238.857789ms","start":"2026-08-26T01:04:39.073785Z","end":"2026-08-26T01:04:39.312643Z","steps":["trace[1333991658] 'count revisions from in-memory index tree' (duration: 238.54547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:04:39.874865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.784861ms","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-08-26T01:04:39.874942Z","caller":"traceutil/trace.go:171","msg":"trace[1517781529] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20502; }","duration":"113.920995ms","start":"2026-08-26T01:04:39.761004Z","end":"2026-08-26T01:04:39.874925Z","steps":["trace[1517781529] 'range keys from in-memory index tree' (duration: 113.635347ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:04:40.124379Z","caller":"traceutil/trace.go:171","msg":"trace[367109741] transaction","detail":"{read_only:false; response_revision:20504; number_of_response:1; }","duration":"245.783036ms","start":"2026-08-26T01:04:39.878571Z","end":"2026-08-26T01:04:40.124354Z","steps":["trace[367109741] 'process raft request' (duration: 245.656923ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:04:40.124644Z","caller":"traceutil/trace.go:171","msg":"trace[1806481024] linearizableReadLoop","detail":"{readStateIndex:21194; appliedIndex:21191; }","duration":"202.843014ms","start":"2026-08-26T01:04:39.921771Z","end":"2026-08-26T01:04:40.124614Z","steps":["trace[1806481024] 'read index received' (duration: 147.279029ms)","trace[1806481024] 'applied index is now lower than readState.Index' (duration: 55.562465ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T01:04:40.12475Z","caller":"traceutil/trace.go:171","msg":"trace[565347347] transaction","detail":"{read_only:false; response_revision:20505; number_of_response:1; }","duration":"203.141112ms","start":"2026-08-26T01:04:39.921586Z","end":"2026-08-26T01:04:40.124727Z","steps":["trace[565347347] 'process raft request' (duration: 202.876154ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:04:40.124905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.119361ms","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-08-26T01:04:40.124982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.039524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-26T01:04:40.125028Z","caller":"traceutil/trace.go:171","msg":"trace[37565153] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:20505; }","duration":"129.103265ms","start":"2026-08-26T01:04:39.995913Z","end":"2026-08-26T01:04:40.125016Z","steps":["trace[37565153] 'agreement among raft nodes before linearized reading' (duration: 128.990012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:04:40.125232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.545524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-26T01:04:40.124943Z","caller":"traceutil/trace.go:171","msg":"trace[1090208975] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20505; }","duration":"203.213164ms","start":"2026-08-26T01:04:39.921718Z","end":"2026-08-26T01:04:40.124931Z","steps":["trace[1090208975] 'agreement among raft nodes before linearized reading' (duration: 203.043779ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:04:40.125325Z","caller":"traceutil/trace.go:171","msg":"trace[939026289] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20505; }","duration":"120.660947ms","start":"2026-08-26T01:04:40.004632Z","end":"2026-08-26T01:04:40.125293Z","steps":["trace[939026289] 'agreement among raft nodes before linearized reading' (duration: 120.445121ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:08:26.824095Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20107} {"level":"info","ts":"2026-08-26T01:08:26.939927Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20107,"took":"112.83943ms","hash":2507238777,"current-db-size-bytes":57503744,"current-db-size":"58 MB","current-db-size-in-use-bytes":40833024,"current-db-size-in-use":"41 MB"} {"level":"info","ts":"2026-08-26T01:08:26.940039Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2507238777,"revision":20107,"compact-revision":17530} {"level":"warn","ts":"2026-08-26T01:08:32.005427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.58121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-26T01:08:32.005504Z","caller":"traceutil/trace.go:171","msg":"trace[1814725865] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:22971; }","duration":"168.710654ms","start":"2026-08-26T01:08:31.83678Z","end":"2026-08-26T01:08:32.005491Z","steps":["trace[1814725865] 'count revisions from in-memory index tree' (duration: 168.503988ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T01:08:32.024085Z","caller":"traceutil/trace.go:171","msg":"trace[878618133] transaction","detail":"{read_only:false; response_revision:22972; number_of_response:1; }","duration":"117.122288ms","start":"2026-08-26T01:08:31.906939Z","end":"2026-08-26T01:08:32.024062Z","steps":["trace[878618133] 'process raft request' (duration: 116.835349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:08:32.509181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.293279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-26T01:08:32.509246Z","caller":"traceutil/trace.go:171","msg":"trace[1068825748] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22972; }","duration":"331.412632ms","start":"2026-08-26T01:08:32.177817Z","end":"2026-08-26T01:08:32.50923Z","steps":["trace[1068825748] 'range keys from in-memory index tree' (duration: 331.158624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T01:08:32.509277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T01:08:32.177797Z","time spent":"331.473334ms","remote":"127.0.0.1:56606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-26T01:13:26.830709Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22858} {"level":"info","ts":"2026-08-26T01:13:26.962739Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22858,"took":"128.116124ms","hash":3195621478,"current-db-size-bytes":57503744,"current-db-size":"58 MB","current-db-size-in-use-bytes":35606528,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-26T01:13:26.962803Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3195621478,"revision":22858,"compact-revision":20107} {"level":"info","ts":"2026-08-26T01:18:26.83587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25480} {"level":"info","ts":"2026-08-26T01:18:26.966885Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25480,"took":"128.011387ms","hash":2316860932,"current-db-size-bytes":57503744,"current-db-size":"58 MB","current-db-size-in-use-bytes":29442048,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-26T01:18:26.966971Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2316860932,"revision":25480,"compact-revision":22858}