{"level":"warn","ts":"2026-09-18T01:23:53.674874Z","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-18T01:23:53.674966Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.50: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.50:2380","--initial-cluster=instance=https://162.253.55.50:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.50:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.50: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-18T01:23:53.675021Z","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-18T01:23:53.67503Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.50:2380"]} {"level":"info","ts":"2026-09-18T01:23:53.675049Z","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-18T01:23:53.675772Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.50:2379"]} {"level":"info","ts":"2026-09-18T01:23:53.675845Z","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.50:2380"],"listen-peer-urls":["https://162.253.55.50:2380"],"advertise-client-urls":["https://162.253.55.50:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.50:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.50: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-18T01:23:53.679782Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.764599ms"} {"level":"info","ts":"2026-09-18T01:23:53.686611Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a940341cf49588e3","cluster-id":"d4202939771fbaa5"} {"level":"info","ts":"2026-09-18T01:23:53.686792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a940341cf49588e3 switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T01:23:53.68686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a940341cf49588e3 became follower at term 0"} {"level":"info","ts":"2026-09-18T01:23:53.686887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a940341cf49588e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T01:23:53.686916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a940341cf49588e3 became follower at term 1"} {"level":"info","ts":"2026-09-18T01:23:53.687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a940341cf49588e3 switched to configuration voters=(12195805089886472419)"} {"level":"warn","ts":"2026-09-18T01:23:53.697807Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T01:23:53.70032Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T01:23:53.701849Z","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-18T01:23:53.703705Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a940341cf49588e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T01:23:53.704172Z","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-18T01:23:53.704361Z","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-18T01:23:53.704373Z","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-18T01:23:53.704064Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a940341cf49588e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T01:23:53.707728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a940341cf49588e3 switched to configuration voters=(12195805089886472419)"} {"level":"info","ts":"2026-09-18T01:23:53.707897Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d4202939771fbaa5","local-member-id":"a940341cf49588e3","added-peer-id":"a940341cf49588e3","added-peer-peer-urls":["https://162.253.55.50:2380"]} {"level":"info","ts":"2026-09-18T01:23:53.709572Z","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-18T01:23:53.709723Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.50:2380"} {"level":"info","ts":"2026-09-18T01:23:53.709748Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.50:2380"} {"level":"info","ts":"2026-09-18T01:23:53.711105Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a940341cf49588e3","initial-advertise-peer-urls":["https://162.253.55.50:2380"],"listen-peer-urls":["https://162.253.55.50:2380"],"advertise-client-urls":["https://162.253.55.50:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.50:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T01:23:53.711163Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T01:23:54.389509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a940341cf49588e3 is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T01:23:54.390751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a940341cf49588e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T01:23:54.390774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a940341cf49588e3 received MsgPreVoteResp from a940341cf49588e3 at term 1"} {"level":"info","ts":"2026-09-18T01:23:54.390792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a940341cf49588e3 became candidate at term 2"} {"level":"info","ts":"2026-09-18T01:23:54.390803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a940341cf49588e3 received MsgVoteResp from a940341cf49588e3 at term 2"} {"level":"info","ts":"2026-09-18T01:23:54.390818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a940341cf49588e3 became leader at term 2"} {"level":"info","ts":"2026-09-18T01:23:54.390832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a940341cf49588e3 elected leader a940341cf49588e3 at term 2"} {"level":"info","ts":"2026-09-18T01:23:54.392394Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T01:23:54.393607Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a940341cf49588e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.50:2379]}","request-path":"/0/members/a940341cf49588e3/attributes","cluster-id":"d4202939771fbaa5","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T01:23:54.393638Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T01:23:54.393677Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T01:23:54.393961Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T01:23:54.394022Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T01:23:54.394203Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d4202939771fbaa5","local-member-id":"a940341cf49588e3","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T01:23:54.39436Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T01:23:54.394396Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T01:23:54.397003Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.50:2379"} {"level":"info","ts":"2026-09-18T01:23:54.398855Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T01:24:00.033262Z","caller":"traceutil/trace.go:171","msg":"trace[957151741] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"115.758472ms","start":"2026-09-18T01:23:59.917448Z","end":"2026-09-18T01:24:00.033206Z","steps":["trace[957151741] 'process raft request' (duration: 112.229623ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:03.120162Z","caller":"traceutil/trace.go:171","msg":"trace[621898893] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"116.362941ms","start":"2026-09-18T01:24:03.003769Z","end":"2026-09-18T01:24:03.120132Z","steps":["trace[621898893] 'process raft request' (duration: 115.900231ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:24:03.496288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.891976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-09-18T01:24:03.49636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.723896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-18T01:24:03.496454Z","caller":"traceutil/trace.go:171","msg":"trace[165973349] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:296; }","duration":"243.198199ms","start":"2026-09-18T01:24:03.25323Z","end":"2026-09-18T01:24:03.496428Z","steps":["trace[165973349] 'range keys from in-memory index tree' (duration: 242.72824ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:03.496476Z","caller":"traceutil/trace.go:171","msg":"trace[1364154942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:296; }","duration":"259.929354ms","start":"2026-09-18T01:24:03.236516Z","end":"2026-09-18T01:24:03.496446Z","steps":["trace[1364154942] 'range keys from in-memory index tree' (duration: 259.546589ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:03.685318Z","caller":"traceutil/trace.go:171","msg":"trace[1758035514] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"182.981471ms","start":"2026-09-18T01:24:03.502309Z","end":"2026-09-18T01:24:03.685291Z","steps":["trace[1758035514] 'process raft request' (duration: 182.814234ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:24:04.111789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.299399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:24:04.111889Z","caller":"traceutil/trace.go:171","msg":"trace[1530057880] linearizableReadLoop","detail":"{readStateIndex:310; appliedIndex:309; }","duration":"422.783351ms","start":"2026-09-18T01:24:03.689091Z","end":"2026-09-18T01:24:04.111874Z","steps":["trace[1530057880] 'read index received' (duration: 183.677109ms)","trace[1530057880] 'applied index is now lower than readState.Index' (duration: 239.105102ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:24:04.112078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.966538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-09-18T01:24:04.112175Z","caller":"traceutil/trace.go:171","msg":"trace[1158326216] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:298; }","duration":"423.126045ms","start":"2026-09-18T01:24:03.689024Z","end":"2026-09-18T01:24:04.112151Z","steps":["trace[1158326216] 'agreement among raft nodes before linearized reading' (duration: 422.938837ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:24:04.112229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:24:03.689006Z","time spent":"423.214308ms","remote":"127.0.0.1:49770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-09-18T01:24:04.112113Z","caller":"traceutil/trace.go:171","msg":"trace[334690222] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"609.359889ms","start":"2026-09-18T01:24:03.502666Z","end":"2026-09-18T01:24:04.112026Z","steps":["trace[334690222] 'process raft request' (duration: 370.128922ms)","trace[334690222] 'compare' (duration: 238.170423ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:24:04.11267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:24:03.502656Z","time spent":"609.850938ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T01:24:38.641486Z","caller":"traceutil/trace.go:171","msg":"trace[426609674] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:503; }","duration":"128.142039ms","start":"2026-09-18T01:24:38.513314Z","end":"2026-09-18T01:24:38.641456Z","steps":["trace[426609674] 'read index received' (duration: 41.065912ms)","trace[426609674] 'applied index is now lower than readState.Index' (duration: 87.075497ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:24:38.641498Z","caller":"traceutil/trace.go:171","msg":"trace[159532651] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"132.164293ms","start":"2026-09-18T01:24:38.509277Z","end":"2026-09-18T01:24:38.641441Z","steps":["trace[159532651] 'process raft request' (duration: 45.301423ms)","trace[159532651] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/secrets/kube-system/sh.helm.release.v1.cilium.v1; req_size:317271; } (duration: 86.151329ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:24:38.641788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.458418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-09-18T01:24:38.641825Z","caller":"traceutil/trace.go:171","msg":"trace[1236764900] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:486; }","duration":"128.527571ms","start":"2026-09-18T01:24:38.513288Z","end":"2026-09-18T01:24:38.641816Z","steps":["trace[1236764900] 'agreement among raft nodes before linearized reading' (duration: 128.250182ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:38.642264Z","caller":"traceutil/trace.go:171","msg":"trace[1806960283] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"127.928093ms","start":"2026-09-18T01:24:38.514327Z","end":"2026-09-18T01:24:38.642255Z","steps":["trace[1806960283] 'process raft request' (duration: 127.098887ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:38.642423Z","caller":"traceutil/trace.go:171","msg":"trace[91271888] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"127.966375ms","start":"2026-09-18T01:24:38.514449Z","end":"2026-09-18T01:24:38.642416Z","steps":["trace[91271888] 'process raft request' (duration: 127.762139ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:24:38.642838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.756387ms","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-18T01:24:38.642871Z","caller":"traceutil/trace.go:171","msg":"trace[1115937567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"106.817259ms","start":"2026-09-18T01:24:38.536045Z","end":"2026-09-18T01:24:38.642862Z","steps":["trace[1115937567] 'agreement among raft nodes before linearized reading' (duration: 106.756977ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:38.642568Z","caller":"traceutil/trace.go:171","msg":"trace[1673527543] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"126.570531ms","start":"2026-09-18T01:24:38.51599Z","end":"2026-09-18T01:24:38.64256Z","steps":["trace[1673527543] 'process raft request' (duration: 126.331224ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:41.701773Z","caller":"traceutil/trace.go:171","msg":"trace[381113776] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"228.178675ms","start":"2026-09-18T01:24:41.473568Z","end":"2026-09-18T01:24:41.701747Z","steps":["trace[381113776] 'process raft request' (duration: 227.749141ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:41.709272Z","caller":"traceutil/trace.go:171","msg":"trace[1070355403] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"199.723572ms","start":"2026-09-18T01:24:41.509523Z","end":"2026-09-18T01:24:41.709247Z","steps":["trace[1070355403] 'process raft request' (duration: 199.620599ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:43.883995Z","caller":"traceutil/trace.go:171","msg":"trace[291032517] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"101.595852ms","start":"2026-09-18T01:24:43.782369Z","end":"2026-09-18T01:24:43.883964Z","steps":["trace[291032517] 'process raft request' (duration: 101.141579ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:47.117574Z","caller":"traceutil/trace.go:171","msg":"trace[1572230989] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"570.319767ms","start":"2026-09-18T01:24:46.547211Z","end":"2026-09-18T01:24:47.11753Z","steps":["trace[1572230989] 'process raft request' (duration: 498.018777ms)","trace[1572230989] 'compare' (duration: 71.772544ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:24:47.117862Z","caller":"traceutil/trace.go:171","msg":"trace[504226105] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"563.355961ms","start":"2026-09-18T01:24:46.554462Z","end":"2026-09-18T01:24:47.117818Z","steps":["trace[504226105] 'read index received' (duration: 490.757703ms)","trace[504226105] 'applied index is now lower than readState.Index' (duration: 72.591898ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:24:47.118208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:24:46.547192Z","time spent":"570.459951ms","remote":"127.0.0.1:49934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T01:24:47.118415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.559795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-09-18T01:24:47.118534Z","caller":"traceutil/trace.go:171","msg":"trace[2046636014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:608; }","duration":"519.764401ms","start":"2026-09-18T01:24:46.598743Z","end":"2026-09-18T01:24:47.118507Z","steps":["trace[2046636014] 'agreement among raft nodes before linearized reading' (duration: 519.426301ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:24:47.118578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:24:46.598713Z","time spent":"519.857364ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-18T01:24:47.118681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.196866ms","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-18T01:24:47.118694Z","caller":"traceutil/trace.go:171","msg":"trace[232217876] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"395.114512ms","start":"2026-09-18T01:24:46.723553Z","end":"2026-09-18T01:24:47.118668Z","steps":["trace[232217876] 'process raft request' (duration: 393.886456ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:24:47.11884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:24:46.72353Z","time spent":"395.219645ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T01:24:47.119028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.328929ms","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-18T01:24:47.119088Z","caller":"traceutil/trace.go:171","msg":"trace[1721682758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"361.416371ms","start":"2026-09-18T01:24:46.757654Z","end":"2026-09-18T01:24:47.119071Z","steps":["trace[1721682758] 'agreement among raft nodes before linearized reading' (duration: 361.262667ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:24:47.119126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:24:46.757644Z","time spent":"361.474843ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-18T01:24:47.119063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.256189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lbjbd\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-18T01:24:47.11927Z","caller":"traceutil/trace.go:171","msg":"trace[1290837245] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lbjbd; range_end:; response_count:1; response_revision:608; }","duration":"310.487546ms","start":"2026-09-18T01:24:46.808758Z","end":"2026-09-18T01:24:47.119246Z","steps":["trace[1290837245] 'agreement among raft nodes before linearized reading' (duration: 310.112985ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:24:47.119288Z","caller":"traceutil/trace.go:171","msg":"trace[2059149696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"564.32849ms","start":"2026-09-18T01:24:46.554405Z","end":"2026-09-18T01:24:47.118733Z","steps":["trace[2059149696] 'agreement among raft nodes before linearized reading' (duration: 563.737523ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:24:47.119375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:24:46.808742Z","time spent":"310.586029ms","remote":"127.0.0.1:49754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-lbjbd\" "} {"level":"warn","ts":"2026-09-18T01:24:47.119392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:24:46.554385Z","time spent":"564.988699ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-18T01:25:02.70863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.882542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-18T01:25:02.708774Z","caller":"traceutil/trace.go:171","msg":"trace[1007039519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:774; }","duration":"168.120417ms","start":"2026-09-18T01:25:02.540626Z","end":"2026-09-18T01:25:02.708746Z","steps":["trace[1007039519] 'range keys from in-memory index tree' (duration: 167.585244ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:25:02.887237Z","caller":"traceutil/trace.go:171","msg":"trace[745953744] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"173.050799ms","start":"2026-09-18T01:25:02.714046Z","end":"2026-09-18T01:25:02.887097Z","steps":["trace[745953744] 'process raft request' (duration: 172.833663ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:22.848661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.122875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:26:22.848795Z","caller":"traceutil/trace.go:171","msg":"trace[1939147316] linearizableReadLoop","detail":"{readStateIndex:1927; appliedIndex:1926; }","duration":"321.258626ms","start":"2026-09-18T01:26:22.527514Z","end":"2026-09-18T01:26:22.848772Z","steps":["trace[1939147316] 'read index received' (duration: 1.774775ms)","trace[1939147316] 'applied index is now lower than readState.Index' (duration: 319.482031ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:26:22.848959Z","caller":"traceutil/trace.go:171","msg":"trace[1349157240] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"398.616635ms","start":"2026-09-18T01:26:22.450324Z","end":"2026-09-18T01:26:22.848941Z","steps":["trace[1349157240] 'process raft request' (duration: 79.111973ms)","trace[1349157240] 'compare' (duration: 318.994892ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:26:22.849052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:26:22.450288Z","time spent":"398.718537ms","remote":"127.0.0.1:49754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10969,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T01:26:22.84911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.576884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T01:26:22.849223Z","caller":"traceutil/trace.go:171","msg":"trace[875005253] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1852; }","duration":"321.742447ms","start":"2026-09-18T01:26:22.527461Z","end":"2026-09-18T01:26:22.849203Z","steps":["trace[875005253] 'agreement among raft nodes before linearized reading' (duration: 321.525382ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:22.849372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:26:22.527442Z","time spent":"321.87069ms","remote":"127.0.0.1:49746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-09-18T01:26:23.052706Z","caller":"traceutil/trace.go:171","msg":"trace[603067073] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1931; }","duration":"111.05754ms","start":"2026-09-18T01:26:22.941604Z","end":"2026-09-18T01:26:23.052662Z","steps":["trace[603067073] 'read index received' (duration: 21.031801ms)","trace[603067073] 'applied index is now lower than readState.Index' (duration: 90.023889ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:26:23.052994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.370946ms","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-09-18T01:26:23.053081Z","caller":"traceutil/trace.go:171","msg":"trace[975777468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1856; }","duration":"111.539159ms","start":"2026-09-18T01:26:22.941523Z","end":"2026-09-18T01:26:23.053062Z","steps":["trace[975777468] 'agreement among raft nodes before linearized reading' (duration: 111.256243ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:26:23.319301Z","caller":"traceutil/trace.go:171","msg":"trace[1629642682] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1932; }","duration":"221.145746ms","start":"2026-09-18T01:26:23.098122Z","end":"2026-09-18T01:26:23.319268Z","steps":["trace[1629642682] 'read index received' (duration: 220.777798ms)","trace[1629642682] 'applied index is now lower than readState.Index' (duration: 366.107µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:26:23.319559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.418821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:26:23.319672Z","caller":"traceutil/trace.go:171","msg":"trace[2039690809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1857; }","duration":"221.591505ms","start":"2026-09-18T01:26:23.098062Z","end":"2026-09-18T01:26:23.319653Z","steps":["trace[2039690809] 'agreement among raft nodes before linearized reading' (duration: 221.420392ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:26:23.319429Z","caller":"traceutil/trace.go:171","msg":"trace[820296650] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"261.599452ms","start":"2026-09-18T01:26:23.057783Z","end":"2026-09-18T01:26:23.319382Z","steps":["trace[820296650] 'process raft request' (duration: 261.251425ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:23.32057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.760322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f88d36ac-d4ea-4580-8ebc-6345380d2071\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-09-18T01:26:23.320703Z","caller":"traceutil/trace.go:171","msg":"trace[873308722] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f88d36ac-d4ea-4580-8ebc-6345380d2071; range_end:; response_count:1; response_revision:1857; }","duration":"110.945434ms","start":"2026-09-18T01:26:23.209725Z","end":"2026-09-18T01:26:23.32067Z","steps":["trace[873308722] 'agreement among raft nodes before linearized reading' (duration: 109.782342ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:26:56.728521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.52456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:26:56.728933Z","caller":"traceutil/trace.go:171","msg":"trace[265327094] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"157.363925ms","start":"2026-09-18T01:26:56.571509Z","end":"2026-09-18T01:26:56.728873Z","steps":["trace[265327094] 'process raft request' (duration: 19.405697ms)","trace[265327094] 'compare' (duration: 137.421018ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:27:02.632985Z","caller":"traceutil/trace.go:171","msg":"trace[1638685291] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"125.822052ms","start":"2026-09-18T01:27:02.507138Z","end":"2026-09-18T01:27:02.632961Z","steps":["trace[1638685291] 'process raft request' (duration: 38.743587ms)","trace[1638685291] 'compare' (duration: 86.702948ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:27:03.249903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.940613ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-18T01:27:03.250174Z","caller":"traceutil/trace.go:171","msg":"trace[1656043104] linearizableReadLoop","detail":"{readStateIndex:2206; appliedIndex:2205; }","duration":"151.48189ms","start":"2026-09-18T01:27:03.098667Z","end":"2026-09-18T01:27:03.250149Z","steps":["trace[1656043104] 'read index received' (duration: 2.196391ms)","trace[1656043104] 'applied index is now lower than readState.Index' (duration: 149.283509ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:27:03.250445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.735464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:27:03.250578Z","caller":"traceutil/trace.go:171","msg":"trace[602087655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2122; }","duration":"151.928507ms","start":"2026-09-18T01:27:03.098621Z","end":"2026-09-18T01:27:03.250549Z","steps":["trace[602087655] 'agreement among raft nodes before linearized reading' (duration: 151.669232ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:27:03.250968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.878509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:27:03.251098Z","caller":"traceutil/trace.go:171","msg":"trace[92271539] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2122; }","duration":"102.009381ms","start":"2026-09-18T01:27:03.149038Z","end":"2026-09-18T01:27:03.251047Z","steps":["trace[92271539] 'agreement among raft nodes before linearized reading' (duration: 101.615744ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:27:03.251631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.043559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-18T01:27:03.251716Z","caller":"traceutil/trace.go:171","msg":"trace[1208401252] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2122; }","duration":"113.154062ms","start":"2026-09-18T01:27:03.138548Z","end":"2026-09-18T01:27:03.251702Z","steps":["trace[1208401252] 'agreement among raft nodes before linearized reading' (duration: 111.917829ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:27:03.444525Z","caller":"traceutil/trace.go:171","msg":"trace[2106834770] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"138.017288ms","start":"2026-09-18T01:27:03.306436Z","end":"2026-09-18T01:27:03.444454Z","steps":["trace[2106834770] 'process raft request' (duration: 137.835164ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:27:03.657369Z","caller":"traceutil/trace.go:171","msg":"trace[2120737972] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"113.832215ms","start":"2026-09-18T01:27:03.543495Z","end":"2026-09-18T01:27:03.657327Z","steps":["trace[2120737972] 'process raft request' (duration: 113.535118ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:27:44.54315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.436497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-09-18T01:27:44.54326Z","caller":"traceutil/trace.go:171","msg":"trace[468373942] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2398; }","duration":"105.60522ms","start":"2026-09-18T01:27:44.437632Z","end":"2026-09-18T01:27:44.543238Z","steps":["trace[468373942] 'range keys from in-memory index tree' (duration: 105.269494ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:27:55.951132Z","caller":"traceutil/trace.go:171","msg":"trace[334365330] transaction","detail":"{read_only:false; response_revision:2512; number_of_response:1; }","duration":"137.749677ms","start":"2026-09-18T01:27:55.813342Z","end":"2026-09-18T01:27:55.951092Z","steps":["trace[334365330] 'process raft request' (duration: 137.41914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:28:31.24275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.675525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:28:31.242974Z","caller":"traceutil/trace.go:171","msg":"trace[1033604515] transaction","detail":"{read_only:false; response_revision:2708; number_of_response:1; }","duration":"159.566441ms","start":"2026-09-18T01:28:31.08339Z","end":"2026-09-18T01:28:31.242956Z","steps":["trace[1033604515] 'process raft request' (duration: 159.487117ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:28:31.243349Z","caller":"traceutil/trace.go:171","msg":"trace[773792318] transaction","detail":"{read_only:false; response_revision:2707; number_of_response:1; }","duration":"251.341741ms","start":"2026-09-18T01:28:30.991941Z","end":"2026-09-18T01:28:31.243283Z","steps":["trace[773792318] 'process raft request' (duration: 101.021073ms)","trace[773792318] 'compare' (duration: 149.326188ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:28:31.512362Z","caller":"traceutil/trace.go:171","msg":"trace[357943705] transaction","detail":"{read_only:false; response_revision:2709; number_of_response:1; }","duration":"222.896538ms","start":"2026-09-18T01:28:31.289436Z","end":"2026-09-18T01:28:31.512332Z","steps":["trace[357943705] 'process raft request' (duration: 127.03045ms)","trace[357943705] 'compare' (duration: 95.675209ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:32:55.16962Z","caller":"traceutil/trace.go:171","msg":"trace[504595602] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"128.567391ms","start":"2026-09-18T01:32:55.041029Z","end":"2026-09-18T01:32:55.169597Z","steps":["trace[504595602] 'process raft request' (duration: 128.417197ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:32:55.178765Z","caller":"traceutil/trace.go:171","msg":"trace[954121913] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"130.008348ms","start":"2026-09-18T01:32:55.048734Z","end":"2026-09-18T01:32:55.178742Z","steps":["trace[954121913] 'process raft request' (duration: 129.841594ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:32:55.303748Z","caller":"traceutil/trace.go:171","msg":"trace[1965293797] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"119.777471ms","start":"2026-09-18T01:32:55.183907Z","end":"2026-09-18T01:32:55.303685Z","steps":["trace[1965293797] 'process raft request' (duration: 107.882234ms)","trace[1965293797] 'compare' (duration: 11.6128ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:33:03.152832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.233129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T01:33:03.152943Z","caller":"traceutil/trace.go:171","msg":"trace[1011335251] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:4869; }","duration":"365.407654ms","start":"2026-09-18T01:33:02.78751Z","end":"2026-09-18T01:33:03.152918Z","steps":["trace[1011335251] 'count revisions from in-memory index tree' (duration: 365.165218ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:33:03.152992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:33:02.787495Z","time spent":"365.483177ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-09-18T01:33:03.189712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.651973ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-18T01:33:03.189847Z","caller":"traceutil/trace.go:171","msg":"trace[504938842] linearizableReadLoop","detail":"{readStateIndex:5057; appliedIndex:5056; }","duration":"186.233171ms","start":"2026-09-18T01:33:03.003591Z","end":"2026-09-18T01:33:03.189824Z","steps":["trace[504938842] 'read index received' (duration: 46.651µs)","trace[504938842] 'applied index is now lower than readState.Index' (duration: 186.1845ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:33:03.189954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.356925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T01:33:03.19Z","caller":"traceutil/trace.go:171","msg":"trace[1848650518] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:4869; }","duration":"186.455817ms","start":"2026-09-18T01:33:03.003526Z","end":"2026-09-18T01:33:03.189982Z","steps":["trace[1848650518] 'agreement among raft nodes before linearized reading' (duration: 186.362725ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:33:03.430237Z","caller":"traceutil/trace.go:171","msg":"trace[930735242] transaction","detail":"{read_only:false; response_revision:4873; number_of_response:1; }","duration":"110.069336ms","start":"2026-09-18T01:33:03.320141Z","end":"2026-09-18T01:33:03.430211Z","steps":["trace[930735242] 'process raft request' (duration: 109.897621ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:33:12.460196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.256761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T01:33:12.460225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.168236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-83b2d897-5ea5-49bb-9116-4edecafbb621\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-09-18T01:33:12.46032Z","caller":"traceutil/trace.go:171","msg":"trace[1277828067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5042; }","duration":"217.413745ms","start":"2026-09-18T01:33:12.242885Z","end":"2026-09-18T01:33:12.460299Z","steps":["trace[1277828067] 'range keys from in-memory index tree' (duration: 217.194079ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:33:12.460343Z","caller":"traceutil/trace.go:171","msg":"trace[1232144049] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-83b2d897-5ea5-49bb-9116-4edecafbb621; range_end:; response_count:1; response_revision:5042; }","duration":"256.33394ms","start":"2026-09-18T01:33:12.20398Z","end":"2026-09-18T01:33:12.460314Z","steps":["trace[1232144049] 'range keys from in-memory index tree' (duration: 255.9528ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:33:12.460514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.010597ms","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-18T01:33:12.460613Z","caller":"traceutil/trace.go:171","msg":"trace[1130497981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5042; }","duration":"427.192092ms","start":"2026-09-18T01:33:12.033394Z","end":"2026-09-18T01:33:12.460586Z","steps":["trace[1130497981] 'range keys from in-memory index tree' (duration: 426.775662ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:33:12.460658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:33:12.033377Z","time spent":"427.269334ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-18T01:33:12.46477Z","caller":"traceutil/trace.go:171","msg":"trace[1765283580] transaction","detail":"{read_only:false; response_revision:5043; number_of_response:1; }","duration":"186.323302ms","start":"2026-09-18T01:33:12.278428Z","end":"2026-09-18T01:33:12.464751Z","steps":["trace[1765283580] 'process raft request' (duration: 186.158508ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:33:54.432071Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2829} {"level":"info","ts":"2026-09-18T01:33:54.508508Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2829,"took":"75.060566ms","hash":2117566696,"current-db-size-bytes":52998144,"current-db-size":"53 MB","current-db-size-in-use-bytes":28356608,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-18T01:33:54.508574Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2117566696,"revision":2829,"compact-revision":-1} {"level":"warn","ts":"2026-09-18T01:37:13.387308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.912661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:37:13.387476Z","caller":"traceutil/trace.go:171","msg":"trace[353945922] linearizableReadLoop","detail":"{readStateIndex:7036; appliedIndex:7035; }","duration":"145.252089ms","start":"2026-09-18T01:37:13.242199Z","end":"2026-09-18T01:37:13.387451Z","steps":["trace[353945922] 'read index received' (duration: 54.522µs)","trace[353945922] 'applied index is now lower than readState.Index' (duration: 145.196297ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:37:13.387606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.407615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T01:37:13.387647Z","caller":"traceutil/trace.go:171","msg":"trace[876575914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6788; }","duration":"145.485978ms","start":"2026-09-18T01:37:13.242147Z","end":"2026-09-18T01:37:13.387633Z","steps":["trace[876575914] 'agreement among raft nodes before linearized reading' (duration: 145.367763ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:37:13.387617Z","caller":"traceutil/trace.go:171","msg":"trace[593589234] transaction","detail":"{read_only:false; response_revision:6788; number_of_response:1; }","duration":"198.511243ms","start":"2026-09-18T01:37:13.189067Z","end":"2026-09-18T01:37:13.387578Z","steps":["trace[593589234] 'process raft request' (duration: 48.23766ms)","trace[593589234] 'compare' (duration: 149.804177ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:37:13.853218Z","caller":"traceutil/trace.go:171","msg":"trace[1046951705] transaction","detail":"{read_only:false; response_revision:6789; number_of_response:1; }","duration":"357.539561ms","start":"2026-09-18T01:37:13.495647Z","end":"2026-09-18T01:37:13.853187Z","steps":["trace[1046951705] 'process raft request' (duration: 326.534487ms)","trace[1046951705] 'compare' (duration: 30.877879ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:37:13.853363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:37:13.495625Z","time spent":"357.662474ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T01:37:13.931209Z","caller":"traceutil/trace.go:171","msg":"trace[561581794] linearizableReadLoop","detail":"{readStateIndex:7038; appliedIndex:7036; }","duration":"153.741084ms","start":"2026-09-18T01:37:13.777438Z","end":"2026-09-18T01:37:13.931179Z","steps":["trace[561581794] 'read index received' (duration: 44.730999ms)","trace[561581794] 'applied index is now lower than readState.Index' (duration: 109.008835ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:37:13.931369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.9133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T01:37:13.931415Z","caller":"traceutil/trace.go:171","msg":"trace[1987545787] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:6790; }","duration":"154.024883ms","start":"2026-09-18T01:37:13.777376Z","end":"2026-09-18T01:37:13.9314Z","steps":["trace[1987545787] 'agreement among raft nodes before linearized reading' (duration: 153.910439ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:37:13.931856Z","caller":"traceutil/trace.go:171","msg":"trace[852452688] transaction","detail":"{read_only:false; response_revision:6790; number_of_response:1; }","duration":"378.32707ms","start":"2026-09-18T01:37:13.553511Z","end":"2026-09-18T01:37:13.931838Z","steps":["trace[852452688] 'process raft request' (duration: 377.514572ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T01:37:13.932004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T01:37:13.553495Z","time spent":"378.410663ms","remote":"127.0.0.1:49840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T01:37:25.551444Z","caller":"traceutil/trace.go:171","msg":"trace[281395594] transaction","detail":"{read_only:false; response_revision:6900; number_of_response:1; }","duration":"104.632287ms","start":"2026-09-18T01:37:25.446791Z","end":"2026-09-18T01:37:25.551423Z","steps":["trace[281395594] 'process raft request' (duration: 63.404582ms)","trace[281395594] 'compare' (duration: 40.838363ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:37:32.258728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.21771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T01:37:32.258815Z","caller":"traceutil/trace.go:171","msg":"trace[306557641] linearizableReadLoop","detail":"{readStateIndex:7190; appliedIndex:7189; }","duration":"181.805114ms","start":"2026-09-18T01:37:32.076997Z","end":"2026-09-18T01:37:32.258802Z","steps":["trace[306557641] 'read index received' (duration: 25.384038ms)","trace[306557641] 'applied index is now lower than readState.Index' (duration: 156.420226ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T01:37:32.258934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.933279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:12169"} {"level":"info","ts":"2026-09-18T01:37:32.258951Z","caller":"traceutil/trace.go:171","msg":"trace[1131626364] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:6936; }","duration":"181.974681ms","start":"2026-09-18T01:37:32.07697Z","end":"2026-09-18T01:37:32.258945Z","steps":["trace[1131626364] 'agreement among raft nodes before linearized reading' (duration: 181.863206ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T01:37:32.259221Z","caller":"traceutil/trace.go:171","msg":"trace[355092828] transaction","detail":"{read_only:false; response_revision:6936; number_of_response:1; }","duration":"186.738353ms","start":"2026-09-18T01:37:32.072455Z","end":"2026-09-18T01:37:32.259194Z","steps":["trace[355092828] 'process raft request' (duration: 29.973484ms)","trace[355092828] 'compare' (duration: 156.098836ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T01:38:54.437412Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5427} {"level":"info","ts":"2026-09-18T01:38:54.548445Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5427,"took":"109.726426ms","hash":2619052563,"current-db-size-bytes":52998144,"current-db-size":"53 MB","current-db-size-in-use-bytes":18726912,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-09-18T01:38:54.548512Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2619052563,"revision":5427,"compact-revision":2829}