{"level":"warn","ts":"2026-09-18T15:22:33.870039Z","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-18T15:22:33.870119Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.162: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.162:2380","--initial-cluster=instance=https://162.253.55.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.162: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-18T15:22:33.870192Z","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-18T15:22:33.870202Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.162:2380"]} {"level":"info","ts":"2026-09-18T15:22:33.870221Z","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-18T15:22:33.870875Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.162:2379"]} {"level":"info","ts":"2026-09-18T15:22:33.870971Z","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.162:2380"],"listen-peer-urls":["https://162.253.55.162:2380"],"advertise-client-urls":["https://162.253.55.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.162: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-18T15:22:33.877958Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.772696ms"} {"level":"info","ts":"2026-09-18T15:22:33.885072Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f61dce011a423b00","cluster-id":"d363ade277557413"} {"level":"info","ts":"2026-09-18T15:22:33.885177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T15:22:33.885209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became follower at term 0"} {"level":"info","ts":"2026-09-18T15:22:33.885301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f61dce011a423b00 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T15:22:33.885443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became follower at term 1"} {"level":"info","ts":"2026-09-18T15:22:33.885588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 switched to configuration voters=(17734557411785718528)"} {"level":"warn","ts":"2026-09-18T15:22:33.892732Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T15:22:33.894013Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T15:22:33.895764Z","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-18T15:22:33.897812Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f61dce011a423b00","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T15:22:33.897993Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f61dce011a423b00","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T15:22:33.898161Z","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-18T15:22:33.898303Z","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-18T15:22:33.89834Z","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-18T15:22:33.90513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 switched to configuration voters=(17734557411785718528)"} {"level":"info","ts":"2026-09-18T15:22:33.906053Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d363ade277557413","local-member-id":"f61dce011a423b00","added-peer-id":"f61dce011a423b00","added-peer-peer-urls":["https://162.253.55.162:2380"]} {"level":"info","ts":"2026-09-18T15:22:33.906253Z","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-18T15:22:33.906409Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.162:2380"} {"level":"info","ts":"2026-09-18T15:22:33.906468Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f61dce011a423b00","initial-advertise-peer-urls":["https://162.253.55.162:2380"],"listen-peer-urls":["https://162.253.55.162:2380"],"advertise-client-urls":["https://162.253.55.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T15:22:33.906541Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T15:22:33.906475Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.162:2380"} {"level":"info","ts":"2026-09-18T15:22:34.885915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T15:22:34.886034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T15:22:34.886061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 received MsgPreVoteResp from f61dce011a423b00 at term 1"} {"level":"info","ts":"2026-09-18T15:22:34.886097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became candidate at term 2"} {"level":"info","ts":"2026-09-18T15:22:34.886114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 received MsgVoteResp from f61dce011a423b00 at term 2"} {"level":"info","ts":"2026-09-18T15:22:34.886131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f61dce011a423b00 became leader at term 2"} {"level":"info","ts":"2026-09-18T15:22:34.886186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f61dce011a423b00 elected leader f61dce011a423b00 at term 2"} {"level":"info","ts":"2026-09-18T15:22:34.88743Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f61dce011a423b00","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.162:2379]}","request-path":"/0/members/f61dce011a423b00/attributes","cluster-id":"d363ade277557413","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T15:22:34.88741Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:22:34.887442Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T15:22:34.887526Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T15:22:34.887918Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T15:22:34.887953Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T15:22:34.888566Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d363ade277557413","local-member-id":"f61dce011a423b00","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:22:34.893516Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:22:34.893621Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T15:22:34.89545Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T15:22:34.895455Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.162:2379"} {"level":"info","ts":"2026-09-18T15:23:16.127057Z","caller":"traceutil/trace.go:171","msg":"trace[1794432143] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"232.832815ms","start":"2026-09-18T15:23:15.894204Z","end":"2026-09-18T15:23:16.127037Z","steps":["trace[1794432143] 'process raft request' (duration: 232.726112ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:16.133402Z","caller":"traceutil/trace.go:171","msg":"trace[1914926243] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"235.532602ms","start":"2026-09-18T15:23:15.897845Z","end":"2026-09-18T15:23:16.133378Z","steps":["trace[1914926243] 'process raft request' (duration: 235.371917ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:16.133429Z","caller":"traceutil/trace.go:171","msg":"trace[659533609] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"235.46725ms","start":"2026-09-18T15:23:15.897932Z","end":"2026-09-18T15:23:16.133399Z","steps":["trace[659533609] 'process raft request' (duration: 235.371278ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:16.134437Z","caller":"traceutil/trace.go:171","msg":"trace[892681080] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"234.288466ms","start":"2026-09-18T15:23:15.899822Z","end":"2026-09-18T15:23:16.134111Z","steps":["trace[892681080] 'process raft request' (duration: 233.540654ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:16.134618Z","caller":"traceutil/trace.go:171","msg":"trace[1414701168] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"231.259988ms","start":"2026-09-18T15:23:15.903266Z","end":"2026-09-18T15:23:16.134526Z","steps":["trace[1414701168] 'process raft request' (duration: 230.123145ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:16.135489Z","caller":"traceutil/trace.go:171","msg":"trace[944236800] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"229.088904ms","start":"2026-09-18T15:23:15.906249Z","end":"2026-09-18T15:23:16.135338Z","steps":["trace[944236800] 'process raft request' (duration: 228.59549ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:16.136268Z","caller":"traceutil/trace.go:171","msg":"trace[361932137] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"228.322583ms","start":"2026-09-18T15:23:15.907688Z","end":"2026-09-18T15:23:16.136011Z","steps":["trace[361932137] 'process raft request' (duration: 227.613182ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:16.138381Z","caller":"traceutil/trace.go:171","msg":"trace[1535389928] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"224.81315ms","start":"2026-09-18T15:23:15.913534Z","end":"2026-09-18T15:23:16.138347Z","steps":["trace[1535389928] 'process raft request' (duration: 223.06307ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:16.14036Z","caller":"traceutil/trace.go:171","msg":"trace[853696605] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"211.517974ms","start":"2026-09-18T15:23:15.927133Z","end":"2026-09-18T15:23:16.138651Z","steps":["trace[853696605] 'process raft request' (duration: 211.199024ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:16.140912Z","caller":"traceutil/trace.go:171","msg":"trace[1911861374] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"203.100939ms","start":"2026-09-18T15:23:15.937801Z","end":"2026-09-18T15:23:16.140902Z","steps":["trace[1911861374] 'process raft request' (duration: 200.860904ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:16.141095Z","caller":"traceutil/trace.go:171","msg":"trace[1531114749] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"196.409954ms","start":"2026-09-18T15:23:15.944676Z","end":"2026-09-18T15:23:16.141086Z","steps":["trace[1531114749] 'process raft request' (duration: 195.874739ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:22.600177Z","caller":"traceutil/trace.go:171","msg":"trace[2147238661] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"279.529077ms","start":"2026-09-18T15:23:22.320621Z","end":"2026-09-18T15:23:22.60015Z","steps":["trace[2147238661] 'process raft request' (duration: 278.884699ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:23.284756Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4251574747969233520,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-18T15:23:23.396979Z","caller":"traceutil/trace.go:171","msg":"trace[250744236] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"622.394439ms","start":"2026-09-18T15:23:22.774466Z","end":"2026-09-18T15:23:23.396861Z","steps":["trace[250744236] 'process raft request' (duration: 589.243323ms)","trace[250744236] 'compare' (duration: 32.493189ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:23:23.397131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.838797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b6958\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-18T15:23:23.397244Z","caller":"traceutil/trace.go:171","msg":"trace[1283251721] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b6958; range_end:; response_count:1; response_revision:648; }","duration":"612.993631ms","start":"2026-09-18T15:23:22.784226Z","end":"2026-09-18T15:23:23.39722Z","steps":["trace[1283251721] 'agreement among raft nodes before linearized reading' (duration: 612.695412ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:23.397293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:22.784205Z","time spent":"613.073673ms","remote":"127.0.0.1:34106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-b6958\" "} {"level":"info","ts":"2026-09-18T15:23:23.396876Z","caller":"traceutil/trace.go:171","msg":"trace[715389696] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"612.545038ms","start":"2026-09-18T15:23:22.784261Z","end":"2026-09-18T15:23:23.396806Z","steps":["trace[715389696] 'read index received' (duration: 579.35289ms)","trace[715389696] 'applied index is now lower than readState.Index' (duration: 33.190468ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:23:23.397922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.669614ms","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-18T15:23:23.397978Z","caller":"traceutil/trace.go:171","msg":"trace[146892478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"430.760426ms","start":"2026-09-18T15:23:22.967201Z","end":"2026-09-18T15:23:23.397962Z","steps":["trace[146892478] 'agreement among raft nodes before linearized reading' (duration: 430.621463ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:23.397999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.132143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-18T15:23:23.398024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:22.967177Z","time spent":"430.8352ms","remote":"127.0.0.1:34180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-18T15:23:23.398064Z","caller":"traceutil/trace.go:171","msg":"trace[1409818540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"152.239258ms","start":"2026-09-18T15:23:23.245806Z","end":"2026-09-18T15:23:23.398045Z","steps":["trace[1409818540] 'agreement among raft nodes before linearized reading' (duration: 152.132474ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:23.398166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.346526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T15:23:23.398189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.553025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-18T15:23:23.398214Z","caller":"traceutil/trace.go:171","msg":"trace[342554210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"317.425698ms","start":"2026-09-18T15:23:23.080775Z","end":"2026-09-18T15:23:23.398201Z","steps":["trace[342554210] 'agreement among raft nodes before linearized reading' (duration: 317.360056ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:23.398251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:23.080754Z","time spent":"317.487279ms","remote":"127.0.0.1:33924","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-18T15:23:23.398237Z","caller":"traceutil/trace.go:171","msg":"trace[189019764] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"293.639027ms","start":"2026-09-18T15:23:23.104583Z","end":"2026-09-18T15:23:23.398222Z","steps":["trace[189019764] 'agreement among raft nodes before linearized reading' (duration: 293.521734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:23.398455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:23:22.774435Z","time spent":"622.665658ms","remote":"127.0.0.1:34106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T15:23:39.683749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.43336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-18T15:23:39.683826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.906369ms","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-18T15:23:39.683844Z","caller":"traceutil/trace.go:171","msg":"trace[1444030196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:807; }","duration":"174.537062ms","start":"2026-09-18T15:23:39.509288Z","end":"2026-09-18T15:23:39.683825Z","steps":["trace[1444030196] 'range keys from in-memory index tree' (duration: 174.228845ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:23:39.683855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.362917ms","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-18T15:23:39.683867Z","caller":"traceutil/trace.go:171","msg":"trace[1134844559] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:807; }","duration":"180.98083ms","start":"2026-09-18T15:23:39.502878Z","end":"2026-09-18T15:23:39.683859Z","steps":["trace[1134844559] 'range keys from in-memory index tree' (duration: 180.812187ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:23:39.683888Z","caller":"traceutil/trace.go:171","msg":"trace[1540029565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:807; }","duration":"174.46484ms","start":"2026-09-18T15:23:39.509415Z","end":"2026-09-18T15:23:39.68388Z","steps":["trace[1540029565] 'range keys from in-memory index tree' (duration: 174.145962ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:24:08.318832Z","caller":"traceutil/trace.go:171","msg":"trace[1925013340] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"143.225553ms","start":"2026-09-18T15:24:08.175565Z","end":"2026-09-18T15:24:08.31879Z","steps":["trace[1925013340] 'process raft request' (duration: 142.995137ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:24:31.306803Z","caller":"traceutil/trace.go:171","msg":"trace[1634640724] transaction","detail":"{read_only:false; response_revision:1471; number_of_response:1; }","duration":"132.281356ms","start":"2026-09-18T15:24:31.174505Z","end":"2026-09-18T15:24:31.306786Z","steps":["trace[1634640724] 'process raft request' (duration: 132.166354ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:24:56.622192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.944653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:24:56.622286Z","caller":"traceutil/trace.go:171","msg":"trace[1814806145] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"374.502138ms","start":"2026-09-18T15:24:56.247769Z","end":"2026-09-18T15:24:56.622271Z","steps":["trace[1814806145] 'process raft request' (duration: 263.180778ms)","trace[1814806145] 'compare' (duration: 110.718859ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:24:56.622385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:24:56.247751Z","time spent":"374.609359ms","remote":"127.0.0.1:34180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T15:24:56.630014Z","caller":"traceutil/trace.go:171","msg":"trace[286338113] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"381.792977ms","start":"2026-09-18T15:24:56.2482Z","end":"2026-09-18T15:24:56.629993Z","steps":["trace[286338113] 'process raft request' (duration: 381.633134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:24:56.630174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:24:56.24818Z","time spent":"381.902668ms","remote":"127.0.0.1:34180","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-18T15:24:56.63026Z","caller":"traceutil/trace.go:171","msg":"trace[1340458630] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"377.040042ms","start":"2026-09-18T15:24:56.253183Z","end":"2026-09-18T15:24:56.630223Z","steps":["trace[1340458630] 'process raft request' (duration: 376.743537ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:24:56.630291Z","caller":"traceutil/trace.go:171","msg":"trace[691804508] linearizableReadLoop","detail":"{readStateIndex:1952; appliedIndex:1949; }","duration":"108.538168ms","start":"2026-09-18T15:24:56.521732Z","end":"2026-09-18T15:24:56.63027Z","steps":["trace[691804508] 'read index received' (duration: 108.03155ms)","trace[691804508] 'applied index is now lower than readState.Index' (duration: 505.698µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:24:56.63043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:24:56.253164Z","time spent":"377.168665ms","remote":"127.0.0.1:34106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:24:56.630426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.686321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e82c3b03-68bd-41ec-94b1-31bae81f6c5f\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-09-18T15:24:56.630505Z","caller":"traceutil/trace.go:171","msg":"trace[81838299] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"143.753535ms","start":"2026-09-18T15:24:56.486741Z","end":"2026-09-18T15:24:56.630494Z","steps":["trace[81838299] 'process raft request' (duration: 143.231847ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:24:56.630546Z","caller":"traceutil/trace.go:171","msg":"trace[34631557] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e82c3b03-68bd-41ec-94b1-31bae81f6c5f; range_end:; response_count:1; response_revision:1875; }","duration":"108.827314ms","start":"2026-09-18T15:24:56.521686Z","end":"2026-09-18T15:24:56.630513Z","steps":["trace[34631557] 'agreement among raft nodes before linearized reading' (duration: 108.687601ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:24:56.630544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.476759ms","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-18T15:24:56.630647Z","caller":"traceutil/trace.go:171","msg":"trace[698749442] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1875; }","duration":"103.620701ms","start":"2026-09-18T15:24:56.527016Z","end":"2026-09-18T15:24:56.630636Z","steps":["trace[698749442] 'agreement among raft nodes before linearized reading' (duration: 103.365806ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:25:30.030701Z","caller":"traceutil/trace.go:171","msg":"trace[655451324] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"135.652303ms","start":"2026-09-18T15:25:29.895035Z","end":"2026-09-18T15:25:30.030687Z","steps":["trace[655451324] 'process raft request' (duration: 135.502171ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:25:30.030946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.538922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18d673b568ebc757\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-09-18T15:25:30.031046Z","caller":"traceutil/trace.go:171","msg":"trace[804617375] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18d673b568ebc757; range_end:; response_count:1; response_revision:2113; }","duration":"111.688274ms","start":"2026-09-18T15:25:29.919338Z","end":"2026-09-18T15:25:30.031026Z","steps":["trace[804617375] 'agreement among raft nodes before linearized reading' (duration: 111.39678ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:25:30.031068Z","caller":"traceutil/trace.go:171","msg":"trace[1450567449] linearizableReadLoop","detail":"{readStateIndex:2197; appliedIndex:2196; }","duration":"111.260538ms","start":"2026-09-18T15:25:29.919384Z","end":"2026-09-18T15:25:30.030644Z","steps":["trace[1450567449] 'read index received' (duration: 111.103555ms)","trace[1450567449] 'applied index is now lower than readState.Index' (duration: 156.323µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:25:30.203954Z","caller":"traceutil/trace.go:171","msg":"trace[1683221274] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"139.366031ms","start":"2026-09-18T15:25:30.064566Z","end":"2026-09-18T15:25:30.203932Z","steps":["trace[1683221274] 'process raft request' (duration: 106.673108ms)","trace[1683221274] 'compare' (duration: 32.598191ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:25:30.871328Z","caller":"traceutil/trace.go:171","msg":"trace[611212101] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"116.134765ms","start":"2026-09-18T15:25:30.755167Z","end":"2026-09-18T15:25:30.871302Z","steps":["trace[611212101] 'process raft request' (duration: 115.396023ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:25:31.07031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.854202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T15:25:31.070549Z","caller":"traceutil/trace.go:171","msg":"trace[1443719487] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"236.090441ms","start":"2026-09-18T15:25:30.834406Z","end":"2026-09-18T15:25:31.070496Z","steps":["trace[1443719487] 'process raft request' (duration: 126.973465ms)","trace[1443719487] 'compare' (duration: 108.70897ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:25:31.070818Z","caller":"traceutil/trace.go:171","msg":"trace[1625418608] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"196.453536ms","start":"2026-09-18T15:25:30.87434Z","end":"2026-09-18T15:25:31.070793Z","steps":["trace[1625418608] 'process raft request' (duration: 196.096989ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:25:31.071031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.807608ms","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-18T15:25:31.071094Z","caller":"traceutil/trace.go:171","msg":"trace[922786393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2119; }","duration":"267.906749ms","start":"2026-09-18T15:25:30.803174Z","end":"2026-09-18T15:25:31.07108Z","steps":["trace[922786393] 'agreement among raft nodes before linearized reading' (duration: 267.691936ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:25:31.071251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.166217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-09-18T15:25:31.071284Z","caller":"traceutil/trace.go:171","msg":"trace[381068169] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2119; }","duration":"229.226367ms","start":"2026-09-18T15:25:30.842049Z","end":"2026-09-18T15:25:31.071275Z","steps":["trace[381068169] 'agreement among raft nodes before linearized reading' (duration: 229.139666ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:25:31.071233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.255046ms","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-18T15:25:31.07138Z","caller":"traceutil/trace.go:171","msg":"trace[179113653] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2119; }","duration":"203.40682ms","start":"2026-09-18T15:25:30.867913Z","end":"2026-09-18T15:25:31.07132Z","steps":["trace[179113653] 'agreement among raft nodes before linearized reading' (duration: 203.083355ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:25:31.070836Z","caller":"traceutil/trace.go:171","msg":"trace[865148991] linearizableReadLoop","detail":"{readStateIndex:2202; appliedIndex:2201; }","duration":"200.109936ms","start":"2026-09-18T15:25:30.870708Z","end":"2026-09-18T15:25:31.070818Z","steps":["trace[865148991] 'read index received' (duration: 90.580683ms)","trace[865148991] 'applied index is now lower than readState.Index' (duration: 109.526463ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:25:31.375101Z","caller":"traceutil/trace.go:171","msg":"trace[53543202] linearizableReadLoop","detail":"{readStateIndex:2205; appliedIndex:2203; }","duration":"294.920052ms","start":"2026-09-18T15:25:31.080157Z","end":"2026-09-18T15:25:31.375077Z","steps":["trace[53543202] 'read index received' (duration: 289.178127ms)","trace[53543202] 'applied index is now lower than readState.Index' (duration: 5.740885ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:25:31.375147Z","caller":"traceutil/trace.go:171","msg":"trace[209729643] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"298.596973ms","start":"2026-09-18T15:25:31.076533Z","end":"2026-09-18T15:25:31.37513Z","steps":["trace[209729643] 'process raft request' (duration: 298.452661ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:25:31.375164Z","caller":"traceutil/trace.go:171","msg":"trace[1001732577] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"299.048031ms","start":"2026-09-18T15:25:31.076096Z","end":"2026-09-18T15:25:31.375144Z","steps":["trace[1001732577] 'process raft request' (duration: 293.237205ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:25:31.375232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.058984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:25:31.375636Z","caller":"traceutil/trace.go:171","msg":"trace[2094816077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2121; }","duration":"295.498021ms","start":"2026-09-18T15:25:31.080127Z","end":"2026-09-18T15:25:31.375626Z","steps":["trace[2094816077] 'agreement among raft nodes before linearized reading' (duration: 295.065644ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:39.935197Z","caller":"traceutil/trace.go:171","msg":"trace[1418557311] linearizableReadLoop","detail":"{readStateIndex:2690; appliedIndex:2689; }","duration":"181.761292ms","start":"2026-09-18T15:26:39.753416Z","end":"2026-09-18T15:26:39.935177Z","steps":["trace[1418557311] 'read index received' (duration: 181.641637ms)","trace[1418557311] 'applied index is now lower than readState.Index' (duration: 118.985µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:26:39.935355Z","caller":"traceutil/trace.go:171","msg":"trace[1604233385] transaction","detail":"{read_only:false; response_revision:2590; number_of_response:1; }","duration":"189.915535ms","start":"2026-09-18T15:26:39.745421Z","end":"2026-09-18T15:26:39.935337Z","steps":["trace[1604233385] 'process raft request' (duration: 189.597961ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:26:39.935481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.043844ms","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-18T15:26:39.935505Z","caller":"traceutil/trace.go:171","msg":"trace[1287320991] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2590; }","duration":"182.100196ms","start":"2026-09-18T15:26:39.753397Z","end":"2026-09-18T15:26:39.935498Z","steps":["trace[1287320991] 'agreement among raft nodes before linearized reading' (duration: 182.041514ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:26:39.952588Z","caller":"traceutil/trace.go:171","msg":"trace[1266969735] transaction","detail":"{read_only:false; response_revision:2591; number_of_response:1; }","duration":"123.202521ms","start":"2026-09-18T15:26:39.829366Z","end":"2026-09-18T15:26:39.952568Z","steps":["trace[1266969735] 'process raft request' (duration: 123.077026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:26:39.952684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.758588ms","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-18T15:26:39.952736Z","caller":"traceutil/trace.go:171","msg":"trace[1238344541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2591; }","duration":"189.847581ms","start":"2026-09-18T15:26:39.762877Z","end":"2026-09-18T15:26:39.952724Z","steps":["trace[1238344541] 'agreement among raft nodes before linearized reading' (duration: 189.741497ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:31:07.214151Z","caller":"traceutil/trace.go:171","msg":"trace[873818435] transaction","detail":"{read_only:false; response_revision:4601; number_of_response:1; }","duration":"102.514743ms","start":"2026-09-18T15:31:07.111604Z","end":"2026-09-18T15:31:07.214119Z","steps":["trace[873818435] 'process raft request' (duration: 102.34461ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:31:12.244844Z","caller":"traceutil/trace.go:171","msg":"trace[227267630] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"160.511721ms","start":"2026-09-18T15:31:12.084299Z","end":"2026-09-18T15:31:12.24481Z","steps":["trace[227267630] 'process raft request' (duration: 160.253485ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:31:12.245043Z","caller":"traceutil/trace.go:171","msg":"trace[976323502] linearizableReadLoop","detail":"{readStateIndex:4905; appliedIndex:4902; }","duration":"153.754603ms","start":"2026-09-18T15:31:12.091259Z","end":"2026-09-18T15:31:12.245013Z","steps":["trace[976323502] 'read index received' (duration: 18.457099ms)","trace[976323502] 'applied index is now lower than readState.Index' (duration: 135.296084ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:31:12.245094Z","caller":"traceutil/trace.go:171","msg":"trace[18647767] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4722; }","duration":"158.953528ms","start":"2026-09-18T15:31:12.086121Z","end":"2026-09-18T15:31:12.245075Z","steps":["trace[18647767] 'process raft request' (duration: 158.635302ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:31:12.245201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.922646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:31:12.24523Z","caller":"traceutil/trace.go:171","msg":"trace[569400761] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4722; }","duration":"153.986507ms","start":"2026-09-18T15:31:12.091233Z","end":"2026-09-18T15:31:12.245219Z","steps":["trace[569400761] 'agreement among raft nodes before linearized reading' (duration: 153.902455ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:31:23.818076Z","caller":"traceutil/trace.go:171","msg":"trace[1472530595] linearizableReadLoop","detail":"{readStateIndex:5056; appliedIndex:5055; }","duration":"336.624016ms","start":"2026-09-18T15:31:23.481429Z","end":"2026-09-18T15:31:23.818053Z","steps":["trace[1472530595] 'read index received' (duration: 317.435008ms)","trace[1472530595] 'applied index is now lower than readState.Index' (duration: 19.188088ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:31:23.818142Z","caller":"traceutil/trace.go:171","msg":"trace[969520701] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"376.570427ms","start":"2026-09-18T15:31:23.441542Z","end":"2026-09-18T15:31:23.818112Z","steps":["trace[969520701] 'process raft request' (duration: 357.308846ms)","trace[969520701] 'compare' (duration: 18.860003ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:31:23.818288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.845012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-18T15:31:23.818338Z","caller":"traceutil/trace.go:171","msg":"trace[186631044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4870; }","duration":"336.934044ms","start":"2026-09-18T15:31:23.48139Z","end":"2026-09-18T15:31:23.818325Z","steps":["trace[186631044] 'agreement among raft nodes before linearized reading' (duration: 336.742549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:31:23.818384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:31:23.441518Z","time spent":"376.681278ms","remote":"127.0.0.1:34180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T15:31:23.818397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:31:23.481348Z","time spent":"337.038775ms","remote":"127.0.0.1:34180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-18T15:31:23.818594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.410513ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:31:23.818648Z","caller":"traceutil/trace.go:171","msg":"trace[2020519014] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4869; }","duration":"375.196329ms","start":"2026-09-18T15:31:23.443444Z","end":"2026-09-18T15:31:23.81864Z","steps":["trace[2020519014] 'range keys from in-memory index tree' (duration: 374.390792ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:31:23.889008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.212377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-18T15:31:23.889064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.828247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T15:31:23.889093Z","caller":"traceutil/trace.go:171","msg":"trace[1203124750] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:4871; }","duration":"112.892ms","start":"2026-09-18T15:31:23.776187Z","end":"2026-09-18T15:31:23.889079Z","steps":["trace[1203124750] 'agreement among raft nodes before linearized reading' (duration: 112.854549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:31:23.889091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.48797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-67f768bfc8-l8rrh.18d67407d91aac66\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-09-18T15:31:23.889153Z","caller":"traceutil/trace.go:171","msg":"trace[734534431] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-67f768bfc8-l8rrh.18d67407d91aac66; range_end:; response_count:1; response_revision:4871; }","duration":"360.600213ms","start":"2026-09-18T15:31:23.528537Z","end":"2026-09-18T15:31:23.889137Z","steps":["trace[734534431] 'agreement among raft nodes before linearized reading' (duration: 360.416109ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:31:23.88918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:31:23.528518Z","time spent":"360.654113ms","remote":"127.0.0.1:33994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":920,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-67f768bfc8-l8rrh.18d67407d91aac66\" "} {"level":"warn","ts":"2026-09-18T15:31:23.889193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.762652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-h5sb9\" ","response":"range_response_count:1 size:5151"} {"level":"info","ts":"2026-09-18T15:31:23.889241Z","caller":"traceutil/trace.go:171","msg":"trace[690892877] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-h5sb9; range_end:; response_count:1; response_revision:4871; }","duration":"379.817033ms","start":"2026-09-18T15:31:23.509393Z","end":"2026-09-18T15:31:23.88921Z","steps":["trace[690892877] 'agreement among raft nodes before linearized reading' (duration: 379.71437ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:31:23.889269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:31:23.509347Z","time spent":"379.913765ms","remote":"127.0.0.1:34106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":5175,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-h5sb9\" "} {"level":"warn","ts":"2026-09-18T15:31:23.889399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.560254ms","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":"warn","ts":"2026-09-18T15:31:23.889406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.343477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-241ceced-2aa4-4a64-a2e7-07c62357cc7a\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-18T15:31:23.889422Z","caller":"traceutil/trace.go:171","msg":"trace[124803729] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4871; }","duration":"256.642245ms","start":"2026-09-18T15:31:23.632773Z","end":"2026-09-18T15:31:23.889416Z","steps":["trace[124803729] 'agreement among raft nodes before linearized reading' (duration: 256.531873ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:31:23.88943Z","caller":"traceutil/trace.go:171","msg":"trace[64888114] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-241ceced-2aa4-4a64-a2e7-07c62357cc7a; range_end:; response_count:1; response_revision:4871; }","duration":"358.395578ms","start":"2026-09-18T15:31:23.531028Z","end":"2026-09-18T15:31:23.889424Z","steps":["trace[64888114] 'agreement among raft nodes before linearized reading' (duration: 358.289456ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:31:23.889093Z","caller":"traceutil/trace.go:171","msg":"trace[1257084088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4870; }","duration":"402.31949ms","start":"2026-09-18T15:31:23.486757Z","end":"2026-09-18T15:31:23.889076Z","steps":["trace[1257084088] 'agreement among raft nodes before linearized reading' (duration: 402.087325ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:31:23.889499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:31:23.486743Z","time spent":"402.731328ms","remote":"127.0.0.1:34180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-18T15:31:23.889008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.214487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-18T15:31:23.889556Z","caller":"traceutil/trace.go:171","msg":"trace[1379424166] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4871; }","duration":"259.86872ms","start":"2026-09-18T15:31:23.62968Z","end":"2026-09-18T15:31:23.889549Z","steps":["trace[1379424166] 'agreement among raft nodes before linearized reading' (duration: 259.203396ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:31:23.889448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T15:31:23.531016Z","time spent":"358.427269ms","remote":"127.0.0.1:34106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3670,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-241ceced-2aa4-4a64-a2e7-07c62357cc7a\" "} {"level":"info","ts":"2026-09-18T15:31:34.078648Z","caller":"traceutil/trace.go:171","msg":"trace[520972788] linearizableReadLoop","detail":"{readStateIndex:5215; appliedIndex:5214; }","duration":"108.527009ms","start":"2026-09-18T15:31:33.970096Z","end":"2026-09-18T15:31:34.078623Z","steps":["trace[520972788] 'read index received' (duration: 73.513276ms)","trace[520972788] 'applied index is now lower than readState.Index' (duration: 35.012723ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T15:31:34.078697Z","caller":"traceutil/trace.go:171","msg":"trace[141491781] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"109.264465ms","start":"2026-09-18T15:31:33.969393Z","end":"2026-09-18T15:31:34.078657Z","steps":["trace[141491781] 'process raft request' (duration: 74.377254ms)","trace[141491781] 'compare' (duration: 34.740098ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T15:31:34.078929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.639791ms","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-18T15:31:34.079Z","caller":"traceutil/trace.go:171","msg":"trace[71562800] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5023; }","duration":"107.755994ms","start":"2026-09-18T15:31:33.971224Z","end":"2026-09-18T15:31:34.07898Z","steps":["trace[71562800] 'agreement among raft nodes before linearized reading' (duration: 107.57172ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:31:34.079222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.799314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-18T15:31:34.079287Z","caller":"traceutil/trace.go:171","msg":"trace[752960014] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5023; }","duration":"109.219132ms","start":"2026-09-18T15:31:33.970041Z","end":"2026-09-18T15:31:34.07926Z","steps":["trace[752960014] 'agreement among raft nodes before linearized reading' (duration: 108.734373ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T15:32:17.416618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.040437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-09-18T15:32:17.416679Z","caller":"traceutil/trace.go:171","msg":"trace[753098257] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5385; }","duration":"111.1461ms","start":"2026-09-18T15:32:17.30552Z","end":"2026-09-18T15:32:17.416666Z","steps":["trace[753098257] 'range keys from in-memory index tree' (duration: 110.387154ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T15:32:34.928492Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2891} {"level":"info","ts":"2026-09-18T15:32:35.010719Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2891,"took":"80.368676ms","hash":50057703,"current-db-size-bytes":52428800,"current-db-size":"52 MB","current-db-size-in-use-bytes":28897280,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-18T15:32:35.010847Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":50057703,"revision":2891,"compact-revision":-1} {"level":"info","ts":"2026-09-18T15:37:34.936656Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5529} {"level":"info","ts":"2026-09-18T15:37:35.023441Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5529,"took":"85.037575ms","hash":1569437336,"current-db-size-bytes":52428800,"current-db-size":"52 MB","current-db-size-in-use-bytes":18370560,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-18T15:37:35.023488Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1569437336,"revision":5529,"compact-revision":2891}