{"level":"warn","ts":"2026-09-19T10:21:43.961645Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T10:21:43.96175Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.91: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.91:2380","--initial-cluster=instance=https://162.253.55.91:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.91:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.91:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-19T10:21:43.961847Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-19T10:21:43.961863Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.91:2380"]} {"level":"info","ts":"2026-09-19T10:21:43.96188Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-19T10:21:43.962463Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"]} {"level":"info","ts":"2026-09-19T10:21:43.962533Z","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":8,"max-cpu-available":8,"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.91:2380"],"listen-peer-urls":["https://162.253.55.91:2380"],"advertise-client-urls":["https://162.253.55.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.91:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-19T10:21:43.966007Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.29786ms"} {"level":"info","ts":"2026-09-19T10:21:43.973746Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8a5b61ed9120d6b3","cluster-id":"9e0c1e8055c016ad"} {"level":"info","ts":"2026-09-19T10:21:43.9738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=()"} {"level":"info","ts":"2026-09-19T10:21:43.973818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became follower at term 0"} {"level":"info","ts":"2026-09-19T10:21:43.973826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8a5b61ed9120d6b3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-19T10:21:43.973837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became follower at term 1"} {"level":"info","ts":"2026-09-19T10:21:43.973866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=(9969669873084716723)"} {"level":"warn","ts":"2026-09-19T10:21:43.978938Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-19T10:21:43.980978Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-19T10:21:43.9825Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-19T10:21:43.984287Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8a5b61ed9120d6b3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-19T10:21:43.984776Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8a5b61ed9120d6b3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-19T10:21:43.984912Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T10:21:43.984957Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T10:21:43.984966Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-19T10:21:43.987151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=(9969669873084716723)"} {"level":"info","ts":"2026-09-19T10:21:43.987477Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9e0c1e8055c016ad","local-member-id":"8a5b61ed9120d6b3","added-peer-id":"8a5b61ed9120d6b3","added-peer-peer-urls":["https://162.253.55.91:2380"]} {"level":"info","ts":"2026-09-19T10:21:43.988847Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-19T10:21:43.989049Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8a5b61ed9120d6b3","initial-advertise-peer-urls":["https://162.253.55.91:2380"],"listen-peer-urls":["https://162.253.55.91:2380"],"advertise-client-urls":["https://162.253.55.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-19T10:21:43.989081Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-19T10:21:43.989183Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.91:2380"} {"level":"info","ts":"2026-09-19T10:21:43.989196Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.91:2380"} {"level":"info","ts":"2026-09-19T10:21:44.474931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 is starting a new election at term 1"} {"level":"info","ts":"2026-09-19T10:21:44.474997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-19T10:21:44.475019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 received MsgPreVoteResp from 8a5b61ed9120d6b3 at term 1"} {"level":"info","ts":"2026-09-19T10:21:44.475049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became candidate at term 2"} {"level":"info","ts":"2026-09-19T10:21:44.47506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 received MsgVoteResp from 8a5b61ed9120d6b3 at term 2"} {"level":"info","ts":"2026-09-19T10:21:44.475076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became leader at term 2"} {"level":"info","ts":"2026-09-19T10:21:44.475089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8a5b61ed9120d6b3 elected leader 8a5b61ed9120d6b3 at term 2"} {"level":"info","ts":"2026-09-19T10:21:44.492823Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8a5b61ed9120d6b3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.91:2379]}","request-path":"/0/members/8a5b61ed9120d6b3/attributes","cluster-id":"9e0c1e8055c016ad","publish-timeout":"7s"} {"level":"info","ts":"2026-09-19T10:21:44.493052Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T10:21:44.493332Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-19T10:21:44.49391Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-19T10:21:44.493942Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-19T10:21:44.496674Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-19T10:21:44.497496Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:21:44.501788Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.91:2379"} {"level":"info","ts":"2026-09-19T10:21:44.550291Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9e0c1e8055c016ad","local-member-id":"8a5b61ed9120d6b3","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:21:44.550485Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:21:44.550582Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-19T10:21:49.110394Z","caller":"traceutil/trace.go:171","msg":"trace[1492970766] linearizableReadLoop","detail":"{readStateIndex:210; appliedIndex:209; }","duration":"251.848156ms","start":"2026-09-19T10:21:48.858524Z","end":"2026-09-19T10:21:49.110372Z","steps":["trace[1492970766] 'read index received' (duration: 251.654739ms)","trace[1492970766] 'applied index is now lower than readState.Index' (duration: 192.737µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:21:49.110477Z","caller":"traceutil/trace.go:171","msg":"trace[1131742701] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"540.673053ms","start":"2026-09-19T10:21:48.569787Z","end":"2026-09-19T10:21:49.11046Z","steps":["trace[1131742701] 'process raft request' (duration: 540.420144ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:21:49.110544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.9702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:21:49.110586Z","caller":"traceutil/trace.go:171","msg":"trace[1171159711] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:204; }","duration":"252.103016ms","start":"2026-09-19T10:21:48.858475Z","end":"2026-09-19T10:21:49.110578Z","steps":["trace[1171159711] 'agreement among raft nodes before linearized reading' (duration: 251.991831ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:21:49.110983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:21:48.569759Z","time spent":"540.762176ms","remote":"127.0.0.1:52678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3694,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:21:49.524628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.021142ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-09-19T10:21:49.524889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:21:49.166944Z","time spent":"357.93952ms","remote":"127.0.0.1:52604","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-09-19T10:21:49.525024Z","caller":"traceutil/trace.go:171","msg":"trace[790717860] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"406.578988ms","start":"2026-09-19T10:21:49.118384Z","end":"2026-09-19T10:21:49.524963Z","steps":["trace[790717860] 'process raft request' (duration: 241.946204ms)","trace[790717860] 'compare' (duration: 163.778442ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:21:49.525166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:21:49.118354Z","time spent":"406.746254ms","remote":"127.0.0.1:52626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:21:49.525263Z","caller":"traceutil/trace.go:171","msg":"trace[763654842] linearizableReadLoop","detail":"{readStateIndex:212; appliedIndex:211; }","duration":"360.020276ms","start":"2026-09-19T10:21:49.165222Z","end":"2026-09-19T10:21:49.525242Z","steps":["trace[763654842] 'read index received' (duration: 195.125182ms)","trace[763654842] 'applied index is now lower than readState.Index' (duration: 164.893424ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:21:49.52551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.270507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-19T10:21:49.525558Z","caller":"traceutil/trace.go:171","msg":"trace[1793928877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:206; }","duration":"360.35968ms","start":"2026-09-19T10:21:49.165184Z","end":"2026-09-19T10:21:49.525544Z","steps":["trace[1793928877] 'agreement among raft nodes before linearized reading' (duration: 360.254366ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:21:49.525595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:21:49.165166Z","time spent":"360.420191ms","remote":"127.0.0.1:52778","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-09-19T10:21:49.525592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.119931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:21:49.525678Z","caller":"traceutil/trace.go:171","msg":"trace[740848400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:206; }","duration":"360.214364ms","start":"2026-09-19T10:21:49.165448Z","end":"2026-09-19T10:21:49.525662Z","steps":["trace[740848400] 'agreement among raft nodes before linearized reading' (duration: 360.073919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:21:49.52572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:21:49.165445Z","time spent":"360.267916ms","remote":"127.0.0.1:52500","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-09-19T10:22:36.741342Z","caller":"traceutil/trace.go:171","msg":"trace[776135735] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"117.629856ms","start":"2026-09-19T10:22:36.62368Z","end":"2026-09-19T10:22:36.74131Z","steps":["trace[776135735] 'read index received' (duration: 115.958282ms)","trace[776135735] 'applied index is now lower than readState.Index' (duration: 1.670774ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:22:36.74201Z","caller":"traceutil/trace.go:171","msg":"trace[1362923695] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"331.27865ms","start":"2026-09-19T10:22:36.410657Z","end":"2026-09-19T10:22:36.741936Z","steps":["trace[1362923695] 'process raft request' (duration: 329.278438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:22:36.742231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.494188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-19T10:22:36.742263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:22:36.410627Z","time spent":"331.512116ms","remote":"127.0.0.1:52626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:22:36.742357Z","caller":"traceutil/trace.go:171","msg":"trace[1648771278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"118.677692ms","start":"2026-09-19T10:22:36.62363Z","end":"2026-09-19T10:22:36.742308Z","steps":["trace[1648771278] 'agreement among raft nodes before linearized reading' (duration: 118.332373ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:22:36.742247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.56991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-19T10:22:36.742635Z","caller":"traceutil/trace.go:171","msg":"trace[1905444988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"107.039893ms","start":"2026-09-19T10:22:36.635559Z","end":"2026-09-19T10:22:36.742598Z","steps":["trace[1905444988] 'agreement among raft nodes before linearized reading' (duration: 106.516579ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:22:36.743087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.535502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:22:36.74326Z","caller":"traceutil/trace.go:171","msg":"trace[323724706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"103.670766ms","start":"2026-09-19T10:22:36.639495Z","end":"2026-09-19T10:22:36.743166Z","steps":["trace[323724706] 'agreement among raft nodes before linearized reading' (duration: 103.369968ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:22:37.338021Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15470885862176893487,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-19T10:22:37.446688Z","caller":"traceutil/trace.go:171","msg":"trace[39533297] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:635; }","duration":"609.352684ms","start":"2026-09-19T10:22:36.837305Z","end":"2026-09-19T10:22:37.446658Z","steps":["trace[39533297] 'read index received' (duration: 557.520024ms)","trace[39533297] 'applied index is now lower than readState.Index' (duration: 51.83136ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:22:37.446841Z","caller":"traceutil/trace.go:171","msg":"trace[577239124] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"617.080364ms","start":"2026-09-19T10:22:36.829739Z","end":"2026-09-19T10:22:37.446819Z","steps":["trace[577239124] 'process raft request' (duration: 565.07918ms)","trace[577239124] 'compare' (duration: 51.417788ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:22:37.446898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.578969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-19T10:22:37.446891Z","caller":"traceutil/trace.go:171","msg":"trace[2022915730] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"209.345478ms","start":"2026-09-19T10:22:37.237442Z","end":"2026-09-19T10:22:37.446788Z","steps":["trace[2022915730] 'process raft request' (duration: 209.119162ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:22:37.446966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:22:36.829713Z","time spent":"617.196326ms","remote":"127.0.0.1:53044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:22:37.446957Z","caller":"traceutil/trace.go:171","msg":"trace[462798312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"609.676002ms","start":"2026-09-19T10:22:36.837264Z","end":"2026-09-19T10:22:37.44694Z","steps":["trace[462798312] 'agreement among raft nodes before linearized reading' (duration: 609.494347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:22:37.44712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:22:36.837251Z","time spent":"609.845616ms","remote":"127.0.0.1:52778","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":"info","ts":"2026-09-19T10:22:54.926213Z","caller":"traceutil/trace.go:171","msg":"trace[2096909649] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"111.759493ms","start":"2026-09-19T10:22:54.814436Z","end":"2026-09-19T10:22:54.926196Z","steps":["trace[2096909649] 'read index received' (duration: 111.545258ms)","trace[2096909649] 'applied index is now lower than readState.Index' (duration: 213.545µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:22:54.926289Z","caller":"traceutil/trace.go:171","msg":"trace[424357676] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"133.498997ms","start":"2026-09-19T10:22:54.792773Z","end":"2026-09-19T10:22:54.926272Z","steps":["trace[424357676] 'process raft request' (duration: 133.269562ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:22:54.926347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.902606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:22:54.926373Z","caller":"traceutil/trace.go:171","msg":"trace[520022120] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:780; }","duration":"111.967398ms","start":"2026-09-19T10:22:54.814398Z","end":"2026-09-19T10:22:54.926365Z","steps":["trace[520022120] 'agreement among raft nodes before linearized reading' (duration: 111.905847ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:22:54.926561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.111891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:22:54.926653Z","caller":"traceutil/trace.go:171","msg":"trace[1681797489] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:780; }","duration":"112.234394ms","start":"2026-09-19T10:22:54.814409Z","end":"2026-09-19T10:22:54.926644Z","steps":["trace[1681797489] 'agreement among raft nodes before linearized reading' (duration: 112.127382ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:22:54.927167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.267546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:22:54.927204Z","caller":"traceutil/trace.go:171","msg":"trace[790844557] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:780; }","duration":"112.337678ms","start":"2026-09-19T10:22:54.814857Z","end":"2026-09-19T10:22:54.927195Z","steps":["trace[790844557] 'agreement among raft nodes before linearized reading' (duration: 112.272726ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:23:43.189558Z","caller":"traceutil/trace.go:171","msg":"trace[2105457215] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"107.821432ms","start":"2026-09-19T10:23:43.081691Z","end":"2026-09-19T10:23:43.189513Z","steps":["trace[2105457215] 'process raft request' (duration: 107.661439ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:23:51.170242Z","caller":"traceutil/trace.go:171","msg":"trace[85476230] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"101.463477ms","start":"2026-09-19T10:23:51.068747Z","end":"2026-09-19T10:23:51.17021Z","steps":["trace[85476230] 'process raft request' (duration: 101.275323ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:13.392513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.237276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3995"} {"level":"info","ts":"2026-09-19T10:24:13.392596Z","caller":"traceutil/trace.go:171","msg":"trace[1465603523] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1829; }","duration":"113.362409ms","start":"2026-09-19T10:24:13.279214Z","end":"2026-09-19T10:24:13.392577Z","steps":["trace[1465603523] 'agreement among raft nodes before linearized reading' (duration: 113.088164ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:13.392592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.743445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/percona-xtradb-pxc\" ","response":"range_response_count:1 size:18155"} {"level":"warn","ts":"2026-09-19T10:24:13.392675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.55872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e8b9466-1962-44a5-b954-80188de3d71b\" ","response":"range_response_count:1 size:2561"} {"level":"info","ts":"2026-09-19T10:24:13.392683Z","caller":"traceutil/trace.go:171","msg":"trace[1874405243] range","detail":"{range_begin:/registry/statefulsets/openstack/percona-xtradb-pxc; range_end:; response_count:1; response_revision:1829; }","duration":"133.881328ms","start":"2026-09-19T10:24:13.258778Z","end":"2026-09-19T10:24:13.392659Z","steps":["trace[1874405243] 'agreement among raft nodes before linearized reading' (duration: 133.585723ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:13.392782Z","caller":"traceutil/trace.go:171","msg":"trace[452474814] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1e8b9466-1962-44a5-b954-80188de3d71b; range_end:; response_count:1; response_revision:1829; }","duration":"138.735613ms","start":"2026-09-19T10:24:13.254027Z","end":"2026-09-19T10:24:13.392762Z","steps":["trace[452474814] 'agreement among raft nodes before linearized reading' (duration: 138.304066ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:13.685366Z","caller":"traceutil/trace.go:171","msg":"trace[1419372609] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"132.355702ms","start":"2026-09-19T10:24:13.552984Z","end":"2026-09-19T10:24:13.685339Z","steps":["trace[1419372609] 'process raft request' (duration: 127.941284ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:14.080275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.19205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:24:14.080498Z","caller":"traceutil/trace.go:171","msg":"trace[533310674] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"380.605964ms","start":"2026-09-19T10:24:13.699857Z","end":"2026-09-19T10:24:14.080463Z","steps":["trace[533310674] 'process raft request' (duration: 168.119339ms)","trace[533310674] 'compare' (duration: 212.005777ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:24:14.080549Z","caller":"traceutil/trace.go:171","msg":"trace[1152563491] linearizableReadLoop","detail":"{readStateIndex:1926; appliedIndex:1924; }","duration":"378.727601ms","start":"2026-09-19T10:24:13.701807Z","end":"2026-09-19T10:24:14.080534Z","steps":["trace[1152563491] 'read index received' (duration: 166.223226ms)","trace[1152563491] 'applied index is now lower than readState.Index' (duration: 212.502755ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:24:14.080616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:13.699842Z","time spent":"380.727537ms","remote":"127.0.0.1:52672","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:24:14.080798Z","caller":"traceutil/trace.go:171","msg":"trace[1843215228] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"380.90933ms","start":"2026-09-19T10:24:13.699873Z","end":"2026-09-19T10:24:14.080783Z","steps":["trace[1843215228] 'process raft request' (duration: 380.510673ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:14.080881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:13.699861Z","time spent":"380.984701ms","remote":"127.0.0.1:52780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1414,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-19T10:24:14.081314Z","caller":"traceutil/trace.go:171","msg":"trace[185151913] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"239.627451ms","start":"2026-09-19T10:24:13.84167Z","end":"2026-09-19T10:24:14.081298Z","steps":["trace[185151913] 'process raft request' (duration: 238.787426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:14.08143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.607837ms","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-09-19T10:24:14.081489Z","caller":"traceutil/trace.go:171","msg":"trace[1180921002] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:1850; }","duration":"379.691728ms","start":"2026-09-19T10:24:13.701783Z","end":"2026-09-19T10:24:14.081474Z","steps":["trace[1180921002] 'agreement among raft nodes before linearized reading' (duration: 379.603716ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:14.081556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:24:13.701773Z","time spent":"379.769829ms","remote":"127.0.0.1:52694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-09-19T10:24:16.353288Z","caller":"traceutil/trace.go:171","msg":"trace[1763663958] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"160.729853ms","start":"2026-09-19T10:24:16.192531Z","end":"2026-09-19T10:24:16.353261Z","steps":["trace[1763663958] 'process raft request' (duration: 134.042508ms)","trace[1763663958] 'compare' (duration: 26.511922ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:24:16.591566Z","caller":"traceutil/trace.go:171","msg":"trace[1605600909] linearizableReadLoop","detail":"{readStateIndex:1953; appliedIndex:1952; }","duration":"185.407073ms","start":"2026-09-19T10:24:16.406134Z","end":"2026-09-19T10:24:16.591541Z","steps":["trace[1605600909] 'read index received' (duration: 179.524901ms)","trace[1605600909] 'applied index is now lower than readState.Index' (duration: 5.881012ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:24:16.591919Z","caller":"traceutil/trace.go:171","msg":"trace[1786945256] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"222.203584ms","start":"2026-09-19T10:24:16.369685Z","end":"2026-09-19T10:24:16.591888Z","steps":["trace[1786945256] 'process raft request' (duration: 215.920445ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:16.592186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.041544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1e8b9466-1962-44a5-b954-80188de3d71b\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-09-19T10:24:16.592266Z","caller":"traceutil/trace.go:171","msg":"trace[1937869572] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1e8b9466-1962-44a5-b954-80188de3d71b; range_end:; response_count:1; response_revision:1876; }","duration":"186.214447ms","start":"2026-09-19T10:24:16.406028Z","end":"2026-09-19T10:24:16.592243Z","steps":["trace[1937869572] 'agreement among raft nodes before linearized reading' (duration: 185.946013ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:16.604446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.325551ms","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-09-19T10:24:16.604521Z","caller":"traceutil/trace.go:171","msg":"trace[1318333107] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1877; }","duration":"193.455414ms","start":"2026-09-19T10:24:16.411048Z","end":"2026-09-19T10:24:16.604504Z","steps":["trace[1318333107] 'agreement among raft nodes before linearized reading' (duration: 193.23383ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:24:16.604669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.155234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-19T10:24:16.604754Z","caller":"traceutil/trace.go:171","msg":"trace[1816114117] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1877; }","duration":"105.289756ms","start":"2026-09-19T10:24:16.49944Z","end":"2026-09-19T10:24:16.60473Z","steps":["trace[1816114117] 'agreement among raft nodes before linearized reading' (duration: 105.011072ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:43.308478Z","caller":"traceutil/trace.go:171","msg":"trace[1089741476] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"110.90253ms","start":"2026-09-19T10:24:43.197549Z","end":"2026-09-19T10:24:43.308452Z","steps":["trace[1089741476] 'process raft request' (duration: 110.765958ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:55.482118Z","caller":"traceutil/trace.go:171","msg":"trace[2131828877] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"110.947704ms","start":"2026-09-19T10:24:55.371116Z","end":"2026-09-19T10:24:55.482064Z","steps":["trace[2131828877] 'process raft request' (duration: 110.747841ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:56.07323Z","caller":"traceutil/trace.go:171","msg":"trace[962236701] linearizableReadLoop","detail":"{readStateIndex:2231; appliedIndex:2229; }","duration":"198.510882ms","start":"2026-09-19T10:24:55.874694Z","end":"2026-09-19T10:24:56.073205Z","steps":["trace[962236701] 'read index received' (duration: 144.892222ms)","trace[962236701] 'applied index is now lower than readState.Index' (duration: 53.61713ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:24:56.073813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.096021ms","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-09-19T10:24:56.073911Z","caller":"traceutil/trace.go:171","msg":"trace[432552299] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2146; }","duration":"199.236093ms","start":"2026-09-19T10:24:55.874652Z","end":"2026-09-19T10:24:56.073888Z","steps":["trace[432552299] 'agreement among raft nodes before linearized reading' (duration: 198.926788ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:56.073515Z","caller":"traceutil/trace.go:171","msg":"trace[1120331836] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"207.201665ms","start":"2026-09-19T10:24:55.866282Z","end":"2026-09-19T10:24:56.073484Z","steps":["trace[1120331836] 'process raft request' (duration: 206.855899ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:24:56.073729Z","caller":"traceutil/trace.go:171","msg":"trace[611888099] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"208.713369ms","start":"2026-09-19T10:24:55.86467Z","end":"2026-09-19T10:24:56.073384Z","steps":["trace[611888099] 'process raft request' (duration: 154.901646ms)","trace[611888099] 'compare' (duration: 53.426737ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:26:32.694598Z","caller":"traceutil/trace.go:171","msg":"trace[1114180727] linearizableReadLoop","detail":"{readStateIndex:2879; appliedIndex:2878; }","duration":"102.475996ms","start":"2026-09-19T10:26:32.5921Z","end":"2026-09-19T10:26:32.694576Z","steps":["trace[1114180727] 'read index received' (duration: 102.296188ms)","trace[1114180727] 'applied index is now lower than readState.Index' (duration: 178.898µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:26:32.694772Z","caller":"traceutil/trace.go:171","msg":"trace[1075744874] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"106.426169ms","start":"2026-09-19T10:26:32.588286Z","end":"2026-09-19T10:26:32.694712Z","steps":["trace[1075744874] 'process raft request' (duration: 106.118617ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:26:32.695008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.891333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-19T10:26:32.695046Z","caller":"traceutil/trace.go:171","msg":"trace[520458122] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2772; }","duration":"102.972456ms","start":"2026-09-19T10:26:32.592064Z","end":"2026-09-19T10:26:32.695037Z","steps":["trace[520458122] 'agreement among raft nodes before linearized reading' (duration: 102.84915ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:26:33.014882Z","caller":"traceutil/trace.go:171","msg":"trace[387629383] linearizableReadLoop","detail":"{readStateIndex:2881; appliedIndex:2880; }","duration":"119.49442ms","start":"2026-09-19T10:26:32.895362Z","end":"2026-09-19T10:26:33.014856Z","steps":["trace[387629383] 'read index received' (duration: 119.25083ms)","trace[387629383] 'applied index is now lower than readState.Index' (duration: 242.73µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:26:33.015092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.708428ms","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-09-19T10:26:33.015152Z","caller":"traceutil/trace.go:171","msg":"trace[1275301387] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2774; }","duration":"119.829463ms","start":"2026-09-19T10:26:32.89531Z","end":"2026-09-19T10:26:33.01514Z","steps":["trace[1275301387] 'agreement among raft nodes before linearized reading' (duration: 119.723829ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:26:33.015352Z","caller":"traceutil/trace.go:171","msg":"trace[1487439198] transaction","detail":"{read_only:false; response_revision:2774; number_of_response:1; }","duration":"129.600477ms","start":"2026-09-19T10:26:32.88571Z","end":"2026-09-19T10:26:33.015311Z","steps":["trace[1487439198] 'process raft request' (duration: 128.966042ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:26:33.043406Z","caller":"traceutil/trace.go:171","msg":"trace[567672635] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"146.636429ms","start":"2026-09-19T10:26:32.896723Z","end":"2026-09-19T10:26:33.04336Z","steps":["trace[567672635] 'process raft request' (duration: 145.612777ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:26:33.043649Z","caller":"traceutil/trace.go:171","msg":"trace[1217578891] transaction","detail":"{read_only:false; response_revision:2776; number_of_response:1; }","duration":"143.696507ms","start":"2026-09-19T10:26:32.899941Z","end":"2026-09-19T10:26:33.043638Z","steps":["trace[1217578891] 'process raft request' (duration: 142.627553ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:26:33.277772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.198496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T10:26:33.278569Z","caller":"traceutil/trace.go:171","msg":"trace[2106156244] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2776; }","duration":"165.971158ms","start":"2026-09-19T10:26:33.112533Z","end":"2026-09-19T10:26:33.278504Z","steps":["trace[2106156244] 'count revisions from in-memory index tree' (duration: 165.102252ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:49.269892Z","caller":"traceutil/trace.go:171","msg":"trace[1124113197] linearizableReadLoop","detail":"{readStateIndex:4694; appliedIndex:4693; }","duration":"178.011577ms","start":"2026-09-19T10:30:49.091848Z","end":"2026-09-19T10:30:49.26986Z","steps":["trace[1124113197] 'read index received' (duration: 161.330565ms)","trace[1124113197] 'applied index is now lower than readState.Index' (duration: 16.679452ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:30:49.270043Z","caller":"traceutil/trace.go:171","msg":"trace[293469053] transaction","detail":"{read_only:false; response_revision:4513; number_of_response:1; }","duration":"182.965027ms","start":"2026-09-19T10:30:49.087049Z","end":"2026-09-19T10:30:49.270014Z","steps":["trace[293469053] 'process raft request' (duration: 166.122551ms)","trace[293469053] 'compare' (duration: 16.224892ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:30:49.270371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.491338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-19T10:30:49.270456Z","caller":"traceutil/trace.go:171","msg":"trace[489013845] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4513; }","duration":"178.622181ms","start":"2026-09-19T10:30:49.091813Z","end":"2026-09-19T10:30:49.270435Z","steps":["trace[489013845] 'agreement among raft nodes before linearized reading' (duration: 178.284404ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:49.304608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.280469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-kube-state-metrics\" ","response":"range_response_count:1 size:1104"} {"level":"info","ts":"2026-09-19T10:30:49.304666Z","caller":"traceutil/trace.go:171","msg":"trace[3037478] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-kube-state-metrics; range_end:; response_count:1; response_revision:4515; }","duration":"185.36739ms","start":"2026-09-19T10:30:49.119283Z","end":"2026-09-19T10:30:49.30465Z","steps":["trace[3037478] 'agreement among raft nodes before linearized reading' (duration: 185.177136ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:49.304841Z","caller":"traceutil/trace.go:171","msg":"trace[750761054] transaction","detail":"{read_only:false; response_revision:4514; number_of_response:1; }","duration":"201.439539ms","start":"2026-09-19T10:30:49.103356Z","end":"2026-09-19T10:30:49.304795Z","steps":["trace[750761054] 'process raft request' (duration: 200.986079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:49.304894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.628461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-operator\" ","response":"range_response_count:1 size:1231"} {"level":"info","ts":"2026-09-19T10:30:49.304944Z","caller":"traceutil/trace.go:171","msg":"trace[1888036759] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-operator; range_end:; response_count:1; response_revision:4515; }","duration":"183.691972ms","start":"2026-09-19T10:30:49.121241Z","end":"2026-09-19T10:30:49.304933Z","steps":["trace[1888036759] 'agreement among raft nodes before linearized reading' (duration: 183.58591ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:49.304931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.54927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus-node-exporter\" ","response":"range_response_count:1 size:1176"} {"level":"info","ts":"2026-09-19T10:30:49.305352Z","caller":"traceutil/trace.go:171","msg":"trace[1769739715] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus-node-exporter; range_end:; response_count:1; response_revision:4515; }","duration":"183.692863ms","start":"2026-09-19T10:30:49.121318Z","end":"2026-09-19T10:30:49.305011Z","steps":["trace[1769739715] 'agreement among raft nodes before linearized reading' (duration: 183.434627ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:49.470438Z","caller":"traceutil/trace.go:171","msg":"trace[1575323479] transaction","detail":"{read_only:false; response_revision:4522; number_of_response:1; }","duration":"116.522747ms","start":"2026-09-19T10:30:49.35389Z","end":"2026-09-19T10:30:49.470412Z","steps":["trace[1575323479] 'process raft request' (duration: 101.030971ms)","trace[1575323479] 'compare' (duration: 15.320351ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:30:49.470797Z","caller":"traceutil/trace.go:171","msg":"trace[609073205] transaction","detail":"{read_only:false; response_revision:4523; number_of_response:1; }","duration":"116.829863ms","start":"2026-09-19T10:30:49.353921Z","end":"2026-09-19T10:30:49.470751Z","steps":["trace[609073205] 'process raft request' (duration: 116.68965ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:49.470956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.69778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/alertmanager-tls\" ","response":"range_response_count:1 size:3186"} {"level":"info","ts":"2026-09-19T10:30:49.470983Z","caller":"traceutil/trace.go:171","msg":"trace[2029790296] transaction","detail":"{read_only:false; response_revision:4524; number_of_response:1; }","duration":"106.572805ms","start":"2026-09-19T10:30:49.364379Z","end":"2026-09-19T10:30:49.470952Z","steps":["trace[2029790296] 'process raft request' (duration: 106.313839ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:49.471015Z","caller":"traceutil/trace.go:171","msg":"trace[432608189] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/alertmanager-tls; range_end:; response_count:1; response_revision:4525; }","duration":"116.799523ms","start":"2026-09-19T10:30:49.354203Z","end":"2026-09-19T10:30:49.471002Z","steps":["trace[432608189] 'agreement among raft nodes before linearized reading' (duration: 116.643539ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:49.470812Z","caller":"traceutil/trace.go:171","msg":"trace[667850299] linearizableReadLoop","detail":"{readStateIndex:4704; appliedIndex:4702; }","duration":"116.540327ms","start":"2026-09-19T10:30:49.354253Z","end":"2026-09-19T10:30:49.470793Z","steps":["trace[667850299] 'read index received' (duration: 100.667443ms)","trace[667850299] 'applied index is now lower than readState.Index' (duration: 15.871934ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:30:49.471097Z","caller":"traceutil/trace.go:171","msg":"trace[2040405998] transaction","detail":"{read_only:false; response_revision:4525; number_of_response:1; }","duration":"104.151181ms","start":"2026-09-19T10:30:49.366911Z","end":"2026-09-19T10:30:49.471063Z","steps":["trace[2040405998] 'process raft request' (duration: 103.832374ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:54.277896Z","caller":"traceutil/trace.go:171","msg":"trace[330980083] transaction","detail":"{read_only:false; response_revision:4700; number_of_response:1; }","duration":"181.921164ms","start":"2026-09-19T10:30:54.095949Z","end":"2026-09-19T10:30:54.27787Z","steps":["trace[330980083] 'process raft request' (duration: 171.893391ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:54.278046Z","caller":"traceutil/trace.go:171","msg":"trace[1365990887] linearizableReadLoop","detail":"{readStateIndex:4885; appliedIndex:4881; }","duration":"172.332121ms","start":"2026-09-19T10:30:54.105691Z","end":"2026-09-19T10:30:54.278023Z","steps":["trace[1365990887] 'read index received' (duration: 162.165705ms)","trace[1365990887] 'applied index is now lower than readState.Index' (duration: 10.165386ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:30:54.278088Z","caller":"traceutil/trace.go:171","msg":"trace[549689777] transaction","detail":"{read_only:false; response_revision:4701; number_of_response:1; }","duration":"181.418633ms","start":"2026-09-19T10:30:54.096631Z","end":"2026-09-19T10:30:54.27805Z","steps":["trace[549689777] 'process raft request' (duration: 181.203188ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:54.278153Z","caller":"traceutil/trace.go:171","msg":"trace[350141845] transaction","detail":"{read_only:false; response_revision:4702; number_of_response:1; }","duration":"181.345511ms","start":"2026-09-19T10:30:54.096796Z","end":"2026-09-19T10:30:54.278142Z","steps":["trace[350141845] 'process raft request' (duration: 181.136476ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:54.27846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.75247ms","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-09-19T10:30:54.278666Z","caller":"traceutil/trace.go:171","msg":"trace[2010288634] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"180.237196ms","start":"2026-09-19T10:30:54.098412Z","end":"2026-09-19T10:30:54.27865Z","steps":["trace[2010288634] 'process raft request' (duration: 179.562911ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:54.278706Z","caller":"traceutil/trace.go:171","msg":"trace[892736593] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:4703; }","duration":"172.868183ms","start":"2026-09-19T10:30:54.10566Z","end":"2026-09-19T10:30:54.278528Z","steps":["trace[892736593] 'agreement among raft nodes before linearized reading' (duration: 172.663549ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:54.281391Z","caller":"traceutil/trace.go:171","msg":"trace[1618129851] transaction","detail":"{read_only:false; response_revision:4704; number_of_response:1; }","duration":"175.495991ms","start":"2026-09-19T10:30:54.105872Z","end":"2026-09-19T10:30:54.281368Z","steps":["trace[1618129851] 'process raft request' (duration: 174.045759ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:30:54.281449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.788304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:46 size:1954231"} {"level":"info","ts":"2026-09-19T10:30:54.281903Z","caller":"traceutil/trace.go:171","msg":"trace[126546979] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:46; response_revision:4704; }","duration":"105.260264ms","start":"2026-09-19T10:30:54.176607Z","end":"2026-09-19T10:30:54.281867Z","steps":["trace[126546979] 'agreement among raft nodes before linearized reading' (duration: 103.459324ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:30:59.456128Z","caller":"traceutil/trace.go:171","msg":"trace[1430568394] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"154.222993ms","start":"2026-09-19T10:30:59.301882Z","end":"2026-09-19T10:30:59.456105Z","steps":["trace[1430568394] 'process raft request' (duration: 154.057489ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:04.679765Z","caller":"traceutil/trace.go:171","msg":"trace[1172650728] linearizableReadLoop","detail":"{readStateIndex:5119; appliedIndex:5118; }","duration":"120.189719ms","start":"2026-09-19T10:31:04.559526Z","end":"2026-09-19T10:31:04.679715Z","steps":["trace[1172650728] 'read index received' (duration: 139.223µs)","trace[1172650728] 'applied index is now lower than readState.Index' (duration: 120.046986ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:31:04.67985Z","caller":"traceutil/trace.go:171","msg":"trace[1842938019] transaction","detail":"{read_only:false; response_revision:4928; number_of_response:1; }","duration":"120.315082ms","start":"2026-09-19T10:31:04.559508Z","end":"2026-09-19T10:31:04.679824Z","steps":["trace[1842938019] 'process raft request' (duration: 119.990115ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:04.680004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.460424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:service-account-issuer-discovery\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-09-19T10:31:04.680065Z","caller":"traceutil/trace.go:171","msg":"trace[1889584156] range","detail":"{range_begin:/registry/clusterroles/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:4928; }","duration":"120.548107ms","start":"2026-09-19T10:31:04.559503Z","end":"2026-09-19T10:31:04.680051Z","steps":["trace[1889584156] 'agreement among raft nodes before linearized reading' (duration: 120.386814ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:04.717998Z","caller":"traceutil/trace.go:171","msg":"trace[2136728036] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"138.138633ms","start":"2026-09-19T10:31:04.57985Z","end":"2026-09-19T10:31:04.717988Z","steps":["trace[2136728036] 'process raft request' (duration: 138.074851ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:04.718265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.603743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-09-19T10:31:04.718318Z","caller":"traceutil/trace.go:171","msg":"trace[1029663710] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4929; }","duration":"158.669194ms","start":"2026-09-19T10:31:04.559632Z","end":"2026-09-19T10:31:04.718301Z","steps":["trace[1029663710] 'agreement among raft nodes before linearized reading' (duration: 158.491569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:06.135537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.508373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:31:06.13566Z","caller":"traceutil/trace.go:171","msg":"trace[185630640] linearizableReadLoop","detail":"{readStateIndex:5159; appliedIndex:5158; }","duration":"214.44321ms","start":"2026-09-19T10:31:05.921201Z","end":"2026-09-19T10:31:06.135644Z","steps":["trace[185630640] 'read index received' (duration: 14.753613ms)","trace[185630640] 'applied index is now lower than readState.Index' (duration: 199.688447ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:31:06.135935Z","caller":"traceutil/trace.go:171","msg":"trace[1551200707] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"273.244302ms","start":"2026-09-19T10:31:05.862654Z","end":"2026-09-19T10:31:06.135898Z","steps":["trace[1551200707] 'process raft request' (duration: 73.291038ms)","trace[1551200707] 'compare' (duration: 199.36406ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:31:06.136104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.899802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-19T10:31:06.13618Z","caller":"traceutil/trace.go:171","msg":"trace[1019870256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4968; }","duration":"215.026835ms","start":"2026-09-19T10:31:05.921136Z","end":"2026-09-19T10:31:06.136163Z","steps":["trace[1019870256] 'agreement among raft nodes before linearized reading' (duration: 214.81125ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:06.518889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.566995ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-19T10:31:06.519112Z","caller":"traceutil/trace.go:171","msg":"trace[1144372273] linearizableReadLoop","detail":"{readStateIndex:5161; appliedIndex:5159; }","duration":"383.416754ms","start":"2026-09-19T10:31:06.135683Z","end":"2026-09-19T10:31:06.5191Z","steps":["trace[1144372273] 'read index received' (duration: 125.582473ms)","trace[1144372273] 'applied index is now lower than readState.Index' (duration: 257.833471ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:31:06.519104Z","caller":"traceutil/trace.go:171","msg":"trace[1651525939] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"556.935317ms","start":"2026-09-19T10:31:05.962146Z","end":"2026-09-19T10:31:06.519081Z","steps":["trace[1651525939] 'process raft request' (duration: 299.075345ms)","trace[1651525939] 'compare' (duration: 257.382681ms)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:31:06.519247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:05.962122Z","time spent":"557.081269ms","remote":"127.0.0.1:52778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:31:06.519313Z","caller":"traceutil/trace.go:171","msg":"trace[324504163] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"490.258265ms","start":"2026-09-19T10:31:06.029043Z","end":"2026-09-19T10:31:06.519301Z","steps":["trace[324504163] 'process raft request' (duration: 489.942929ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:06.519369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:06.029011Z","time spent":"490.325376ms","remote":"127.0.0.1:52778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-19T10:31:06.51949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.59965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4f1dad11-ea22-42fa-accd-f4ba10499535\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-19T10:31:06.519535Z","caller":"traceutil/trace.go:171","msg":"trace[119753665] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4f1dad11-ea22-42fa-accd-f4ba10499535; range_end:; response_count:1; response_revision:4971; }","duration":"489.648712ms","start":"2026-09-19T10:31:06.029874Z","end":"2026-09-19T10:31:06.519523Z","steps":["trace[119753665] 'agreement among raft nodes before linearized reading' (duration: 489.476787ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:06.519565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:06.029836Z","time spent":"489.722154ms","remote":"127.0.0.1:52680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4f1dad11-ea22-42fa-accd-f4ba10499535\" "} {"level":"warn","ts":"2026-09-19T10:31:06.519593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.965472ms","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-09-19T10:31:06.519607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.708375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12972"} {"level":"info","ts":"2026-09-19T10:31:06.519636Z","caller":"traceutil/trace.go:171","msg":"trace[1319722002] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4971; }","duration":"333.767406ms","start":"2026-09-19T10:31:06.185858Z","end":"2026-09-19T10:31:06.519626Z","steps":["trace[1319722002] 'agreement among raft nodes before linearized reading' (duration: 333.675194ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:06.519626Z","caller":"traceutil/trace.go:171","msg":"trace[736782850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4971; }","duration":"403.020063ms","start":"2026-09-19T10:31:06.116595Z","end":"2026-09-19T10:31:06.519615Z","steps":["trace[736782850] 'agreement among raft nodes before linearized reading' (duration: 402.874889ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:06.519667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:06.185837Z","time spent":"333.824447ms","remote":"127.0.0.1:52680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":12996,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-09-19T10:31:06.519708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:06.116585Z","time spent":"403.089384ms","remote":"127.0.0.1:52778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-19T10:31:06.519784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.13983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-19T10:31:06.519819Z","caller":"traceutil/trace.go:171","msg":"trace[391361835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4971; }","duration":"410.245972ms","start":"2026-09-19T10:31:06.109564Z","end":"2026-09-19T10:31:06.51981Z","steps":["trace[391361835] 'agreement among raft nodes before linearized reading' (duration: 410.1588ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:06.519843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:06.109537Z","time spent":"410.299693ms","remote":"127.0.0.1:52778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-19T10:31:06.519822Z","caller":"traceutil/trace.go:171","msg":"trace[1157366119] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"379.310844ms","start":"2026-09-19T10:31:06.1405Z","end":"2026-09-19T10:31:06.519811Z","steps":["trace[1157366119] 'process raft request' (duration: 378.552127ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:06.520073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.691855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-19T10:31:06.520112Z","caller":"traceutil/trace.go:171","msg":"trace[498976437] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4971; }","duration":"229.774866ms","start":"2026-09-19T10:31:06.290328Z","end":"2026-09-19T10:31:06.520103Z","steps":["trace[498976437] 'agreement among raft nodes before linearized reading' (duration: 229.715034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:06.520203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.235829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-09-19T10:31:06.520282Z","caller":"traceutil/trace.go:171","msg":"trace[1406561791] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4971; }","duration":"294.363162ms","start":"2026-09-19T10:31:06.225905Z","end":"2026-09-19T10:31:06.520268Z","steps":["trace[1406561791] 'agreement among raft nodes before linearized reading' (duration: 294.176488ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:06.519908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-19T10:31:06.140479Z","time spent":"379.396746ms","remote":"127.0.0.1:52778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-19T10:31:06.658737Z","caller":"traceutil/trace.go:171","msg":"trace[1216088601] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"133.016055ms","start":"2026-09-19T10:31:06.525688Z","end":"2026-09-19T10:31:06.658704Z","steps":["trace[1216088601] 'process raft request' (duration: 112.987616ms)","trace[1216088601] 'compare' (duration: 19.695672ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:31:06.65875Z","caller":"traceutil/trace.go:171","msg":"trace[2029637981] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"133.021705ms","start":"2026-09-19T10:31:06.5257Z","end":"2026-09-19T10:31:06.658722Z","steps":["trace[2029637981] 'process raft request' (duration: 132.898203ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:06.659115Z","caller":"traceutil/trace.go:171","msg":"trace[1144650204] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"120.91964ms","start":"2026-09-19T10:31:06.538184Z","end":"2026-09-19T10:31:06.659104Z","steps":["trace[1144650204] 'process raft request' (duration: 120.48389ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:10.863805Z","caller":"traceutil/trace.go:171","msg":"trace[989849032] linearizableReadLoop","detail":"{readStateIndex:5231; appliedIndex:5230; }","duration":"224.86426ms","start":"2026-09-19T10:31:10.638917Z","end":"2026-09-19T10:31:10.863781Z","steps":["trace[989849032] 'read index received' (duration: 224.708067ms)","trace[989849032] 'applied index is now lower than readState.Index' (duration: 155.233µs)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:31:10.863886Z","caller":"traceutil/trace.go:171","msg":"trace[459958180] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"248.503246ms","start":"2026-09-19T10:31:10.615352Z","end":"2026-09-19T10:31:10.863855Z","steps":["trace[459958180] 'process raft request' (duration: 248.289921ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:10.86396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.024153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:31:10.86401Z","caller":"traceutil/trace.go:171","msg":"trace[1693861053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5039; }","duration":"225.108966ms","start":"2026-09-19T10:31:10.638889Z","end":"2026-09-19T10:31:10.863998Z","steps":["trace[1693861053] 'agreement among raft nodes before linearized reading' (duration: 225.008794ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:10.865028Z","caller":"traceutil/trace.go:171","msg":"trace[660444726] transaction","detail":"{read_only:false; response_revision:5040; number_of_response:1; }","duration":"109.149953ms","start":"2026-09-19T10:31:10.755856Z","end":"2026-09-19T10:31:10.865006Z","steps":["trace[660444726] 'process raft request' (duration: 108.99574ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:10.865248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.001061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-19T10:31:10.865297Z","caller":"traceutil/trace.go:171","msg":"trace[1193242216] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:5040; }","duration":"120.086682ms","start":"2026-09-19T10:31:10.745199Z","end":"2026-09-19T10:31:10.865285Z","steps":["trace[1193242216] 'agreement among raft nodes before linearized reading' (duration: 119.99705ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:10.865376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.330578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-19T10:31:10.865449Z","caller":"traceutil/trace.go:171","msg":"trace[111991676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5040; }","duration":"191.43181ms","start":"2026-09-19T10:31:10.674002Z","end":"2026-09-19T10:31:10.865434Z","steps":["trace[111991676] 'agreement among raft nodes before linearized reading' (duration: 191.230705ms)"],"step_count":1} {"level":"warn","ts":"2026-09-19T10:31:10.865572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.307134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-19T10:31:10.865668Z","caller":"traceutil/trace.go:171","msg":"trace[575761919] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5040; }","duration":"189.418896ms","start":"2026-09-19T10:31:10.676233Z","end":"2026-09-19T10:31:10.865652Z","steps":["trace[575761919] 'agreement among raft nodes before linearized reading' (duration: 189.227842ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:14.361065Z","caller":"traceutil/trace.go:171","msg":"trace[1955304784] transaction","detail":"{read_only:false; response_revision:5078; number_of_response:1; }","duration":"103.444961ms","start":"2026-09-19T10:31:14.257603Z","end":"2026-09-19T10:31:14.361048Z","steps":["trace[1955304784] 'process raft request' (duration: 103.345609ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:20.517936Z","caller":"traceutil/trace.go:171","msg":"trace[332822241] linearizableReadLoop","detail":"{readStateIndex:5355; appliedIndex:5354; }","duration":"124.121527ms","start":"2026-09-19T10:31:20.393786Z","end":"2026-09-19T10:31:20.517908Z","steps":["trace[332822241] 'read index received' (duration: 123.899152ms)","trace[332822241] 'applied index is now lower than readState.Index' (duration: 221.485µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:31:20.518162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.361933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-19T10:31:20.5182Z","caller":"traceutil/trace.go:171","msg":"trace[1401493378] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5158; }","duration":"124.465435ms","start":"2026-09-19T10:31:20.393724Z","end":"2026-09-19T10:31:20.51819Z","steps":["trace[1401493378] 'agreement among raft nodes before linearized reading' (duration: 124.288561ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:20.51864Z","caller":"traceutil/trace.go:171","msg":"trace[1497778110] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"143.698101ms","start":"2026-09-19T10:31:20.374929Z","end":"2026-09-19T10:31:20.518627Z","steps":["trace[1497778110] 'process raft request' (duration: 142.825092ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:20.773021Z","caller":"traceutil/trace.go:171","msg":"trace[656375805] transaction","detail":"{read_only:false; response_revision:5165; number_of_response:1; }","duration":"106.314452ms","start":"2026-09-19T10:31:20.666674Z","end":"2026-09-19T10:31:20.772989Z","steps":["trace[656375805] 'process raft request' (duration: 53.219392ms)","trace[656375805] 'compare' (duration: 52.963056ms)"],"step_count":2} {"level":"info","ts":"2026-09-19T10:31:44.540555Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2833} {"level":"info","ts":"2026-09-19T10:31:44.610853Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2833,"took":"69.360604ms","hash":2399043105,"current-db-size-bytes":52088832,"current-db-size":"52 MB","current-db-size-in-use-bytes":27475968,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-19T10:31:44.610909Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2399043105,"revision":2833,"compact-revision":-1} {"level":"info","ts":"2026-09-19T10:31:55.10529Z","caller":"traceutil/trace.go:171","msg":"trace[1434730288] transaction","detail":"{read_only:false; response_revision:5473; number_of_response:1; }","duration":"126.678062ms","start":"2026-09-19T10:31:54.97859Z","end":"2026-09-19T10:31:55.105268Z","steps":["trace[1434730288] 'process raft request' (duration: 126.55864ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:31:55.105335Z","caller":"traceutil/trace.go:171","msg":"trace[237961687] linearizableReadLoop","detail":"{readStateIndex:5679; appliedIndex:5679; }","duration":"114.70706ms","start":"2026-09-19T10:31:54.990593Z","end":"2026-09-19T10:31:55.1053Z","steps":["trace[237961687] 'read index received' (duration: 114.69416ms)","trace[237961687] 'applied index is now lower than readState.Index' (duration: 10.38µs)"],"step_count":2} {"level":"warn","ts":"2026-09-19T10:31:55.105643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.012066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-09-19T10:31:55.105688Z","caller":"traceutil/trace.go:171","msg":"trace[2105711570] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5473; }","duration":"115.126209ms","start":"2026-09-19T10:31:54.990548Z","end":"2026-09-19T10:31:55.105675Z","steps":["trace[2105711570] 'agreement among raft nodes before linearized reading' (duration: 114.884924ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:35:19.139523Z","caller":"traceutil/trace.go:171","msg":"trace[2090444277] transaction","detail":"{read_only:false; response_revision:6906; number_of_response:1; }","duration":"178.897446ms","start":"2026-09-19T10:35:18.960603Z","end":"2026-09-19T10:35:19.139501Z","steps":["trace[2090444277] 'process raft request' (duration: 178.773829ms)"],"step_count":1} {"level":"info","ts":"2026-09-19T10:36:44.550493Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5317} {"level":"info","ts":"2026-09-19T10:36:44.626027Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5317,"took":"74.095483ms","hash":3590628059,"current-db-size-bytes":52088832,"current-db-size":"52 MB","current-db-size-in-use-bytes":18489344,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-19T10:36:44.626089Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3590628059,"revision":5317,"compact-revision":2833}