{"level":"warn","ts":"2026-04-08T12:59:02.343124Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T12:59:02.34324Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200: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.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-08T12:59:02.343325Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T12:59:02.343344Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-04-08T12:59:02.343365Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-08T12:59:02.344288Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-04-08T12:59:02.344488Z","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":16,"max-cpu-available":16,"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.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-08T12:59:02.348689Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.876346ms"} {"level":"info","ts":"2026-04-08T12:59:02.354684Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-04-08T12:59:02.354768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T12:59:02.354875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-04-08T12:59:02.354946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T12:59:02.354968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-04-08T12:59:02.355038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-04-08T12:59:02.360759Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T12:59:02.362201Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T12:59:02.363763Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-08T12:59:02.367092Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T12:59:02.367277Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T12:59:02.367381Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T12:59:02.367394Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-08T12:59:02.367353Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T12:59:02.368216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-04-08T12:59:02.368576Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-04-08T12:59:02.373144Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-08T12:59:02.373303Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-04-08T12:59:02.373338Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-04-08T12:59:02.373445Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T12:59:02.373517Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T12:59:02.555842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T12:59:02.555943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T12:59:02.555973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-04-08T12:59:02.555997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-04-08T12:59:02.556008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-04-08T12:59:02.556028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-04-08T12:59:02.556053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-04-08T12:59:02.557242Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:59:02.558276Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T12:59:02.558282Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T12:59:02.558373Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T12:59:02.558677Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T12:59:02.558728Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T12:59:02.558923Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:59:02.559096Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:59:02.55914Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:59:02.562195Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-04-08T12:59:02.56229Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T12:59:33.613105Z","caller":"traceutil/trace.go:171","msg":"trace[1764191474] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"143.67249ms","start":"2026-04-08T12:59:33.469402Z","end":"2026-04-08T12:59:33.613075Z","steps":["trace[1764191474] 'process raft request' (duration: 143.404303ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:33.613393Z","caller":"traceutil/trace.go:171","msg":"trace[1522187406] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"141.480881ms","start":"2026-04-08T12:59:33.471868Z","end":"2026-04-08T12:59:33.613348Z","steps":["trace[1522187406] 'process raft request' (duration: 141.128872ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:39.647751Z","caller":"traceutil/trace.go:171","msg":"trace[1921872141] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"165.415052ms","start":"2026-04-08T12:59:39.482276Z","end":"2026-04-08T12:59:39.647691Z","steps":["trace[1921872141] 'process raft request' (duration: 163.148408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:40.059668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.8115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T12:59:40.059828Z","caller":"traceutil/trace.go:171","msg":"trace[290959837] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"236.301464ms","start":"2026-04-08T12:59:39.823503Z","end":"2026-04-08T12:59:40.059805Z","steps":["trace[290959837] 'read index received' (duration: 114.644334ms)","trace[290959837] 'applied index is now lower than readState.Index' (duration: 121.65527ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:59:40.059931Z","caller":"traceutil/trace.go:171","msg":"trace[1115697926] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"356.50576ms","start":"2026-04-08T12:59:39.703391Z","end":"2026-04-08T12:59:40.059897Z","steps":["trace[1115697926] 'process raft request' (duration: 234.742387ms)","trace[1115697926] 'compare' (duration: 120.542554ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:59:40.06006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:59:39.703366Z","time spent":"356.632253ms","remote":"127.0.0.1:58790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T12:59:40.060124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.59126ms","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-04-08T12:59:40.060178Z","caller":"traceutil/trace.go:171","msg":"trace[1198421616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"236.710193ms","start":"2026-04-08T12:59:39.823452Z","end":"2026-04-08T12:59:40.060162Z","steps":["trace[1198421616] 'agreement among raft nodes before linearized reading' (duration: 236.459947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:40.060388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.521384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fjv8q\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-08T12:59:40.060418Z","caller":"traceutil/trace.go:171","msg":"trace[1686178632] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fjv8q; range_end:; response_count:1; response_revision:576; }","duration":"225.591646ms","start":"2026-04-08T12:59:39.834818Z","end":"2026-04-08T12:59:40.060409Z","steps":["trace[1686178632] 'agreement among raft nodes before linearized reading' (duration: 225.466263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:59.154486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.978679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T12:59:59.154688Z","caller":"traceutil/trace.go:171","msg":"trace[470045307] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"248.045285ms","start":"2026-04-08T12:59:58.906557Z","end":"2026-04-08T12:59:59.154603Z","steps":["trace[470045307] 'read index received' (duration: 134.155751ms)","trace[470045307] 'applied index is now lower than readState.Index' (duration: 113.887314ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:59:59.154762Z","caller":"traceutil/trace.go:171","msg":"trace[1102485185] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"276.578221ms","start":"2026-04-08T12:59:58.878115Z","end":"2026-04-08T12:59:59.154694Z","steps":["trace[1102485185] 'process raft request' (duration: 162.670086ms)","trace[1102485185] 'compare' (duration: 111.660861ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:59:59.1549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.536707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nxnrc\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-08T12:59:59.154942Z","caller":"traceutil/trace.go:171","msg":"trace[818879418] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nxnrc; range_end:; response_count:1; response_revision:748; }","duration":"228.60257ms","start":"2026-04-08T12:59:58.926319Z","end":"2026-04-08T12:59:59.154921Z","steps":["trace[818879418] 'agreement among raft nodes before linearized reading' (duration: 228.465246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:59:59.154934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.365173ms","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-04-08T12:59:59.155052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.493671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:59:59.155081Z","caller":"traceutil/trace.go:171","msg":"trace[1163275677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:748; }","duration":"248.565589ms","start":"2026-04-08T12:59:58.906485Z","end":"2026-04-08T12:59:59.155051Z","steps":["trace[1163275677] 'agreement among raft nodes before linearized reading' (duration: 248.24182ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:59:59.155129Z","caller":"traceutil/trace.go:171","msg":"trace[368542041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:748; }","duration":"174.551342ms","start":"2026-04-08T12:59:58.980543Z","end":"2026-04-08T12:59:59.155094Z","steps":["trace[368542041] 'agreement among raft nodes before linearized reading' (duration: 174.46145ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:01:38.892054Z","caller":"traceutil/trace.go:171","msg":"trace[831270819] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"141.620375ms","start":"2026-04-08T13:01:38.750391Z","end":"2026-04-08T13:01:38.892011Z","steps":["trace[831270819] 'process raft request' (duration: 53.395ms)","trace[831270819] 'compare' (duration: 87.537529ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:14.670491Z","caller":"traceutil/trace.go:171","msg":"trace[40307230] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"128.363779ms","start":"2026-04-08T13:02:14.542096Z","end":"2026-04-08T13:02:14.67046Z","steps":["trace[40307230] 'process raft request' (duration: 128.193425ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:52.039719Z","caller":"traceutil/trace.go:171","msg":"trace[619990134] transaction","detail":"{read_only:false; response_revision:2390; number_of_response:1; }","duration":"111.530087ms","start":"2026-04-08T13:02:51.92816Z","end":"2026-04-08T13:02:52.03969Z","steps":["trace[619990134] 'process raft request' (duration: 111.354273ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:02:57.366939Z","caller":"traceutil/trace.go:171","msg":"trace[1357238697] linearizableReadLoop","detail":"{readStateIndex:2514; appliedIndex:2513; }","duration":"112.832256ms","start":"2026-04-08T13:02:57.254079Z","end":"2026-04-08T13:02:57.366911Z","steps":["trace[1357238697] 'read index received' (duration: 112.517919ms)","trace[1357238697] 'applied index is now lower than readState.Index' (duration: 312.957µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:02:57.366984Z","caller":"traceutil/trace.go:171","msg":"trace[497611397] transaction","detail":"{read_only:false; response_revision:2418; number_of_response:1; }","duration":"135.145386ms","start":"2026-04-08T13:02:57.23181Z","end":"2026-04-08T13:02:57.366955Z","steps":["trace[497611397] 'process raft request' (duration: 134.848389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:02:57.367131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.037391ms","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-04-08T13:02:57.367168Z","caller":"traceutil/trace.go:171","msg":"trace[61276370] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2418; }","duration":"113.126393ms","start":"2026-04-08T13:02:57.254029Z","end":"2026-04-08T13:02:57.367155Z","steps":["trace[61276370] 'agreement among raft nodes before linearized reading' (duration: 112.98192ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:03:47.699447Z","caller":"traceutil/trace.go:171","msg":"trace[444919105] transaction","detail":"{read_only:false; response_revision:2858; number_of_response:1; }","duration":"151.649213ms","start":"2026-04-08T13:03:47.547761Z","end":"2026-04-08T13:03:47.69941Z","steps":["trace[444919105] 'process raft request' (duration: 151.425448ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:20.655492Z","caller":"traceutil/trace.go:171","msg":"trace[1939151867] transaction","detail":"{read_only:false; response_revision:3060; number_of_response:1; }","duration":"114.96299ms","start":"2026-04-08T13:04:20.540504Z","end":"2026-04-08T13:04:20.655467Z","steps":["trace[1939151867] 'process raft request' (duration: 114.793396ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:04:20.948322Z","caller":"traceutil/trace.go:171","msg":"trace[127899226] transaction","detail":"{read_only:false; response_revision:3061; number_of_response:1; }","duration":"289.168503ms","start":"2026-04-08T13:04:20.659114Z","end":"2026-04-08T13:04:20.948282Z","steps":["trace[127899226] 'process raft request' (duration: 202.070072ms)","trace[127899226] 'compare' (duration: 86.856016ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:04:20.948335Z","caller":"traceutil/trace.go:171","msg":"trace[1970130871] transaction","detail":"{read_only:false; response_revision:3062; number_of_response:1; }","duration":"288.866697ms","start":"2026-04-08T13:04:20.659438Z","end":"2026-04-08T13:04:20.948305Z","steps":["trace[1970130871] 'process raft request' (duration: 288.783435ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:11.613323Z","caller":"traceutil/trace.go:171","msg":"trace[1002970006] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"101.249502ms","start":"2026-04-08T13:08:11.512046Z","end":"2026-04-08T13:08:11.613295Z","steps":["trace[1002970006] 'process raft request' (duration: 100.645098ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:11.642771Z","caller":"traceutil/trace.go:171","msg":"trace[1681710630] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"119.684902ms","start":"2026-04-08T13:08:11.523066Z","end":"2026-04-08T13:08:11.642751Z","steps":["trace[1681710630] 'process raft request' (duration: 119.5918ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:14.496959Z","caller":"traceutil/trace.go:171","msg":"trace[566446932] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"125.064559ms","start":"2026-04-08T13:08:14.371858Z","end":"2026-04-08T13:08:14.496922Z","steps":["trace[566446932] 'process raft request' (duration: 124.771452ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:14.629668Z","caller":"traceutil/trace.go:171","msg":"trace[531441286] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"105.635927ms","start":"2026-04-08T13:08:14.524003Z","end":"2026-04-08T13:08:14.629639Z","steps":["trace[531441286] 'process raft request' (duration: 88.296244ms)","trace[531441286] 'compare' (duration: 17.163719ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:08:18.327808Z","caller":"traceutil/trace.go:171","msg":"trace[547542586] linearizableReadLoop","detail":"{readStateIndex:5528; appliedIndex:5527; }","duration":"166.776634ms","start":"2026-04-08T13:08:18.161007Z","end":"2026-04-08T13:08:18.327783Z","steps":["trace[547542586] 'read index received' (duration: 148.233152ms)","trace[547542586] 'applied index is now lower than readState.Index' (duration: 18.542512ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:08:18.327877Z","caller":"traceutil/trace.go:171","msg":"trace[28296472] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"180.733396ms","start":"2026-04-08T13:08:18.14709Z","end":"2026-04-08T13:08:18.327823Z","steps":["trace[28296472] 'process raft request' (duration: 162.163713ms)","trace[28296472] 'compare' (duration: 18.410709ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:08:18.328194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.633912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17319"} {"level":"warn","ts":"2026-04-08T13:08:18.3282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.169683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:125601"} {"level":"info","ts":"2026-04-08T13:08:18.328249Z","caller":"traceutil/trace.go:171","msg":"trace[1345262966] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5323; }","duration":"143.749855ms","start":"2026-04-08T13:08:18.184487Z","end":"2026-04-08T13:08:18.328237Z","steps":["trace[1345262966] 'agreement among raft nodes before linearized reading' (duration: 143.481918ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:18.328285Z","caller":"traceutil/trace.go:171","msg":"trace[1140262181] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:5323; }","duration":"167.330627ms","start":"2026-04-08T13:08:18.160935Z","end":"2026-04-08T13:08:18.328265Z","steps":["trace[1140262181] 'agreement among raft nodes before linearized reading' (duration: 166.961669ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:18.446407Z","caller":"traceutil/trace.go:171","msg":"trace[1012856383] linearizableReadLoop","detail":"{readStateIndex:5529; appliedIndex:5528; }","duration":"112.543001ms","start":"2026-04-08T13:08:18.333835Z","end":"2026-04-08T13:08:18.446378Z","steps":["trace[1012856383] 'read index received' (duration: 109.226473ms)","trace[1012856383] 'applied index is now lower than readState.Index' (duration: 3.315428ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:08:18.446434Z","caller":"traceutil/trace.go:171","msg":"trace[17411706] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"114.404076ms","start":"2026-04-08T13:08:18.332004Z","end":"2026-04-08T13:08:18.446408Z","steps":["trace[17411706] 'process raft request' (duration: 111.163799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:18.446736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.89542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:17319"} {"level":"info","ts":"2026-04-08T13:08:18.446818Z","caller":"traceutil/trace.go:171","msg":"trace[1023866804] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5324; }","duration":"113.022332ms","start":"2026-04-08T13:08:18.333778Z","end":"2026-04-08T13:08:18.4468Z","steps":["trace[1023866804] 'agreement among raft nodes before linearized reading' (duration: 112.708145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:18.457617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.666506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-08T13:08:18.457651Z","caller":"traceutil/trace.go:171","msg":"trace[36571391] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5325; }","duration":"123.719848ms","start":"2026-04-08T13:08:18.333923Z","end":"2026-04-08T13:08:18.457643Z","steps":["trace[36571391] 'agreement among raft nodes before linearized reading' (duration: 123.587715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:08:18.457981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.842646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-04-08T13:08:18.458052Z","caller":"traceutil/trace.go:171","msg":"trace[2099471398] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5325; }","duration":"114.948209ms","start":"2026-04-08T13:08:18.343087Z","end":"2026-04-08T13:08:18.458035Z","steps":["trace[2099471398] 'agreement among raft nodes before linearized reading' (duration: 114.701263ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:45.388817Z","caller":"traceutil/trace.go:171","msg":"trace[1381740224] transaction","detail":"{read_only:false; response_revision:5786; number_of_response:1; }","duration":"118.207679ms","start":"2026-04-08T13:08:45.270579Z","end":"2026-04-08T13:08:45.388786Z","steps":["trace[1381740224] 'process raft request' (duration: 117.933262ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:08:45.813759Z","caller":"traceutil/trace.go:171","msg":"trace[758824010] transaction","detail":"{read_only:false; response_revision:5789; number_of_response:1; }","duration":"121.13753ms","start":"2026-04-08T13:08:45.692587Z","end":"2026-04-08T13:08:45.813724Z","steps":["trace[758824010] 'process raft request' (duration: 75.538781ms)","trace[758824010] 'compare' (duration: 45.235999ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:09:02.933542Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2961} {"level":"info","ts":"2026-04-08T13:09:02.99973Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2961,"took":"64.809715ms","hash":2079268761,"current-db-size-bytes":55787520,"current-db-size":"56 MB","current-db-size-in-use-bytes":32251904,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-08T13:09:02.99981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2079268761,"revision":2961,"compact-revision":-1} {"level":"info","ts":"2026-04-08T13:14:02.938901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5923} {"level":"info","ts":"2026-04-08T13:14:03.022637Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5923,"took":"81.648615ms","hash":2827764405,"current-db-size-bytes":55787520,"current-db-size":"56 MB","current-db-size-in-use-bytes":26091520,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-08T13:14:03.02276Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2827764405,"revision":5923,"compact-revision":2961} {"level":"warn","ts":"2026-04-08T13:14:41.605291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.944394ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-08T13:14:41.605551Z","caller":"traceutil/trace.go:171","msg":"trace[780039101] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"278.161265ms","start":"2026-04-08T13:14:41.327373Z","end":"2026-04-08T13:14:41.605534Z","steps":["trace[780039101] 'process raft request' (duration: 278.024181ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:14:41.605691Z","caller":"traceutil/trace.go:171","msg":"trace[292494745] linearizableReadLoop","detail":"{readStateIndex:8779; appliedIndex:8778; }","duration":"457.035443ms","start":"2026-04-08T13:14:41.148605Z","end":"2026-04-08T13:14:41.605641Z","steps":["trace[292494745] 'read index received' (duration: 290.672009ms)","trace[292494745] 'applied index is now lower than readState.Index' (duration: 166.357554ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:14:41.606991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.377654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qb2mq\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-08T13:14:41.607038Z","caller":"traceutil/trace.go:171","msg":"trace[26912086] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qb2mq; range_end:; response_count:1; response_revision:8479; }","duration":"458.464626ms","start":"2026-04-08T13:14:41.14856Z","end":"2026-04-08T13:14:41.607025Z","steps":["trace[26912086] 'agreement among raft nodes before linearized reading' (duration: 457.301589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:14:41.607071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:14:41.148538Z","time spent":"458.525688ms","remote":"127.0.0.1:58458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6548,"request content":"key:\"/registry/pods/openstack/keepalived-qb2mq\" "} {"level":"warn","ts":"2026-04-08T13:14:41.607054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.44049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-08T13:14:41.608186Z","caller":"traceutil/trace.go:171","msg":"trace[527703758] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8479; }","duration":"457.606417ms","start":"2026-04-08T13:14:41.150564Z","end":"2026-04-08T13:14:41.60817Z","steps":["trace[527703758] 'agreement among raft nodes before linearized reading' (duration: 456.365937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:14:41.608219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:14:41.150551Z","time spent":"457.660469ms","remote":"127.0.0.1:58544","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":"info","ts":"2026-04-08T13:14:52.277956Z","caller":"traceutil/trace.go:171","msg":"trace[1554887514] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"135.558113ms","start":"2026-04-08T13:14:52.142369Z","end":"2026-04-08T13:14:52.277927Z","steps":["trace[1554887514] 'process raft request' (duration: 118.916409ms)","trace[1554887514] 'compare' (duration: 16.483361ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:14:54.22979Z","caller":"traceutil/trace.go:171","msg":"trace[1793789263] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"224.122302ms","start":"2026-04-08T13:14:54.005647Z","end":"2026-04-08T13:14:54.229769Z","steps":["trace[1793789263] 'process raft request' (duration: 223.988979ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:14:54.345227Z","caller":"traceutil/trace.go:171","msg":"trace[1077523087] linearizableReadLoop","detail":"{readStateIndex:8851; appliedIndex:8849; }","duration":"267.334426ms","start":"2026-04-08T13:14:54.077871Z","end":"2026-04-08T13:14:54.345205Z","steps":["trace[1077523087] 'read index received' (duration: 151.782898ms)","trace[1077523087] 'applied index is now lower than readState.Index' (duration: 115.550778ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:14:54.345337Z","caller":"traceutil/trace.go:171","msg":"trace[59731189] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"300.951302ms","start":"2026-04-08T13:14:54.044359Z","end":"2026-04-08T13:14:54.34531Z","steps":["trace[59731189] 'process raft request' (duration: 239.467385ms)","trace[59731189] 'compare' (duration: 61.281883ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:14:54.345381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.488439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T13:14:54.34541Z","caller":"traceutil/trace.go:171","msg":"trace[992107596] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:8547; }","duration":"267.611313ms","start":"2026-04-08T13:14:54.077793Z","end":"2026-04-08T13:14:54.345404Z","steps":["trace[992107596] 'agreement among raft nodes before linearized reading' (duration: 267.51682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:14:54.34547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:14:54.04433Z","time spent":"301.061305ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T13:14:54.345909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.661555ms","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-04-08T13:14:54.346023Z","caller":"traceutil/trace.go:171","msg":"trace[1751596046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8547; }","duration":"121.822638ms","start":"2026-04-08T13:14:54.224178Z","end":"2026-04-08T13:14:54.346001Z","steps":["trace[1751596046] 'agreement among raft nodes before linearized reading' (duration: 121.335955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:21.818799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.928201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-08T13:15:21.81887Z","caller":"traceutil/trace.go:171","msg":"trace[1636870059] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:8747; }","duration":"187.044642ms","start":"2026-04-08T13:15:21.631811Z","end":"2026-04-08T13:15:21.818856Z","steps":["trace[1636870059] 'count revisions from in-memory index tree' (duration: 186.825757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:21.819261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.571867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-08T13:15:21.819361Z","caller":"traceutil/trace.go:171","msg":"trace[1922946040] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8747; }","duration":"433.67945ms","start":"2026-04-08T13:15:21.385661Z","end":"2026-04-08T13:15:21.81934Z","steps":["trace[1922946040] 'range keys from in-memory index tree' (duration: 433.392914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:21.81945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T13:15:21.385598Z","time spent":"433.824534ms","remote":"127.0.0.1:58458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-08T13:15:34.870543Z","caller":"traceutil/trace.go:171","msg":"trace[1037204416] linearizableReadLoop","detail":"{readStateIndex:9174; appliedIndex:9173; }","duration":"109.547618ms","start":"2026-04-08T13:15:34.760974Z","end":"2026-04-08T13:15:34.870521Z","steps":["trace[1037204416] 'read index received' (duration: 97.920442ms)","trace[1037204416] 'applied index is now lower than readState.Index' (duration: 11.626456ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:15:34.87056Z","caller":"traceutil/trace.go:171","msg":"trace[2001398393] transaction","detail":"{read_only:false; response_revision:8857; number_of_response:1; }","duration":"136.368063ms","start":"2026-04-08T13:15:34.734165Z","end":"2026-04-08T13:15:34.870533Z","steps":["trace[2001398393] 'process raft request' (duration: 124.663355ms)","trace[2001398393] 'compare' (duration: 11.552904ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:15:34.870667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.679671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:15:34.870714Z","caller":"traceutil/trace.go:171","msg":"trace[238705546] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8857; }","duration":"109.787343ms","start":"2026-04-08T13:15:34.760915Z","end":"2026-04-08T13:15:34.870703Z","steps":["trace[238705546] 'agreement among raft nodes before linearized reading' (duration: 109.685691ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:35.027031Z","caller":"traceutil/trace.go:171","msg":"trace[239613297] transaction","detail":"{read_only:false; response_revision:8858; number_of_response:1; }","duration":"160.864972ms","start":"2026-04-08T13:15:34.866146Z","end":"2026-04-08T13:15:35.027011Z","steps":["trace[239613297] 'process raft request' (duration: 160.708229ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:35.027303Z","caller":"traceutil/trace.go:171","msg":"trace[2100838551] linearizableReadLoop","detail":"{readStateIndex:9175; appliedIndex:9174; }","duration":"156.680713ms","start":"2026-04-08T13:15:34.870611Z","end":"2026-04-08T13:15:35.027291Z","steps":["trace[2100838551] 'read index received' (duration: 156.279234ms)","trace[2100838551] 'applied index is now lower than readState.Index' (duration: 400.389µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:15:35.027482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.518947ms","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-04-08T13:15:35.027586Z","caller":"traceutil/trace.go:171","msg":"trace[505277291] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8858; }","duration":"201.707341ms","start":"2026-04-08T13:15:34.825872Z","end":"2026-04-08T13:15:35.02758Z","steps":["trace[505277291] 'agreement among raft nodes before linearized reading' (duration: 201.489645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:35.027578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.660462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-64qlh\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-04-08T13:15:35.027818Z","caller":"traceutil/trace.go:171","msg":"trace[1244937389] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-64qlh; range_end:; response_count:1; response_revision:8858; }","duration":"161.848497ms","start":"2026-04-08T13:15:34.865859Z","end":"2026-04-08T13:15:35.027708Z","steps":["trace[1244937389] 'agreement among raft nodes before linearized reading' (duration: 161.481897ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:35.041552Z","caller":"traceutil/trace.go:171","msg":"trace[1108923057] transaction","detail":"{read_only:false; response_revision:8859; number_of_response:1; }","duration":"128.252781ms","start":"2026-04-08T13:15:34.913271Z","end":"2026-04-08T13:15:35.041523Z","steps":["trace[1108923057] 'process raft request' (duration: 127.950392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:35.041772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.605638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-08T13:15:35.04192Z","caller":"traceutil/trace.go:171","msg":"trace[1018734207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8859; }","duration":"163.799863ms","start":"2026-04-08T13:15:34.878096Z","end":"2026-04-08T13:15:35.041896Z","steps":["trace[1018734207] 'agreement among raft nodes before linearized reading' (duration: 163.404254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:35.66371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.696116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T13:15:35.66378Z","caller":"traceutil/trace.go:171","msg":"trace[965937562] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:8866; }","duration":"105.809608ms","start":"2026-04-08T13:15:35.557951Z","end":"2026-04-08T13:15:35.663761Z","steps":["trace[965937562] 'count revisions from in-memory index tree' (duration: 105.627354ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:15:36.022086Z","caller":"traceutil/trace.go:171","msg":"trace[1229996589] linearizableReadLoop","detail":"{readStateIndex:9185; appliedIndex:9184; }","duration":"123.109408ms","start":"2026-04-08T13:15:35.898952Z","end":"2026-04-08T13:15:36.022061Z","steps":["trace[1229996589] 'read index received' (duration: 100.46063ms)","trace[1229996589] 'applied index is now lower than readState.Index' (duration: 22.647668ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T13:15:36.022246Z","caller":"traceutil/trace.go:171","msg":"trace[2115952445] transaction","detail":"{read_only:false; response_revision:8868; number_of_response:1; }","duration":"135.034002ms","start":"2026-04-08T13:15:35.887162Z","end":"2026-04-08T13:15:36.022196Z","steps":["trace[2115952445] 'process raft request' (duration: 112.284602ms)","trace[2115952445] 'compare' (duration: 22.082773ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T13:15:36.022406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.888369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-qb2mq\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-08T13:15:36.022437Z","caller":"traceutil/trace.go:171","msg":"trace[1908598488] range","detail":"{range_begin:/registry/pods/openstack/keepalived-qb2mq; range_end:; response_count:1; response_revision:8868; }","duration":"102.93369ms","start":"2026-04-08T13:15:35.919493Z","end":"2026-04-08T13:15:36.022427Z","steps":["trace[1908598488] 'agreement among raft nodes before linearized reading' (duration: 102.867289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T13:15:36.022304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.333043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-08T13:15:36.022604Z","caller":"traceutil/trace.go:171","msg":"trace[552420910] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8868; }","duration":"123.677921ms","start":"2026-04-08T13:15:35.898909Z","end":"2026-04-08T13:15:36.022587Z","steps":["trace[552420910] 'agreement among raft nodes before linearized reading' (duration: 123.285152ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T13:16:17.416871Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002564.wal"} {"level":"info","ts":"2026-04-08T13:16:58.818054Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"e10bb51ed7578852","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-08T13:16:58.825142Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-08T13:16:58.825259Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-08T13:19:02.944611Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8098} {"level":"info","ts":"2026-04-08T13:19:03.007305Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8098,"took":"60.252942ms","hash":1307904767,"current-db-size-bytes":55787520,"current-db-size":"56 MB","current-db-size-in-use-bytes":28499968,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-08T13:19:03.007365Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1307904767,"revision":8098,"compact-revision":5923} {"level":"info","ts":"2026-04-08T13:24:02.950456Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10507} {"level":"info","ts":"2026-04-08T13:24:03.039997Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10507,"took":"86.173486ms","hash":4057421012,"current-db-size-bytes":55787520,"current-db-size":"56 MB","current-db-size-in-use-bytes":21573632,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-08T13:24:03.040065Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4057421012,"revision":10507,"compact-revision":8098}