{"level":"warn","ts":"2026-08-11T08:16:26.466717Z","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-11T08:16:26.466815Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.73: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.73:2380","--initial-cluster=instance=https://199.19.213.73:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.73:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.73: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-11T08:16:26.466944Z","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-11T08:16:26.466958Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.73:2380"]} {"level":"info","ts":"2026-08-11T08:16:26.466979Z","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-11T08:16:26.467561Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"]} {"level":"info","ts":"2026-08-11T08:16:26.467645Z","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.73:2380"],"listen-peer-urls":["https://199.19.213.73:2380"],"advertise-client-urls":["https://199.19.213.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.73: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-11T08:16:26.471466Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.598427ms"} {"level":"info","ts":"2026-08-11T08:16:26.477311Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"34a8f480fa9ab6cb","cluster-id":"4820536600e0938e"} {"level":"info","ts":"2026-08-11T08:16:26.477565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=()"} {"level":"info","ts":"2026-08-11T08:16:26.477931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became follower at term 0"} {"level":"info","ts":"2026-08-11T08:16:26.477967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 34a8f480fa9ab6cb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-11T08:16:26.477989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became follower at term 1"} {"level":"info","ts":"2026-08-11T08:16:26.478059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=(3794551520857077451)"} {"level":"warn","ts":"2026-08-11T08:16:26.484164Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-11T08:16:26.488049Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-11T08:16:26.489311Z","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-11T08:16:26.491786Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"34a8f480fa9ab6cb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-11T08:16:26.492058Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"34a8f480fa9ab6cb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-11T08:16:26.492237Z","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-11T08:16:26.492347Z","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-11T08:16:26.492365Z","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-11T08:16:26.493792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=(3794551520857077451)"} {"level":"info","ts":"2026-08-11T08:16:26.494435Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4820536600e0938e","local-member-id":"34a8f480fa9ab6cb","added-peer-id":"34a8f480fa9ab6cb","added-peer-peer-urls":["https://199.19.213.73:2380"]} {"level":"info","ts":"2026-08-11T08:16:26.496067Z","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-11T08:16:26.496293Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.73:2380"} {"level":"info","ts":"2026-08-11T08:16:26.496348Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.73:2380"} {"level":"info","ts":"2026-08-11T08:16:26.496439Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-11T08:16:26.49636Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"34a8f480fa9ab6cb","initial-advertise-peer-urls":["https://199.19.213.73:2380"],"listen-peer-urls":["https://199.19.213.73:2380"],"advertise-client-urls":["https://199.19.213.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-11T08:16:26.77913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb is starting a new election at term 1"} {"level":"info","ts":"2026-08-11T08:16:26.779188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became pre-candidate at term 1"} {"level":"info","ts":"2026-08-11T08:16:26.779231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb received MsgPreVoteResp from 34a8f480fa9ab6cb at term 1"} {"level":"info","ts":"2026-08-11T08:16:26.779251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became candidate at term 2"} {"level":"info","ts":"2026-08-11T08:16:26.779263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb received MsgVoteResp from 34a8f480fa9ab6cb at term 2"} {"level":"info","ts":"2026-08-11T08:16:26.779279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became leader at term 2"} {"level":"info","ts":"2026-08-11T08:16:26.779294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 34a8f480fa9ab6cb elected leader 34a8f480fa9ab6cb at term 2"} {"level":"info","ts":"2026-08-11T08:16:26.78043Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"34a8f480fa9ab6cb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.73:2379]}","request-path":"/0/members/34a8f480fa9ab6cb/attributes","cluster-id":"4820536600e0938e","publish-timeout":"7s"} {"level":"info","ts":"2026-08-11T08:16:26.780445Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T08:16:26.780491Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T08:16:26.780619Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T08:16:26.780756Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-11T08:16:26.780897Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-11T08:16:26.78151Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4820536600e0938e","local-member-id":"34a8f480fa9ab6cb","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T08:16:26.781693Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T08:16:26.78178Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T08:16:26.784781Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.73:2379"} {"level":"info","ts":"2026-08-11T08:16:26.785515Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-11T08:17:03.357762Z","caller":"traceutil/trace.go:171","msg":"trace[1007950573] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"201.882197ms","start":"2026-08-11T08:17:03.155851Z","end":"2026-08-11T08:17:03.357733Z","steps":["trace[1007950573] 'read index received' (duration: 201.314332ms)","trace[1007950573] 'applied index is now lower than readState.Index' (duration: 566.624µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:17:03.357911Z","caller":"traceutil/trace.go:171","msg":"trace[2043952051] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"204.770533ms","start":"2026-08-11T08:17:03.15311Z","end":"2026-08-11T08:17:03.357881Z","steps":["trace[2043952051] 'process raft request' (duration: 204.245241ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:17:03.358059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.188156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:65214"} {"level":"info","ts":"2026-08-11T08:17:03.358128Z","caller":"traceutil/trace.go:171","msg":"trace[856530566] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:556; }","duration":"202.298473ms","start":"2026-08-11T08:17:03.155813Z","end":"2026-08-11T08:17:03.358112Z","steps":["trace[856530566] 'agreement among raft nodes before linearized reading' (duration: 202.028187ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:17:08.032071Z","caller":"traceutil/trace.go:171","msg":"trace[564711716] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"209.509968ms","start":"2026-08-11T08:17:07.822496Z","end":"2026-08-11T08:17:08.032006Z","steps":["trace[564711716] 'process raft request' (duration: 186.405577ms)","trace[564711716] 'compare' (duration: 22.518815ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T08:17:08.499067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.358502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-11T08:17:08.499185Z","caller":"traceutil/trace.go:171","msg":"trace[124533753] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"390.525764ms","start":"2026-08-11T08:17:08.10864Z","end":"2026-08-11T08:17:08.499166Z","steps":["trace[124533753] 'process raft request' (duration: 230.417255ms)","trace[124533753] 'compare' (duration: 159.080766ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T08:17:08.499503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:17:08.108615Z","time spent":"390.843183ms","remote":"127.0.0.1:59088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T08:17:25.368199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.283549ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-11T08:18:43.354032Z","caller":"traceutil/trace.go:171","msg":"trace[1967069009] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1921; }","duration":"110.747944ms","start":"2026-08-11T08:18:43.243249Z","end":"2026-08-11T08:18:43.353997Z","steps":["trace[1967069009] 'read index received' (duration: 105.679092ms)","trace[1967069009] 'applied index is now lower than readState.Index' (duration: 5.067622ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T08:18:43.354433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.164345ms","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-11T08:18:43.354498Z","caller":"traceutil/trace.go:171","msg":"trace[353768421] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1842; }","duration":"111.285468ms","start":"2026-08-11T08:18:43.243193Z","end":"2026-08-11T08:18:43.354479Z","steps":["trace[353768421] 'agreement among raft nodes before linearized reading' (duration: 110.982589ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:18:43.354226Z","caller":"traceutil/trace.go:171","msg":"trace[455931145] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"175.912339ms","start":"2026-08-11T08:18:43.178274Z","end":"2026-08-11T08:18:43.354186Z","steps":["trace[455931145] 'process raft request' (duration: 170.617492ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:19:23.771701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.290837ms","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-11T08:19:23.771794Z","caller":"traceutil/trace.go:171","msg":"trace[1028056736] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2112; }","duration":"147.467323ms","start":"2026-08-11T08:19:23.624303Z","end":"2026-08-11T08:19:23.77177Z","steps":["trace[1028056736] 'range keys from in-memory index tree' (duration: 147.026242ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:19:24.163384Z","caller":"traceutil/trace.go:171","msg":"trace[837033320] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"143.873428ms","start":"2026-08-11T08:19:24.01947Z","end":"2026-08-11T08:19:24.163343Z","steps":["trace[837033320] 'process raft request' (duration: 143.609073ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:20:04.859377Z","caller":"traceutil/trace.go:171","msg":"trace[768807316] transaction","detail":"{read_only:false; response_revision:2387; number_of_response:1; }","duration":"108.17963ms","start":"2026-08-11T08:20:04.751165Z","end":"2026-08-11T08:20:04.859344Z","steps":["trace[768807316] 'process raft request' (duration: 107.872928ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:20:46.587431Z","caller":"traceutil/trace.go:171","msg":"trace[24631121] transaction","detail":"{read_only:false; response_revision:2668; number_of_response:1; }","duration":"136.104722ms","start":"2026-08-11T08:20:46.451272Z","end":"2026-08-11T08:20:46.587377Z","steps":["trace[24631121] 'process raft request' (duration: 135.945099ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:24:42.163468Z","caller":"traceutil/trace.go:171","msg":"trace[1808216009] linearizableReadLoop","detail":"{readStateIndex:4765; appliedIndex:4764; }","duration":"104.024482ms","start":"2026-08-11T08:24:42.059402Z","end":"2026-08-11T08:24:42.163427Z","steps":["trace[1808216009] 'read index received' (duration: 103.659004ms)","trace[1808216009] 'applied index is now lower than readState.Index' (duration: 363.868µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:24:42.163573Z","caller":"traceutil/trace.go:171","msg":"trace[1420086994] transaction","detail":"{read_only:false; response_revision:4579; number_of_response:1; }","duration":"104.39351ms","start":"2026-08-11T08:24:42.059132Z","end":"2026-08-11T08:24:42.163526Z","steps":["trace[1420086994] 'process raft request' (duration: 104.062593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:24:42.163739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.321018ms","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-11T08:24:42.163814Z","caller":"traceutil/trace.go:171","msg":"trace[1229569707] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4579; }","duration":"104.427931ms","start":"2026-08-11T08:24:42.059364Z","end":"2026-08-11T08:24:42.163792Z","steps":["trace[1229569707] 'agreement among raft nodes before linearized reading' (duration: 104.218577ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:24:42.289765Z","caller":"traceutil/trace.go:171","msg":"trace[580681198] transaction","detail":"{read_only:false; response_revision:4580; number_of_response:1; }","duration":"120.214798ms","start":"2026-08-11T08:24:42.169516Z","end":"2026-08-11T08:24:42.28973Z","steps":["trace[580681198] 'process raft request' (duration: 79.582999ms)","trace[580681198] 'compare' (duration: 40.202739ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:24:46.470265Z","caller":"traceutil/trace.go:171","msg":"trace[1573636377] transaction","detail":"{read_only:false; response_revision:4711; number_of_response:1; }","duration":"135.720689ms","start":"2026-08-11T08:24:46.334495Z","end":"2026-08-11T08:24:46.470216Z","steps":["trace[1573636377] 'process raft request' (duration: 96.531332ms)","trace[1573636377] 'compare' (duration: 38.975683ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T08:25:00.639059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.315474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T08:25:00.639406Z","caller":"traceutil/trace.go:171","msg":"trace[1583580040] transaction","detail":"{read_only:false; response_revision:4874; number_of_response:1; }","duration":"383.654312ms","start":"2026-08-11T08:25:00.255703Z","end":"2026-08-11T08:25:00.639357Z","steps":["trace[1583580040] 'process raft request' (duration: 169.833786ms)","trace[1583580040] 'compare' (duration: 212.517975ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T08:25:00.639561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:25:00.255686Z","time spent":"383.785466ms","remote":"127.0.0.1:58804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13835,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T08:25:00.639535Z","caller":"traceutil/trace.go:171","msg":"trace[1981363068] linearizableReadLoop","detail":"{readStateIndex:5069; appliedIndex:5068; }","duration":"364.71868ms","start":"2026-08-11T08:25:00.274779Z","end":"2026-08-11T08:25:00.639498Z","steps":["trace[1981363068] 'read index received' (duration: 150.72234ms)","trace[1981363068] 'applied index is now lower than readState.Index' (duration: 213.99244ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T08:25:00.639653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.869204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T08:25:00.639693Z","caller":"traceutil/trace.go:171","msg":"trace[1721032219] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4875; }","duration":"364.931254ms","start":"2026-08-11T08:25:00.274746Z","end":"2026-08-11T08:25:00.639677Z","steps":["trace[1721032219] 'agreement among raft nodes before linearized reading' (duration: 364.816652ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:25:00.639695Z","caller":"traceutil/trace.go:171","msg":"trace[244892850] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"245.247236ms","start":"2026-08-11T08:25:00.394432Z","end":"2026-08-11T08:25:00.639679Z","steps":["trace[244892850] 'process raft request' (duration: 244.731074ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:25:00.639725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T08:25:00.27473Z","time spent":"364.990057ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-08-11T08:25:00.639959Z","caller":"traceutil/trace.go:171","msg":"trace[1182660552] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"237.952613ms","start":"2026-08-11T08:25:00.401998Z","end":"2026-08-11T08:25:00.63995Z","steps":["trace[1182660552] 'process raft request' (duration: 237.544032ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:25:00.640312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.068105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-11T08:25:00.640411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.408273ms","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-08-11T08:25:00.640441Z","caller":"traceutil/trace.go:171","msg":"trace[1494359288] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4876; }","duration":"212.489895ms","start":"2026-08-11T08:25:00.427944Z","end":"2026-08-11T08:25:00.640434Z","steps":["trace[1494359288] 'agreement among raft nodes before linearized reading' (duration: 212.324921ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:25:00.640455Z","caller":"traceutil/trace.go:171","msg":"trace[421304567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4876; }","duration":"104.28048ms","start":"2026-08-11T08:25:00.536153Z","end":"2026-08-11T08:25:00.640433Z","steps":["trace[421304567] 'agreement among raft nodes before linearized reading' (duration: 103.931402ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:25:00.926018Z","caller":"traceutil/trace.go:171","msg":"trace[1843498223] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"137.959675ms","start":"2026-08-11T08:25:00.78802Z","end":"2026-08-11T08:25:00.92598Z","steps":["trace[1843498223] 'process raft request' (duration: 137.703409ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:25:00.926387Z","caller":"traceutil/trace.go:171","msg":"trace[1909232782] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"138.276953ms","start":"2026-08-11T08:25:00.788071Z","end":"2026-08-11T08:25:00.926348Z","steps":["trace[1909232782] 'process raft request' (duration: 137.824542ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:25:01.195266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.786642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T08:25:01.195451Z","caller":"traceutil/trace.go:171","msg":"trace[846012453] linearizableReadLoop","detail":"{readStateIndex:5089; appliedIndex:5087; }","duration":"245.225379ms","start":"2026-08-11T08:25:00.950212Z","end":"2026-08-11T08:25:01.195437Z","steps":["trace[846012453] 'read index received' (duration: 30.929739ms)","trace[846012453] 'applied index is now lower than readState.Index' (duration: 214.29488ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:25:01.195526Z","caller":"traceutil/trace.go:171","msg":"trace[1460153] transaction","detail":"{read_only:false; response_revision:4893; number_of_response:1; }","duration":"258.755591ms","start":"2026-08-11T08:25:00.936741Z","end":"2026-08-11T08:25:01.195496Z","steps":["trace[1460153] 'process raft request' (duration: 132.684602ms)","trace[1460153] 'compare' (duration: 125.467535ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T08:25:01.195563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.845239ms","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-11T08:25:01.195587Z","caller":"traceutil/trace.go:171","msg":"trace[304675604] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4894; }","duration":"218.900059ms","start":"2026-08-11T08:25:00.976679Z","end":"2026-08-11T08:25:01.195579Z","steps":["trace[304675604] 'agreement among raft nodes before linearized reading' (duration: 218.838148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:25:01.195823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.74962ms","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-11T08:25:01.195832Z","caller":"traceutil/trace.go:171","msg":"trace[445170318] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"249.953721ms","start":"2026-08-11T08:25:00.945867Z","end":"2026-08-11T08:25:01.195821Z","steps":["trace[445170318] 'process raft request' (duration: 249.516001ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:25:01.195851Z","caller":"traceutil/trace.go:171","msg":"trace[243603294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4894; }","duration":"140.807452ms","start":"2026-08-11T08:25:01.055037Z","end":"2026-08-11T08:25:01.195844Z","steps":["trace[243603294] 'agreement among raft nodes before linearized reading' (duration: 140.707999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:25:01.196025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.798712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:62019"} {"level":"info","ts":"2026-08-11T08:25:01.196128Z","caller":"traceutil/trace.go:171","msg":"trace[1784190627] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4894; }","duration":"245.965526ms","start":"2026-08-11T08:25:00.950153Z","end":"2026-08-11T08:25:01.196119Z","steps":["trace[1784190627] 'agreement among raft nodes before linearized reading' (duration: 245.357452ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:25:01.354361Z","caller":"traceutil/trace.go:171","msg":"trace[2069560583] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"149.224084ms","start":"2026-08-11T08:25:01.205114Z","end":"2026-08-11T08:25:01.354338Z","steps":["trace[2069560583] 'process raft request' (duration: 112.125995ms)","trace[2069560583] 'compare' (duration: 36.902545ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:25:01.354544Z","caller":"traceutil/trace.go:171","msg":"trace[338732126] linearizableReadLoop","detail":"{readStateIndex:5092; appliedIndex:5089; }","duration":"131.847207ms","start":"2026-08-11T08:25:01.222659Z","end":"2026-08-11T08:25:01.354506Z","steps":["trace[338732126] 'read index received' (duration: 64.091464ms)","trace[338732126] 'applied index is now lower than readState.Index' (duration: 67.752783ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T08:25:01.354583Z","caller":"traceutil/trace.go:171","msg":"trace[585065000] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"145.389592ms","start":"2026-08-11T08:25:01.209165Z","end":"2026-08-11T08:25:01.354555Z","steps":["trace[585065000] 'process raft request' (duration: 145.110425ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:25:01.354709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.030242ms","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-11T08:25:01.354745Z","caller":"traceutil/trace.go:171","msg":"trace[1182080595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4897; }","duration":"132.117054ms","start":"2026-08-11T08:25:01.222619Z","end":"2026-08-11T08:25:01.354737Z","steps":["trace[1182080595] 'agreement among raft nodes before linearized reading' (duration: 131.97425ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:25:01.354785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.126875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-11T08:25:01.354823Z","caller":"traceutil/trace.go:171","msg":"trace[567921063] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:4897; }","duration":"127.253148ms","start":"2026-08-11T08:25:01.227562Z","end":"2026-08-11T08:25:01.354815Z","steps":["trace[567921063] 'agreement among raft nodes before linearized reading' (duration: 127.135285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:25:01.354897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.722247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13850"} {"level":"info","ts":"2026-08-11T08:25:01.35497Z","caller":"traceutil/trace.go:171","msg":"trace[719361063] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4897; }","duration":"114.85226ms","start":"2026-08-11T08:25:01.240101Z","end":"2026-08-11T08:25:01.354953Z","steps":["trace[719361063] 'agreement among raft nodes before linearized reading' (duration: 114.664856ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:26:26.83038Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2881} {"level":"info","ts":"2026-08-11T08:26:26.921321Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2881,"took":"89.465653ms","hash":1689968782,"current-db-size-bytes":53841920,"current-db-size":"54 MB","current-db-size-in-use-bytes":29229056,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-11T08:26:26.92137Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1689968782,"revision":2881,"compact-revision":-1} {"level":"warn","ts":"2026-08-11T08:29:14.848113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.429948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/ingress-nginx/\" range_end:\"/registry/secrets/ingress-nginx0\" ","response":"range_response_count:2 size:107255"} {"level":"info","ts":"2026-08-11T08:29:14.848229Z","caller":"traceutil/trace.go:171","msg":"trace[76228003] range","detail":"{range_begin:/registry/secrets/ingress-nginx/; range_end:/registry/secrets/ingress-nginx0; response_count:2; response_revision:6709; }","duration":"152.569863ms","start":"2026-08-11T08:29:14.695629Z","end":"2026-08-11T08:29:14.848199Z","steps":["trace[76228003] 'range keys from in-memory index tree' (duration: 152.255293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T08:29:14.848554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.468975ms","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-08-11T08:29:14.848621Z","caller":"traceutil/trace.go:171","msg":"trace[1161467861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6709; }","duration":"203.59852ms","start":"2026-08-11T08:29:14.645008Z","end":"2026-08-11T08:29:14.848607Z","steps":["trace[1161467861] 'range keys from in-memory index tree' (duration: 203.28653ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T08:31:26.837564Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5549} {"level":"info","ts":"2026-08-11T08:31:26.931061Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5549,"took":"91.080055ms","hash":2919120434,"current-db-size-bytes":53841920,"current-db-size":"54 MB","current-db-size-in-use-bytes":18374656,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-11T08:31:26.931195Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2919120434,"revision":5549,"compact-revision":2881}