{"level":"warn","ts":"2026-08-15T02:33:18.410464Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-15T02:33:18.410536Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.243:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.243:2380","--initial-cluster=instance=https://199.19.213.243:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.243:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.243:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-15T02:33:18.41059Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-15T02:33:18.410601Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.243:2380"]} {"level":"info","ts":"2026-08-15T02:33:18.410616Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-15T02:33:18.41136Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"]} {"level":"info","ts":"2026-08-15T02:33:18.411449Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.243:2380"],"listen-peer-urls":["https://199.19.213.243:2380"],"advertise-client-urls":["https://199.19.213.243:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.243:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-15T02:33:18.415435Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.783908ms"} {"level":"info","ts":"2026-08-15T02:33:18.420367Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"864e14457b828f9c","cluster-id":"6c0ae60a355554b9"} {"level":"info","ts":"2026-08-15T02:33:18.420456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=()"} {"level":"info","ts":"2026-08-15T02:33:18.420483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became follower at term 0"} {"level":"info","ts":"2026-08-15T02:33:18.42052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 864e14457b828f9c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-15T02:33:18.420531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became follower at term 1"} {"level":"info","ts":"2026-08-15T02:33:18.420593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=(9677694937923227548)"} {"level":"warn","ts":"2026-08-15T02:33:18.427505Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-15T02:33:18.428714Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-15T02:33:18.429876Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-15T02:33:18.43244Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"864e14457b828f9c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-15T02:33:18.432606Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-15T02:33:18.433231Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-15T02:33:18.433337Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-15T02:33:18.432829Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"864e14457b828f9c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-15T02:33:18.434287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=(9677694937923227548)"} {"level":"info","ts":"2026-08-15T02:33:18.435017Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c0ae60a355554b9","local-member-id":"864e14457b828f9c","added-peer-id":"864e14457b828f9c","added-peer-peer-urls":["https://199.19.213.243:2380"]} {"level":"info","ts":"2026-08-15T02:33:18.440572Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-15T02:33:18.440813Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.243:2380"} {"level":"info","ts":"2026-08-15T02:33:18.440885Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.243:2380"} {"level":"info","ts":"2026-08-15T02:33:18.440961Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"864e14457b828f9c","initial-advertise-peer-urls":["https://199.19.213.243:2380"],"listen-peer-urls":["https://199.19.213.243:2380"],"advertise-client-urls":["https://199.19.213.243:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-15T02:33:18.441022Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-15T02:33:18.521157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c is starting a new election at term 1"} {"level":"info","ts":"2026-08-15T02:33:18.521261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became pre-candidate at term 1"} {"level":"info","ts":"2026-08-15T02:33:18.521633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c received MsgPreVoteResp from 864e14457b828f9c at term 1"} {"level":"info","ts":"2026-08-15T02:33:18.521668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became candidate at term 2"} {"level":"info","ts":"2026-08-15T02:33:18.521723Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c received MsgVoteResp from 864e14457b828f9c at term 2"} {"level":"info","ts":"2026-08-15T02:33:18.52174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became leader at term 2"} {"level":"info","ts":"2026-08-15T02:33:18.521826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 864e14457b828f9c elected leader 864e14457b828f9c at term 2"} {"level":"info","ts":"2026-08-15T02:33:18.522759Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"864e14457b828f9c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.243:2379]}","request-path":"/0/members/864e14457b828f9c/attributes","cluster-id":"6c0ae60a355554b9","publish-timeout":"7s"} {"level":"info","ts":"2026-08-15T02:33:18.522996Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-15T02:33:18.523099Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-15T02:33:18.52307Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:33:18.524151Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c0ae60a355554b9","local-member-id":"864e14457b828f9c","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:33:18.524247Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:33:18.524307Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-15T02:33:18.524594Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-15T02:33:18.524665Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-15T02:33:18.527415Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.243:2379"} {"level":"info","ts":"2026-08-15T02:33:18.529626Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-15T02:33:35.69596Z","caller":"traceutil/trace.go:171","msg":"trace[1300332843] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"199.560685ms","start":"2026-08-15T02:33:35.496381Z","end":"2026-08-15T02:33:35.695942Z","steps":["trace[1300332843] 'read index received' (duration: 199.430503ms)","trace[1300332843] 'applied index is now lower than readState.Index' (duration: 129.742µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:33:35.69604Z","caller":"traceutil/trace.go:171","msg":"trace[1705784658] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"311.863232ms","start":"2026-08-15T02:33:35.384151Z","end":"2026-08-15T02:33:35.696014Z","steps":["trace[1705784658] 'process raft request' (duration: 311.670829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:33:35.696097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.695507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:33:35.69614Z","caller":"traceutil/trace.go:171","msg":"trace[1135718913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:368; }","duration":"199.756657ms","start":"2026-08-15T02:33:35.496375Z","end":"2026-08-15T02:33:35.696132Z","steps":["trace[1135718913] 'agreement among raft nodes before linearized reading' (duration: 199.676766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:33:35.696239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.27609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-15T02:33:35.696293Z","caller":"traceutil/trace.go:171","msg":"trace[1186180468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:368; }","duration":"181.356081ms","start":"2026-08-15T02:33:35.514922Z","end":"2026-08-15T02:33:35.696278Z","steps":["trace[1186180468] 'agreement among raft nodes before linearized reading' (duration: 181.26262ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:33:35.696595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:33:35.384128Z","time spent":"311.976623ms","remote":"127.0.0.1:44710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:33:35.914797Z","caller":"traceutil/trace.go:171","msg":"trace[1006140033] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"214.741778ms","start":"2026-08-15T02:33:35.700021Z","end":"2026-08-15T02:33:35.914762Z","steps":["trace[1006140033] 'process raft request' (duration: 146.667216ms)","trace[1006140033] 'compare' (duration: 67.779628ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:33:35.914896Z","caller":"traceutil/trace.go:171","msg":"trace[1137027222] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"213.462541ms","start":"2026-08-15T02:33:35.70141Z","end":"2026-08-15T02:33:35.914873Z","steps":["trace[1137027222] 'process raft request' (duration: 213.285899ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:33:35.915127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.810729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-08-15T02:33:35.915173Z","caller":"traceutil/trace.go:171","msg":"trace[197943279] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:370; }","duration":"214.89702ms","start":"2026-08-15T02:33:35.700266Z","end":"2026-08-15T02:33:35.915163Z","steps":["trace[197943279] 'agreement among raft nodes before linearized reading' (duration: 214.803809ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:33:35.915018Z","caller":"traceutil/trace.go:171","msg":"trace[1316360666] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"214.694788ms","start":"2026-08-15T02:33:35.700297Z","end":"2026-08-15T02:33:35.914992Z","steps":["trace[1316360666] 'read index received' (duration: 146.401833ms)","trace[1316360666] 'applied index is now lower than readState.Index' (duration: 68.291045ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:33:57.023596Z","caller":"traceutil/trace.go:171","msg":"trace[183121894] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"143.154676ms","start":"2026-08-15T02:33:56.88042Z","end":"2026-08-15T02:33:57.023574Z","steps":["trace[183121894] 'process raft request' (duration: 143.025035ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:34:03.36786Z","caller":"traceutil/trace.go:171","msg":"trace[1210909511] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"206.987772ms","start":"2026-08-15T02:34:03.160847Z","end":"2026-08-15T02:34:03.367835Z","steps":["trace[1210909511] 'process raft request' (duration: 206.79659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:34:18.759971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.305371ms","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-08-15T02:34:18.760086Z","caller":"traceutil/trace.go:171","msg":"trace[1300134463] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:763; }","duration":"229.502949ms","start":"2026-08-15T02:34:18.530556Z","end":"2026-08-15T02:34:18.760059Z","steps":["trace[1300134463] 'range keys from in-memory index tree' (duration: 229.079561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:35:33.898253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.352322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-15T02:35:33.898737Z","caller":"traceutil/trace.go:171","msg":"trace[1858295733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1827; }","duration":"367.10682ms","start":"2026-08-15T02:35:33.531603Z","end":"2026-08-15T02:35:33.89871Z","steps":["trace[1858295733] 'range keys from in-memory index tree' (duration: 366.116997ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:35:33.898983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:35:33.531576Z","time spent":"367.359955ms","remote":"127.0.0.1:44806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-15T02:35:33.898342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.232441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-74fe04b0-4b88-40c1-9fec-f48dc7ded7d7\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-15T02:35:33.89926Z","caller":"traceutil/trace.go:171","msg":"trace[904461236] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-74fe04b0-4b88-40c1-9fec-f48dc7ded7d7; range_end:; response_count:1; response_revision:1827; }","duration":"291.143871ms","start":"2026-08-15T02:35:33.608069Z","end":"2026-08-15T02:35:33.899213Z","steps":["trace[904461236] 'range keys from in-memory index tree' (duration: 289.855752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:35:34.2807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.590442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-15T02:35:34.280799Z","caller":"traceutil/trace.go:171","msg":"trace[735762394] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1829; }","duration":"157.749466ms","start":"2026-08-15T02:35:34.123027Z","end":"2026-08-15T02:35:34.280776Z","steps":["trace[735762394] 'range keys from in-memory index tree' (duration: 157.366628ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:35:34.280951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.291535ms","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-08-15T02:35:34.281344Z","caller":"traceutil/trace.go:171","msg":"trace[520929689] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1829; }","duration":"269.886289ms","start":"2026-08-15T02:35:34.011432Z","end":"2026-08-15T02:35:34.281318Z","steps":["trace[520929689] 'range keys from in-memory index tree' (duration: 269.119121ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:35:46.477942Z","caller":"traceutil/trace.go:171","msg":"trace[401544] linearizableReadLoop","detail":"{readStateIndex:2004; appliedIndex:2003; }","duration":"108.497698ms","start":"2026-08-15T02:35:46.369413Z","end":"2026-08-15T02:35:46.477911Z","steps":["trace[401544] 'read index received' (duration: 108.294924ms)","trace[401544] 'applied index is now lower than readState.Index' (duration: 201.384µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:35:46.478072Z","caller":"traceutil/trace.go:171","msg":"trace[1774296867] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"123.48941ms","start":"2026-08-15T02:35:46.354527Z","end":"2026-08-15T02:35:46.478016Z","steps":["trace[1774296867] 'process raft request' (duration: 123.198643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:35:46.478281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.842996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-15T02:35:46.478371Z","caller":"traceutil/trace.go:171","msg":"trace[991114764] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1932; }","duration":"108.963678ms","start":"2026-08-15T02:35:46.369385Z","end":"2026-08-15T02:35:46.478349Z","steps":["trace[991114764] 'agreement among raft nodes before linearized reading' (duration: 108.624031ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:35:48.820054Z","caller":"traceutil/trace.go:171","msg":"trace[1797387553] linearizableReadLoop","detail":"{readStateIndex:2017; appliedIndex:2016; }","duration":"177.496509ms","start":"2026-08-15T02:35:48.642536Z","end":"2026-08-15T02:35:48.820033Z","steps":["trace[1797387553] 'read index received' (duration: 177.337325ms)","trace[1797387553] 'applied index is now lower than readState.Index' (duration: 157.994µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:35:48.82029Z","caller":"traceutil/trace.go:171","msg":"trace[57148586] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"182.490612ms","start":"2026-08-15T02:35:48.637792Z","end":"2026-08-15T02:35:48.820282Z","steps":["trace[57148586] 'process raft request' (duration: 182.062443ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:35:48.820615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.04137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-15T02:35:48.820735Z","caller":"traceutil/trace.go:171","msg":"trace[67449368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1944; }","duration":"178.223534ms","start":"2026-08-15T02:35:48.642485Z","end":"2026-08-15T02:35:48.820708Z","steps":["trace[67449368] 'agreement among raft nodes before linearized reading' (duration: 177.831186ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:35:48.844458Z","caller":"traceutil/trace.go:171","msg":"trace[1433092317] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"127.450432ms","start":"2026-08-15T02:35:48.716946Z","end":"2026-08-15T02:35:48.844396Z","steps":["trace[1433092317] 'process raft request' (duration: 127.285419ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:35:48.844553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.784066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-15T02:35:48.844626Z","caller":"traceutil/trace.go:171","msg":"trace[1575029075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1945; }","duration":"161.920348ms","start":"2026-08-15T02:35:48.682687Z","end":"2026-08-15T02:35:48.844607Z","steps":["trace[1575029075] 'agreement among raft nodes before linearized reading' (duration: 161.700714ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:35:48.844657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.560044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-15T02:35:48.844723Z","caller":"traceutil/trace.go:171","msg":"trace[1582555621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1945; }","duration":"185.669526ms","start":"2026-08-15T02:35:48.659036Z","end":"2026-08-15T02:35:48.844706Z","steps":["trace[1582555621] 'agreement among raft nodes before linearized reading' (duration: 185.413951ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:35:49.398131Z","caller":"traceutil/trace.go:171","msg":"trace[397399091] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"118.350963ms","start":"2026-08-15T02:35:49.279752Z","end":"2026-08-15T02:35:49.398103Z","steps":["trace[397399091] 'process raft request' (duration: 118.167349ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:36:08.872995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.271075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-15T02:36:08.873315Z","caller":"traceutil/trace.go:171","msg":"trace[1417851005] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2064; }","duration":"166.624762ms","start":"2026-08-15T02:36:08.706636Z","end":"2026-08-15T02:36:08.873261Z","steps":["trace[1417851005] 'range keys from in-memory index tree' (duration: 166.019381ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:36:09.048618Z","caller":"traceutil/trace.go:171","msg":"trace[1139990276] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"139.33464ms","start":"2026-08-15T02:36:08.909243Z","end":"2026-08-15T02:36:09.048577Z","steps":["trace[1139990276] 'process raft request' (duration: 133.526741ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:37:36.062541Z","caller":"traceutil/trace.go:171","msg":"trace[1477391761] linearizableReadLoop","detail":"{readStateIndex:2745; appliedIndex:2744; }","duration":"110.79495ms","start":"2026-08-15T02:37:35.951714Z","end":"2026-08-15T02:37:36.062508Z","steps":["trace[1477391761] 'read index received' (duration: 110.585617ms)","trace[1477391761] 'applied index is now lower than readState.Index' (duration: 207.333µs)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:37:36.062623Z","caller":"traceutil/trace.go:171","msg":"trace[1975591665] transaction","detail":"{read_only:false; response_revision:2648; number_of_response:1; }","duration":"128.824227ms","start":"2026-08-15T02:37:35.933754Z","end":"2026-08-15T02:37:36.062578Z","steps":["trace[1975591665] 'process raft request' (duration: 128.601854ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:37:36.063035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.275457ms","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-08-15T02:37:36.063357Z","caller":"traceutil/trace.go:171","msg":"trace[1930416964] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2648; }","duration":"111.576111ms","start":"2026-08-15T02:37:35.951673Z","end":"2026-08-15T02:37:36.063249Z","steps":["trace[1930416964] 'agreement among raft nodes before linearized reading' (duration: 110.965013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:37:36.366135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.011032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:37:36.366221Z","caller":"traceutil/trace.go:171","msg":"trace[2135395421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2649; }","duration":"128.137695ms","start":"2026-08-15T02:37:36.238062Z","end":"2026-08-15T02:37:36.3662Z","steps":["trace[2135395421] 'range keys from in-memory index tree' (duration: 127.926121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:37:36.366308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.983162ms","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-08-15T02:37:36.366358Z","caller":"traceutil/trace.go:171","msg":"trace[398556946] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2649; }","duration":"107.079734ms","start":"2026-08-15T02:37:36.259264Z","end":"2026-08-15T02:37:36.366343Z","steps":["trace[398556946] 'range keys from in-memory index tree' (duration: 106.778229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:19.367269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.636135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-15T02:41:19.367655Z","caller":"traceutil/trace.go:171","msg":"trace[526169006] linearizableReadLoop","detail":"{readStateIndex:4724; appliedIndex:4722; }","duration":"291.845141ms","start":"2026-08-15T02:41:19.075743Z","end":"2026-08-15T02:41:19.367588Z","steps":["trace[526169006] 'read index received' (duration: 107.917117ms)","trace[526169006] 'applied index is now lower than readState.Index' (duration: 183.926934ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:19.367741Z","caller":"traceutil/trace.go:171","msg":"trace[1759421016] transaction","detail":"{read_only:false; response_revision:4551; number_of_response:1; }","duration":"293.807779ms","start":"2026-08-15T02:41:19.073924Z","end":"2026-08-15T02:41:19.367731Z","steps":["trace[1759421016] 'process raft request' (duration: 293.522292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:19.368376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.5805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-15T02:41:19.368427Z","caller":"traceutil/trace.go:171","msg":"trace[1054511919] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4551; }","duration":"292.648741ms","start":"2026-08-15T02:41:19.075767Z","end":"2026-08-15T02:41:19.368415Z","steps":["trace[1054511919] 'agreement among raft nodes before linearized reading' (duration: 292.450836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:19.36842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.652871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-08-15T02:41:19.368506Z","caller":"traceutil/trace.go:171","msg":"trace[2096739164] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4551; }","duration":"292.766415ms","start":"2026-08-15T02:41:19.075717Z","end":"2026-08-15T02:41:19.368483Z","steps":["trace[2096739164] 'agreement among raft nodes before linearized reading' (duration: 292.520048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:19.368513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.486798ms","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-08-15T02:41:19.368599Z","caller":"traceutil/trace.go:171","msg":"trace[231468461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4551; }","duration":"242.622202ms","start":"2026-08-15T02:41:19.125957Z","end":"2026-08-15T02:41:19.368579Z","steps":["trace[231468461] 'agreement among raft nodes before linearized reading' (duration: 242.327634ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:19.368875Z","caller":"traceutil/trace.go:171","msg":"trace[607336222] transaction","detail":"{read_only:false; response_revision:4550; number_of_response:1; }","duration":"295.283854ms","start":"2026-08-15T02:41:19.072621Z","end":"2026-08-15T02:41:19.367905Z","steps":["trace[607336222] 'process raft request' (duration: 110.922928ms)","trace[607336222] 'compare' (duration: 181.971289ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:19.673809Z","caller":"traceutil/trace.go:171","msg":"trace[82846131] linearizableReadLoop","detail":"{readStateIndex:4739; appliedIndex:4738; }","duration":"124.578443ms","start":"2026-08-15T02:41:19.549203Z","end":"2026-08-15T02:41:19.673781Z","steps":["trace[82846131] 'read index received' (duration: 97.825557ms)","trace[82846131] 'applied index is now lower than readState.Index' (duration: 26.751106ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:41:19.673918Z","caller":"traceutil/trace.go:171","msg":"trace[1287217193] transaction","detail":"{read_only:false; response_revision:4566; number_of_response:1; }","duration":"127.33405ms","start":"2026-08-15T02:41:19.546534Z","end":"2026-08-15T02:41:19.673868Z","steps":["trace[1287217193] 'process raft request' (duration: 100.517272ms)","trace[1287217193] 'compare' (duration: 26.422718ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:41:19.67413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.902351ms","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-08-15T02:41:19.674318Z","caller":"traceutil/trace.go:171","msg":"trace[913486267] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4566; }","duration":"125.031035ms","start":"2026-08-15T02:41:19.549191Z","end":"2026-08-15T02:41:19.674222Z","steps":["trace[913486267] 'agreement among raft nodes before linearized reading' (duration: 124.778149ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:19.941679Z","caller":"traceutil/trace.go:171","msg":"trace[735430741] transaction","detail":"{read_only:false; response_revision:4571; number_of_response:1; }","duration":"100.327277ms","start":"2026-08-15T02:41:19.841322Z","end":"2026-08-15T02:41:19.941649Z","steps":["trace[735430741] 'process raft request' (duration: 100.192044ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:41:19.949243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.94968ms","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-08-15T02:41:19.9493Z","caller":"traceutil/trace.go:171","msg":"trace[1871397372] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4572; }","duration":"106.028432ms","start":"2026-08-15T02:41:19.843257Z","end":"2026-08-15T02:41:19.949285Z","steps":["trace[1871397372] 'agreement among raft nodes before linearized reading' (duration: 105.909689ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:22.585751Z","caller":"traceutil/trace.go:171","msg":"trace[1869736740] transaction","detail":"{read_only:false; response_revision:4628; number_of_response:1; }","duration":"102.138454ms","start":"2026-08-15T02:41:22.483586Z","end":"2026-08-15T02:41:22.585724Z","steps":["trace[1869736740] 'process raft request' (duration: 101.97089ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:31.561759Z","caller":"traceutil/trace.go:171","msg":"trace[1274485903] transaction","detail":"{read_only:false; response_revision:4774; number_of_response:1; }","duration":"120.052192ms","start":"2026-08-15T02:41:31.441651Z","end":"2026-08-15T02:41:31.561703Z","steps":["trace[1274485903] 'process raft request' (duration: 119.862658ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:31.774195Z","caller":"traceutil/trace.go:171","msg":"trace[936669565] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"124.29645ms","start":"2026-08-15T02:41:31.64987Z","end":"2026-08-15T02:41:31.774166Z","steps":["trace[936669565] 'process raft request' (duration: 124.172007ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:31.774201Z","caller":"traceutil/trace.go:171","msg":"trace[1621330073] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"124.572637ms","start":"2026-08-15T02:41:31.649594Z","end":"2026-08-15T02:41:31.774167Z","steps":["trace[1621330073] 'process raft request' (duration: 124.298211ms)"],"step_count":1} {"level":"info","ts":"2026-08-15T02:41:31.774471Z","caller":"traceutil/trace.go:171","msg":"trace[1344980956] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"123.709246ms","start":"2026-08-15T02:41:31.650737Z","end":"2026-08-15T02:41:31.774446Z","steps":["trace[1344980956] 'process raft request' (duration: 123.388769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:13.534425Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348321979680653918,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-15T02:42:13.984035Z","caller":"traceutil/trace.go:171","msg":"trace[814674629] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"962.318065ms","start":"2026-08-15T02:42:13.021688Z","end":"2026-08-15T02:42:13.984006Z","steps":["trace[814674629] 'process raft request' (duration: 962.148301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:13.984213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:13.021667Z","time spent":"962.448328ms","remote":"127.0.0.1:51934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7439,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-15T02:42:14.000136Z","caller":"traceutil/trace.go:171","msg":"trace[1746165467] linearizableReadLoop","detail":"{readStateIndex:5310; appliedIndex:5308; }","duration":"966.490735ms","start":"2026-08-15T02:42:13.033612Z","end":"2026-08-15T02:42:14.000102Z","steps":["trace[1746165467] 'read index received' (duration: 950.258717ms)","trace[1746165467] 'applied index is now lower than readState.Index' (duration: 16.230078ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:42:14.000205Z","caller":"traceutil/trace.go:171","msg":"trace[1398373464] transaction","detail":"{read_only:false; response_revision:5122; number_of_response:1; }","duration":"877.799085ms","start":"2026-08-15T02:42:13.122361Z","end":"2026-08-15T02:42:14.000161Z","steps":["trace[1398373464] 'process raft request' (duration: 862.328444ms)","trace[1398373464] 'compare' (duration: 15.258776ms)"],"step_count":2} {"level":"warn","ts":"2026-08-15T02:42:14.000408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:13.122341Z","time spent":"877.974438ms","remote":"127.0.0.1:44806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-15T02:42:14.000481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.867262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-15T02:42:14.000566Z","caller":"traceutil/trace.go:171","msg":"trace[724140957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5122; }","duration":"967.018755ms","start":"2026-08-15T02:42:13.033525Z","end":"2026-08-15T02:42:14.000543Z","steps":["trace[724140957] 'agreement among raft nodes before linearized reading' (duration: 966.823441ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:14.000618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:13.033491Z","time spent":"967.117637ms","remote":"127.0.0.1:44806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-15T02:42:14.000589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.508222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-15T02:42:14.000696Z","caller":"traceutil/trace.go:171","msg":"trace[1168389101] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5122; }","duration":"965.671926ms","start":"2026-08-15T02:42:13.035012Z","end":"2026-08-15T02:42:14.000684Z","steps":["trace[1168389101] 'agreement among raft nodes before linearized reading' (duration: 965.354769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:14.000753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:13.034971Z","time spent":"965.771778ms","remote":"127.0.0.1:44806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-15T02:42:14.000833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.626894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:42:14.000909Z","caller":"traceutil/trace.go:171","msg":"trace[708639893] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:5122; }","duration":"341.746937ms","start":"2026-08-15T02:42:13.659144Z","end":"2026-08-15T02:42:14.000891Z","steps":["trace[708639893] 'agreement among raft nodes before linearized reading' (duration: 341.619124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:14.000969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:13.659125Z","time spent":"341.82803ms","remote":"127.0.0.1:48790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":30,"request content":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true "} {"level":"warn","ts":"2026-08-15T02:42:14.001003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.472284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-15T02:42:14.001064Z","caller":"traceutil/trace.go:171","msg":"trace[1248778881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5122; }","duration":"208.606057ms","start":"2026-08-15T02:42:13.792447Z","end":"2026-08-15T02:42:14.001053Z","steps":["trace[1248778881] 'agreement among raft nodes before linearized reading' (duration: 208.487114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:14.001161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.015276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:42:14.001219Z","caller":"traceutil/trace.go:171","msg":"trace[265133121] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5122; }","duration":"379.118678ms","start":"2026-08-15T02:42:13.622091Z","end":"2026-08-15T02:42:14.00121Z","steps":["trace[265133121] 'agreement among raft nodes before linearized reading' (duration: 379.031816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:14.001232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.240758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-15T02:42:14.00126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:13.622068Z","time spent":"379.18367ms","remote":"127.0.0.1:35554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-08-15T02:42:14.001297Z","caller":"traceutil/trace.go:171","msg":"trace[1284631179] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:5122; }","duration":"285.347699ms","start":"2026-08-15T02:42:13.715933Z","end":"2026-08-15T02:42:14.001281Z","steps":["trace[1284631179] 'agreement among raft nodes before linearized reading' (duration: 285.250497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:14.001373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.820089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-15T02:42:14.001416Z","caller":"traceutil/trace.go:171","msg":"trace[1451860041] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5122; }","duration":"936.916401ms","start":"2026-08-15T02:42:13.06449Z","end":"2026-08-15T02:42:14.001407Z","steps":["trace[1451860041] 'agreement among raft nodes before linearized reading' (duration: 936.809929ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:14.001478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:13.06447Z","time spent":"937.000643ms","remote":"127.0.0.1:44806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-15T02:42:14.001703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.340592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-15T02:42:14.001744Z","caller":"traceutil/trace.go:171","msg":"trace[699519461] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:5122; }","duration":"612.435954ms","start":"2026-08-15T02:42:13.389297Z","end":"2026-08-15T02:42:14.001733Z","steps":["trace[699519461] 'agreement among raft nodes before linearized reading' (duration: 612.370283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-15T02:42:14.001781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-15T02:42:13.389272Z","time spent":"612.496325ms","remote":"127.0.0.1:45026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":4,"response size":30,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-08-15T02:43:18.691872Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2871} {"level":"info","ts":"2026-08-15T02:43:18.792568Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2871,"took":"99.089613ms","hash":2128098943,"current-db-size-bytes":54198272,"current-db-size":"54 MB","current-db-size-in-use-bytes":29405184,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-15T02:43:18.792635Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2128098943,"revision":2871,"compact-revision":-1} {"level":"warn","ts":"2026-08-15T02:45:16.930521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.418975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-15T02:45:16.930657Z","caller":"traceutil/trace.go:171","msg":"trace[800105629] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:6388; }","duration":"125.549497ms","start":"2026-08-15T02:45:16.80505Z","end":"2026-08-15T02:45:16.9306Z","steps":["trace[800105629] 'agreement among raft nodes before linearized reading' (duration: 96.26864ms)","trace[800105629] 'count revisions from in-memory index tree' (duration: 29.112134ms)"],"step_count":2} {"level":"info","ts":"2026-08-15T02:48:18.698598Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5591} {"level":"info","ts":"2026-08-15T02:48:18.79351Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5591,"took":"93.451689ms","hash":2342839878,"current-db-size-bytes":54198272,"current-db-size":"54 MB","current-db-size-in-use-bytes":17948672,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-15T02:48:18.793583Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2342839878,"revision":5591,"compact-revision":2871}