{"level":"warn","ts":"2026-04-18T09:04:13.952174Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-18T09:04:13.952363Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-18T09:04:13.952525Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-18T09:04:13.952549Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-04-18T09:04:13.952684Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-18T09:04:13.954605Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-04-18T09:04:13.95475Z","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://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-18T09:04:13.961868Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.576161ms"} {"level":"info","ts":"2026-04-18T09:04:13.978499Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-04-18T09:04:13.978738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-04-18T09:04:13.978819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-04-18T09:04:13.978873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-18T09:04:13.978937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-04-18T09:04:13.979055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-04-18T09:04:13.987102Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-18T09:04:13.988629Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-18T09:04:13.989919Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-18T09:04:13.992431Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-18T09:04:13.999475Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-18T09:04:14.002617Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-18T09:04:14.002747Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-18T09:04:14.002765Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-18T09:04:14.002948Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-18T09:04:14.003057Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-04-18T09:04:14.00309Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-04-18T09:04:14.003403Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-18T09:04:14.00345Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-18T09:04:14.005376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-04-18T09:04:14.005682Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-04-18T09:04:14.781401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-04-18T09:04:14.781457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-04-18T09:04:14.781505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-04-18T09:04:14.781525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-04-18T09:04:14.781551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-04-18T09:04:14.781578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-04-18T09:04:14.781598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-04-18T09:04:14.782826Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-04-18T09:04:14.782863Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T09:04:14.782902Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:04:14.782988Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T09:04:14.783933Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:04:14.784062Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:04:14.784103Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T09:04:14.785627Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-18T09:04:14.786127Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-04-18T09:04:14.786682Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-18T09:04:14.786776Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-04-18T09:04:44.183565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.492479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:04:44.18369Z","caller":"traceutil/trace.go:171","msg":"trace[825104015] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"197.402654ms","start":"2026-04-18T09:04:43.98627Z","end":"2026-04-18T09:04:44.183673Z","steps":["trace[825104015] 'process raft request' (duration: 92.375881ms)","trace[825104015] 'compare' (duration: 104.372225ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:04:44.185589Z","caller":"traceutil/trace.go:171","msg":"trace[388805393] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"130.773547ms","start":"2026-04-18T09:04:44.054799Z","end":"2026-04-18T09:04:44.185573Z","steps":["trace[388805393] 'process raft request' (duration: 130.730106ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:04:44.186009Z","caller":"traceutil/trace.go:171","msg":"trace[1204994275] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"163.090721ms","start":"2026-04-18T09:04:44.022883Z","end":"2026-04-18T09:04:44.185973Z","steps":["trace[1204994275] 'process raft request' (duration: 162.346651ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:04:44.186146Z","caller":"traceutil/trace.go:171","msg":"trace[650357494] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"163.179664ms","start":"2026-04-18T09:04:44.022953Z","end":"2026-04-18T09:04:44.186132Z","steps":["trace[650357494] 'process raft request' (duration: 162.353391ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:04:44.186243Z","caller":"traceutil/trace.go:171","msg":"trace[468521648] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"155.013599ms","start":"2026-04-18T09:04:44.03122Z","end":"2026-04-18T09:04:44.186233Z","steps":["trace[468521648] 'process raft request' (duration: 154.116335ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:04:44.186286Z","caller":"traceutil/trace.go:171","msg":"trace[1719129824] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"179.278854ms","start":"2026-04-18T09:04:44.006995Z","end":"2026-04-18T09:04:44.186274Z","steps":["trace[1719129824] 'process raft request' (duration: 177.921517ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:04:44.186369Z","caller":"traceutil/trace.go:171","msg":"trace[2034786857] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"151.532135ms","start":"2026-04-18T09:04:44.034828Z","end":"2026-04-18T09:04:44.18636Z","steps":["trace[2034786857] 'process raft request' (duration: 150.539088ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:04:44.186523Z","caller":"traceutil/trace.go:171","msg":"trace[1704552856] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"151.639388ms","start":"2026-04-18T09:04:44.034875Z","end":"2026-04-18T09:04:44.186514Z","steps":["trace[1704552856] 'process raft request' (duration: 150.531648ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:04:44.186562Z","caller":"traceutil/trace.go:171","msg":"trace[1345042113] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"172.107537ms","start":"2026-04-18T09:04:44.014443Z","end":"2026-04-18T09:04:44.186551Z","steps":["trace[1345042113] 'process raft request' (duration: 170.557934ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:04:44.18671Z","caller":"traceutil/trace.go:171","msg":"trace[1957104651] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"168.403947ms","start":"2026-04-18T09:04:44.018296Z","end":"2026-04-18T09:04:44.1867Z","steps":["trace[1957104651] 'process raft request' (duration: 166.843364ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:04:44.186802Z","caller":"traceutil/trace.go:171","msg":"trace[327187462] transaction","detail":"{read_only:false; number_of_response:1; response_revision:476; }","duration":"163.996665ms","start":"2026-04-18T09:04:44.022797Z","end":"2026-04-18T09:04:44.186794Z","steps":["trace[327187462] 'process raft request' (duration: 162.398832ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:04:44.186962Z","caller":"traceutil/trace.go:171","msg":"trace[1503130367] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"141.860841ms","start":"2026-04-18T09:04:44.04504Z","end":"2026-04-18T09:04:44.1869Z","steps":["trace[1503130367] 'process raft request' (duration: 140.408802ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:04:44.192568Z","caller":"traceutil/trace.go:171","msg":"trace[2091247368] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"113.120174ms","start":"2026-04-18T09:04:44.079429Z","end":"2026-04-18T09:04:44.192549Z","steps":["trace[2091247368] 'process raft request' (duration: 112.433015ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:04:50.470556Z","caller":"traceutil/trace.go:171","msg":"trace[1076710261] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"147.852841ms","start":"2026-04-18T09:04:50.32267Z","end":"2026-04-18T09:04:50.470523Z","steps":["trace[1076710261] 'process raft request' (duration: 147.006119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:04:51.050534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.236283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:04:51.050681Z","caller":"traceutil/trace.go:171","msg":"trace[887658255] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"475.193467ms","start":"2026-04-18T09:04:50.575457Z","end":"2026-04-18T09:04:51.05065Z","steps":["trace[887658255] 'read index received' (duration: 232.653897ms)","trace[887658255] 'applied index is now lower than readState.Index' (duration: 242.53769ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:04:51.050769Z","caller":"traceutil/trace.go:171","msg":"trace[1799303052] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"518.86087ms","start":"2026-04-18T09:04:50.53185Z","end":"2026-04-18T09:04:51.050711Z","steps":["trace[1799303052] 'process raft request' (duration: 276.32555ms)","trace[1799303052] 'compare' (duration: 242.092348ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:04:51.050969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.509896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T09:04:51.051022Z","caller":"traceutil/trace.go:171","msg":"trace[1770065228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"475.635168ms","start":"2026-04-18T09:04:50.575369Z","end":"2026-04-18T09:04:51.051004Z","steps":["trace[1770065228] 'agreement among raft nodes before linearized reading' (duration: 475.404202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:04:51.051122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.914069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zfw9k\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-18T09:04:51.051087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:04:50.575301Z","time spent":"475.755262ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-18T09:04:51.051188Z","caller":"traceutil/trace.go:171","msg":"trace[655134498] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zfw9k; range_end:; response_count:1; response_revision:582; }","duration":"221.004751ms","start":"2026-04-18T09:04:50.830163Z","end":"2026-04-18T09:04:51.051168Z","steps":["trace[655134498] 'agreement among raft nodes before linearized reading' (duration: 220.822297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:04:51.051301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:04:50.531836Z","time spent":"519.083585ms","remote":"127.0.0.1:47668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T09:06:19.174883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.776199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:06:19.174991Z","caller":"traceutil/trace.go:171","msg":"trace[243058595] linearizableReadLoop","detail":"{readStateIndex:1859; appliedIndex:1858; }","duration":"227.456184ms","start":"2026-04-18T09:06:18.947517Z","end":"2026-04-18T09:06:19.174974Z","steps":["trace[243058595] 'read index received' (duration: 57.354219ms)","trace[243058595] 'applied index is now lower than readState.Index' (duration: 170.100825ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:06:19.175157Z","caller":"traceutil/trace.go:171","msg":"trace[220601252] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"229.026695ms","start":"2026-04-18T09:06:18.946103Z","end":"2026-04-18T09:06:19.17513Z","steps":["trace[220601252] 'process raft request' (duration: 58.789377ms)","trace[220601252] 'compare' (duration: 169.351481ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:06:19.175325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.526165ms","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-04-18T09:06:19.175413Z","caller":"traceutil/trace.go:171","msg":"trace[1127387345] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1793; }","duration":"129.669808ms","start":"2026-04-18T09:06:19.04572Z","end":"2026-04-18T09:06:19.17539Z","steps":["trace[1127387345] 'agreement among raft nodes before linearized reading' (duration: 129.377052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:19.175433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.76549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-18T09:06:19.175544Z","caller":"traceutil/trace.go:171","msg":"trace[1158735796] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1793; }","duration":"187.904403ms","start":"2026-04-18T09:06:18.987626Z","end":"2026-04-18T09:06:19.17553Z","steps":["trace[1158735796] 'agreement among raft nodes before linearized reading' (duration: 187.448285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:19.17527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.733619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T09:06:19.176239Z","caller":"traceutil/trace.go:171","msg":"trace[227336520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1793; }","duration":"228.720818ms","start":"2026-04-18T09:06:18.947491Z","end":"2026-04-18T09:06:19.176212Z","steps":["trace[227336520] 'agreement among raft nodes before linearized reading' (duration: 227.577877ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:06:27.497415Z","caller":"traceutil/trace.go:171","msg":"trace[960306731] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1944; }","duration":"110.220749ms","start":"2026-04-18T09:06:27.38717Z","end":"2026-04-18T09:06:27.497391Z","steps":["trace[960306731] 'read index received' (duration: 110.107347ms)","trace[960306731] 'applied index is now lower than readState.Index' (duration: 112.232µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:06:27.497541Z","caller":"traceutil/trace.go:171","msg":"trace[2144356957] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"122.497957ms","start":"2026-04-18T09:06:27.375032Z","end":"2026-04-18T09:06:27.49753Z","steps":["trace[2144356957] 'process raft request' (duration: 122.191052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:27.497696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.505084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T09:06:27.497755Z","caller":"traceutil/trace.go:171","msg":"trace[472098722] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1876; }","duration":"110.605185ms","start":"2026-04-18T09:06:27.387136Z","end":"2026-04-18T09:06:27.497741Z","steps":["trace[472098722] 'agreement among raft nodes before linearized reading' (duration: 110.510864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:27.498025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.789199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-04-18T09:06:27.498126Z","caller":"traceutil/trace.go:171","msg":"trace[511980326] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1876; }","duration":"110.932752ms","start":"2026-04-18T09:06:27.387168Z","end":"2026-04-18T09:06:27.4981Z","steps":["trace[511980326] 'agreement among raft nodes before linearized reading' (duration: 110.701198ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:06:52.6419Z","caller":"traceutil/trace.go:171","msg":"trace[1458131753] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"176.181196ms","start":"2026-04-18T09:06:52.465686Z","end":"2026-04-18T09:06:52.641867Z","steps":["trace[1458131753] 'process raft request' (duration: 110.213951ms)","trace[1458131753] 'compare' (duration: 65.815193ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:06:53.247873Z","caller":"traceutil/trace.go:171","msg":"trace[518792506] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"141.268297ms","start":"2026-04-18T09:06:53.106542Z","end":"2026-04-18T09:06:53.247811Z","steps":["trace[518792506] 'process raft request' (duration: 140.952721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:53.57557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.34138ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-18T09:06:53.575873Z","caller":"traceutil/trace.go:171","msg":"trace[1439018034] linearizableReadLoop","detail":"{readStateIndex:2109; appliedIndex:2108; }","duration":"244.306756ms","start":"2026-04-18T09:06:53.331547Z","end":"2026-04-18T09:06:53.575854Z","steps":["trace[1439018034] 'read index received' (duration: 38.24µs)","trace[1439018034] 'applied index is now lower than readState.Index' (duration: 244.267486ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:06:53.57609Z","caller":"traceutil/trace.go:171","msg":"trace[1877067295] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"159.525854ms","start":"2026-04-18T09:06:53.416517Z","end":"2026-04-18T09:06:53.576042Z","steps":["trace[1877067295] 'process raft request' (duration: 159.150128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:53.606047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.367209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-18T09:06:53.606124Z","caller":"traceutil/trace.go:171","msg":"trace[920401368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2035; }","duration":"273.459611ms","start":"2026-04-18T09:06:53.332646Z","end":"2026-04-18T09:06:53.606106Z","steps":["trace[920401368] 'agreement among raft nodes before linearized reading' (duration: 243.3795ms)","trace[920401368] 'range keys from in-memory index tree' (duration: 29.894987ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:06:53.606092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.988055ms","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-04-18T09:06:53.606182Z","caller":"traceutil/trace.go:171","msg":"trace[1928420566] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2035; }","duration":"213.125567ms","start":"2026-04-18T09:06:53.393032Z","end":"2026-04-18T09:06:53.606157Z","steps":["trace[1928420566] 'agreement among raft nodes before linearized reading' (duration: 183.020496ms)","trace[1928420566] 'range keys from in-memory index tree' (duration: 29.900057ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:06:53.606294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.716352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T09:06:53.60638Z","caller":"traceutil/trace.go:171","msg":"trace[1768284624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2035; }","duration":"274.846235ms","start":"2026-04-18T09:06:53.331507Z","end":"2026-04-18T09:06:53.606353Z","steps":["trace[1768284624] 'agreement among raft nodes before linearized reading' (duration: 244.451059ms)","trace[1768284624] 'range keys from in-memory index tree' (duration: 30.165862ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:06:53.895385Z","caller":"traceutil/trace.go:171","msg":"trace[1452982598] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"287.32937ms","start":"2026-04-18T09:06:53.608026Z","end":"2026-04-18T09:06:53.895355Z","steps":["trace[1452982598] 'process raft request' (duration: 287.168647ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:06:53.903138Z","caller":"traceutil/trace.go:171","msg":"trace[1243083738] linearizableReadLoop","detail":"{readStateIndex:2113; appliedIndex:2110; }","duration":"200.047212ms","start":"2026-04-18T09:06:53.703071Z","end":"2026-04-18T09:06:53.903118Z","steps":["trace[1243083738] 'read index received' (duration: 191.57486ms)","trace[1243083738] 'applied index is now lower than readState.Index' (duration: 8.471422ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:06:53.903227Z","caller":"traceutil/trace.go:171","msg":"trace[1911721615] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"293.627142ms","start":"2026-04-18T09:06:53.609592Z","end":"2026-04-18T09:06:53.90322Z","steps":["trace[1911721615] 'process raft request' (duration: 293.48122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:53.903399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.516259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T09:06:53.903501Z","caller":"traceutil/trace.go:171","msg":"trace[2047980444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2038; }","duration":"113.6331ms","start":"2026-04-18T09:06:53.789815Z","end":"2026-04-18T09:06:53.903448Z","steps":["trace[2047980444] 'agreement among raft nodes before linearized reading' (duration: 113.427257ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:06:53.903431Z","caller":"traceutil/trace.go:171","msg":"trace[196738843] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"293.989349ms","start":"2026-04-18T09:06:53.609374Z","end":"2026-04-18T09:06:53.903363Z","steps":["trace[196738843] 'process raft request' (duration: 293.528941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:06:53.904234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.132071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T09:06:53.904304Z","caller":"traceutil/trace.go:171","msg":"trace[754960909] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:2038; }","duration":"201.263614ms","start":"2026-04-18T09:06:53.703021Z","end":"2026-04-18T09:06:53.904285Z","steps":["trace[754960909] 'agreement among raft nodes before linearized reading' (duration: 201.125801ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:07:50.33369Z","caller":"traceutil/trace.go:171","msg":"trace[638704594] linearizableReadLoop","detail":"{readStateIndex:2480; appliedIndex:2479; }","duration":"158.978341ms","start":"2026-04-18T09:07:50.174689Z","end":"2026-04-18T09:07:50.333667Z","steps":["trace[638704594] 'read index received' (duration: 158.783928ms)","trace[638704594] 'applied index is now lower than readState.Index' (duration: 193.553µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:07:50.333796Z","caller":"traceutil/trace.go:171","msg":"trace[1071684206] transaction","detail":"{read_only:false; response_revision:2392; number_of_response:1; }","duration":"163.370916ms","start":"2026-04-18T09:07:50.170387Z","end":"2026-04-18T09:07:50.333758Z","steps":["trace[1071684206] 'process raft request' (duration: 163.151523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:07:50.333965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.264986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-18T09:07:50.334005Z","caller":"traceutil/trace.go:171","msg":"trace[1188130350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2392; }","duration":"159.355007ms","start":"2026-04-18T09:07:50.17464Z","end":"2026-04-18T09:07:50.333995Z","steps":["trace[1188130350] 'agreement among raft nodes before linearized reading' (duration: 159.192515ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:07:50.337493Z","caller":"traceutil/trace.go:171","msg":"trace[729484839] transaction","detail":"{read_only:false; response_revision:2393; number_of_response:1; }","duration":"122.842933ms","start":"2026-04-18T09:07:50.214639Z","end":"2026-04-18T09:07:50.337481Z","steps":["trace[729484839] 'process raft request' (duration: 121.301357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:07:50.337777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.352307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-18T09:07:50.337934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.566322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-18T09:07:50.33797Z","caller":"traceutil/trace.go:171","msg":"trace[1727568728] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2393; }","duration":"155.623463ms","start":"2026-04-18T09:07:50.182339Z","end":"2026-04-18T09:07:50.337962Z","steps":["trace[1727568728] 'agreement among raft nodes before linearized reading' (duration: 155.530321ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:07:50.338044Z","caller":"traceutil/trace.go:171","msg":"trace[519081973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2393; }","duration":"159.641132ms","start":"2026-04-18T09:07:50.178377Z","end":"2026-04-18T09:07:50.338018Z","steps":["trace[519081973] 'agreement among raft nodes before linearized reading' (duration: 159.059522ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:08:01.017291Z","caller":"traceutil/trace.go:171","msg":"trace[1775733422] transaction","detail":"{read_only:false; response_revision:2500; number_of_response:1; }","duration":"133.499423ms","start":"2026-04-18T09:08:00.883738Z","end":"2026-04-18T09:08:01.017237Z","steps":["trace[1775733422] 'process raft request' (duration: 133.131257ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:08:11.031022Z","caller":"traceutil/trace.go:171","msg":"trace[745852121] transaction","detail":"{read_only:false; response_revision:2557; number_of_response:1; }","duration":"181.697097ms","start":"2026-04-18T09:08:10.849307Z","end":"2026-04-18T09:08:11.031004Z","steps":["trace[745852121] 'process raft request' (duration: 181.585601ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:08:11.037018Z","caller":"traceutil/trace.go:171","msg":"trace[979136922] transaction","detail":"{read_only:false; response_revision:2558; number_of_response:1; }","duration":"102.864753ms","start":"2026-04-18T09:08:10.93413Z","end":"2026-04-18T09:08:11.036995Z","steps":["trace[979136922] 'process raft request' (duration: 102.392581ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:08:17.228612Z","caller":"traceutil/trace.go:171","msg":"trace[936993048] transaction","detail":"{read_only:false; response_revision:2591; number_of_response:1; }","duration":"171.463256ms","start":"2026-04-18T09:08:17.057127Z","end":"2026-04-18T09:08:17.22859Z","steps":["trace[936993048] 'process raft request' (duration: 171.283418ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:08:17.229214Z","caller":"traceutil/trace.go:171","msg":"trace[569136080] linearizableReadLoop","detail":"{readStateIndex:2685; appliedIndex:2683; }","duration":"120.071932ms","start":"2026-04-18T09:08:17.109117Z","end":"2026-04-18T09:08:17.229189Z","steps":["trace[569136080] 'read index received' (duration: 119.386841ms)","trace[569136080] 'applied index is now lower than readState.Index' (duration: 683.991µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:08:17.229568Z","caller":"traceutil/trace.go:171","msg":"trace[287450982] transaction","detail":"{read_only:false; response_revision:2592; number_of_response:1; }","duration":"163.941074ms","start":"2026-04-18T09:08:17.065614Z","end":"2026-04-18T09:08:17.229555Z","steps":["trace[287450982] 'process raft request' (duration: 163.447381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:08:17.2298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.667139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:08:17.229834Z","caller":"traceutil/trace.go:171","msg":"trace[439628079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2592; }","duration":"120.735522ms","start":"2026-04-18T09:08:17.109089Z","end":"2026-04-18T09:08:17.229825Z","steps":["trace[439628079] 'agreement among raft nodes before linearized reading' (duration: 120.669189ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:08:21.270374Z","caller":"traceutil/trace.go:171","msg":"trace[763623240] transaction","detail":"{read_only:false; response_revision:2609; number_of_response:1; }","duration":"390.785271ms","start":"2026-04-18T09:08:20.879564Z","end":"2026-04-18T09:08:21.270349Z","steps":["trace[763623240] 'process raft request' (duration: 390.618534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:08:21.270614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:08:20.879341Z","time spent":"391.103206ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:08:21.271575Z","caller":"traceutil/trace.go:171","msg":"trace[1382776828] transaction","detail":"{read_only:false; response_revision:2610; number_of_response:1; }","duration":"105.840491ms","start":"2026-04-18T09:08:21.165694Z","end":"2026-04-18T09:08:21.271535Z","steps":["trace[1382776828] 'process raft request' (duration: 105.529398ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:08:22.963522Z","caller":"traceutil/trace.go:171","msg":"trace[833532726] linearizableReadLoop","detail":"{readStateIndex:2711; appliedIndex:2710; }","duration":"276.559696ms","start":"2026-04-18T09:08:22.686946Z","end":"2026-04-18T09:08:22.963506Z","steps":["trace[833532726] 'read index received' (duration: 276.461562ms)","trace[833532726] 'applied index is now lower than readState.Index' (duration: 97.564µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:08:22.96358Z","caller":"traceutil/trace.go:171","msg":"trace[948497717] transaction","detail":"{read_only:false; response_revision:2617; number_of_response:1; }","duration":"313.280788ms","start":"2026-04-18T09:08:22.650281Z","end":"2026-04-18T09:08:22.963562Z","steps":["trace[948497717] 'process raft request' (duration: 313.133261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:08:22.963669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.134338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T09:08:22.963693Z","caller":"traceutil/trace.go:171","msg":"trace[1492833606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2617; }","duration":"276.18479ms","start":"2026-04-18T09:08:22.687498Z","end":"2026-04-18T09:08:22.963683Z","steps":["trace[1492833606] 'agreement among raft nodes before linearized reading' (duration: 276.092136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:08:22.963689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:08:22.650256Z","time spent":"313.364921ms","remote":"127.0.0.1:47416","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-04-18T09:08:22.963785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.663466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-18T09:08:22.963806Z","caller":"traceutil/trace.go:171","msg":"trace[638841798] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2617; }","duration":"275.703339ms","start":"2026-04-18T09:08:22.688096Z","end":"2026-04-18T09:08:22.9638Z","steps":["trace[638841798] 'agreement among raft nodes before linearized reading' (duration: 275.659386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:08:22.963882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.917043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-18T09:08:22.963961Z","caller":"traceutil/trace.go:171","msg":"trace[954210695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2617; }","duration":"277.0922ms","start":"2026-04-18T09:08:22.686847Z","end":"2026-04-18T09:08:22.96394Z","steps":["trace[954210695] 'agreement among raft nodes before linearized reading' (duration: 276.752884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:08:23.780541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555605226925502770,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T09:08:23.941357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.062783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:08:23.941464Z","caller":"traceutil/trace.go:171","msg":"trace[1171504809] transaction","detail":"{read_only:false; response_revision:2618; number_of_response:1; }","duration":"972.068818ms","start":"2026-04-18T09:08:22.969373Z","end":"2026-04-18T09:08:23.941442Z","steps":["trace[1171504809] 'process raft request' (duration: 62.846607ms)","trace[1171504809] 'compare' (duration: 907.657932ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:08:23.941602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:08:22.969348Z","time spent":"972.147562ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:08:23.944533Z","caller":"traceutil/trace.go:171","msg":"trace[1059642034] transaction","detail":"{read_only:false; response_revision:2619; number_of_response:1; }","duration":"974.853821ms","start":"2026-04-18T09:08:22.969653Z","end":"2026-04-18T09:08:23.944507Z","steps":["trace[1059642034] 'process raft request' (duration: 974.722545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:08:23.94472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:08:22.969639Z","time spent":"974.979686ms","remote":"127.0.0.1:47416","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-04-18T09:08:23.952646Z","caller":"traceutil/trace.go:171","msg":"trace[1046285618] linearizableReadLoop","detail":"{readStateIndex:2717; appliedIndex:2712; }","duration":"672.952169ms","start":"2026-04-18T09:08:23.279679Z","end":"2026-04-18T09:08:23.952631Z","steps":["trace[1046285618] 'read index received' (duration: 664.702566ms)","trace[1046285618] 'applied index is now lower than readState.Index' (duration: 8.248513ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:08:23.952832Z","caller":"traceutil/trace.go:171","msg":"trace[997629475] transaction","detail":"{read_only:false; response_revision:2622; number_of_response:1; }","duration":"666.820408ms","start":"2026-04-18T09:08:23.285998Z","end":"2026-04-18T09:08:23.952819Z","steps":["trace[997629475] 'process raft request' (duration: 666.606867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:08:23.952983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:08:23.285986Z","time spent":"666.907982ms","remote":"127.0.0.1:47416","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-04-18T09:08:23.953038Z","caller":"traceutil/trace.go:171","msg":"trace[384454916] transaction","detail":"{read_only:false; response_revision:2621; number_of_response:1; }","duration":"676.065917ms","start":"2026-04-18T09:08:23.276948Z","end":"2026-04-18T09:08:23.953013Z","steps":["trace[384454916] 'process raft request' (duration: 675.630527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:08:23.953122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.907657ms","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":"warn","ts":"2026-04-18T09:08:23.953145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:08:23.276928Z","time spent":"676.156851ms","remote":"127.0.0.1:47416","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-04-18T09:08:23.953166Z","caller":"traceutil/trace.go:171","msg":"trace[277486239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2622; }","duration":"280.99338ms","start":"2026-04-18T09:08:23.672162Z","end":"2026-04-18T09:08:23.953156Z","steps":["trace[277486239] 'agreement among raft nodes before linearized reading' (duration: 280.865065ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:08:23.953354Z","caller":"traceutil/trace.go:171","msg":"trace[578936565] transaction","detail":"{read_only:false; response_revision:2620; number_of_response:1; }","duration":"687.037013ms","start":"2026-04-18T09:08:23.265952Z","end":"2026-04-18T09:08:23.952989Z","steps":["trace[578936565] 'process raft request' (duration: 686.53769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:08:23.953493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.231058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-18T09:08:23.953542Z","caller":"traceutil/trace.go:171","msg":"trace[1654868439] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2622; }","duration":"672.284459ms","start":"2026-04-18T09:08:23.281237Z","end":"2026-04-18T09:08:23.953521Z","steps":["trace[1654868439] 'agreement among raft nodes before linearized reading' (duration: 671.624561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:08:23.953566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:08:23.281229Z","time spent":"672.331432ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-18T09:08:23.953403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.718424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-18T09:08:23.953694Z","caller":"traceutil/trace.go:171","msg":"trace[1631224260] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2622; }","duration":"674.029368ms","start":"2026-04-18T09:08:23.279652Z","end":"2026-04-18T09:08:23.953681Z","steps":["trace[1631224260] 'agreement among raft nodes before linearized reading' (duration: 673.681762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:08:23.953734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:08:23.279642Z","time spent":"674.080379ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-18T09:08:23.953427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.174733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T09:08:23.953832Z","caller":"traceutil/trace.go:171","msg":"trace[413619340] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2622; }","duration":"348.614302ms","start":"2026-04-18T09:08:23.60521Z","end":"2026-04-18T09:08:23.953824Z","steps":["trace[413619340] 'agreement among raft nodes before linearized reading' (duration: 348.165572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:08:23.95386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:08:23.605192Z","time spent":"348.658784ms","remote":"127.0.0.1:47346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":24,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-04-18T09:08:23.954027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:08:23.26593Z","time spent":"687.571167ms","remote":"127.0.0.1:47416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:08:26.763357Z","caller":"traceutil/trace.go:171","msg":"trace[1991695074] transaction","detail":"{read_only:false; response_revision:2637; number_of_response:1; }","duration":"331.286228ms","start":"2026-04-18T09:08:26.432009Z","end":"2026-04-18T09:08:26.763295Z","steps":["trace[1991695074] 'process raft request' (duration: 331.09207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:08:26.763549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:08:26.431978Z","time spent":"331.469286ms","remote":"127.0.0.1:47256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":764,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T09:08:27.11159Z","caller":"traceutil/trace.go:171","msg":"trace[483880774] linearizableReadLoop","detail":"{readStateIndex:2733; appliedIndex:2732; }","duration":"333.784276ms","start":"2026-04-18T09:08:26.777788Z","end":"2026-04-18T09:08:27.111572Z","steps":["trace[483880774] 'read index received' (duration: 333.6346ms)","trace[483880774] 'applied index is now lower than readState.Index' (duration: 149.136µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:08:27.111808Z","caller":"traceutil/trace.go:171","msg":"trace[2077455999] transaction","detail":"{read_only:false; response_revision:2638; number_of_response:1; }","duration":"559.232023ms","start":"2026-04-18T09:08:26.552534Z","end":"2026-04-18T09:08:27.111766Z","steps":["trace[2077455999] 'process raft request' (duration: 558.93337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:08:27.11186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.065729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:08:27.112033Z","caller":"traceutil/trace.go:171","msg":"trace[2080231810] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:2638; }","duration":"334.265087ms","start":"2026-04-18T09:08:26.777744Z","end":"2026-04-18T09:08:27.112009Z","steps":["trace[2080231810] 'agreement among raft nodes before linearized reading' (duration: 333.890331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:08:27.112028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:08:26.552504Z","time spent":"559.434521ms","remote":"127.0.0.1:47342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9267,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T09:08:27.112062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T09:08:26.777727Z","time spent":"334.32707ms","remote":"127.0.0.1:37520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-04-18T09:08:27.52256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.208242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T09:08:27.522644Z","caller":"traceutil/trace.go:171","msg":"trace[520151393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2638; }","duration":"219.331198ms","start":"2026-04-18T09:08:27.303293Z","end":"2026-04-18T09:08:27.522625Z","steps":["trace[520151393] 'range keys from in-memory index tree' (duration: 219.169751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:08:27.522899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.796441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T09:08:27.522982Z","caller":"traceutil/trace.go:171","msg":"trace[1546604792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2638; }","duration":"234.925407ms","start":"2026-04-18T09:08:27.288037Z","end":"2026-04-18T09:08:27.522962Z","steps":["trace[1546604792] 'range keys from in-memory index tree' (duration: 234.630934ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:21.45689Z","caller":"traceutil/trace.go:171","msg":"trace[98123768] linearizableReadLoop","detail":"{readStateIndex:4580; appliedIndex:4579; }","duration":"116.694819ms","start":"2026-04-18T09:12:21.340175Z","end":"2026-04-18T09:12:21.456869Z","steps":["trace[98123768] 'read index received' (duration: 72.785647ms)","trace[98123768] 'applied index is now lower than readState.Index' (duration: 43.908332ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:12:21.456975Z","caller":"traceutil/trace.go:171","msg":"trace[634723270] transaction","detail":"{read_only:false; response_revision:4412; number_of_response:1; }","duration":"123.363977ms","start":"2026-04-18T09:12:21.333602Z","end":"2026-04-18T09:12:21.456966Z","steps":["trace[634723270] 'process raft request' (duration: 79.365593ms)","trace[634723270] 'compare' (duration: 43.81168ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:12:21.457281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.096788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-tls-cb64d\" ","response":"range_response_count:1 size:2456"} {"level":"info","ts":"2026-04-18T09:12:21.45739Z","caller":"traceutil/trace.go:171","msg":"trace[1184488702] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-tls-cb64d; range_end:; response_count:1; response_revision:4412; }","duration":"117.247222ms","start":"2026-04-18T09:12:21.340118Z","end":"2026-04-18T09:12:21.457366Z","steps":["trace[1184488702] 'agreement among raft nodes before linearized reading' (duration: 117.010966ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:21.517245Z","caller":"traceutil/trace.go:171","msg":"trace[133460085] transaction","detail":"{read_only:false; response_revision:4413; number_of_response:1; }","duration":"113.748779ms","start":"2026-04-18T09:12:21.403419Z","end":"2026-04-18T09:12:21.517167Z","steps":["trace[133460085] 'process raft request' (duration: 113.581305ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:21.571944Z","caller":"traceutil/trace.go:171","msg":"trace[1554103278] transaction","detail":"{read_only:false; response_revision:4414; number_of_response:1; }","duration":"121.243236ms","start":"2026-04-18T09:12:21.450681Z","end":"2026-04-18T09:12:21.571924Z","steps":["trace[1554103278] 'process raft request' (duration: 120.962669ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:21.572167Z","caller":"traceutil/trace.go:171","msg":"trace[1452213977] linearizableReadLoop","detail":"{readStateIndex:4582; appliedIndex:4580; }","duration":"115.194193ms","start":"2026-04-18T09:12:21.456947Z","end":"2026-04-18T09:12:21.572141Z","steps":["trace[1452213977] 'read index received' (duration: 60.002404ms)","trace[1452213977] 'applied index is now lower than readState.Index' (duration: 55.190719ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:12:21.572253Z","caller":"traceutil/trace.go:171","msg":"trace[1509471734] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4415; }","duration":"111.934186ms","start":"2026-04-18T09:12:21.460286Z","end":"2026-04-18T09:12:21.57222Z","steps":["trace[1509471734] 'process raft request' (duration: 111.608338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:21.572387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.535187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-tls\" ","response":"range_response_count:1 size:3226"} {"level":"info","ts":"2026-04-18T09:12:21.572473Z","caller":"traceutil/trace.go:171","msg":"trace[628665936] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-tls; range_end:; response_count:1; response_revision:4415; }","duration":"125.65972ms","start":"2026-04-18T09:12:21.446794Z","end":"2026-04-18T09:12:21.572453Z","steps":["trace[628665936] 'agreement among raft nodes before linearized reading' (duration: 125.433575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:34.66333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.080869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:12:34.66421Z","caller":"traceutil/trace.go:171","msg":"trace[1563254936] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"263.438594ms","start":"2026-04-18T09:12:34.400701Z","end":"2026-04-18T09:12:34.664139Z","steps":["trace[1563254936] 'process raft request' (duration: 114.484114ms)","trace[1563254936] 'compare' (duration: 147.959726ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:12:34.664303Z","caller":"traceutil/trace.go:171","msg":"trace[542579087] linearizableReadLoop","detail":"{readStateIndex:4892; appliedIndex:4891; }","duration":"259.532543ms","start":"2026-04-18T09:12:34.404739Z","end":"2026-04-18T09:12:34.664271Z","steps":["trace[542579087] 'read index received' (duration: 110.374049ms)","trace[542579087] 'applied index is now lower than readState.Index' (duration: 149.137194ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T09:12:34.664621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.854131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9635"} {"level":"info","ts":"2026-04-18T09:12:34.664665Z","caller":"traceutil/trace.go:171","msg":"trace[443167218] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4716; }","duration":"259.950223ms","start":"2026-04-18T09:12:34.404703Z","end":"2026-04-18T09:12:34.664653Z","steps":["trace[443167218] 'agreement among raft nodes before linearized reading' (duration: 259.759659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:34.665108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.539019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-18T09:12:34.665983Z","caller":"traceutil/trace.go:171","msg":"trace[466511022] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4716; }","duration":"214.546212ms","start":"2026-04-18T09:12:34.451417Z","end":"2026-04-18T09:12:34.665963Z","steps":["trace[466511022] 'agreement among raft nodes before linearized reading' (duration: 213.089399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:34.666997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.187844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2348486"} {"level":"info","ts":"2026-04-18T09:12:34.667067Z","caller":"traceutil/trace.go:171","msg":"trace[794841007] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4716; }","duration":"187.656484ms","start":"2026-04-18T09:12:34.47939Z","end":"2026-04-18T09:12:34.667046Z","steps":["trace[794841007] 'agreement among raft nodes before linearized reading' (duration: 185.761719ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:12:34.975016Z","caller":"traceutil/trace.go:171","msg":"trace[146634580] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"149.275309ms","start":"2026-04-18T09:12:34.825711Z","end":"2026-04-18T09:12:34.974986Z","steps":["trace[146634580] 'process raft request' (duration: 98.785709ms)","trace[146634580] 'compare' (duration: 50.242094ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:12:34.975094Z","caller":"traceutil/trace.go:171","msg":"trace[565386111] linearizableReadLoop","detail":"{readStateIndex:4903; appliedIndex:4902; }","duration":"140.78269ms","start":"2026-04-18T09:12:34.834261Z","end":"2026-04-18T09:12:34.975043Z","steps":["trace[565386111] 'read index received' (duration: 90.182288ms)","trace[565386111] 'applied index is now lower than readState.Index' (duration: 50.596022ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:12:34.975242Z","caller":"traceutil/trace.go:171","msg":"trace[869643156] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"112.583ms","start":"2026-04-18T09:12:34.862633Z","end":"2026-04-18T09:12:34.975216Z","steps":["trace[869643156] 'process raft request' (duration: 112.283153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:34.975608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.404001ms","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-04-18T09:12:34.975658Z","caller":"traceutil/trace.go:171","msg":"trace[773701269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4728; }","duration":"122.488292ms","start":"2026-04-18T09:12:34.853155Z","end":"2026-04-18T09:12:34.975644Z","steps":["trace[773701269] 'agreement among raft nodes before linearized reading' (duration: 122.257687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:34.975691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.519004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-04-18T09:12:34.975758Z","caller":"traceutil/trace.go:171","msg":"trace[210497477] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4728; }","duration":"158.599956ms","start":"2026-04-18T09:12:34.817138Z","end":"2026-04-18T09:12:34.975738Z","steps":["trace[210497477] 'agreement among raft nodes before linearized reading' (duration: 158.118165ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:34.976281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.525906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-18T09:12:34.976601Z","caller":"traceutil/trace.go:171","msg":"trace[975836599] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4728; }","duration":"138.623219ms","start":"2026-04-18T09:12:34.837698Z","end":"2026-04-18T09:12:34.976321Z","steps":["trace[975836599] 'agreement among raft nodes before linearized reading' (duration: 137.654086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T09:12:35.262449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.28468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T09:12:35.26263Z","caller":"traceutil/trace.go:171","msg":"trace[1422717975] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"158.99919ms","start":"2026-04-18T09:12:35.103594Z","end":"2026-04-18T09:12:35.262593Z","steps":["trace[1422717975] 'process raft request' (duration: 16.490114ms)","trace[1422717975] 'compare' (duration: 142.074576ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:13:35.344573Z","caller":"traceutil/trace.go:171","msg":"trace[1435200638] transaction","detail":"{read_only:false; response_revision:5317; number_of_response:1; }","duration":"112.580786ms","start":"2026-04-18T09:13:35.231972Z","end":"2026-04-18T09:13:35.344553Z","steps":["trace[1435200638] 'process raft request' (duration: 112.504764ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T09:13:35.345143Z","caller":"traceutil/trace.go:171","msg":"trace[749801037] transaction","detail":"{read_only:false; response_revision:5316; number_of_response:1; }","duration":"136.310379ms","start":"2026-04-18T09:13:35.208806Z","end":"2026-04-18T09:13:35.345116Z","steps":["trace[749801037] 'process raft request' (duration: 89.303841ms)","trace[749801037] 'compare' (duration: 46.255482ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T09:14:14.806715Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2892} {"level":"info","ts":"2026-04-18T09:14:14.875402Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2892,"took":"67.34982ms","hash":3184129096,"current-db-size-bytes":55103488,"current-db-size":"55 MB","current-db-size-in-use-bytes":30011392,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-18T09:14:14.8755Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3184129096,"revision":2892,"compact-revision":-1} {"level":"info","ts":"2026-04-18T09:19:14.813108Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5629} {"level":"info","ts":"2026-04-18T09:19:14.89607Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5629,"took":"81.563984ms","hash":1817091640,"current-db-size-bytes":55103488,"current-db-size":"55 MB","current-db-size-in-use-bytes":18464768,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-18T09:19:14.896136Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1817091640,"revision":5629,"compact-revision":2892}