{"level":"warn","ts":"2026-09-16T21:37:57.729184Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-16T21:37:57.729341Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.176: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.176:2380","--initial-cluster=instance=https://162.253.55.176:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.176:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.176:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-09-16T21:37:57.729437Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-16T21:37:57.729451Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.176:2380"]} {"level":"info","ts":"2026-09-16T21:37:57.729473Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-16T21:37:57.730905Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.176:2379"]} {"level":"info","ts":"2026-09-16T21:37:57.731034Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.176:2380"],"listen-peer-urls":["https://162.253.55.176:2380"],"advertise-client-urls":["https://162.253.55.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.176:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-09-16T21:37:57.740163Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.828424ms"} {"level":"info","ts":"2026-09-16T21:37:57.749873Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"590b810b00f00655","cluster-id":"dcc6923d0deac545"} {"level":"info","ts":"2026-09-16T21:37:57.750077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 switched to configuration voters=()"} {"level":"info","ts":"2026-09-16T21:37:57.750178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became follower at term 0"} {"level":"info","ts":"2026-09-16T21:37:57.7502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 590b810b00f00655 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-16T21:37:57.750208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became follower at term 1"} {"level":"info","ts":"2026-09-16T21:37:57.750253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 switched to configuration voters=(6416363978379757141)"} {"level":"warn","ts":"2026-09-16T21:37:57.7647Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-16T21:37:57.766124Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-16T21:37:57.768543Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-09-16T21:37:57.770646Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"590b810b00f00655","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-16T21:37:57.774107Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"590b810b00f00655","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-16T21:37:57.774278Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-16T21:37:57.774355Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-16T21:37:57.774392Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-09-16T21:37:57.778476Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-09-16T21:37:57.778561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 switched to configuration voters=(6416363978379757141)"} {"level":"info","ts":"2026-09-16T21:37:57.778633Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.176:2380"} {"level":"info","ts":"2026-09-16T21:37:57.778741Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dcc6923d0deac545","local-member-id":"590b810b00f00655","added-peer-id":"590b810b00f00655","added-peer-peer-urls":["https://162.253.55.176:2380"]} {"level":"info","ts":"2026-09-16T21:37:57.778803Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"590b810b00f00655","initial-advertise-peer-urls":["https://162.253.55.176:2380"],"listen-peer-urls":["https://162.253.55.176:2380"],"advertise-client-urls":["https://162.253.55.176:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.176:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-16T21:37:57.778844Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-16T21:37:57.778706Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.176:2380"} {"level":"info","ts":"2026-09-16T21:37:57.950837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 is starting a new election at term 1"} {"level":"info","ts":"2026-09-16T21:37:57.950892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-16T21:37:57.95094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 received MsgPreVoteResp from 590b810b00f00655 at term 1"} {"level":"info","ts":"2026-09-16T21:37:57.95096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became candidate at term 2"} {"level":"info","ts":"2026-09-16T21:37:57.950971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 received MsgVoteResp from 590b810b00f00655 at term 2"} {"level":"info","ts":"2026-09-16T21:37:57.950987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"590b810b00f00655 became leader at term 2"} {"level":"info","ts":"2026-09-16T21:37:57.951007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 590b810b00f00655 elected leader 590b810b00f00655 at term 2"} {"level":"info","ts":"2026-09-16T21:37:57.953492Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"590b810b00f00655","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.176:2379]}","request-path":"/0/members/590b810b00f00655/attributes","cluster-id":"dcc6923d0deac545","publish-timeout":"7s"} {"level":"info","ts":"2026-09-16T21:37:57.9536Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-16T21:37:57.953834Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-16T21:37:57.954674Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-16T21:37:57.954703Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-16T21:37:57.956528Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T21:37:57.957521Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-16T21:37:57.964531Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dcc6923d0deac545","local-member-id":"590b810b00f00655","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T21:37:57.96492Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T21:37:57.968056Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.176:2379"} {"level":"info","ts":"2026-09-16T21:37:57.970508Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-09-16T21:38:07.88038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.794235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-16T21:38:07.880439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.197394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-16T21:38:07.880747Z","caller":"traceutil/trace.go:171","msg":"trace[251716579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:382; }","duration":"122.625416ms","start":"2026-09-16T21:38:07.758109Z","end":"2026-09-16T21:38:07.880734Z","steps":["trace[251716579] 'range keys from in-memory index tree' (duration: 121.960751ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:38:07.880647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.689529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-09-16T21:38:07.880608Z","caller":"traceutil/trace.go:171","msg":"trace[1470756349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:382; }","duration":"243.072597ms","start":"2026-09-16T21:38:07.637505Z","end":"2026-09-16T21:38:07.880578Z","steps":["trace[1470756349] 'range keys from in-memory index tree' (duration: 242.678254ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:38:07.880885Z","caller":"traceutil/trace.go:171","msg":"trace[1974712506] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:382; }","duration":"222.950711ms","start":"2026-09-16T21:38:07.657895Z","end":"2026-09-16T21:38:07.880846Z","steps":["trace[1974712506] 'range keys from in-memory index tree' (duration: 222.595459ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:38:08.033668Z","caller":"traceutil/trace.go:171","msg":"trace[1650257764] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"140.814268ms","start":"2026-09-16T21:38:07.892819Z","end":"2026-09-16T21:38:08.033634Z","steps":["trace[1650257764] 'process raft request' (duration: 140.628107ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:38:08.308946Z","caller":"traceutil/trace.go:171","msg":"trace[555258264] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"415.902704ms","start":"2026-09-16T21:38:07.893015Z","end":"2026-09-16T21:38:08.308918Z","steps":["trace[555258264] 'process raft request' (duration: 415.624562ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:38:08.309311Z","caller":"traceutil/trace.go:171","msg":"trace[224418904] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:396; }","duration":"383.969962ms","start":"2026-09-16T21:38:07.925313Z","end":"2026-09-16T21:38:08.309283Z","steps":["trace[224418904] 'read index received' (duration: 108.171032ms)","trace[224418904] 'applied index is now lower than readState.Index' (duration: 275.79724ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:38:08.309461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.124823ms","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-09-16T21:38:08.309499Z","caller":"traceutil/trace.go:171","msg":"trace[1558540050] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:385; }","duration":"384.214695ms","start":"2026-09-16T21:38:07.925273Z","end":"2026-09-16T21:38:08.309488Z","steps":["trace[1558540050] 'agreement among raft nodes before linearized reading' (duration: 384.100703ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:38:08.30953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:38:07.925259Z","time spent":"384.264715ms","remote":"127.0.0.1:60004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-16T21:38:08.309691Z","caller":"traceutil/trace.go:171","msg":"trace[1310380453] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"410.848727ms","start":"2026-09-16T21:38:07.898744Z","end":"2026-09-16T21:38:08.309592Z","steps":["trace[1310380453] 'process raft request' (duration: 410.094662ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:38:08.310637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:38:07.893005Z","time spent":"416.130825ms","remote":"127.0.0.1:60004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T21:38:08.310655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:38:07.898697Z","time spent":"411.649633ms","remote":"127.0.0.1:59942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T21:38:44.114348Z","caller":"traceutil/trace.go:171","msg":"trace[1864480945] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"160.635771ms","start":"2026-09-16T21:38:43.953685Z","end":"2026-09-16T21:38:44.11432Z","steps":["trace[1864480945] 'process raft request' (duration: 160.526916ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:38:44.114697Z","caller":"traceutil/trace.go:171","msg":"trace[2095675911] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"161.779668ms","start":"2026-09-16T21:38:43.95285Z","end":"2026-09-16T21:38:44.11463Z","steps":["trace[2095675911] 'process raft request' (duration: 87.741321ms)","trace[2095675911] 'compare' (duration: 73.239289ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:38:52.639759Z","caller":"traceutil/trace.go:171","msg":"trace[1088757065] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"161.967563ms","start":"2026-09-16T21:38:52.477763Z","end":"2026-09-16T21:38:52.63973Z","steps":["trace[1088757065] 'process raft request' (duration: 157.367192ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:38:52.881214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.852998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T21:38:52.881338Z","caller":"traceutil/trace.go:171","msg":"trace[492930428] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"208.143556ms","start":"2026-09-16T21:38:52.673178Z","end":"2026-09-16T21:38:52.881321Z","steps":["trace[492930428] 'read index received' (duration: 67.865319ms)","trace[492930428] 'applied index is now lower than readState.Index' (duration: 140.277107ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:38:52.881429Z","caller":"traceutil/trace.go:171","msg":"trace[1956663289] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"234.350929ms","start":"2026-09-16T21:38:52.647039Z","end":"2026-09-16T21:38:52.88139Z","steps":["trace[1956663289] 'process raft request' (duration: 94.021529ms)","trace[1956663289] 'compare' (duration: 139.673451ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:38:52.881569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.387186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-16T21:38:52.881637Z","caller":"traceutil/trace.go:171","msg":"trace[913370179] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:656; }","duration":"208.417747ms","start":"2026-09-16T21:38:52.673171Z","end":"2026-09-16T21:38:52.881588Z","steps":["trace[913370179] 'agreement among raft nodes before linearized reading' (duration: 208.236629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:38:52.894898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.457915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q28qp\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-16T21:38:52.895008Z","caller":"traceutil/trace.go:171","msg":"trace[128393396] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q28qp; range_end:; response_count:1; response_revision:657; }","duration":"118.629032ms","start":"2026-09-16T21:38:52.776359Z","end":"2026-09-16T21:38:52.894988Z","steps":["trace[128393396] 'agreement among raft nodes before linearized reading' (duration: 118.388172ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:38:52.894886Z","caller":"traceutil/trace.go:171","msg":"trace[1236977670] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"171.800591ms","start":"2026-09-16T21:38:52.723043Z","end":"2026-09-16T21:38:52.894843Z","steps":["trace[1236977670] 'process raft request' (duration: 171.572041ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:38:53.492294Z","caller":"traceutil/trace.go:171","msg":"trace[1208229970] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"184.176201ms","start":"2026-09-16T21:38:53.30809Z","end":"2026-09-16T21:38:53.492267Z","steps":["trace[1208229970] 'process raft request' (duration: 182.482868ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:39:10.019599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.323931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T21:39:10.01971Z","caller":"traceutil/trace.go:171","msg":"trace[2066043235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:818; }","duration":"222.518499ms","start":"2026-09-16T21:39:09.79717Z","end":"2026-09-16T21:39:10.019689Z","steps":["trace[2066043235] 'range keys from in-memory index tree' (duration: 222.197486ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:39:10.019796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.525583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hwjms\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-09-16T21:39:10.019888Z","caller":"traceutil/trace.go:171","msg":"trace[1641097796] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hwjms; range_end:; response_count:1; response_revision:818; }","duration":"131.714559ms","start":"2026-09-16T21:39:09.888152Z","end":"2026-09-16T21:39:10.019867Z","steps":["trace[1641097796] 'range keys from in-memory index tree' (duration: 131.265774ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:39:10.248275Z","caller":"traceutil/trace.go:171","msg":"trace[826649644] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"222.402727ms","start":"2026-09-16T21:39:10.025846Z","end":"2026-09-16T21:39:10.248248Z","steps":["trace[826649644] 'process raft request' (duration: 222.253101ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:40:28.418683Z","caller":"traceutil/trace.go:171","msg":"trace[315842408] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"126.056292ms","start":"2026-09-16T21:40:28.292599Z","end":"2026-09-16T21:40:28.418656Z","steps":["trace[315842408] 'process raft request' (duration: 125.864278ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:40:28.425047Z","caller":"traceutil/trace.go:171","msg":"trace[460435637] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"120.939631ms","start":"2026-09-16T21:40:28.304077Z","end":"2026-09-16T21:40:28.425017Z","steps":["trace[460435637] 'process raft request' (duration: 120.793308ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:40:28.621345Z","caller":"traceutil/trace.go:171","msg":"trace[826566162] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"154.320062ms","start":"2026-09-16T21:40:28.466989Z","end":"2026-09-16T21:40:28.621309Z","steps":["trace[826566162] 'process raft request' (duration: 143.093824ms)","trace[826566162] 'compare' (duration: 10.967462ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:41:07.92077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.726095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T21:41:07.920884Z","caller":"traceutil/trace.go:171","msg":"trace[783171107] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"178.697985ms","start":"2026-09-16T21:41:07.742164Z","end":"2026-09-16T21:41:07.920862Z","steps":["trace[783171107] 'process raft request' (duration: 15.800067ms)","trace[783171107] 'compare' (duration: 162.42944ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:41:07.920871Z","caller":"traceutil/trace.go:171","msg":"trace[1617308792] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2254; }","duration":"173.059063ms","start":"2026-09-16T21:41:07.747796Z","end":"2026-09-16T21:41:07.920855Z","steps":["trace[1617308792] 'read index received' (duration: 10.068593ms)","trace[1617308792] 'applied index is now lower than readState.Index' (duration: 162.98921ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:41:07.921223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.783659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T21:41:07.921262Z","caller":"traceutil/trace.go:171","msg":"trace[1884538725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2166; }","duration":"123.86079ms","start":"2026-09-16T21:41:07.797391Z","end":"2026-09-16T21:41:07.921251Z","steps":["trace[1884538725] 'agreement among raft nodes before linearized reading' (duration: 123.790289ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:41:07.921227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.415288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-16T21:41:07.921684Z","caller":"traceutil/trace.go:171","msg":"trace[48081872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2166; }","duration":"173.912746ms","start":"2026-09-16T21:41:07.747758Z","end":"2026-09-16T21:41:07.921671Z","steps":["trace[48081872] 'agreement among raft nodes before linearized reading' (duration: 173.347837ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:42:01.315968Z","caller":"traceutil/trace.go:171","msg":"trace[256148924] transaction","detail":"{read_only:false; response_revision:2555; number_of_response:1; }","duration":"200.727379ms","start":"2026-09-16T21:42:01.115215Z","end":"2026-09-16T21:42:01.315943Z","steps":["trace[256148924] 'process raft request' (duration: 200.574277ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:42:31.645162Z","caller":"traceutil/trace.go:171","msg":"trace[5760228] linearizableReadLoop","detail":"{readStateIndex:2829; appliedIndex:2828; }","duration":"105.291712ms","start":"2026-09-16T21:42:31.539849Z","end":"2026-09-16T21:42:31.645141Z","steps":["trace[5760228] 'read index received' (duration: 105.12264ms)","trace[5760228] 'applied index is now lower than readState.Index' (duration: 168.152µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:42:31.645324Z","caller":"traceutil/trace.go:171","msg":"trace[1366466150] transaction","detail":"{read_only:false; response_revision:2722; number_of_response:1; }","duration":"170.817383ms","start":"2026-09-16T21:42:31.47449Z","end":"2026-09-16T21:42:31.645307Z","steps":["trace[1366466150] 'process raft request' (duration: 170.50402ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:42:31.645591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.676787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-16T21:42:31.645633Z","caller":"traceutil/trace.go:171","msg":"trace[1370734438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2722; }","duration":"105.822719ms","start":"2026-09-16T21:42:31.5398Z","end":"2026-09-16T21:42:31.645623Z","steps":["trace[1370734438] 'agreement among raft nodes before linearized reading' (duration: 105.621407ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:13.190346Z","caller":"traceutil/trace.go:171","msg":"trace[1712427985] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"218.406458ms","start":"2026-09-16T21:47:12.971911Z","end":"2026-09-16T21:47:13.190317Z","steps":["trace[1712427985] 'process raft request' (duration: 198.750565ms)","trace[1712427985] 'compare' (duration: 18.652812ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:47:13.190388Z","caller":"traceutil/trace.go:171","msg":"trace[1456417279] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"165.063155ms","start":"2026-09-16T21:47:13.025297Z","end":"2026-09-16T21:47:13.19036Z","steps":["trace[1456417279] 'process raft request' (duration: 164.942152ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:13.19053Z","caller":"traceutil/trace.go:171","msg":"trace[65543153] linearizableReadLoop","detail":"{readStateIndex:5150; appliedIndex:5148; }","duration":"209.00747ms","start":"2026-09-16T21:47:12.981509Z","end":"2026-09-16T21:47:13.190516Z","steps":["trace[65543153] 'read index received' (duration: 665.544µs)","trace[65543153] 'applied index is now lower than readState.Index' (duration: 208.340486ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:47:13.190749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.227354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1449"} {"level":"info","ts":"2026-09-16T21:47:13.190778Z","caller":"traceutil/trace.go:171","msg":"trace[697337221] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4957; }","duration":"209.270775ms","start":"2026-09-16T21:47:12.981498Z","end":"2026-09-16T21:47:13.190769Z","steps":["trace[697337221] 'agreement among raft nodes before linearized reading' (duration: 209.114382ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:13.193082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.911105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207418"} {"level":"info","ts":"2026-09-16T21:47:13.193149Z","caller":"traceutil/trace.go:171","msg":"trace[266103336] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4957; }","duration":"133.023458ms","start":"2026-09-16T21:47:13.060112Z","end":"2026-09-16T21:47:13.193135Z","steps":["trace[266103336] 'agreement among raft nodes before linearized reading' (duration: 130.6271ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:13.425982Z","caller":"traceutil/trace.go:171","msg":"trace[490075967] linearizableReadLoop","detail":"{readStateIndex:5152; appliedIndex:5151; }","duration":"225.224222ms","start":"2026-09-16T21:47:13.200733Z","end":"2026-09-16T21:47:13.425957Z","steps":["trace[490075967] 'read index received' (duration: 216.454118ms)","trace[490075967] 'applied index is now lower than readState.Index' (duration: 8.769074ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:47:13.426098Z","caller":"traceutil/trace.go:171","msg":"trace[604751865] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"226.598101ms","start":"2026-09-16T21:47:13.199478Z","end":"2026-09-16T21:47:13.426076Z","steps":["trace[604751865] 'process raft request' (duration: 217.777096ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:13.426259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.511538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-09-16T21:47:13.426349Z","caller":"traceutil/trace.go:171","msg":"trace[1657429680] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4958; }","duration":"225.636581ms","start":"2026-09-16T21:47:13.200697Z","end":"2026-09-16T21:47:13.426333Z","steps":["trace[1657429680] 'agreement among raft nodes before linearized reading' (duration: 225.388156ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:13.43267Z","caller":"traceutil/trace.go:171","msg":"trace[167191450] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"121.725812ms","start":"2026-09-16T21:47:13.310911Z","end":"2026-09-16T21:47:13.432637Z","steps":["trace[167191450] 'process raft request' (duration: 121.547589ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:13.433221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.283017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"warn","ts":"2026-09-16T21:47:13.433262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.45902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-16T21:47:13.433252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.350289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-16T21:47:13.433297Z","caller":"traceutil/trace.go:171","msg":"trace[950492335] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4959; }","duration":"194.399839ms","start":"2026-09-16T21:47:13.238878Z","end":"2026-09-16T21:47:13.433278Z","steps":["trace[950492335] 'agreement among raft nodes before linearized reading' (duration: 194.159264ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:13.433319Z","caller":"traceutil/trace.go:171","msg":"trace[143499209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4959; }","duration":"192.544062ms","start":"2026-09-16T21:47:13.240759Z","end":"2026-09-16T21:47:13.433303Z","steps":["trace[143499209] 'agreement among raft nodes before linearized reading' (duration: 192.343098ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:13.433355Z","caller":"traceutil/trace.go:171","msg":"trace[978469228] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4959; }","duration":"191.481611ms","start":"2026-09-16T21:47:13.241844Z","end":"2026-09-16T21:47:13.433325Z","steps":["trace[978469228] 'agreement among raft nodes before linearized reading' (duration: 191.176464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:13.433681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.431052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-16T21:47:13.433682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.150642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-16T21:47:13.433723Z","caller":"traceutil/trace.go:171","msg":"trace[1389436934] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:4959; }","duration":"177.489463ms","start":"2026-09-16T21:47:13.256224Z","end":"2026-09-16T21:47:13.433713Z","steps":["trace[1389436934] 'agreement among raft nodes before linearized reading' (duration: 177.420501ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:13.433743Z","caller":"traceutil/trace.go:171","msg":"trace[376293905] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:4959; }","duration":"112.237214ms","start":"2026-09-16T21:47:13.321488Z","end":"2026-09-16T21:47:13.433725Z","steps":["trace[376293905] 'agreement among raft nodes before linearized reading' (duration: 112.097311ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:13.80463Z","caller":"traceutil/trace.go:171","msg":"trace[820714682] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"228.430046ms","start":"2026-09-16T21:47:13.576119Z","end":"2026-09-16T21:47:13.804549Z","steps":["trace[820714682] 'process raft request' (duration: 136.461945ms)","trace[820714682] 'compare' (duration: 91.638954ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:47:13.80507Z","caller":"traceutil/trace.go:171","msg":"trace[214648350] transaction","detail":"{read_only:false; response_revision:4976; number_of_response:1; }","duration":"209.106452ms","start":"2026-09-16T21:47:13.595944Z","end":"2026-09-16T21:47:13.80505Z","steps":["trace[214648350] 'process raft request' (duration: 209.039311ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:13.805118Z","caller":"traceutil/trace.go:171","msg":"trace[316300531] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"221.03075ms","start":"2026-09-16T21:47:13.584065Z","end":"2026-09-16T21:47:13.805096Z","steps":["trace[316300531] 'process raft request' (duration: 220.482079ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:13.805255Z","caller":"traceutil/trace.go:171","msg":"trace[2041310596] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"209.740484ms","start":"2026-09-16T21:47:13.595499Z","end":"2026-09-16T21:47:13.805239Z","steps":["trace[2041310596] 'process raft request' (duration: 209.196163ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:13.805357Z","caller":"traceutil/trace.go:171","msg":"trace[997358] transaction","detail":"{read_only:false; response_revision:4975; number_of_response:1; }","duration":"209.439759ms","start":"2026-09-16T21:47:13.595901Z","end":"2026-09-16T21:47:13.805341Z","steps":["trace[997358] 'process raft request' (duration: 208.903338ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:13.805331Z","caller":"traceutil/trace.go:171","msg":"trace[83857023] linearizableReadLoop","detail":"{readStateIndex:5165; appliedIndex:5164; }","duration":"224.875096ms","start":"2026-09-16T21:47:13.580299Z","end":"2026-09-16T21:47:13.805174Z","steps":["trace[83857023] 'read index received' (duration: 132.211592ms)","trace[83857023] 'applied index is now lower than readState.Index' (duration: 92.660614ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:47:13.805235Z","caller":"traceutil/trace.go:171","msg":"trace[38650088] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"209.403277ms","start":"2026-09-16T21:47:13.595816Z","end":"2026-09-16T21:47:13.805219Z","steps":["trace[38650088] 'process raft request' (duration: 208.937928ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:13.805639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.787673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1162"} {"level":"info","ts":"2026-09-16T21:47:13.805699Z","caller":"traceutil/trace.go:171","msg":"trace[1645901398] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4976; }","duration":"247.921915ms","start":"2026-09-16T21:47:13.557761Z","end":"2026-09-16T21:47:13.805683Z","steps":["trace[1645901398] 'agreement among raft nodes before linearized reading' (duration: 247.591058ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:13.805734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.178969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1346"} {"level":"info","ts":"2026-09-16T21:47:13.805814Z","caller":"traceutil/trace.go:171","msg":"trace[704144494] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4976; }","duration":"250.303192ms","start":"2026-09-16T21:47:13.555493Z","end":"2026-09-16T21:47:13.805796Z","steps":["trace[704144494] 'agreement among raft nodes before linearized reading' (duration: 250.056837ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:13.805922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.369546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T21:47:13.80597Z","caller":"traceutil/trace.go:171","msg":"trace[1243458303] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:4976; }","duration":"127.489918ms","start":"2026-09-16T21:47:13.678456Z","end":"2026-09-16T21:47:13.805946Z","steps":["trace[1243458303] 'agreement among raft nodes before linearized reading' (duration: 127.324805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:13.805718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.476011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T21:47:13.807857Z","caller":"traceutil/trace.go:171","msg":"trace[1142189166] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:4976; }","duration":"200.620733ms","start":"2026-09-16T21:47:13.607198Z","end":"2026-09-16T21:47:13.807819Z","steps":["trace[1142189166] 'agreement among raft nodes before linearized reading' (duration: 198.466741ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:25.872577Z","caller":"traceutil/trace.go:171","msg":"trace[2048438804] linearizableReadLoop","detail":"{readStateIndex:5326; appliedIndex:5325; }","duration":"403.412848ms","start":"2026-09-16T21:47:25.469144Z","end":"2026-09-16T21:47:25.872557Z","steps":["trace[2048438804] 'read index received' (duration: 403.256945ms)","trace[2048438804] 'applied index is now lower than readState.Index' (duration: 154.893µs)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:47:25.872697Z","caller":"traceutil/trace.go:171","msg":"trace[367709417] transaction","detail":"{read_only:false; response_revision:5126; number_of_response:1; }","duration":"405.615792ms","start":"2026-09-16T21:47:25.467071Z","end":"2026-09-16T21:47:25.872687Z","steps":["trace[367709417] 'process raft request' (duration: 405.304846ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:25.872791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:47:25.467039Z","time spent":"405.681592ms","remote":"127.0.0.1:59942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T21:47:25.873443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.289006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-4a1e1d04-a57a-4592-9007-545831548143\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-09-16T21:47:25.873562Z","caller":"traceutil/trace.go:171","msg":"trace[513529524] range","detail":"{range_begin:/registry/persistentvolumes/pvc-4a1e1d04-a57a-4592-9007-545831548143; range_end:; response_count:1; response_revision:5126; }","duration":"404.420398ms","start":"2026-09-16T21:47:25.469098Z","end":"2026-09-16T21:47:25.873519Z","steps":["trace[513529524] 'agreement among raft nodes before linearized reading' (duration: 403.889887ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:25.873608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:47:25.469079Z","time spent":"404.518041ms","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-4a1e1d04-a57a-4592-9007-545831548143\" "} {"level":"info","ts":"2026-09-16T21:47:25.987411Z","caller":"traceutil/trace.go:171","msg":"trace[140295485] linearizableReadLoop","detail":"{readStateIndex:5328; appliedIndex:5326; }","duration":"114.735367ms","start":"2026-09-16T21:47:25.872643Z","end":"2026-09-16T21:47:25.987378Z","steps":["trace[140295485] 'read index received' (duration: 64.663536ms)","trace[140295485] 'applied index is now lower than readState.Index' (duration: 50.069481ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:47:25.987761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.878538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T21:47:25.987827Z","caller":"traceutil/trace.go:171","msg":"trace[1215410650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5128; }","duration":"190.00817ms","start":"2026-09-16T21:47:25.797797Z","end":"2026-09-16T21:47:25.987805Z","steps":["trace[1215410650] 'agreement among raft nodes before linearized reading' (duration: 189.810136ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:25.987813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.937879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-09-16T21:47:25.987906Z","caller":"traceutil/trace.go:171","msg":"trace[850370340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5128; }","duration":"476.05363ms","start":"2026-09-16T21:47:25.511828Z","end":"2026-09-16T21:47:25.987882Z","steps":["trace[850370340] 'agreement among raft nodes before linearized reading' (duration: 475.907427ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:25.987951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:47:25.511811Z","time spent":"476.132213ms","remote":"127.0.0.1:60004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-16T21:47:25.988168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.137615ms","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-09-16T21:47:25.988306Z","caller":"traceutil/trace.go:171","msg":"trace[1212707079] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5128; }","duration":"472.242736ms","start":"2026-09-16T21:47:25.515981Z","end":"2026-09-16T21:47:25.988224Z","steps":["trace[1212707079] 'agreement among raft nodes before linearized reading' (duration: 471.984272ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:25.988363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:47:25.515973Z","time spent":"472.37696ms","remote":"127.0.0.1:60004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-16T21:47:25.988366Z","caller":"traceutil/trace.go:171","msg":"trace[2039514428] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"494.420452ms","start":"2026-09-16T21:47:25.493926Z","end":"2026-09-16T21:47:25.988346Z","steps":["trace[2039514428] 'process raft request' (duration: 443.889132ms)","trace[2039514428] 'compare' (duration: 49.232545ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:47:25.988398Z","caller":"traceutil/trace.go:171","msg":"trace[1120569743] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"354.755906ms","start":"2026-09-16T21:47:25.633607Z","end":"2026-09-16T21:47:25.988363Z","steps":["trace[1120569743] 'process raft request' (duration: 353.666435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:25.98833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.961559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-09-16T21:47:25.988856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:47:25.493905Z","time spent":"494.494093ms","remote":"127.0.0.1:60004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T21:47:25.988879Z","caller":"traceutil/trace.go:171","msg":"trace[1557834952] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5128; }","duration":"478.54667ms","start":"2026-09-16T21:47:25.510313Z","end":"2026-09-16T21:47:25.98886Z","steps":["trace[1557834952] 'agreement among raft nodes before linearized reading' (duration: 477.793165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:25.989082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:47:25.510296Z","time spent":"478.770335ms","remote":"127.0.0.1:60004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-09-16T21:47:25.988583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:47:25.633592Z","time spent":"354.882749ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":901,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-16T21:47:25.988734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.167131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-16T21:47:25.989697Z","caller":"traceutil/trace.go:171","msg":"trace[1441816171] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5128; }","duration":"126.196641ms","start":"2026-09-16T21:47:25.863482Z","end":"2026-09-16T21:47:25.989679Z","steps":["trace[1441816171] 'agreement among raft nodes before linearized reading' (duration: 124.031939ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:26.012724Z","caller":"traceutil/trace.go:171","msg":"trace[897027752] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"133.276059ms","start":"2026-09-16T21:47:25.879425Z","end":"2026-09-16T21:47:26.012702Z","steps":["trace[897027752] 'process raft request' (duration: 133.062565ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:26.012743Z","caller":"traceutil/trace.go:171","msg":"trace[1779352665] transaction","detail":"{read_only:false; response_revision:5130; number_of_response:1; }","duration":"131.865512ms","start":"2026-09-16T21:47:25.88086Z","end":"2026-09-16T21:47:26.012725Z","steps":["trace[1779352665] 'process raft request' (duration: 131.7626ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:26.012972Z","caller":"traceutil/trace.go:171","msg":"trace[1120580990] transaction","detail":"{read_only:false; response_revision:5131; number_of_response:1; }","duration":"131.964733ms","start":"2026-09-16T21:47:25.880989Z","end":"2026-09-16T21:47:26.012954Z","steps":["trace[1120580990] 'process raft request' (duration: 131.676047ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:26.013057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.860608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-16T21:47:26.013075Z","caller":"traceutil/trace.go:171","msg":"trace[1375261745] transaction","detail":"{read_only:false; response_revision:5132; number_of_response:1; }","duration":"131.408142ms","start":"2026-09-16T21:47:25.881631Z","end":"2026-09-16T21:47:26.013039Z","steps":["trace[1375261745] 'process raft request' (duration: 131.156438ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:26.013112Z","caller":"traceutil/trace.go:171","msg":"trace[765797039] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:5132; }","duration":"114.95011ms","start":"2026-09-16T21:47:25.898146Z","end":"2026-09-16T21:47:26.013096Z","steps":["trace[765797039] 'agreement among raft nodes before linearized reading' (duration: 114.816388ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:58.304067Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2866} {"level":"info","ts":"2026-09-16T21:47:58.37804Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2866,"took":"73.043035ms","hash":172030137,"current-db-size-bytes":52596736,"current-db-size":"53 MB","current-db-size-in-use-bytes":28532736,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-16T21:47:58.378093Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":172030137,"revision":2866,"compact-revision":-1} {"level":"info","ts":"2026-09-16T21:50:57.489455Z","caller":"traceutil/trace.go:171","msg":"trace[1822320644] transaction","detail":"{read_only:false; response_revision:6690; number_of_response:1; }","duration":"124.002828ms","start":"2026-09-16T21:50:57.365413Z","end":"2026-09-16T21:50:57.489415Z","steps":["trace[1822320644] 'process raft request' (duration: 123.882966ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:50:57.489732Z","caller":"traceutil/trace.go:171","msg":"trace[1366134915] transaction","detail":"{read_only:false; response_revision:6688; number_of_response:1; }","duration":"124.820842ms","start":"2026-09-16T21:50:57.364892Z","end":"2026-09-16T21:50:57.489713Z","steps":["trace[1366134915] 'process raft request' (duration: 124.077359ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:50:57.489761Z","caller":"traceutil/trace.go:171","msg":"trace[129899420] transaction","detail":"{read_only:false; response_revision:6691; number_of_response:1; }","duration":"124.256662ms","start":"2026-09-16T21:50:57.365478Z","end":"2026-09-16T21:50:57.489735Z","steps":["trace[129899420] 'process raft request' (duration: 123.861265ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:50:57.489459Z","caller":"traceutil/trace.go:171","msg":"trace[884535886] transaction","detail":"{read_only:false; response_revision:6689; number_of_response:1; }","duration":"124.474267ms","start":"2026-09-16T21:50:57.364928Z","end":"2026-09-16T21:50:57.489402Z","steps":["trace[884535886] 'process raft request' (duration: 124.321914ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:52:58.312972Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5444} {"level":"info","ts":"2026-09-16T21:52:58.398958Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5444,"took":"84.864938ms","hash":2885537313,"current-db-size-bytes":52596736,"current-db-size":"53 MB","current-db-size-in-use-bytes":18616320,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-16T21:52:58.399048Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2885537313,"revision":5444,"compact-revision":2866}