{"level":"warn","ts":"2026-04-07T01:24:28.296211Z","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-07T01:24:28.296376Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.207: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.207:2380","--initial-cluster=instance=https://162.253.55.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.207: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-07T01:24:28.29647Z","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-07T01:24:28.296502Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-04-07T01:24:28.29654Z","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-07T01:24:28.297323Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"]} {"level":"info","ts":"2026-04-07T01:24:28.297457Z","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.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.207: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-07T01:24:28.301726Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.001735ms"} {"level":"info","ts":"2026-04-07T01:24:28.312247Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6f1fcbe50562a63a","cluster-id":"a9b9f04f4e8a9fba"} {"level":"info","ts":"2026-04-07T01:24:28.312345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T01:24:28.312377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 0"} {"level":"info","ts":"2026-04-07T01:24:28.312398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6f1fcbe50562a63a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T01:24:28.312414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 1"} {"level":"info","ts":"2026-04-07T01:24:28.312458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"warn","ts":"2026-04-07T01:24:28.3231Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T01:24:28.326016Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T01:24:28.327476Z","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-07T01:24:28.329721Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6f1fcbe50562a63a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T01:24:28.33015Z","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-07T01:24:28.33025Z","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-07T01:24:28.330261Z","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-07T01:24:28.330347Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6f1fcbe50562a63a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T01:24:28.331188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"info","ts":"2026-04-07T01:24:28.331634Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","added-peer-id":"6f1fcbe50562a63a","added-peer-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-04-07T01:24:28.332964Z","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-07T01:24:28.333023Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-04-07T01:24:28.333109Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-04-07T01:24:28.333188Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6f1fcbe50562a63a","initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T01:24:28.333254Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T01:24:28.4127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T01:24:28.412846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T01:24:28.412872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgPreVoteResp from 6f1fcbe50562a63a at term 1"} {"level":"info","ts":"2026-04-07T01:24:28.413057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became candidate at term 2"} {"level":"info","ts":"2026-04-07T01:24:28.413083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgVoteResp from 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-04-07T01:24:28.4131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became leader at term 2"} {"level":"info","ts":"2026-04-07T01:24:28.413333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6f1fcbe50562a63a elected leader 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-04-07T01:24:28.414243Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:24:28.415031Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6f1fcbe50562a63a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.207:2379]}","request-path":"/0/members/6f1fcbe50562a63a/attributes","cluster-id":"a9b9f04f4e8a9fba","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T01:24:28.415037Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T01:24:28.415081Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T01:24:28.415303Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T01:24:28.415449Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T01:24:28.415593Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:24:28.415691Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:24:28.415782Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T01:24:28.419618Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.207:2379"} {"level":"info","ts":"2026-04-07T01:24:28.419892Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T01:24:46.564908Z","caller":"traceutil/trace.go:171","msg":"trace[125965381] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"117.860316ms","start":"2026-04-07T01:24:46.447024Z","end":"2026-04-07T01:24:46.564884Z","steps":["trace[125965381] 'process raft request' (duration: 117.703042ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:57.568242Z","caller":"traceutil/trace.go:171","msg":"trace[1558661869] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"109.227881ms","start":"2026-04-07T01:24:57.458982Z","end":"2026-04-07T01:24:57.56821Z","steps":["trace[1558661869] 'process raft request' (duration: 51.138451ms)","trace[1558661869] 'compare' (duration: 57.967478ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:24:57.571877Z","caller":"traceutil/trace.go:171","msg":"trace[138855211] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"112.007481ms","start":"2026-04-07T01:24:57.459839Z","end":"2026-04-07T01:24:57.571847Z","steps":["trace[138855211] 'process raft request' (duration: 111.818966ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:57.572012Z","caller":"traceutil/trace.go:171","msg":"trace[922354445] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"110.607071ms","start":"2026-04-07T01:24:57.461373Z","end":"2026-04-07T01:24:57.57198Z","steps":["trace[922354445] 'process raft request' (duration: 110.411517ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:57.572402Z","caller":"traceutil/trace.go:171","msg":"trace[951076866] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"110.07312ms","start":"2026-04-07T01:24:57.462316Z","end":"2026-04-07T01:24:57.572389Z","steps":["trace[951076866] 'process raft request' (duration: 109.516528ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:24:57.57241Z","caller":"traceutil/trace.go:171","msg":"trace[1770872148] transaction","detail":"{read_only:false; number_of_response:1; response_revision:467; }","duration":"109.478446ms","start":"2026-04-07T01:24:57.462918Z","end":"2026-04-07T01:24:57.572396Z","steps":["trace[1770872148] 'process raft request' (duration: 108.980726ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:04.005654Z","caller":"traceutil/trace.go:171","msg":"trace[1214685833] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"197.486185ms","start":"2026-04-07T01:25:03.808146Z","end":"2026-04-07T01:25:04.005632Z","steps":["trace[1214685833] 'read index received' (duration: 150.182865ms)","trace[1214685833] 'applied index is now lower than readState.Index' (duration: 47.30268ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:25:04.005796Z","caller":"traceutil/trace.go:171","msg":"trace[1575833549] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"241.515585ms","start":"2026-04-07T01:25:03.764242Z","end":"2026-04-07T01:25:04.005757Z","steps":["trace[1575833549] 'process raft request' (duration: 195.006273ms)","trace[1575833549] 'compare' (duration: 45.834598ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:25:04.005842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.672919ms","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-04-07T01:25:04.0059Z","caller":"traceutil/trace.go:171","msg":"trace[479132124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"197.789871ms","start":"2026-04-07T01:25:03.808101Z","end":"2026-04-07T01:25:04.00589Z","steps":["trace[479132124] 'agreement among raft nodes before linearized reading' (duration: 197.612617ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:25:04.469337Z","caller":"traceutil/trace.go:171","msg":"trace[2003108053] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"196.535045ms","start":"2026-04-07T01:25:04.272775Z","end":"2026-04-07T01:25:04.469311Z","steps":["trace[2003108053] 'read index received' (duration: 196.29943ms)","trace[2003108053] 'applied index is now lower than readState.Index' (duration: 234.345µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:25:04.469415Z","caller":"traceutil/trace.go:171","msg":"trace[785444247] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"402.841719ms","start":"2026-04-07T01:25:04.066534Z","end":"2026-04-07T01:25:04.469376Z","steps":["trace[785444247] 'process raft request' (duration: 402.605614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:04.46963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.038645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-07T01:25:04.469677Z","caller":"traceutil/trace.go:171","msg":"trace[1504487704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"159.141678ms","start":"2026-04-07T01:25:04.310524Z","end":"2026-04-07T01:25:04.469666Z","steps":["trace[1504487704] 'agreement among raft nodes before linearized reading' (duration: 158.947394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:04.469746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.951064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-07T01:25:04.469845Z","caller":"traceutil/trace.go:171","msg":"trace[636068255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:574; }","duration":"197.100067ms","start":"2026-04-07T01:25:04.272722Z","end":"2026-04-07T01:25:04.469823Z","steps":["trace[636068255] 'agreement among raft nodes before linearized reading' (duration: 196.734969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:04.469964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:25:04.066507Z","time spent":"402.981122ms","remote":"127.0.0.1:34342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T01:25:55.040205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.688793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T01:25:55.040364Z","caller":"traceutil/trace.go:171","msg":"trace[806751113] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1191; }","duration":"268.429087ms","start":"2026-04-07T01:25:54.771918Z","end":"2026-04-07T01:25:55.040347Z","steps":["trace[806751113] 'read index received' (duration: 29.207873ms)","trace[806751113] 'applied index is now lower than readState.Index' (duration: 239.219404ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:25:55.040455Z","caller":"traceutil/trace.go:171","msg":"trace[41826784] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"273.560697ms","start":"2026-04-07T01:25:54.766883Z","end":"2026-04-07T01:25:55.040443Z","steps":["trace[41826784] 'process raft request' (duration: 34.237461ms)","trace[41826784] 'compare' (duration: 238.492419ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:25:55.040638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.686563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:25:55.040712Z","caller":"traceutil/trace.go:171","msg":"trace[111733952] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1156; }","duration":"268.799705ms","start":"2026-04-07T01:25:54.771896Z","end":"2026-04-07T01:25:55.040696Z","steps":["trace[111733952] 'agreement among raft nodes before linearized reading' (duration: 268.620161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:25:55.040768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.290615ms","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-07T01:25:55.040879Z","caller":"traceutil/trace.go:171","msg":"trace[909859751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1156; }","duration":"177.47164ms","start":"2026-04-07T01:25:54.863383Z","end":"2026-04-07T01:25:55.040854Z","steps":["trace[909859751] 'agreement among raft nodes before linearized reading' (duration: 177.161874ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:26:36.545836Z","caller":"traceutil/trace.go:171","msg":"trace[1400620148] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"234.681449ms","start":"2026-04-07T01:26:36.311105Z","end":"2026-04-07T01:26:36.545787Z","steps":["trace[1400620148] 'process raft request' (duration: 234.428454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:15.751475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.386307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:27:15.751573Z","caller":"traceutil/trace.go:171","msg":"trace[1862112397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2068; }","duration":"145.52416ms","start":"2026-04-07T01:27:15.606015Z","end":"2026-04-07T01:27:15.751539Z","steps":["trace[1862112397] 'range keys from in-memory index tree' (duration: 145.283825ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:21.575422Z","caller":"traceutil/trace.go:171","msg":"trace[171005514] linearizableReadLoop","detail":"{readStateIndex:2177; appliedIndex:2176; }","duration":"108.585733ms","start":"2026-04-07T01:27:21.466805Z","end":"2026-04-07T01:27:21.575391Z","steps":["trace[171005514] 'read index received' (duration: 108.325418ms)","trace[171005514] 'applied index is now lower than readState.Index' (duration: 259.305µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:27:21.575472Z","caller":"traceutil/trace.go:171","msg":"trace[299223860] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"137.021169ms","start":"2026-04-07T01:27:21.438416Z","end":"2026-04-07T01:27:21.575437Z","steps":["trace[299223860] 'process raft request' (duration: 136.788624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:21.575709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.881419ms","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-04-07T01:27:21.575744Z","caller":"traceutil/trace.go:171","msg":"trace[623357376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2096; }","duration":"108.995401ms","start":"2026-04-07T01:27:21.466738Z","end":"2026-04-07T01:27:21.575733Z","steps":["trace[623357376] 'agreement among raft nodes before linearized reading' (duration: 108.816478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:27:21.575849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.478456ms","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-07T01:27:21.57606Z","caller":"traceutil/trace.go:171","msg":"trace[1413818944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2096; }","duration":"105.769272ms","start":"2026-04-07T01:27:21.470266Z","end":"2026-04-07T01:27:21.576035Z","steps":["trace[1413818944] 'agreement among raft nodes before linearized reading' (duration: 105.390084ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:21.778181Z","caller":"traceutil/trace.go:171","msg":"trace[1525566682] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"197.846275ms","start":"2026-04-07T01:27:21.580313Z","end":"2026-04-07T01:27:21.778159Z","steps":["trace[1525566682] 'process raft request' (duration: 197.662451ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:21.778457Z","caller":"traceutil/trace.go:171","msg":"trace[1122032045] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"197.839454ms","start":"2026-04-07T01:27:21.580583Z","end":"2026-04-07T01:27:21.778423Z","steps":["trace[1122032045] 'process raft request' (duration: 197.537848ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:21.778471Z","caller":"traceutil/trace.go:171","msg":"trace[65632619] linearizableReadLoop","detail":"{readStateIndex:2180; appliedIndex:2177; }","duration":"171.420151ms","start":"2026-04-07T01:27:21.606993Z","end":"2026-04-07T01:27:21.778413Z","steps":["trace[65632619] 'read index received' (duration: 62.670834ms)","trace[65632619] 'applied index is now lower than readState.Index' (duration: 108.745737ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:27:21.778641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.636815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:27:21.778694Z","caller":"traceutil/trace.go:171","msg":"trace[122898468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2098; }","duration":"171.751539ms","start":"2026-04-07T01:27:21.60693Z","end":"2026-04-07T01:27:21.778681Z","steps":["trace[122898468] 'agreement among raft nodes before linearized reading' (duration: 171.652707ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:21.779832Z","caller":"traceutil/trace.go:171","msg":"trace[1190426630] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"108.897439ms","start":"2026-04-07T01:27:21.670908Z","end":"2026-04-07T01:27:21.779806Z","steps":["trace[1190426630] 'process raft request' (duration: 108.746816ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:27:27.029198Z","caller":"traceutil/trace.go:171","msg":"trace[1705466963] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"149.492314ms","start":"2026-04-07T01:27:26.87967Z","end":"2026-04-07T01:27:27.029163Z","steps":["trace[1705466963] 'process raft request' (duration: 140.662595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:28:16.27761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.655415ms","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-04-07T01:28:16.277708Z","caller":"traceutil/trace.go:171","msg":"trace[663984573] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2566; }","duration":"142.817038ms","start":"2026-04-07T01:28:16.134872Z","end":"2026-04-07T01:28:16.277689Z","steps":["trace[663984573] 'range keys from in-memory index tree' (duration: 142.495761ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:31:21.156625Z","caller":"traceutil/trace.go:171","msg":"trace[1688490156] transaction","detail":"{read_only:false; response_revision:3767; number_of_response:1; }","duration":"111.151816ms","start":"2026-04-07T01:31:21.045439Z","end":"2026-04-07T01:31:21.156591Z","steps":["trace[1688490156] 'process raft request' (duration: 111.050703ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:32:47.077353Z","caller":"traceutil/trace.go:171","msg":"trace[948948431] linearizableReadLoop","detail":"{readStateIndex:5008; appliedIndex:5006; }","duration":"123.847798ms","start":"2026-04-07T01:32:46.953471Z","end":"2026-04-07T01:32:47.077318Z","steps":["trace[948948431] 'read index received' (duration: 8.694444ms)","trace[948948431] 'applied index is now lower than readState.Index' (duration: 115.152254ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:32:47.077435Z","caller":"traceutil/trace.go:171","msg":"trace[2079492407] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"125.909002ms","start":"2026-04-07T01:32:46.951476Z","end":"2026-04-07T01:32:47.077385Z","steps":["trace[2079492407] 'process raft request' (duration: 125.551775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:32:47.077887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.533214ms","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-07T01:32:47.077964Z","caller":"traceutil/trace.go:171","msg":"trace[1178308231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4821; }","duration":"109.674197ms","start":"2026-04-07T01:32:46.968273Z","end":"2026-04-07T01:32:47.077947Z","steps":["trace[1178308231] 'agreement among raft nodes before linearized reading' (duration: 109.418972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:32:47.078074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.576083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:521833"} {"level":"info","ts":"2026-04-07T01:32:47.078128Z","caller":"traceutil/trace.go:171","msg":"trace[1777660937] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:4821; }","duration":"124.703856ms","start":"2026-04-07T01:32:46.953412Z","end":"2026-04-07T01:32:47.078116Z","steps":["trace[1777660937] 'agreement among raft nodes before linearized reading' (duration: 124.120744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:32:47.078903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.510065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2196043"} {"level":"info","ts":"2026-04-07T01:32:47.079018Z","caller":"traceutil/trace.go:171","msg":"trace[366892518] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4821; }","duration":"118.665249ms","start":"2026-04-07T01:32:46.960335Z","end":"2026-04-07T01:32:47.079Z","steps":["trace[366892518] 'agreement among raft nodes before linearized reading' (duration: 117.353721ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:32:51.455043Z","caller":"traceutil/trace.go:171","msg":"trace[164424538] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4961; }","duration":"137.975629ms","start":"2026-04-07T01:32:51.317033Z","end":"2026-04-07T01:32:51.455008Z","steps":["trace[164424538] 'process raft request' (duration: 124.204007ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:32:51.632529Z","caller":"traceutil/trace.go:171","msg":"trace[1339586756] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"155.465448ms","start":"2026-04-07T01:32:51.477038Z","end":"2026-04-07T01:32:51.632503Z","steps":["trace[1339586756] 'process raft request' (duration: 155.250484ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:32:51.928995Z","caller":"traceutil/trace.go:171","msg":"trace[1810028733] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"143.776921ms","start":"2026-04-07T01:32:51.785156Z","end":"2026-04-07T01:32:51.928933Z","steps":["trace[1810028733] 'process raft request' (duration: 57.621383ms)","trace[1810028733] 'compare' (duration: 85.907463ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:32:51.953522Z","caller":"traceutil/trace.go:171","msg":"trace[181499521] linearizableReadLoop","detail":"{readStateIndex:5167; appliedIndex:5165; }","duration":"124.8558ms","start":"2026-04-07T01:32:51.828649Z","end":"2026-04-07T01:32:51.953505Z","steps":["trace[181499521] 'read index received' (duration: 14.181131ms)","trace[181499521] 'applied index is now lower than readState.Index' (duration: 110.672989ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:32:51.95374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.058554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c5eed1ef-85ae-4aed-ac5e-a7bf0d5c8823\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-07T01:32:51.953794Z","caller":"traceutil/trace.go:171","msg":"trace[758498502] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c5eed1ef-85ae-4aed-ac5e-a7bf0d5c8823; range_end:; response_count:1; response_revision:4976; }","duration":"125.172957ms","start":"2026-04-07T01:32:51.828604Z","end":"2026-04-07T01:32:51.953777Z","steps":["trace[758498502] 'agreement among raft nodes before linearized reading' (duration: 124.968173ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:32:51.953746Z","caller":"traceutil/trace.go:171","msg":"trace[586483787] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"158.549385ms","start":"2026-04-07T01:32:51.795058Z","end":"2026-04-07T01:32:51.953608Z","steps":["trace[586483787] 'process raft request' (duration: 158.192908ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:33:10.668973Z","caller":"traceutil/trace.go:171","msg":"trace[712598912] linearizableReadLoop","detail":"{readStateIndex:5552; appliedIndex:5551; }","duration":"109.668844ms","start":"2026-04-07T01:33:10.559283Z","end":"2026-04-07T01:33:10.668952Z","steps":["trace[712598912] 'read index received' (duration: 109.429219ms)","trace[712598912] 'applied index is now lower than readState.Index' (duration: 238.855µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:33:10.66911Z","caller":"traceutil/trace.go:171","msg":"trace[46737291] transaction","detail":"{read_only:false; response_revision:5350; number_of_response:1; }","duration":"167.267925ms","start":"2026-04-07T01:33:10.501793Z","end":"2026-04-07T01:33:10.669061Z","steps":["trace[46737291] 'process raft request' (duration: 166.992029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:33:10.669192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.888799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-33e93892-11cc-4ede-a506-b781e10f6591\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-07T01:33:10.66928Z","caller":"traceutil/trace.go:171","msg":"trace[1210289981] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-33e93892-11cc-4ede-a506-b781e10f6591; range_end:; response_count:1; response_revision:5350; }","duration":"110.026123ms","start":"2026-04-07T01:33:10.559238Z","end":"2026-04-07T01:33:10.669264Z","steps":["trace[1210289981] 'agreement among raft nodes before linearized reading' (duration: 109.817988ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:33:23.411615Z","caller":"traceutil/trace.go:171","msg":"trace[852262930] transaction","detail":"{read_only:false; response_revision:5524; number_of_response:1; }","duration":"154.049512ms","start":"2026-04-07T01:33:23.257528Z","end":"2026-04-07T01:33:23.411577Z","steps":["trace[852262930] 'process raft request' (duration: 110.586384ms)","trace[852262930] 'compare' (duration: 43.220353ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:33:23.411648Z","caller":"traceutil/trace.go:171","msg":"trace[1948089148] linearizableReadLoop","detail":"{readStateIndex:5732; appliedIndex:5728; }","duration":"122.747944ms","start":"2026-04-07T01:33:23.288867Z","end":"2026-04-07T01:33:23.411615Z","steps":["trace[1948089148] 'read index received' (duration: 25.456564ms)","trace[1948089148] 'applied index is now lower than readState.Index' (duration: 97.2895ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:33:23.411759Z","caller":"traceutil/trace.go:171","msg":"trace[1929479604] transaction","detail":"{read_only:false; response_revision:5525; number_of_response:1; }","duration":"153.999641ms","start":"2026-04-07T01:33:23.257735Z","end":"2026-04-07T01:33:23.411734Z","steps":["trace[1929479604] 'process raft request' (duration: 153.743746ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:33:23.411747Z","caller":"traceutil/trace.go:171","msg":"trace[1978329151] transaction","detail":"{read_only:false; response_revision:5526; number_of_response:1; }","duration":"153.367068ms","start":"2026-04-07T01:33:23.25834Z","end":"2026-04-07T01:33:23.411707Z","steps":["trace[1978329151] 'process raft request' (duration: 153.202754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:33:23.411926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.02707ms","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-07T01:33:23.412001Z","caller":"traceutil/trace.go:171","msg":"trace[1855656408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5526; }","duration":"123.149702ms","start":"2026-04-07T01:33:23.288832Z","end":"2026-04-07T01:33:23.411981Z","steps":["trace[1855656408] 'agreement among raft nodes before linearized reading' (duration: 122.907787ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:34:28.676712Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2983} {"level":"info","ts":"2026-04-07T01:34:28.753951Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2983,"took":"75.27922ms","hash":490741670,"current-db-size-bytes":57917440,"current-db-size":"58 MB","current-db-size-in-use-bytes":33427456,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-07T01:34:28.754038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":490741670,"revision":2983,"compact-revision":-1} {"level":"info","ts":"2026-04-07T01:39:09.344383Z","caller":"traceutil/trace.go:171","msg":"trace[424274157] transaction","detail":"{read_only:false; response_revision:8154; number_of_response:1; }","duration":"100.86648ms","start":"2026-04-07T01:39:09.243485Z","end":"2026-04-07T01:39:09.344352Z","steps":["trace[424274157] 'process raft request' (duration: 100.627135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:20.842016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.723236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T01:39:20.842134Z","caller":"traceutil/trace.go:171","msg":"trace[1181155831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8217; }","duration":"140.928451ms","start":"2026-04-07T01:39:20.701177Z","end":"2026-04-07T01:39:20.842105Z","steps":["trace[1181155831] 'range keys from in-memory index tree' (duration: 140.528343ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:21.644424Z","caller":"traceutil/trace.go:171","msg":"trace[1311080768] transaction","detail":"{read_only:false; response_revision:8219; number_of_response:1; }","duration":"244.073529ms","start":"2026-04-07T01:39:21.400318Z","end":"2026-04-07T01:39:21.644391Z","steps":["trace[1311080768] 'process raft request' (duration: 243.867675ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:21.646336Z","caller":"traceutil/trace.go:171","msg":"trace[1804648993] transaction","detail":"{read_only:false; response_revision:8220; number_of_response:1; }","duration":"196.368807ms","start":"2026-04-07T01:39:21.449932Z","end":"2026-04-07T01:39:21.6463Z","steps":["trace[1804648993] 'process raft request' (duration: 195.60558ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:22.477596Z","caller":"traceutil/trace.go:171","msg":"trace[17572518] transaction","detail":"{read_only:false; response_revision:8227; number_of_response:1; }","duration":"108.281617ms","start":"2026-04-07T01:39:22.369274Z","end":"2026-04-07T01:39:22.477556Z","steps":["trace[17572518] 'process raft request' (duration: 108.032111ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:39:22.637537Z","caller":"traceutil/trace.go:171","msg":"trace[101679913] transaction","detail":"{read_only:false; response_revision:8228; number_of_response:1; }","duration":"142.461572ms","start":"2026-04-07T01:39:22.495031Z","end":"2026-04-07T01:39:22.637492Z","steps":["trace[101679913] 'process raft request' (duration: 65.610702ms)","trace[101679913] 'compare' (duration: 76.447392ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:39:28.683044Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6069} {"level":"info","ts":"2026-04-07T01:39:28.780692Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6069,"took":"95.342604ms","hash":1581687560,"current-db-size-bytes":57917440,"current-db-size":"58 MB","current-db-size-in-use-bytes":22925312,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-07T01:39:28.780765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1581687560,"revision":6069,"compact-revision":2983} {"level":"info","ts":"2026-04-07T01:39:49.072318Z","caller":"traceutil/trace.go:171","msg":"trace[1118331389] linearizableReadLoop","detail":"{readStateIndex:8720; appliedIndex:8719; }","duration":"111.758191ms","start":"2026-04-07T01:39:48.960538Z","end":"2026-04-07T01:39:49.072296Z","steps":["trace[1118331389] 'read index received' (duration: 111.629799ms)","trace[1118331389] 'applied index is now lower than readState.Index' (duration: 127.682µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:39:49.072393Z","caller":"traceutil/trace.go:171","msg":"trace[678449659] transaction","detail":"{read_only:false; response_revision:8420; number_of_response:1; }","duration":"330.707828ms","start":"2026-04-07T01:39:48.741644Z","end":"2026-04-07T01:39:49.072352Z","steps":["trace[678449659] 'process raft request' (duration: 330.507593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:49.072499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.934535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T01:39:49.072535Z","caller":"traceutil/trace.go:171","msg":"trace[1599202666] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:8420; }","duration":"112.030377ms","start":"2026-04-07T01:39:48.960495Z","end":"2026-04-07T01:39:49.072525Z","steps":["trace[1599202666] 'agreement among raft nodes before linearized reading' (duration: 111.926155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:39:49.072573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T01:39:48.741628Z","time spent":"330.84362ms","remote":"127.0.0.1:34366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T01:39:49.217542Z","caller":"traceutil/trace.go:171","msg":"trace[221723147] transaction","detail":"{read_only:false; response_revision:8421; number_of_response:1; }","duration":"140.583093ms","start":"2026-04-07T01:39:49.07694Z","end":"2026-04-07T01:39:49.217523Z","steps":["trace[221723147] 'process raft request' (duration: 139.769396ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:01.438929Z","caller":"traceutil/trace.go:171","msg":"trace[248195838] linearizableReadLoop","detail":"{readStateIndex:8831; appliedIndex:8830; }","duration":"163.779795ms","start":"2026-04-07T01:40:01.275122Z","end":"2026-04-07T01:40:01.438902Z","steps":["trace[248195838] 'read index received' (duration: 163.572331ms)","trace[248195838] 'applied index is now lower than readState.Index' (duration: 205.884µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:40:01.438968Z","caller":"traceutil/trace.go:171","msg":"trace[1743869825] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"163.897237ms","start":"2026-04-07T01:40:01.275039Z","end":"2026-04-07T01:40:01.438936Z","steps":["trace[1743869825] 'process raft request' (duration: 163.684732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:40:01.439142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.997569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-vfg5j\" ","response":"range_response_count:1 size:2804"} {"level":"info","ts":"2026-04-07T01:40:01.439185Z","caller":"traceutil/trace.go:171","msg":"trace[716815850] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/openstack/rook-ceph-detect-version-vfg5j; range_end:; response_count:1; response_revision:8525; }","duration":"164.082911ms","start":"2026-04-07T01:40:01.275089Z","end":"2026-04-07T01:40:01.439172Z","steps":["trace[716815850] 'agreement among raft nodes before linearized reading' (duration: 163.921687ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:01.45426Z","caller":"traceutil/trace.go:171","msg":"trace[1519570567] transaction","detail":"{read_only:false; response_revision:8526; number_of_response:1; }","duration":"178.498548ms","start":"2026-04-07T01:40:01.275746Z","end":"2026-04-07T01:40:01.454244Z","steps":["trace[1519570567] 'process raft request' (duration: 178.411626ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:02.291643Z","caller":"traceutil/trace.go:171","msg":"trace[1375787604] linearizableReadLoop","detail":"{readStateIndex:8841; appliedIndex:8840; }","duration":"179.935948ms","start":"2026-04-07T01:40:02.111679Z","end":"2026-04-07T01:40:02.291615Z","steps":["trace[1375787604] 'read index received' (duration: 149.516843ms)","trace[1375787604] 'applied index is now lower than readState.Index' (duration: 30.417665ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:40:02.291665Z","caller":"traceutil/trace.go:171","msg":"trace[173800651] transaction","detail":"{read_only:false; response_revision:8534; number_of_response:1; }","duration":"219.076669ms","start":"2026-04-07T01:40:02.072561Z","end":"2026-04-07T01:40:02.291637Z","steps":["trace[173800651] 'process raft request' (duration: 188.622012ms)","trace[173800651] 'compare' (duration: 30.321934ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T01:40:02.291823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.121023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-07T01:40:02.291857Z","caller":"traceutil/trace.go:171","msg":"trace[1364013242] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8534; }","duration":"180.197344ms","start":"2026-04-07T01:40:02.111648Z","end":"2026-04-07T01:40:02.291845Z","steps":["trace[1364013242] 'agreement among raft nodes before linearized reading' (duration: 180.058251ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:02.338359Z","caller":"traceutil/trace.go:171","msg":"trace[897070328] transaction","detail":"{read_only:false; response_revision:8535; number_of_response:1; }","duration":"131.132862ms","start":"2026-04-07T01:40:02.207204Z","end":"2026-04-07T01:40:02.338337Z","steps":["trace[897070328] 'process raft request' (duration: 130.704923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:40:02.876592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.542977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T01:40:02.876682Z","caller":"traceutil/trace.go:171","msg":"trace[2073173258] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8540; }","duration":"103.68815ms","start":"2026-04-07T01:40:02.772972Z","end":"2026-04-07T01:40:02.876661Z","steps":["trace[2073173258] 'count revisions from in-memory index tree' (duration: 103.421804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T01:40:02.877359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.803875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210989"} {"level":"info","ts":"2026-04-07T01:40:02.877444Z","caller":"traceutil/trace.go:171","msg":"trace[504580148] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:8540; }","duration":"121.949038ms","start":"2026-04-07T01:40:02.755475Z","end":"2026-04-07T01:40:02.877424Z","steps":["trace[504580148] 'range keys from in-memory index tree' (duration: 120.828824ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:03.107904Z","caller":"traceutil/trace.go:171","msg":"trace[663223378] transaction","detail":"{read_only:false; response_revision:8542; number_of_response:1; }","duration":"131.429209ms","start":"2026-04-07T01:40:02.976446Z","end":"2026-04-07T01:40:03.107875Z","steps":["trace[663223378] 'process raft request' (duration: 131.248335ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:03.240582Z","caller":"traceutil/trace.go:171","msg":"trace[966658015] transaction","detail":"{read_only:false; response_revision:8543; number_of_response:1; }","duration":"211.765573ms","start":"2026-04-07T01:40:03.028788Z","end":"2026-04-07T01:40:03.240553Z","steps":["trace[966658015] 'process raft request' (duration: 150.83571ms)","trace[966658015] 'compare' (duration: 60.550805ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T01:40:03.240856Z","caller":"traceutil/trace.go:171","msg":"trace[623742502] transaction","detail":"{read_only:false; response_revision:8544; number_of_response:1; }","duration":"113.405737ms","start":"2026-04-07T01:40:03.127419Z","end":"2026-04-07T01:40:03.240825Z","steps":["trace[623742502] 'process raft request' (duration: 113.076139ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T01:40:52.527671Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002498.wal"} {"level":"info","ts":"2026-04-07T01:42:04.745865Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6f1fcbe50562a63a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T01:42:04.751838Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T01:42:04.75194Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T01:44:28.689512Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8267} {"level":"info","ts":"2026-04-07T01:44:28.770753Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8267,"took":"77.62548ms","hash":4073261370,"current-db-size-bytes":57917440,"current-db-size":"58 MB","current-db-size-in-use-bytes":25075712,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-07T01:44:28.770835Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4073261370,"revision":8267,"compact-revision":6069} {"level":"info","ts":"2026-04-07T01:49:28.694671Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10687} {"level":"info","ts":"2026-04-07T01:49:28.772821Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10687,"took":"75.924155ms","hash":1650797586,"current-db-size-bytes":57917440,"current-db-size":"58 MB","current-db-size-in-use-bytes":20398080,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-07T01:49:28.772902Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1650797586,"revision":10687,"compact-revision":8267}