{"level":"warn","ts":"2026-09-01T18:23:59.443977Z","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-01T18:23:59.444051Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227: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.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227: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-01T18:23:59.444111Z","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-01T18:23:59.444118Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-09-01T18:23:59.444137Z","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-01T18:23:59.444719Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]} {"level":"info","ts":"2026-09-01T18:23:59.444789Z","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.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227: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-01T18:23:59.449977Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.005393ms"} {"level":"info","ts":"2026-09-01T18:23:59.45785Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-09-01T18:23:59.458022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-09-01T18:23:59.458049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-09-01T18:23:59.458058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-01T18:23:59.458071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-09-01T18:23:59.458138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-09-01T18:23:59.465274Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-01T18:23:59.466656Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-01T18:23:59.46798Z","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-01T18:23:59.469747Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-01T18:23:59.470399Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-01T18:23:59.470575Z","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-01T18:23:59.470638Z","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-01T18:23:59.470646Z","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-01T18:23:59.471782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-09-01T18:23:59.473436Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-09-01T18:23:59.474023Z","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-01T18:23:59.474075Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-09-01T18:23:59.47412Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-09-01T18:23:59.475305Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-01T18:23:59.475344Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-01T18:24:00.158416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-09-01T18:24:00.158514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-01T18:24:00.158602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-09-01T18:24:00.15863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-09-01T18:24:00.158641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-09-01T18:24:00.15869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-09-01T18:24:00.158708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-09-01T18:24:00.169856Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:24:00.177907Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"} {"level":"info","ts":"2026-09-01T18:24:00.178038Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T18:24:00.181875Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"info","ts":"2026-09-01T18:24:00.184361Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-01T18:24:00.184489Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:24:00.184625Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:24:00.184674Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-01T18:24:00.186477Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-01T18:24:00.186537Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-01T18:24:00.189999Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-01T18:24:09.654916Z","caller":"traceutil/trace.go:171","msg":"trace[1492561033] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"119.633755ms","start":"2026-09-01T18:24:09.53523Z","end":"2026-09-01T18:24:09.654864Z","steps":["trace[1492561033] 'process raft request' (duration: 119.388521ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:24:21.999676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.947513ms","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-09-01T18:24:21.999782Z","caller":"traceutil/trace.go:171","msg":"trace[720668026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:384; }","duration":"132.125472ms","start":"2026-09-01T18:24:21.867638Z","end":"2026-09-01T18:24:21.999764Z","steps":["trace[720668026] 'range keys from in-memory index tree' (duration: 131.849274ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:24:21.999873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.426739ms","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-01T18:24:21.999962Z","caller":"traceutil/trace.go:171","msg":"trace[1985854948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:384; }","duration":"132.515127ms","start":"2026-09-01T18:24:21.867399Z","end":"2026-09-01T18:24:21.999914Z","steps":["trace[1985854948] 'range keys from in-memory index tree' (duration: 132.349189ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:24:43.842048Z","caller":"traceutil/trace.go:171","msg":"trace[1398956094] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"195.818895ms","start":"2026-09-01T18:24:43.6462Z","end":"2026-09-01T18:24:43.842019Z","steps":["trace[1398956094] 'process raft request' (duration: 193.820228ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:24:44.009548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.92511ms","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-01T18:24:44.009676Z","caller":"traceutil/trace.go:171","msg":"trace[399182599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"118.081971ms","start":"2026-09-01T18:24:43.891551Z","end":"2026-09-01T18:24:44.009633Z","steps":["trace[399182599] 'range keys from in-memory index tree' (duration: 117.717838ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:24:44.608144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.932945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-01T18:24:44.60823Z","caller":"traceutil/trace.go:171","msg":"trace[603974600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"396.062435ms","start":"2026-09-01T18:24:44.212145Z","end":"2026-09-01T18:24:44.608208Z","steps":["trace[603974600] 'range keys from in-memory index tree' (duration: 395.847864ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:24:44.608278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:24:44.212122Z","time spent":"396.144126ms","remote":"127.0.0.1:40492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-01T18:24:44.608435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.182913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-01T18:24:44.608521Z","caller":"traceutil/trace.go:171","msg":"trace[1967209591] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:584; }","duration":"369.326995ms","start":"2026-09-01T18:24:44.239173Z","end":"2026-09-01T18:24:44.6085Z","steps":["trace[1967209591] 'range keys from in-memory index tree' (duration: 369.009852ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:24:44.608562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:24:44.239162Z","time spent":"369.390455ms","remote":"127.0.0.1:40760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-01T18:24:44.608591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.68894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8rvjd\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-09-01T18:24:44.608593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.949987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-09-01T18:24:44.608611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.163309ms","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-09-01T18:24:44.608674Z","caller":"traceutil/trace.go:171","msg":"trace[1815148447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:584; }","duration":"424.072258ms","start":"2026-09-01T18:24:44.184582Z","end":"2026-09-01T18:24:44.608655Z","steps":["trace[1815148447] 'range keys from in-memory index tree' (duration: 423.791946ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:24:44.60869Z","caller":"traceutil/trace.go:171","msg":"trace[1003006214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"425.289319ms","start":"2026-09-01T18:24:44.183381Z","end":"2026-09-01T18:24:44.60867Z","steps":["trace[1003006214] 'range keys from in-memory index tree' (duration: 424.970407ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:24:44.608719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:24:44.184554Z","time spent":"424.15616ms","remote":"127.0.0.1:40760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-01T18:24:44.608729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:24:44.183352Z","time spent":"425.368081ms","remote":"127.0.0.1:40760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-01T18:24:44.608644Z","caller":"traceutil/trace.go:171","msg":"trace[1134857081] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8rvjd; range_end:; response_count:1; response_revision:584; }","duration":"417.784312ms","start":"2026-09-01T18:24:44.190842Z","end":"2026-09-01T18:24:44.608627Z","steps":["trace[1134857081] 'range keys from in-memory index tree' (duration: 417.60296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:24:44.608834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:24:44.190831Z","time spent":"417.976653ms","remote":"127.0.0.1:40690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-8rvjd\" "} {"level":"info","ts":"2026-09-01T18:24:44.614244Z","caller":"traceutil/trace.go:171","msg":"trace[1834827029] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"167.765944ms","start":"2026-09-01T18:24:44.446438Z","end":"2026-09-01T18:24:44.614204Z","steps":["trace[1834827029] 'process raft request' (duration: 165.222461ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:24:59.555984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.499283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jlvd4\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-09-01T18:24:59.556138Z","caller":"traceutil/trace.go:171","msg":"trace[309544557] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jlvd4; range_end:; response_count:1; response_revision:751; }","duration":"264.723256ms","start":"2026-09-01T18:24:59.291383Z","end":"2026-09-01T18:24:59.556106Z","steps":["trace[309544557] 'range keys from in-memory index tree' (duration: 264.179686ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:25:31.132719Z","caller":"traceutil/trace.go:171","msg":"trace[1368868220] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1191; }","duration":"272.039767ms","start":"2026-09-01T18:25:30.860648Z","end":"2026-09-01T18:25:31.132688Z","steps":["trace[1368868220] 'read index received' (duration: 271.788339ms)","trace[1368868220] 'applied index is now lower than readState.Index' (duration: 250.178µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:25:31.133067Z","caller":"traceutil/trace.go:171","msg":"trace[1585124187] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"288.802575ms","start":"2026-09-01T18:25:30.844251Z","end":"2026-09-01T18:25:31.133054Z","steps":["trace[1585124187] 'process raft request' (duration: 288.280199ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:25:31.133403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.377151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-09-01T18:25:31.13349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.15253ms","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-01T18:25:31.133618Z","caller":"traceutil/trace.go:171","msg":"trace[1296138823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1159; }","duration":"196.327875ms","start":"2026-09-01T18:25:30.937268Z","end":"2026-09-01T18:25:31.133596Z","steps":["trace[1296138823] 'agreement among raft nodes before linearized reading' (duration: 196.064317ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:25:31.133472Z","caller":"traceutil/trace.go:171","msg":"trace[937202047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1159; }","duration":"264.501994ms","start":"2026-09-01T18:25:30.868952Z","end":"2026-09-01T18:25:31.133454Z","steps":["trace[937202047] 'agreement among raft nodes before linearized reading' (duration: 264.175024ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:25:31.133533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.830642ms","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-09-01T18:25:31.134085Z","caller":"traceutil/trace.go:171","msg":"trace[757723783] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1159; }","duration":"273.43671ms","start":"2026-09-01T18:25:30.860606Z","end":"2026-09-01T18:25:31.134043Z","steps":["trace[757723783] 'agreement among raft nodes before linearized reading' (duration: 272.614245ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:26:10.856378Z","caller":"traceutil/trace.go:171","msg":"trace[543672227] transaction","detail":"{read_only:false; response_revision:1681; number_of_response:1; }","duration":"204.720322ms","start":"2026-09-01T18:26:10.651602Z","end":"2026-09-01T18:26:10.856322Z","steps":["trace[543672227] 'process raft request' (duration: 204.539735ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:26:20.842757Z","caller":"traceutil/trace.go:171","msg":"trace[19016345] linearizableReadLoop","detail":"{readStateIndex:1902; appliedIndex:1901; }","duration":"200.875857ms","start":"2026-09-01T18:26:20.641857Z","end":"2026-09-01T18:26:20.842733Z","steps":["trace[19016345] 'read index received' (duration: 200.626137ms)","trace[19016345] 'applied index is now lower than readState.Index' (duration: 248.88µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:26:20.84312Z","caller":"traceutil/trace.go:171","msg":"trace[917031682] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"278.305382ms","start":"2026-09-01T18:26:20.564776Z","end":"2026-09-01T18:26:20.843081Z","steps":["trace[917031682] 'process raft request' (duration: 277.824701ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:20.843191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.323797ms","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-01T18:26:20.843245Z","caller":"traceutil/trace.go:171","msg":"trace[647514766] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1829; }","duration":"201.429151ms","start":"2026-09-01T18:26:20.641805Z","end":"2026-09-01T18:26:20.843234Z","steps":["trace[647514766] 'agreement among raft nodes before linearized reading' (duration: 201.279025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:20.843423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.751148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8f35e9eb-e8eb-4e19-bd72-715b423927ab\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-01T18:26:20.843722Z","caller":"traceutil/trace.go:171","msg":"trace[1270841735] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8f35e9eb-e8eb-4e19-bd72-715b423927ab; range_end:; response_count:1; response_revision:1829; }","duration":"157.090002ms","start":"2026-09-01T18:26:20.686607Z","end":"2026-09-01T18:26:20.843697Z","steps":["trace[1270841735] 'agreement among raft nodes before linearized reading' (duration: 156.493788ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:26:20.957995Z","caller":"traceutil/trace.go:171","msg":"trace[50231176] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"107.294483ms","start":"2026-09-01T18:26:20.850682Z","end":"2026-09-01T18:26:20.957977Z","steps":["trace[50231176] 'process raft request' (duration: 107.140577ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:26:55.247808Z","caller":"traceutil/trace.go:171","msg":"trace[186348290] linearizableReadLoop","detail":"{readStateIndex:2156; appliedIndex:2155; }","duration":"139.716923ms","start":"2026-09-01T18:26:55.108068Z","end":"2026-09-01T18:26:55.247785Z","steps":["trace[186348290] 'read index received' (duration: 139.517404ms)","trace[186348290] 'applied index is now lower than readState.Index' (duration: 198.729µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:26:55.247854Z","caller":"traceutil/trace.go:171","msg":"trace[295185038] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"251.199825ms","start":"2026-09-01T18:26:54.996635Z","end":"2026-09-01T18:26:55.247835Z","steps":["trace[295185038] 'process raft request' (duration: 251.026818ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:55.247995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.909024ms","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-01T18:26:55.248033Z","caller":"traceutil/trace.go:171","msg":"trace[657847002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2075; }","duration":"139.991837ms","start":"2026-09-01T18:26:55.108032Z","end":"2026-09-01T18:26:55.248023Z","steps":["trace[657847002] 'agreement among raft nodes before linearized reading' (duration: 139.859991ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:26:55.551397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.299675ms","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-09-01T18:26:55.551514Z","caller":"traceutil/trace.go:171","msg":"trace[465744982] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2078; }","duration":"263.419731ms","start":"2026-09-01T18:26:55.28807Z","end":"2026-09-01T18:26:55.551489Z","steps":["trace[465744982] 'range keys from in-memory index tree' (duration: 263.159738ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:26:55.551529Z","caller":"traceutil/trace.go:171","msg":"trace[1529682679] transaction","detail":"{read_only:false; response_revision:2079; number_of_response:1; }","duration":"109.227088ms","start":"2026-09-01T18:26:55.442282Z","end":"2026-09-01T18:26:55.551509Z","steps":["trace[1529682679] 'process raft request' (duration: 103.578148ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:28:04.006897Z","caller":"traceutil/trace.go:171","msg":"trace[1587816316] linearizableReadLoop","detail":"{readStateIndex:2646; appliedIndex:2645; }","duration":"165.540263ms","start":"2026-09-01T18:28:03.84133Z","end":"2026-09-01T18:28:04.006871Z","steps":["trace[1587816316] 'read index received' (duration: 165.26063ms)","trace[1587816316] 'applied index is now lower than readState.Index' (duration: 278.553µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:28:04.006955Z","caller":"traceutil/trace.go:171","msg":"trace[1722005085] transaction","detail":"{read_only:false; response_revision:2549; number_of_response:1; }","duration":"219.03498ms","start":"2026-09-01T18:28:03.78789Z","end":"2026-09-01T18:28:04.006925Z","steps":["trace[1722005085] 'process raft request' (duration: 218.806379ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:04.007116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.770554ms","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-09-01T18:28:04.007153Z","caller":"traceutil/trace.go:171","msg":"trace[430322429] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2549; }","duration":"165.838708ms","start":"2026-09-01T18:28:03.841302Z","end":"2026-09-01T18:28:04.007141Z","steps":["trace[430322429] 'agreement among raft nodes before linearized reading' (duration: 165.712531ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:04.007168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.794122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-01T18:28:04.007193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.692261ms","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-09-01T18:28:04.007203Z","caller":"traceutil/trace.go:171","msg":"trace[632403464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2549; }","duration":"113.872916ms","start":"2026-09-01T18:28:03.89332Z","end":"2026-09-01T18:28:04.007193Z","steps":["trace[632403464] 'agreement among raft nodes before linearized reading' (duration: 113.704238ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:28:04.00723Z","caller":"traceutil/trace.go:171","msg":"trace[975692622] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2549; }","duration":"102.738923ms","start":"2026-09-01T18:28:03.904477Z","end":"2026-09-01T18:28:04.007216Z","steps":["trace[975692622] 'agreement among raft nodes before linearized reading' (duration: 102.633248ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:28:04.007393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.940534ms","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-09-01T18:28:04.007459Z","caller":"traceutil/trace.go:171","msg":"trace[400124002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2549; }","duration":"103.022067ms","start":"2026-09-01T18:28:03.904417Z","end":"2026-09-01T18:28:04.007439Z","steps":["trace[400124002] 'agreement among raft nodes before linearized reading' (duration: 102.849329ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:33:08.027115Z","caller":"traceutil/trace.go:171","msg":"trace[1428252550] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"141.085681ms","start":"2026-09-01T18:33:07.885973Z","end":"2026-09-01T18:33:08.027058Z","steps":["trace[1428252550] 'process raft request' (duration: 140.779008ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:33:10.17472Z","caller":"traceutil/trace.go:171","msg":"trace[1046127855] transaction","detail":"{read_only:false; response_revision:4845; number_of_response:1; }","duration":"140.620539ms","start":"2026-09-01T18:33:10.034078Z","end":"2026-09-01T18:33:10.174698Z","steps":["trace[1046127855] 'process raft request' (duration: 140.565487ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:33:10.174725Z","caller":"traceutil/trace.go:171","msg":"trace[1219078751] linearizableReadLoop","detail":"{readStateIndex:5032; appliedIndex:5031; }","duration":"158.37679ms","start":"2026-09-01T18:33:10.016322Z","end":"2026-09-01T18:33:10.174699Z","steps":["trace[1219078751] 'read index received' (duration: 92.793887ms)","trace[1219078751] 'applied index is now lower than readState.Index' (duration: 65.580613ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:33:10.174854Z","caller":"traceutil/trace.go:171","msg":"trace[1302639176] transaction","detail":"{read_only:false; response_revision:4844; number_of_response:1; }","duration":"159.401783ms","start":"2026-09-01T18:33:10.015435Z","end":"2026-09-01T18:33:10.174836Z","steps":["trace[1302639176] 'process raft request' (duration: 93.742767ms)","trace[1302639176] 'compare' (duration: 65.307442ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:33:10.174892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.556937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-09-01T18:33:10.174933Z","caller":"traceutil/trace.go:171","msg":"trace[1154678491] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4845; }","duration":"158.650071ms","start":"2026-09-01T18:33:10.016272Z","end":"2026-09-01T18:33:10.174922Z","steps":["trace[1154678491] 'agreement among raft nodes before linearized reading' (duration: 158.498475ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:33:15.959328Z","caller":"traceutil/trace.go:171","msg":"trace[321699959] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"199.98615ms","start":"2026-09-01T18:33:15.759297Z","end":"2026-09-01T18:33:15.959284Z","steps":["trace[321699959] 'process raft request' (duration: 199.821662ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:33:15.981618Z","caller":"traceutil/trace.go:171","msg":"trace[1840768648] linearizableReadLoop","detail":"{readStateIndex:5139; appliedIndex:5136; }","duration":"215.156639ms","start":"2026-09-01T18:33:15.766439Z","end":"2026-09-01T18:33:15.981596Z","steps":["trace[1840768648] 'read index received' (duration: 192.743215ms)","trace[1840768648] 'applied index is now lower than readState.Index' (duration: 22.412584ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:33:15.981812Z","caller":"traceutil/trace.go:171","msg":"trace[1318657590] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"221.31208ms","start":"2026-09-01T18:33:15.760462Z","end":"2026-09-01T18:33:15.981775Z","steps":["trace[1318657590] 'process raft request' (duration: 221.101391ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:33:15.981909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.444851ms","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-01T18:33:15.981956Z","caller":"traceutil/trace.go:171","msg":"trace[2034975901] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4948; }","duration":"215.529274ms","start":"2026-09-01T18:33:15.766411Z","end":"2026-09-01T18:33:15.981941Z","steps":["trace[2034975901] 'agreement among raft nodes before linearized reading' (duration: 215.339107ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:33:15.982169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.834665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d4479d71-7b79-416a-8030-0ef38e79e283\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-01T18:33:15.982206Z","caller":"traceutil/trace.go:171","msg":"trace[1423300672] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d4479d71-7b79-416a-8030-0ef38e79e283; range_end:; response_count:1; response_revision:4948; }","duration":"188.900588ms","start":"2026-09-01T18:33:15.793296Z","end":"2026-09-01T18:33:15.982196Z","steps":["trace[1423300672] 'agreement among raft nodes before linearized reading' (duration: 188.788893ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:33:15.982423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.711197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d4479d71-7b79-416a-8030-0ef38e79e283\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-01T18:33:15.98236Z","caller":"traceutil/trace.go:171","msg":"trace[2095567587] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"222.411535ms","start":"2026-09-01T18:33:15.759934Z","end":"2026-09-01T18:33:15.982346Z","steps":["trace[2095567587] 'process raft request' (duration: 221.533068ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:33:15.982457Z","caller":"traceutil/trace.go:171","msg":"trace[581711088] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d4479d71-7b79-416a-8030-0ef38e79e283; range_end:; response_count:1; response_revision:4948; }","duration":"135.811452ms","start":"2026-09-01T18:33:15.846637Z","end":"2026-09-01T18:33:15.982449Z","steps":["trace[581711088] 'agreement among raft nodes before linearized reading' (duration: 135.725658ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:33:29.78652Z","caller":"traceutil/trace.go:171","msg":"trace[1096523193] linearizableReadLoop","detail":"{readStateIndex:5326; appliedIndex:5325; }","duration":"365.678559ms","start":"2026-09-01T18:33:29.42082Z","end":"2026-09-01T18:33:29.786498Z","steps":["trace[1096523193] 'read index received' (duration: 365.503042ms)","trace[1096523193] 'applied index is now lower than readState.Index' (duration: 174.687µs)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:33:29.786591Z","caller":"traceutil/trace.go:171","msg":"trace[174608187] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"390.36969ms","start":"2026-09-01T18:33:29.396179Z","end":"2026-09-01T18:33:29.786548Z","steps":["trace[174608187] 'process raft request' (duration: 390.162422ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:33:29.786947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.129058ms","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-09-01T18:33:29.787078Z","caller":"traceutil/trace.go:171","msg":"trace[793453045] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5129; }","duration":"366.296495ms","start":"2026-09-01T18:33:29.420756Z","end":"2026-09-01T18:33:29.787052Z","steps":["trace[793453045] 'agreement among raft nodes before linearized reading' (duration: 365.856997ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:33:29.787149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:33:29.420738Z","time spent":"366.397458ms","remote":"127.0.0.1:40760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-01T18:33:29.78719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:33:29.396149Z","time spent":"390.529337ms","remote":"127.0.0.1:40760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-01T18:33:29.787382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.542289ms","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-01T18:33:29.787447Z","caller":"traceutil/trace.go:171","msg":"trace[61351358] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5129; }","duration":"353.648213ms","start":"2026-09-01T18:33:29.433788Z","end":"2026-09-01T18:33:29.787436Z","steps":["trace[61351358] 'agreement among raft nodes before linearized reading' (duration: 352.85192ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:33:29.7875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:33:29.433777Z","time spent":"353.712326ms","remote":"127.0.0.1:40760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-01T18:33:29.787643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.452597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-09-01T18:33:29.787687Z","caller":"traceutil/trace.go:171","msg":"trace[235835152] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5129; }","duration":"287.556772ms","start":"2026-09-01T18:33:29.500117Z","end":"2026-09-01T18:33:29.787674Z","steps":["trace[235835152] 'agreement among raft nodes before linearized reading' (duration: 286.55666ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:33:29.787916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.775405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-09-01T18:33:29.787983Z","caller":"traceutil/trace.go:171","msg":"trace[2075975239] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5129; }","duration":"343.875469ms","start":"2026-09-01T18:33:29.444092Z","end":"2026-09-01T18:33:29.787967Z","steps":["trace[2075975239] 'agreement among raft nodes before linearized reading' (duration: 342.594056ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:33:29.788054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-01T18:33:29.444082Z","time spent":"343.960963ms","remote":"127.0.0.1:40690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12236,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-09-01T18:33:30.204378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.953814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-01T18:33:30.204473Z","caller":"traceutil/trace.go:171","msg":"trace[2072125367] linearizableReadLoop","detail":"{readStateIndex:5334; appliedIndex:5332; }","duration":"158.203072ms","start":"2026-09-01T18:33:30.046254Z","end":"2026-09-01T18:33:30.204457Z","steps":["trace[2072125367] 'read index received' (duration: 11.599649ms)","trace[2072125367] 'applied index is now lower than readState.Index' (duration: 146.602053ms)"],"step_count":2} {"level":"info","ts":"2026-09-01T18:33:30.204492Z","caller":"traceutil/trace.go:171","msg":"trace[1395290739] transaction","detail":"{read_only:false; response_revision:5137; number_of_response:1; }","duration":"158.60899ms","start":"2026-09-01T18:33:30.045855Z","end":"2026-09-01T18:33:30.204464Z","steps":["trace[1395290739] 'process raft request' (duration: 28.29394ms)","trace[1395290739] 'compare' (duration: 129.8413ms)"],"step_count":2} {"level":"warn","ts":"2026-09-01T18:33:30.204627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.370389ms","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-09-01T18:33:30.204657Z","caller":"traceutil/trace.go:171","msg":"trace[25606037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5137; }","duration":"158.418981ms","start":"2026-09-01T18:33:30.04623Z","end":"2026-09-01T18:33:30.204649Z","steps":["trace[25606037] 'agreement among raft nodes before linearized reading' (duration: 158.290105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:33:30.288764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.381072ms","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":"warn","ts":"2026-09-01T18:33:30.2889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.08027ms","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-01T18:33:30.288985Z","caller":"traceutil/trace.go:171","msg":"trace[1299876674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5138; }","duration":"226.205175ms","start":"2026-09-01T18:33:30.062761Z","end":"2026-09-01T18:33:30.288966Z","steps":["trace[1299876674] 'agreement among raft nodes before linearized reading' (duration: 225.920164ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:33:30.289137Z","caller":"traceutil/trace.go:171","msg":"trace[372021887] transaction","detail":"{read_only:false; response_revision:5138; number_of_response:1; }","duration":"231.222542ms","start":"2026-09-01T18:33:30.057898Z","end":"2026-09-01T18:33:30.289121Z","steps":["trace[372021887] 'process raft request' (duration: 230.501922ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:33:30.289132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.871788ms","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-09-01T18:33:30.289252Z","caller":"traceutil/trace.go:171","msg":"trace[1357726556] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5138; }","duration":"231.019964ms","start":"2026-09-01T18:33:30.058192Z","end":"2026-09-01T18:33:30.289212Z","steps":["trace[1357726556] 'agreement among raft nodes before linearized reading' (duration: 230.799135ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:33:30.288924Z","caller":"traceutil/trace.go:171","msg":"trace[1967855186] 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:5138; }","duration":"189.570279ms","start":"2026-09-01T18:33:30.099331Z","end":"2026-09-01T18:33:30.288901Z","steps":["trace[1967855186] 'agreement among raft nodes before linearized reading' (duration: 189.258207ms)"],"step_count":1} {"level":"warn","ts":"2026-09-01T18:33:30.289916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.72996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-09-01T18:33:30.290018Z","caller":"traceutil/trace.go:171","msg":"trace[683672302] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5138; }","duration":"236.851895ms","start":"2026-09-01T18:33:30.053147Z","end":"2026-09-01T18:33:30.289998Z","steps":["trace[683672302] 'agreement among raft nodes before linearized reading' (duration: 235.397025ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:34:00.232039Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2855} {"level":"info","ts":"2026-09-01T18:34:00.312024Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2855,"took":"78.503299ms","hash":312378254,"current-db-size-bytes":51671040,"current-db-size":"52 MB","current-db-size-in-use-bytes":27586560,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-01T18:34:00.312091Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":312378254,"revision":2855,"compact-revision":-1} {"level":"warn","ts":"2026-09-01T18:37:41.098146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.143834ms","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-01T18:37:41.098259Z","caller":"traceutil/trace.go:171","msg":"trace[41231593] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:6954; }","duration":"198.342716ms","start":"2026-09-01T18:37:40.899893Z","end":"2026-09-01T18:37:41.098236Z","steps":["trace[41231593] 'count revisions from in-memory index tree' (duration: 197.990681ms)"],"step_count":1} {"level":"info","ts":"2026-09-01T18:39:00.238123Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5337} {"level":"info","ts":"2026-09-01T18:39:00.331902Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5337,"took":"92.106568ms","hash":2300778121,"current-db-size-bytes":51671040,"current-db-size":"52 MB","current-db-size-in-use-bytes":18468864,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-09-01T18:39:00.332Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2300778121,"revision":5337,"compact-revision":2855}