{"level":"warn","ts":"2026-09-04T03:15:15.429072Z","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-04T03:15:15.429168Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.57: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.57:2380","--initial-cluster=instance=https://162.253.55.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.57: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-04T03:15:15.429227Z","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-04T03:15:15.429241Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.57:2380"]} {"level":"info","ts":"2026-09-04T03:15:15.429257Z","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-04T03:15:15.429882Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.57:2379"]} {"level":"info","ts":"2026-09-04T03:15:15.429953Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.57:2380"],"listen-peer-urls":["https://162.253.55.57:2380"],"advertise-client-urls":["https://162.253.55.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.57: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-04T03:15:15.43487Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.708529ms"} {"level":"info","ts":"2026-09-04T03:15:15.442132Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"db28a37eabf8270","cluster-id":"33328f64ab44b15e"} {"level":"info","ts":"2026-09-04T03:15:15.442214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 switched to configuration voters=()"} {"level":"info","ts":"2026-09-04T03:15:15.442234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became follower at term 0"} {"level":"info","ts":"2026-09-04T03:15:15.442245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft db28a37eabf8270 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-04T03:15:15.442259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became follower at term 1"} {"level":"info","ts":"2026-09-04T03:15:15.442292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 switched to configuration voters=(987003241113813616)"} {"level":"warn","ts":"2026-09-04T03:15:15.449181Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-04T03:15:15.451836Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-04T03:15:15.453805Z","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-04T03:15:15.457149Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"db28a37eabf8270","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-04T03:15:15.458653Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"db28a37eabf8270","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-04T03:15:15.458888Z","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-04T03:15:15.45913Z","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-04T03:15:15.459203Z","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-04T03:15:15.459898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 switched to configuration voters=(987003241113813616)"} {"level":"info","ts":"2026-09-04T03:15:15.460059Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"33328f64ab44b15e","local-member-id":"db28a37eabf8270","added-peer-id":"db28a37eabf8270","added-peer-peer-urls":["https://162.253.55.57:2380"]} {"level":"info","ts":"2026-09-04T03:15:15.461283Z","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-04T03:15:15.461443Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.57:2380"} {"level":"info","ts":"2026-09-04T03:15:15.461494Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.57:2380"} {"level":"info","ts":"2026-09-04T03:15:15.461627Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"db28a37eabf8270","initial-advertise-peer-urls":["https://162.253.55.57:2380"],"listen-peer-urls":["https://162.253.55.57:2380"],"advertise-client-urls":["https://162.253.55.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-04T03:15:15.461689Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-04T03:15:16.243431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 is starting a new election at term 1"} {"level":"info","ts":"2026-09-04T03:15:16.24349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-04T03:15:16.243524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 received MsgPreVoteResp from db28a37eabf8270 at term 1"} {"level":"info","ts":"2026-09-04T03:15:16.243541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became candidate at term 2"} {"level":"info","ts":"2026-09-04T03:15:16.243552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 received MsgVoteResp from db28a37eabf8270 at term 2"} {"level":"info","ts":"2026-09-04T03:15:16.243566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"db28a37eabf8270 became leader at term 2"} {"level":"info","ts":"2026-09-04T03:15:16.243579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: db28a37eabf8270 elected leader db28a37eabf8270 at term 2"} {"level":"info","ts":"2026-09-04T03:15:16.255716Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T03:15:16.256902Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"db28a37eabf8270","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.57:2379]}","request-path":"/0/members/db28a37eabf8270/attributes","cluster-id":"33328f64ab44b15e","publish-timeout":"7s"} {"level":"info","ts":"2026-09-04T03:15:16.256951Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-04T03:15:16.257008Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-04T03:15:16.257363Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-04T03:15:16.257433Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-04T03:15:16.258068Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"33328f64ab44b15e","local-member-id":"db28a37eabf8270","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T03:15:16.258195Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T03:15:16.258233Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-04T03:15:16.260193Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-04T03:15:16.260648Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.57:2379"} {"level":"warn","ts":"2026-09-04T03:15:39.726514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.854336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4378"} {"level":"info","ts":"2026-09-04T03:15:39.726736Z","caller":"traceutil/trace.go:171","msg":"trace[115653752] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:388; }","duration":"251.115524ms","start":"2026-09-04T03:15:39.475601Z","end":"2026-09-04T03:15:39.726716Z","steps":["trace[115653752] 'range keys from in-memory index tree' (duration: 250.786085ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:15:39.726514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.787428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-04T03:15:39.726884Z","caller":"traceutil/trace.go:171","msg":"trace[420264322] range","detail":"{range_begin:/registry/masterleases/162.253.55.57; range_end:; response_count:1; response_revision:388; }","duration":"104.20849ms","start":"2026-09-04T03:15:39.622654Z","end":"2026-09-04T03:15:39.726863Z","steps":["trace[420264322] 'range keys from in-memory index tree' (duration: 103.647545ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:16:04.968395Z","caller":"traceutil/trace.go:171","msg":"trace[1210630641] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"221.350197ms","start":"2026-09-04T03:16:04.747016Z","end":"2026-09-04T03:16:04.968366Z","steps":["trace[1210630641] 'read index received' (duration: 220.630887ms)","trace[1210630641] 'applied index is now lower than readState.Index' (duration: 717.8µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:16:04.968509Z","caller":"traceutil/trace.go:171","msg":"trace[1445032392] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"332.624397ms","start":"2026-09-04T03:16:04.635852Z","end":"2026-09-04T03:16:04.968476Z","steps":["trace[1445032392] 'process raft request' (duration: 331.98ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:16:04.968755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.725917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kxszj\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-09-04T03:16:04.968846Z","caller":"traceutil/trace.go:171","msg":"trace[1503736626] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kxszj; range_end:; response_count:1; response_revision:599; }","duration":"221.869642ms","start":"2026-09-04T03:16:04.746954Z","end":"2026-09-04T03:16:04.968824Z","steps":["trace[1503736626] 'agreement among raft nodes before linearized reading' (duration: 221.575663ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:16:04.969817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:16:04.635824Z","time spent":"332.807032ms","remote":"127.0.0.1:38586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T03:16:05.600686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.362808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T03:16:05.600951Z","caller":"traceutil/trace.go:171","msg":"trace[913073775] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"599.642708ms","start":"2026-09-04T03:16:05.001277Z","end":"2026-09-04T03:16:05.60092Z","steps":["trace[913073775] 'process raft request' (duration: 401.698303ms)","trace[913073775] 'compare' (duration: 197.238295ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:16:05.601111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:16:05.001247Z","time spent":"599.781722ms","remote":"127.0.0.1:38780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T03:16:05.613295Z","caller":"traceutil/trace.go:171","msg":"trace[1334581843] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"559.584875ms","start":"2026-09-04T03:16:05.053693Z","end":"2026-09-04T03:16:05.613278Z","steps":["trace[1334581843] 'process raft request' (duration: 559.41191ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:16:05.613375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:16:05.053672Z","time spent":"559.671026ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-04T03:16:05.613245Z","caller":"traceutil/trace.go:171","msg":"trace[1656349664] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:625; }","duration":"101.085932ms","start":"2026-09-04T03:16:05.512128Z","end":"2026-09-04T03:16:05.613214Z","steps":["trace[1656349664] 'read index received' (duration: 62.269493ms)","trace[1656349664] 'applied index is now lower than readState.Index' (duration: 38.815179ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:16:05.613577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.433441ms","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-04T03:16:05.613647Z","caller":"traceutil/trace.go:171","msg":"trace[1721900596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"101.555684ms","start":"2026-09-04T03:16:05.512075Z","end":"2026-09-04T03:16:05.613631Z","steps":["trace[1721900596] 'agreement among raft nodes before linearized reading' (duration: 101.249066ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:16:07.104673Z","caller":"traceutil/trace.go:171","msg":"trace[275834185] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"112.533936ms","start":"2026-09-04T03:16:06.992112Z","end":"2026-09-04T03:16:07.104646Z","steps":["trace[275834185] 'read index received' (duration: 112.238097ms)","trace[275834185] 'applied index is now lower than readState.Index' (duration: 294.799µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:16:07.104765Z","caller":"traceutil/trace.go:171","msg":"trace[1288743759] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"117.381033ms","start":"2026-09-04T03:16:06.987354Z","end":"2026-09-04T03:16:07.104735Z","steps":["trace[1288743759] 'process raft request' (duration: 117.109956ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:16:07.104888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.762993ms","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-04T03:16:07.104973Z","caller":"traceutil/trace.go:171","msg":"trace[1653063284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:637; }","duration":"112.870655ms","start":"2026-09-04T03:16:06.992081Z","end":"2026-09-04T03:16:07.104951Z","steps":["trace[1653063284] 'agreement among raft nodes before linearized reading' (duration: 112.68949ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:16:21.918901Z","caller":"traceutil/trace.go:171","msg":"trace[736303901] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"215.762399ms","start":"2026-09-04T03:16:21.703113Z","end":"2026-09-04T03:16:21.918876Z","steps":["trace[736303901] 'process raft request' (duration: 215.635996ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:16:21.996474Z","caller":"traceutil/trace.go:171","msg":"trace[810749424] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:799; }","duration":"273.069982ms","start":"2026-09-04T03:16:21.723361Z","end":"2026-09-04T03:16:21.996431Z","steps":["trace[810749424] 'read index received' (duration: 195.32206ms)","trace[810749424] 'applied index is now lower than readState.Index' (duration: 77.746792ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:16:21.99661Z","caller":"traceutil/trace.go:171","msg":"trace[1038135524] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"291.625966ms","start":"2026-09-04T03:16:21.704932Z","end":"2026-09-04T03:16:21.996558Z","steps":["trace[1038135524] 'process raft request' (duration: 270.111737ms)","trace[1038135524] 'compare' (duration: 21.281833ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:16:21.996679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.263727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:16:21.996767Z","caller":"traceutil/trace.go:171","msg":"trace[171582864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:771; }","duration":"273.35432ms","start":"2026-09-04T03:16:21.723356Z","end":"2026-09-04T03:16:21.99671Z","steps":["trace[171582864] 'agreement among raft nodes before linearized reading' (duration: 273.217726ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:16:21.996808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.967423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wvdph\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-09-04T03:16:21.996908Z","caller":"traceutil/trace.go:171","msg":"trace[1706386482] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-wvdph; range_end:; response_count:1; response_revision:771; }","duration":"140.128277ms","start":"2026-09-04T03:16:21.856753Z","end":"2026-09-04T03:16:21.996882Z","steps":["trace[1706386482] 'agreement among raft nodes before linearized reading' (duration: 139.886261ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:16:52.955035Z","caller":"traceutil/trace.go:171","msg":"trace[1922723507] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"280.508283ms","start":"2026-09-04T03:16:52.6745Z","end":"2026-09-04T03:16:52.955009Z","steps":["trace[1922723507] 'process raft request' (duration: 280.319778ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:17:15.380986Z","caller":"traceutil/trace.go:171","msg":"trace[1893589193] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"137.536625ms","start":"2026-09-04T03:17:15.243427Z","end":"2026-09-04T03:17:15.380964Z","steps":["trace[1893589193] 'process raft request' (duration: 110.844999ms)","trace[1893589193] 'compare' (duration: 26.494321ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:17:15.381124Z","caller":"traceutil/trace.go:171","msg":"trace[583523679] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"102.237985ms","start":"2026-09-04T03:17:15.278854Z","end":"2026-09-04T03:17:15.381092Z","steps":["trace[583523679] 'process raft request' (duration: 102.05425ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:17:41.652667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.531265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T03:17:41.652805Z","caller":"traceutil/trace.go:171","msg":"trace[920128670] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1927; }","duration":"344.689049ms","start":"2026-09-04T03:17:41.308095Z","end":"2026-09-04T03:17:41.652784Z","steps":["trace[920128670] 'read index received' (duration: 41.718011ms)","trace[920128670] 'applied index is now lower than readState.Index' (duration: 302.967458ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:17:41.652868Z","caller":"traceutil/trace.go:171","msg":"trace[1618110048] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"398.454401ms","start":"2026-09-04T03:17:41.254365Z","end":"2026-09-04T03:17:41.652819Z","steps":["trace[1618110048] 'process raft request' (duration: 95.535315ms)","trace[1618110048] 'compare' (duration: 302.039322ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:17:41.653011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.894015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ea605d5-b1b1-4c65-9275-c88dd5f83f5a\" ","response":"range_response_count:1 size:3626"} {"level":"warn","ts":"2026-09-04T03:17:41.653047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:17:41.254333Z","time spent":"398.623706ms","remote":"127.0.0.1:38780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T03:17:41.653098Z","caller":"traceutil/trace.go:171","msg":"trace[1049351193] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9ea605d5-b1b1-4c65-9275-c88dd5f83f5a; range_end:; response_count:1; response_revision:1853; }","duration":"345.04369ms","start":"2026-09-04T03:17:41.308035Z","end":"2026-09-04T03:17:41.653079Z","steps":["trace[1049351193] 'agreement among raft nodes before linearized reading' (duration: 344.825853ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:17:41.653137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:17:41.308012Z","time spent":"345.115663ms","remote":"127.0.0.1:38688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ea605d5-b1b1-4c65-9275-c88dd5f83f5a\" "} {"level":"info","ts":"2026-09-04T03:17:41.658348Z","caller":"traceutil/trace.go:171","msg":"trace[875669856] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"213.380491ms","start":"2026-09-04T03:17:41.444947Z","end":"2026-09-04T03:17:41.658328Z","steps":["trace[875669856] 'process raft request' (duration: 213.302789ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:17:41.742066Z","caller":"traceutil/trace.go:171","msg":"trace[213624298] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"263.503991ms","start":"2026-09-04T03:17:41.478543Z","end":"2026-09-04T03:17:41.742047Z","steps":["trace[213624298] 'process raft request' (duration: 263.341206ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:17:41.742331Z","caller":"traceutil/trace.go:171","msg":"trace[1056867396] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"155.235605ms","start":"2026-09-04T03:17:41.58706Z","end":"2026-09-04T03:17:41.742296Z","steps":["trace[1056867396] 'process raft request' (duration: 154.947808ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:17:41.742671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.366725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ea605d5-b1b1-4c65-9275-c88dd5f83f5a\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-04T03:17:41.742728Z","caller":"traceutil/trace.go:171","msg":"trace[1254720375] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9ea605d5-b1b1-4c65-9275-c88dd5f83f5a; range_end:; response_count:1; response_revision:1857; }","duration":"388.47672ms","start":"2026-09-04T03:17:41.354238Z","end":"2026-09-04T03:17:41.742715Z","steps":["trace[1254720375] 'agreement among raft nodes before linearized reading' (duration: 388.311945ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:17:41.742735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.565654ms","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":"warn","ts":"2026-09-04T03:17:41.742759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:17:41.354215Z","time spent":"388.536451ms","remote":"127.0.0.1:38688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3648,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9ea605d5-b1b1-4c65-9275-c88dd5f83f5a\" "} {"level":"info","ts":"2026-09-04T03:17:41.742782Z","caller":"traceutil/trace.go:171","msg":"trace[879430155] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1857; }","duration":"227.657307ms","start":"2026-09-04T03:17:41.515111Z","end":"2026-09-04T03:17:41.742768Z","steps":["trace[879430155] 'agreement among raft nodes before linearized reading' (duration: 227.437079ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:17:41.889867Z","caller":"traceutil/trace.go:171","msg":"trace[1914062425] linearizableReadLoop","detail":"{readStateIndex:1933; appliedIndex:1932; }","duration":"147.302741ms","start":"2026-09-04T03:17:41.742524Z","end":"2026-09-04T03:17:41.889827Z","steps":["trace[1914062425] 'read index received' (duration: 93.339103ms)","trace[1914062425] 'applied index is now lower than readState.Index' (duration: 53.962478ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:17:41.889887Z","caller":"traceutil/trace.go:171","msg":"trace[1759759226] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"228.594553ms","start":"2026-09-04T03:17:41.66127Z","end":"2026-09-04T03:17:41.889865Z","steps":["trace[1759759226] 'process raft request' (duration: 174.575524ms)","trace[1759759226] 'compare' (duration: 53.672949ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:17:41.889956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.232835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-04T03:17:41.890014Z","caller":"traceutil/trace.go:171","msg":"trace[286599051] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1858; }","duration":"155.342428ms","start":"2026-09-04T03:17:41.734655Z","end":"2026-09-04T03:17:41.889998Z","steps":["trace[286599051] 'agreement among raft nodes before linearized reading' (duration: 155.230465ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:17:41.890016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.861123ms","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-04T03:17:41.890055Z","caller":"traceutil/trace.go:171","msg":"trace[1897891671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1858; }","duration":"167.934454ms","start":"2026-09-04T03:17:41.722111Z","end":"2026-09-04T03:17:41.890046Z","steps":["trace[1897891671] 'agreement among raft nodes before linearized reading' (duration: 167.817131ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:17:41.91214Z","caller":"traceutil/trace.go:171","msg":"trace[1785790056] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"164.687493ms","start":"2026-09-04T03:17:41.747427Z","end":"2026-09-04T03:17:41.912114Z","steps":["trace[1785790056] 'process raft request' (duration: 164.520778ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:17:42.07987Z","caller":"traceutil/trace.go:171","msg":"trace[1824646940] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1933; }","duration":"184.530024ms","start":"2026-09-04T03:17:41.895316Z","end":"2026-09-04T03:17:42.079846Z","steps":["trace[1824646940] 'read index received' (duration: 16.672201ms)","trace[1824646940] 'applied index is now lower than readState.Index' (duration: 167.856543ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:17:42.079933Z","caller":"traceutil/trace.go:171","msg":"trace[1720792691] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"186.087099ms","start":"2026-09-04T03:17:41.893813Z","end":"2026-09-04T03:17:42.079901Z","steps":["trace[1720792691] 'process raft request' (duration: 141.378023ms)","trace[1720792691] 'compare' (duration: 44.250144ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:17:42.080107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.788501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10667"} {"level":"info","ts":"2026-09-04T03:17:42.080145Z","caller":"traceutil/trace.go:171","msg":"trace[974496657] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1860; }","duration":"184.863644ms","start":"2026-09-04T03:17:41.895272Z","end":"2026-09-04T03:17:42.080136Z","steps":["trace[974496657] 'agreement among raft nodes before linearized reading' (duration: 184.706058ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:17:42.080156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.460756ms","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-04T03:17:42.08022Z","caller":"traceutil/trace.go:171","msg":"trace[220638037] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1860; }","duration":"141.574459ms","start":"2026-09-04T03:17:41.938626Z","end":"2026-09-04T03:17:42.0802Z","steps":["trace[220638037] 'agreement among raft nodes before linearized reading' (duration: 141.392133ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:18:20.005149Z","caller":"traceutil/trace.go:171","msg":"trace[1157854843] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"119.15649ms","start":"2026-09-04T03:18:19.885967Z","end":"2026-09-04T03:18:20.005124Z","steps":["trace[1157854843] 'process raft request' (duration: 118.970715ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:18:20.026525Z","caller":"traceutil/trace.go:171","msg":"trace[788146045] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"103.470318ms","start":"2026-09-04T03:18:19.923011Z","end":"2026-09-04T03:18:20.026481Z","steps":["trace[788146045] 'process raft request' (duration: 102.83123ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:18:20.026536Z","caller":"traceutil/trace.go:171","msg":"trace[474174631] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"123.92509ms","start":"2026-09-04T03:18:19.902549Z","end":"2026-09-04T03:18:20.026475Z","steps":["trace[474174631] 'process raft request' (duration: 123.094956ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:18:32.257706Z","caller":"traceutil/trace.go:171","msg":"trace[1523919580] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"206.129271ms","start":"2026-09-04T03:18:32.051546Z","end":"2026-09-04T03:18:32.257675Z","steps":["trace[1523919580] 'process raft request' (duration: 205.869284ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:18:32.301943Z","caller":"traceutil/trace.go:171","msg":"trace[1034596784] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"233.079535ms","start":"2026-09-04T03:18:32.068835Z","end":"2026-09-04T03:18:32.301914Z","steps":["trace[1034596784] 'process raft request' (duration: 232.89415ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:18:32.302118Z","caller":"traceutil/trace.go:171","msg":"trace[293789170] linearizableReadLoop","detail":"{readStateIndex:2271; appliedIndex:2268; }","duration":"149.384671ms","start":"2026-09-04T03:18:32.152709Z","end":"2026-09-04T03:18:32.302093Z","steps":["trace[293789170] 'read index received' (duration: 104.841259ms)","trace[293789170] 'applied index is now lower than readState.Index' (duration: 44.536532ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:18:32.302135Z","caller":"traceutil/trace.go:171","msg":"trace[1047562354] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"230.985575ms","start":"2026-09-04T03:18:32.071121Z","end":"2026-09-04T03:18:32.302107Z","steps":["trace[1047562354] 'process raft request' (duration: 230.751727ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:18:32.302401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.678419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-09-04T03:18:32.302436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.246659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-04T03:18:32.302457Z","caller":"traceutil/trace.go:171","msg":"trace[1825469737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2184; }","duration":"149.813393ms","start":"2026-09-04T03:18:32.152628Z","end":"2026-09-04T03:18:32.302441Z","steps":["trace[1825469737] 'agreement among raft nodes before linearized reading' (duration: 149.606847ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:18:32.302546Z","caller":"traceutil/trace.go:171","msg":"trace[953814587] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2184; }","duration":"106.354263ms","start":"2026-09-04T03:18:32.196138Z","end":"2026-09-04T03:18:32.302493Z","steps":["trace[953814587] 'agreement among raft nodes before linearized reading' (duration: 106.137557ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:19:24.588408Z","caller":"traceutil/trace.go:171","msg":"trace[292910714] transaction","detail":"{read_only:false; response_revision:2635; number_of_response:1; }","duration":"127.223238ms","start":"2026-09-04T03:19:24.461158Z","end":"2026-09-04T03:19:24.588381Z","steps":["trace[292910714] 'process raft request' (duration: 127.046253ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:19:25.292302Z","caller":"traceutil/trace.go:171","msg":"trace[1098248109] transaction","detail":"{read_only:false; response_revision:2641; number_of_response:1; }","duration":"314.202927ms","start":"2026-09-04T03:19:24.978074Z","end":"2026-09-04T03:19:25.292277Z","steps":["trace[1098248109] 'process raft request' (duration: 314.050022ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:19:25.292444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:19:24.978056Z","time spent":"314.315779ms","remote":"127.0.0.1:38780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T03:19:25.299167Z","caller":"traceutil/trace.go:171","msg":"trace[583902583] transaction","detail":"{read_only:false; response_revision:2643; number_of_response:1; }","duration":"236.759464ms","start":"2026-09-04T03:19:25.062383Z","end":"2026-09-04T03:19:25.299142Z","steps":["trace[583902583] 'process raft request' (duration: 236.716913ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:19:25.299204Z","caller":"traceutil/trace.go:171","msg":"trace[51320862] transaction","detail":"{read_only:false; response_revision:2642; number_of_response:1; }","duration":"320.974186ms","start":"2026-09-04T03:19:24.97821Z","end":"2026-09-04T03:19:25.299184Z","steps":["trace[51320862] 'process raft request' (duration: 320.711738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:19:25.29928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:19:24.978191Z","time spent":"321.051168ms","remote":"127.0.0.1:38780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T03:19:58.698617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.66233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-04T03:19:58.69878Z","caller":"traceutil/trace.go:171","msg":"trace[1299787849] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:2841; }","duration":"111.944948ms","start":"2026-09-04T03:19:58.586807Z","end":"2026-09-04T03:19:58.698752Z","steps":["trace[1299787849] 'count revisions from in-memory index tree' (duration: 111.519606ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:19:58.8762Z","caller":"traceutil/trace.go:171","msg":"trace[1197947528] transaction","detail":"{read_only:false; response_revision:2842; number_of_response:1; }","duration":"151.851604ms","start":"2026-09-04T03:19:58.724322Z","end":"2026-09-04T03:19:58.876173Z","steps":["trace[1197947528] 'process raft request' (duration: 151.691689ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:19:59.062074Z","caller":"traceutil/trace.go:171","msg":"trace[1286890872] linearizableReadLoop","detail":"{readStateIndex:2956; appliedIndex:2953; }","duration":"307.634221ms","start":"2026-09-04T03:19:58.754415Z","end":"2026-09-04T03:19:59.062049Z","steps":["trace[1286890872] 'read index received' (duration: 121.629113ms)","trace[1286890872] 'applied index is now lower than readState.Index' (duration: 186.004208ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:19:59.062094Z","caller":"traceutil/trace.go:171","msg":"trace[1781091500] transaction","detail":"{read_only:false; response_revision:2844; number_of_response:1; }","duration":"336.663826ms","start":"2026-09-04T03:19:58.725398Z","end":"2026-09-04T03:19:59.062061Z","steps":["trace[1781091500] 'process raft request' (duration: 336.605285ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:19:59.062101Z","caller":"traceutil/trace.go:171","msg":"trace[1616792414] transaction","detail":"{read_only:false; response_revision:2843; number_of_response:1; }","duration":"337.203093ms","start":"2026-09-04T03:19:58.724863Z","end":"2026-09-04T03:19:59.062066Z","steps":["trace[1616792414] 'process raft request' (duration: 329.458345ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:19:59.062246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:19:58.725387Z","time spent":"336.772311ms","remote":"127.0.0.1:38780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T03:19:59.062264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:19:58.724834Z","time spent":"337.342528ms","remote":"127.0.0.1:38780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T03:19:59.062296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.87016ms","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-04T03:19:59.062333Z","caller":"traceutil/trace.go:171","msg":"trace[1165049936] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2844; }","duration":"307.957002ms","start":"2026-09-04T03:19:58.754366Z","end":"2026-09-04T03:19:59.062323Z","steps":["trace[1165049936] 'agreement among raft nodes before linearized reading' (duration: 307.805028ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:19:59.062362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:19:58.754349Z","time spent":"308.005763ms","remote":"127.0.0.1:38780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-04T03:19:59.062494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.392184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-04T03:19:59.062556Z","caller":"traceutil/trace.go:171","msg":"trace[1800936776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2844; }","duration":"273.519698ms","start":"2026-09-04T03:19:58.789016Z","end":"2026-09-04T03:19:59.062536Z","steps":["trace[1800936776] 'agreement among raft nodes before linearized reading' (duration: 273.351073ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:19:59.062567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.198079ms","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-04T03:19:59.062672Z","caller":"traceutil/trace.go:171","msg":"trace[1981067007] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2844; }","duration":"272.355353ms","start":"2026-09-04T03:19:58.790297Z","end":"2026-09-04T03:19:59.062653Z","steps":["trace[1981067007] 'agreement among raft nodes before linearized reading' (duration: 272.114787ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:16.302237Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2940} {"level":"info","ts":"2026-09-04T03:25:16.386187Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2940,"took":"82.123004ms","hash":3661533160,"current-db-size-bytes":52011008,"current-db-size":"52 MB","current-db-size-in-use-bytes":28274688,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-04T03:25:16.386295Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3661533160,"revision":2940,"compact-revision":-1} {"level":"warn","ts":"2026-09-04T03:25:20.152618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.070681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-04T03:25:20.153052Z","caller":"traceutil/trace.go:171","msg":"trace[1090156716] transaction","detail":"{read_only:false; response_revision:5396; number_of_response:1; }","duration":"131.403584ms","start":"2026-09-04T03:25:20.021609Z","end":"2026-09-04T03:25:20.153012Z","steps":["trace[1090156716] 'process raft request' (duration: 27.827448ms)","trace[1090156716] 'compare' (duration: 102.919846ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:25:23.871692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.601824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:25:23.871855Z","caller":"traceutil/trace.go:171","msg":"trace[230500906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5535; }","duration":"147.792529ms","start":"2026-09-04T03:25:23.724003Z","end":"2026-09-04T03:25:23.871795Z","steps":["trace[230500906] 'range keys from in-memory index tree' (duration: 147.439909ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:24.061357Z","caller":"traceutil/trace.go:171","msg":"trace[2147411607] linearizableReadLoop","detail":"{readStateIndex:5745; appliedIndex:5744; }","duration":"170.461676ms","start":"2026-09-04T03:25:23.890862Z","end":"2026-09-04T03:25:24.061323Z","steps":["trace[2147411607] 'read index received' (duration: 170.085725ms)","trace[2147411607] 'applied index is now lower than readState.Index' (duration: 374.801µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:25:24.061491Z","caller":"traceutil/trace.go:171","msg":"trace[50674836] transaction","detail":"{read_only:false; response_revision:5536; number_of_response:1; }","duration":"171.186707ms","start":"2026-09-04T03:25:23.890252Z","end":"2026-09-04T03:25:24.061438Z","steps":["trace[50674836] 'process raft request' (duration: 170.843497ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:25:24.061549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.662382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:25:24.061781Z","caller":"traceutil/trace.go:171","msg":"trace[1720055914] range","detail":"{range_begin:/registry/daemonsets/monitoring/vector; range_end:; response_count:0; response_revision:5536; }","duration":"170.786066ms","start":"2026-09-04T03:25:23.890799Z","end":"2026-09-04T03:25:24.061585Z","steps":["trace[1720055914] 'agreement among raft nodes before linearized reading' (duration: 170.662042ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:39.304169Z","caller":"traceutil/trace.go:171","msg":"trace[1330018442] transaction","detail":"{read_only:false; response_revision:5805; number_of_response:1; }","duration":"134.230296ms","start":"2026-09-04T03:25:39.16991Z","end":"2026-09-04T03:25:39.304141Z","steps":["trace[1330018442] 'process raft request' (duration: 133.823944ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:39.304127Z","caller":"traceutil/trace.go:171","msg":"trace[875329446] linearizableReadLoop","detail":"{readStateIndex:6022; appliedIndex:6021; }","duration":"109.831758ms","start":"2026-09-04T03:25:39.194254Z","end":"2026-09-04T03:25:39.304086Z","steps":["trace[875329446] 'read index received' (duration: 109.398936ms)","trace[875329446] 'applied index is now lower than readState.Index' (duration: 430.902µs)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:25:39.304429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.142377ms","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-04T03:25:39.304499Z","caller":"traceutil/trace.go:171","msg":"trace[946608188] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5805; }","duration":"110.259089ms","start":"2026-09-04T03:25:39.194226Z","end":"2026-09-04T03:25:39.304485Z","steps":["trace[946608188] 'agreement among raft nodes before linearized reading' (duration: 109.994512ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:25:39.30456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.315637ms","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-04T03:25:39.304669Z","caller":"traceutil/trace.go:171","msg":"trace[1941296457] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5805; }","duration":"104.43799ms","start":"2026-09-04T03:25:39.200211Z","end":"2026-09-04T03:25:39.304649Z","steps":["trace[1941296457] 'agreement among raft nodes before linearized reading' (duration: 104.209283ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:40.278505Z","caller":"traceutil/trace.go:171","msg":"trace[1710265355] transaction","detail":"{read_only:false; response_revision:5818; number_of_response:1; }","duration":"187.321776ms","start":"2026-09-04T03:25:40.091157Z","end":"2026-09-04T03:25:40.278479Z","steps":["trace[1710265355] 'process raft request' (duration: 187.09857ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:40.444467Z","caller":"traceutil/trace.go:171","msg":"trace[994001400] transaction","detail":"{read_only:false; response_revision:5819; number_of_response:1; }","duration":"160.185078ms","start":"2026-09-04T03:25:40.28425Z","end":"2026-09-04T03:25:40.444435Z","steps":["trace[994001400] 'process raft request' (duration: 80.789095ms)","trace[994001400] 'compare' (duration: 79.260049ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:25:40.446836Z","caller":"traceutil/trace.go:171","msg":"trace[1133889865] transaction","detail":"{read_only:false; response_revision:5820; number_of_response:1; }","duration":"161.484516ms","start":"2026-09-04T03:25:40.285314Z","end":"2026-09-04T03:25:40.446799Z","steps":["trace[1133889865] 'process raft request' (duration: 161.179687ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:40.446808Z","caller":"traceutil/trace.go:171","msg":"trace[1645248629] transaction","detail":"{read_only:false; response_revision:5821; number_of_response:1; }","duration":"161.430414ms","start":"2026-09-04T03:25:40.285351Z","end":"2026-09-04T03:25:40.446781Z","steps":["trace[1645248629] 'process raft request' (duration: 161.321341ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:40.447127Z","caller":"traceutil/trace.go:171","msg":"trace[2038613970] transaction","detail":"{read_only:false; response_revision:5822; number_of_response:1; }","duration":"161.421954ms","start":"2026-09-04T03:25:40.285688Z","end":"2026-09-04T03:25:40.44711Z","steps":["trace[2038613970] 'process raft request' (duration: 161.049474ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:25:50.012523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.995985ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-09-04T03:25:50.012793Z","caller":"traceutil/trace.go:171","msg":"trace[1423231990] linearizableReadLoop","detail":"{readStateIndex:6259; appliedIndex:6258; }","duration":"307.183934ms","start":"2026-09-04T03:25:49.705578Z","end":"2026-09-04T03:25:50.012762Z","steps":["trace[1423231990] 'read index received' (duration: 6.825458ms)","trace[1423231990] 'applied index is now lower than readState.Index' (duration: 300.356186ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:25:50.012897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:25:49.657101Z","time spent":"355.784644ms","remote":"127.0.0.1:38524","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-04T03:25:50.013172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.608646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:25:50.013304Z","caller":"traceutil/trace.go:171","msg":"trace[1888704904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6037; }","duration":"288.810251ms","start":"2026-09-04T03:25:49.724469Z","end":"2026-09-04T03:25:50.013279Z","steps":["trace[1888704904] 'agreement among raft nodes before linearized reading' (duration: 288.614815ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:25:50.013286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.672258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be306444-266a-44f7-a9d0-8f0c1d728354\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-09-04T03:25:50.013318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.221887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-04T03:25:50.0134Z","caller":"traceutil/trace.go:171","msg":"trace[1654136785] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-be306444-266a-44f7-a9d0-8f0c1d728354; range_end:; response_count:1; response_revision:6037; }","duration":"307.857934ms","start":"2026-09-04T03:25:49.705516Z","end":"2026-09-04T03:25:50.013374Z","steps":["trace[1654136785] 'agreement among raft nodes before linearized reading' (duration: 307.517404ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:25:50.013418Z","caller":"traceutil/trace.go:171","msg":"trace[1800712439] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6037; }","duration":"203.392053ms","start":"2026-09-04T03:25:49.810003Z","end":"2026-09-04T03:25:50.013395Z","steps":["trace[1800712439] 'agreement among raft nodes before linearized reading' (duration: 203.083923ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:25:50.013462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:25:49.70549Z","time spent":"307.961107ms","remote":"127.0.0.1:38688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-be306444-266a-44f7-a9d0-8f0c1d728354\" "} {"level":"info","ts":"2026-09-04T03:25:50.228932Z","caller":"traceutil/trace.go:171","msg":"trace[1835230954] transaction","detail":"{read_only:false; response_revision:6039; number_of_response:1; }","duration":"110.536427ms","start":"2026-09-04T03:25:50.118345Z","end":"2026-09-04T03:25:50.228881Z","steps":["trace[1835230954] 'process raft request' (duration: 47.596241ms)","trace[1835230954] 'compare' (duration: 62.557115ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:25:50.229075Z","caller":"traceutil/trace.go:171","msg":"trace[1969087503] linearizableReadLoop","detail":"{readStateIndex:6261; appliedIndex:6260; }","duration":"101.884447ms","start":"2026-09-04T03:25:50.127152Z","end":"2026-09-04T03:25:50.229037Z","steps":["trace[1969087503] 'read index received' (duration: 38.713323ms)","trace[1969087503] 'applied index is now lower than readState.Index' (duration: 63.169444ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:25:50.229322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.142215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:25:50.229385Z","caller":"traceutil/trace.go:171","msg":"trace[256848772] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6039; }","duration":"102.279938ms","start":"2026-09-04T03:25:50.127086Z","end":"2026-09-04T03:25:50.229366Z","steps":["trace[256848772] 'agreement among raft nodes before linearized reading' (duration: 102.094983ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:30:16.310078Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5306} {"level":"info","ts":"2026-09-04T03:30:16.398534Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5306,"took":"86.454737ms","hash":1238777118,"current-db-size-bytes":52011008,"current-db-size":"52 MB","current-db-size-in-use-bytes":28319744,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-04T03:30:16.398608Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1238777118,"revision":5306,"compact-revision":2940} {"level":"info","ts":"2026-09-04T03:32:14.267392Z","caller":"traceutil/trace.go:171","msg":"trace[2104616839] transaction","detail":"{read_only:false; response_revision:8905; number_of_response:1; }","duration":"252.930977ms","start":"2026-09-04T03:32:14.014439Z","end":"2026-09-04T03:32:14.26737Z","steps":["trace[2104616839] 'process raft request' (duration: 252.768012ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:32:14.470262Z","caller":"traceutil/trace.go:171","msg":"trace[1401227137] transaction","detail":"{read_only:false; response_revision:8906; number_of_response:1; }","duration":"305.146493ms","start":"2026-09-04T03:32:14.165082Z","end":"2026-09-04T03:32:14.470228Z","steps":["trace[1401227137] 'process raft request' (duration: 304.882636ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:32:14.470317Z","caller":"traceutil/trace.go:171","msg":"trace[1311057509] transaction","detail":"{read_only:false; response_revision:8907; number_of_response:1; }","duration":"207.024586ms","start":"2026-09-04T03:32:14.263266Z","end":"2026-09-04T03:32:14.470291Z","steps":["trace[1311057509] 'process raft request' (duration: 206.908102ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:32:14.470383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.952494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:32:14.470468Z","caller":"traceutil/trace.go:171","msg":"trace[1117717965] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:8907; }","duration":"226.039157ms","start":"2026-09-04T03:32:14.244402Z","end":"2026-09-04T03:32:14.470441Z","steps":["trace[1117717965] 'agreement among raft nodes before linearized reading' (duration: 225.919873ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:32:14.470433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:32:14.165053Z","time spent":"305.291927ms","remote":"127.0.0.1:38780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T03:32:14.470261Z","caller":"traceutil/trace.go:171","msg":"trace[869597600] linearizableReadLoop","detail":"{readStateIndex:9221; appliedIndex:9219; }","duration":"225.81654ms","start":"2026-09-04T03:32:14.244416Z","end":"2026-09-04T03:32:14.470233Z","steps":["trace[869597600] 'read index received' (duration: 22.864088ms)","trace[869597600] 'applied index is now lower than readState.Index' (duration: 202.951172ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:32:14.47253Z","caller":"traceutil/trace.go:171","msg":"trace[551373327] transaction","detail":"{read_only:false; response_revision:8908; number_of_response:1; }","duration":"184.758564ms","start":"2026-09-04T03:32:14.287732Z","end":"2026-09-04T03:32:14.47249Z","steps":["trace[551373327] 'process raft request' (duration: 184.513018ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:32:14.472543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.342122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:32:14.472668Z","caller":"traceutil/trace.go:171","msg":"trace[670055856] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8908; }","duration":"175.525707ms","start":"2026-09-04T03:32:14.297114Z","end":"2026-09-04T03:32:14.47264Z","steps":["trace[670055856] 'agreement among raft nodes before linearized reading' (duration: 175.313821ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:32:26.00725Z","caller":"traceutil/trace.go:171","msg":"trace[980629005] linearizableReadLoop","detail":"{readStateIndex:9283; appliedIndex:9282; }","duration":"121.736916ms","start":"2026-09-04T03:32:25.885488Z","end":"2026-09-04T03:32:26.007225Z","steps":["trace[980629005] 'read index received' (duration: 121.477058ms)","trace[980629005] 'applied index is now lower than readState.Index' (duration: 258.868µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:32:26.007316Z","caller":"traceutil/trace.go:171","msg":"trace[1127719672] transaction","detail":"{read_only:false; response_revision:8965; number_of_response:1; }","duration":"125.729318ms","start":"2026-09-04T03:32:25.881551Z","end":"2026-09-04T03:32:26.007281Z","steps":["trace[1127719672] 'process raft request' (duration: 125.507321ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:32:26.007489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.736118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-04T03:32:26.007531Z","caller":"traceutil/trace.go:171","msg":"trace[1713929458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8965; }","duration":"120.81236ms","start":"2026-09-04T03:32:25.886708Z","end":"2026-09-04T03:32:26.00752Z","steps":["trace[1713929458] 'agreement among raft nodes before linearized reading' (duration: 120.656466ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:32:26.007614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.760069ms","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-04T03:32:26.007672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.867361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-04T03:32:26.007704Z","caller":"traceutil/trace.go:171","msg":"trace[267314913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8965; }","duration":"120.881862ms","start":"2026-09-04T03:32:25.886796Z","end":"2026-09-04T03:32:26.007677Z","steps":["trace[267314913] 'agreement among raft nodes before linearized reading' (duration: 120.610165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:32:26.007675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.140947ms","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-04T03:32:26.007763Z","caller":"traceutil/trace.go:171","msg":"trace[1198564101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8965; }","duration":"120.983195ms","start":"2026-09-04T03:32:25.886761Z","end":"2026-09-04T03:32:26.007744Z","steps":["trace[1198564101] 'agreement among raft nodes before linearized reading' (duration: 120.732018ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:32:26.00779Z","caller":"traceutil/trace.go:171","msg":"trace[1089426732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8965; }","duration":"122.326332ms","start":"2026-09-04T03:32:25.885443Z","end":"2026-09-04T03:32:26.00777Z","steps":["trace[1089426732] 'agreement among raft nodes before linearized reading' (duration: 121.919951ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:32:26.678748Z","caller":"traceutil/trace.go:171","msg":"trace[150763084] transaction","detail":"{read_only:false; response_revision:8970; number_of_response:1; }","duration":"158.332917ms","start":"2026-09-04T03:32:26.520393Z","end":"2026-09-04T03:32:26.678726Z","steps":["trace[150763084] 'process raft request' (duration: 158.127302ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:32:26.732734Z","caller":"traceutil/trace.go:171","msg":"trace[924752291] transaction","detail":"{read_only:false; response_revision:8972; number_of_response:1; }","duration":"170.068355ms","start":"2026-09-04T03:32:26.562643Z","end":"2026-09-04T03:32:26.732711Z","steps":["trace[924752291] 'process raft request' (duration: 169.981083ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:32:26.732754Z","caller":"traceutil/trace.go:171","msg":"trace[463439588] transaction","detail":"{read_only:false; response_revision:8971; number_of_response:1; }","duration":"205.526504ms","start":"2026-09-04T03:32:26.527198Z","end":"2026-09-04T03:32:26.732725Z","steps":["trace[463439588] 'process raft request' (duration: 197.900701ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:32:26.732962Z","caller":"traceutil/trace.go:171","msg":"trace[915946679] transaction","detail":"{read_only:false; response_revision:8973; number_of_response:1; }","duration":"167.853242ms","start":"2026-09-04T03:32:26.565085Z","end":"2026-09-04T03:32:26.732938Z","steps":["trace[915946679] 'process raft request' (duration: 167.583875ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:32:26.733212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.871828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mhnxs\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-04T03:32:26.733273Z","caller":"traceutil/trace.go:171","msg":"trace[802696082] linearizableReadLoop","detail":"{readStateIndex:9291; appliedIndex:9287; }","duration":"141.493617ms","start":"2026-09-04T03:32:26.591312Z","end":"2026-09-04T03:32:26.732806Z","steps":["trace[802696082] 'read index received' (duration: 87.291315ms)","trace[802696082] 'applied index is now lower than readState.Index' (duration: 54.200922ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:32:26.733285Z","caller":"traceutil/trace.go:171","msg":"trace[2146778224] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mhnxs; range_end:; response_count:1; response_revision:8973; }","duration":"141.987731ms","start":"2026-09-04T03:32:26.59128Z","end":"2026-09-04T03:32:26.733268Z","steps":["trace[2146778224] 'agreement among raft nodes before linearized reading' (duration: 141.744924ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:32:51.146999Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024df.wal"} {"level":"info","ts":"2026-09-04T03:32:52.951048Z","caller":"traceutil/trace.go:171","msg":"trace[455249648] transaction","detail":"{read_only:false; response_revision:9164; number_of_response:1; }","duration":"119.37477ms","start":"2026-09-04T03:32:52.831637Z","end":"2026-09-04T03:32:52.951012Z","steps":["trace[455249648] 'process raft request' (duration: 119.130023ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:32:52.952524Z","caller":"traceutil/trace.go:171","msg":"trace[1445927696] transaction","detail":"{read_only:false; response_revision:9165; number_of_response:1; }","duration":"113.878867ms","start":"2026-09-04T03:32:52.83861Z","end":"2026-09-04T03:32:52.952489Z","steps":["trace[1445927696] 'process raft request' (duration: 113.62029ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:32:52.95318Z","caller":"traceutil/trace.go:171","msg":"trace[714313324] transaction","detail":"{read_only:false; response_revision:9166; number_of_response:1; }","duration":"109.898036ms","start":"2026-09-04T03:32:52.84326Z","end":"2026-09-04T03:32:52.953158Z","steps":["trace[714313324] 'process raft request' (duration: 109.173896ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:33:09.198951Z","caller":"traceutil/trace.go:171","msg":"trace[1595332946] transaction","detail":"{read_only:false; response_revision:9251; number_of_response:1; }","duration":"161.510329ms","start":"2026-09-04T03:33:09.037411Z","end":"2026-09-04T03:33:09.198922Z","steps":["trace[1595332946] 'process raft request' (duration: 161.21854ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:33:09.242327Z","caller":"traceutil/trace.go:171","msg":"trace[1831941342] transaction","detail":"{read_only:false; response_revision:9252; number_of_response:1; }","duration":"203.490721ms","start":"2026-09-04T03:33:09.038811Z","end":"2026-09-04T03:33:09.242302Z","steps":["trace[1831941342] 'process raft request' (duration: 203.263703ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:33:09.242691Z","caller":"traceutil/trace.go:171","msg":"trace[751288117] linearizableReadLoop","detail":"{readStateIndex:9582; appliedIndex:9579; }","duration":"184.20865ms","start":"2026-09-04T03:33:09.058448Z","end":"2026-09-04T03:33:09.242657Z","steps":["trace[751288117] 'read index received' (duration: 140.2311ms)","trace[751288117] 'applied index is now lower than readState.Index' (duration: 43.97628ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:33:09.242764Z","caller":"traceutil/trace.go:171","msg":"trace[1173856703] transaction","detail":"{read_only:false; response_revision:9253; number_of_response:1; }","duration":"203.453488ms","start":"2026-09-04T03:33:09.039293Z","end":"2026-09-04T03:33:09.242746Z","steps":["trace[1173856703] 'process raft request' (duration: 202.961184ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:33:09.242864Z","caller":"traceutil/trace.go:171","msg":"trace[209405816] transaction","detail":"{read_only:false; response_revision:9254; number_of_response:1; }","duration":"126.16087ms","start":"2026-09-04T03:33:09.11668Z","end":"2026-09-04T03:33:09.242841Z","steps":["trace[209405816] 'process raft request' (duration: 125.859421ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:33:09.242988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.529498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mhnxs\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-04T03:33:09.243047Z","caller":"traceutil/trace.go:171","msg":"trace[1492355820] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mhnxs; range_end:; response_count:1; response_revision:9254; }","duration":"184.645492ms","start":"2026-09-04T03:33:09.058384Z","end":"2026-09-04T03:33:09.24303Z","steps":["trace[1492355820] 'agreement among raft nodes before linearized reading' (duration: 184.393085ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:33:22.529021Z","caller":"traceutil/trace.go:171","msg":"trace[1841272182] transaction","detail":"{read_only:false; response_revision:9397; number_of_response:1; }","duration":"114.204453ms","start":"2026-09-04T03:33:22.414771Z","end":"2026-09-04T03:33:22.528975Z","steps":["trace[1841272182] 'process raft request' (duration: 111.667689ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:33:22.529231Z","caller":"traceutil/trace.go:171","msg":"trace[2049589745] transaction","detail":"{read_only:false; response_revision:9398; number_of_response:1; }","duration":"114.288924ms","start":"2026-09-04T03:33:22.414902Z","end":"2026-09-04T03:33:22.529191Z","steps":["trace[2049589745] 'process raft request' (duration: 114.023927ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:33:49.682806Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db28a37eabf8270","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-04T03:33:49.692273Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-04T03:33:49.692434Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-04T03:35:16.316759Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7997} {"level":"info","ts":"2026-09-04T03:35:16.407344Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7997,"took":"88.255848ms","hash":2902877411,"current-db-size-bytes":52011008,"current-db-size":"52 MB","current-db-size-in-use-bytes":25554944,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-04T03:35:16.407424Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2902877411,"revision":7997,"compact-revision":5306} {"level":"info","ts":"2026-09-04T03:40:16.324766Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10369} {"level":"info","ts":"2026-09-04T03:40:16.414214Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10369,"took":"86.965803ms","hash":1362314219,"current-db-size-bytes":52011008,"current-db-size":"52 MB","current-db-size-in-use-bytes":25645056,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-04T03:40:16.41429Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1362314219,"revision":10369,"compact-revision":7997} {"level":"info","ts":"2026-09-04T03:40:27.895707Z","caller":"traceutil/trace.go:171","msg":"trace[51161532] transaction","detail":"{read_only:false; response_revision:12606; number_of_response:1; }","duration":"114.130974ms","start":"2026-09-04T03:40:27.781542Z","end":"2026-09-04T03:40:27.895673Z","steps":["trace[51161532] 'process raft request' (duration: 113.900447ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:40:29.096774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.998883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-09-04T03:40:29.096781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.533576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-04T03:40:29.096914Z","caller":"traceutil/trace.go:171","msg":"trace[719557139] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12608; }","duration":"145.169188ms","start":"2026-09-04T03:40:28.951706Z","end":"2026-09-04T03:40:29.096876Z","steps":["trace[719557139] 'range keys from in-memory index tree' (duration: 144.708155ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:40:29.097128Z","caller":"traceutil/trace.go:171","msg":"trace[1469694576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:12608; }","duration":"126.71976ms","start":"2026-09-04T03:40:28.970184Z","end":"2026-09-04T03:40:29.096904Z","steps":["trace[1469694576] 'range keys from in-memory index tree' (duration: 126.319859ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:45:16.331285Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12535} {"level":"info","ts":"2026-09-04T03:45:16.414463Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12535,"took":"80.27824ms","hash":678695817,"current-db-size-bytes":52011008,"current-db-size":"52 MB","current-db-size-in-use-bytes":27656192,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-04T03:45:16.414528Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":678695817,"revision":12535,"compact-revision":10369} {"level":"warn","ts":"2026-09-04T03:47:20.129664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.385198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-k759p\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-09-04T03:47:20.129796Z","caller":"traceutil/trace.go:171","msg":"trace[1307417445] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-k759p; range_end:; response_count:1; response_revision:15331; }","duration":"141.566364ms","start":"2026-09-04T03:47:19.988203Z","end":"2026-09-04T03:47:20.129769Z","steps":["trace[1307417445] 'range keys from in-memory index tree' (duration: 141.074419ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:47:20.332082Z","caller":"traceutil/trace.go:171","msg":"trace[1895530969] transaction","detail":"{read_only:false; response_revision:15332; number_of_response:1; }","duration":"187.387139ms","start":"2026-09-04T03:47:20.144666Z","end":"2026-09-04T03:47:20.332053Z","steps":["trace[1895530969] 'process raft request' (duration: 187.223914ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:47:31.118639Z","caller":"traceutil/trace.go:171","msg":"trace[1279720598] transaction","detail":"{read_only:false; response_revision:15505; number_of_response:1; }","duration":"219.613318ms","start":"2026-09-04T03:47:30.898998Z","end":"2026-09-04T03:47:31.118612Z","steps":["trace[1279720598] 'process raft request' (duration: 219.415542ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:47:31.355807Z","caller":"traceutil/trace.go:171","msg":"trace[2092367634] transaction","detail":"{read_only:false; response_revision:15507; number_of_response:1; }","duration":"436.389722ms","start":"2026-09-04T03:47:30.919385Z","end":"2026-09-04T03:47:31.355775Z","steps":["trace[2092367634] 'process raft request' (duration: 436.221177ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:47:31.355888Z","caller":"traceutil/trace.go:171","msg":"trace[1819756545] transaction","detail":"{read_only:false; response_revision:15506; number_of_response:1; }","duration":"454.465798ms","start":"2026-09-04T03:47:30.90136Z","end":"2026-09-04T03:47:31.355826Z","steps":["trace[1819756545] 'process raft request' (duration: 362.602882ms)","trace[1819756545] 'compare' (duration: 91.2795ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:47:31.355993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:47:30.919302Z","time spent":"436.598268ms","remote":"127.0.0.1:38780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T03:47:31.356021Z","caller":"traceutil/trace.go:171","msg":"trace[1180433298] linearizableReadLoop","detail":"{readStateIndex:16046; appliedIndex:16042; }","duration":"213.319173ms","start":"2026-09-04T03:47:31.142673Z","end":"2026-09-04T03:47:31.355992Z","steps":["trace[1180433298] 'read index received' (duration: 121.265061ms)","trace[1180433298] 'applied index is now lower than readState.Index' (duration: 92.052952ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:47:31.35615Z","caller":"traceutil/trace.go:171","msg":"trace[1331537503] transaction","detail":"{read_only:false; response_revision:15508; number_of_response:1; }","duration":"433.733423ms","start":"2026-09-04T03:47:30.922378Z","end":"2026-09-04T03:47:31.356112Z","steps":["trace[1331537503] 'process raft request' (duration: 433.360133ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:47:31.356179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:47:30.901332Z","time spent":"454.697816ms","remote":"127.0.0.1:38780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T03:47:31.356158Z","caller":"traceutil/trace.go:171","msg":"trace[964367468] transaction","detail":"{read_only:false; response_revision:15509; number_of_response:1; }","duration":"373.231632ms","start":"2026-09-04T03:47:30.982896Z","end":"2026-09-04T03:47:31.356127Z","steps":["trace[964367468] 'process raft request' (duration: 372.996735ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:47:31.356416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:47:30.922286Z","time spent":"433.969202ms","remote":"127.0.0.1:38780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T03:47:31.356459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:47:30.982861Z","time spent":"373.544362ms","remote":"127.0.0.1:38562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":854,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-04T03:47:31.356928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.485847ms","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-04T03:47:31.356999Z","caller":"traceutil/trace.go:171","msg":"trace[1231572779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15509; }","duration":"214.608261ms","start":"2026-09-04T03:47:31.142374Z","end":"2026-09-04T03:47:31.356982Z","steps":["trace[1231572779] 'agreement among raft nodes before linearized reading' (duration: 213.772246ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:47:31.452968Z","caller":"traceutil/trace.go:171","msg":"trace[233225947] transaction","detail":"{read_only:false; response_revision:15510; number_of_response:1; }","duration":"249.857698ms","start":"2026-09-04T03:47:31.203076Z","end":"2026-09-04T03:47:31.452934Z","steps":["trace[233225947] 'process raft request' (duration: 249.483787ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:47:31.453285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.992547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-04T03:47:31.453351Z","caller":"traceutil/trace.go:171","msg":"trace[42133590] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:15510; }","duration":"223.1212ms","start":"2026-09-04T03:47:31.230219Z","end":"2026-09-04T03:47:31.45334Z","steps":["trace[42133590] 'agreement among raft nodes before linearized reading' (duration: 222.985346ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:47:31.453455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.956911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-04T03:47:31.453581Z","caller":"traceutil/trace.go:171","msg":"trace[2086650227] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:15510; }","duration":"240.181277ms","start":"2026-09-04T03:47:31.213369Z","end":"2026-09-04T03:47:31.453551Z","steps":["trace[2086650227] 'agreement among raft nodes before linearized reading' (duration: 239.882629ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:47:31.45368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.380818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-82a12342-5cf9-434d-90db-ae3da13a4600\" ","response":"range_response_count:1 size:3606"} {"level":"info","ts":"2026-09-04T03:47:31.45381Z","caller":"traceutil/trace.go:171","msg":"trace[77534914] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-82a12342-5cf9-434d-90db-ae3da13a4600; range_end:; response_count:1; response_revision:15510; }","duration":"290.628547ms","start":"2026-09-04T03:47:31.163152Z","end":"2026-09-04T03:47:31.45378Z","steps":["trace[77534914] 'agreement among raft nodes before linearized reading' (duration: 290.198435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:49:14.915546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.045681ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-04T03:49:15.072649Z","caller":"traceutil/trace.go:171","msg":"trace[1969637592] transaction","detail":"{read_only:false; response_revision:16616; number_of_response:1; }","duration":"128.323089ms","start":"2026-09-04T03:49:14.944299Z","end":"2026-09-04T03:49:15.072622Z","steps":["trace[1969637592] 'process raft request' (duration: 128.144033ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:49:15.299399Z","caller":"traceutil/trace.go:171","msg":"trace[2136852625] linearizableReadLoop","detail":"{readStateIndex:17187; appliedIndex:17186; }","duration":"158.313831ms","start":"2026-09-04T03:49:15.141063Z","end":"2026-09-04T03:49:15.299377Z","steps":["trace[2136852625] 'read index received' (duration: 158.113025ms)","trace[2136852625] 'applied index is now lower than readState.Index' (duration: 199.896µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:49:15.299503Z","caller":"traceutil/trace.go:171","msg":"trace[1059317785] transaction","detail":"{read_only:false; response_revision:16617; number_of_response:1; }","duration":"243.924255ms","start":"2026-09-04T03:49:15.055568Z","end":"2026-09-04T03:49:15.299492Z","steps":["trace[1059317785] 'process raft request' (duration: 243.652858ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:49:15.300161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.032152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-04T03:49:15.300247Z","caller":"traceutil/trace.go:171","msg":"trace[1199257683] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:16617; }","duration":"159.207977ms","start":"2026-09-04T03:49:15.14102Z","end":"2026-09-04T03:49:15.300228Z","steps":["trace[1199257683] 'agreement among raft nodes before linearized reading' (duration: 159.007352ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:49:15.971263Z","caller":"traceutil/trace.go:171","msg":"trace[10752012] transaction","detail":"{read_only:false; response_revision:16618; number_of_response:1; }","duration":"135.262941ms","start":"2026-09-04T03:49:15.835973Z","end":"2026-09-04T03:49:15.971236Z","steps":["trace[10752012] 'process raft request' (duration: 135.052124ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:49:15.98436Z","caller":"traceutil/trace.go:171","msg":"trace[544366253] transaction","detail":"{read_only:false; response_revision:16619; number_of_response:1; }","duration":"128.794801ms","start":"2026-09-04T03:49:15.855531Z","end":"2026-09-04T03:49:15.984325Z","steps":["trace[544366253] 'process raft request' (duration: 128.535284ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:49:15.984577Z","caller":"traceutil/trace.go:171","msg":"trace[5103483] transaction","detail":"{read_only:false; response_revision:16620; number_of_response:1; }","duration":"115.492784ms","start":"2026-09-04T03:49:15.869057Z","end":"2026-09-04T03:49:15.984549Z","steps":["trace[5103483] 'process raft request' (duration: 115.225886ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:50:16.342468Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14571} {"level":"info","ts":"2026-09-04T03:50:16.429879Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14571,"took":"84.245464ms","hash":3216257891,"current-db-size-bytes":52011008,"current-db-size":"52 MB","current-db-size-in-use-bytes":30838784,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-04T03:50:16.429953Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3216257891,"revision":14571,"compact-revision":12535} {"level":"warn","ts":"2026-09-04T03:54:48.785124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.550438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-04T03:54:48.785414Z","caller":"traceutil/trace.go:171","msg":"trace[1364456036] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19012; }","duration":"252.850736ms","start":"2026-09-04T03:54:48.532541Z","end":"2026-09-04T03:54:48.785392Z","steps":["trace[1364456036] 'range keys from in-memory index tree' (duration: 252.318021ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:54:56.175091Z","caller":"traceutil/trace.go:171","msg":"trace[1774044067] transaction","detail":"{read_only:false; response_revision:19051; number_of_response:1; }","duration":"168.126617ms","start":"2026-09-04T03:54:56.006859Z","end":"2026-09-04T03:54:56.174985Z","steps":["trace[1774044067] 'process raft request' (duration: 167.744315ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:54:56.661568Z","caller":"traceutil/trace.go:171","msg":"trace[1691344637] linearizableReadLoop","detail":"{readStateIndex:19703; appliedIndex:19702; }","duration":"243.967246ms","start":"2026-09-04T03:54:56.417561Z","end":"2026-09-04T03:54:56.661529Z","steps":["trace[1691344637] 'read index received' (duration: 243.535814ms)","trace[1691344637] 'applied index is now lower than readState.Index' (duration: 429.112µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:54:56.661823Z","caller":"traceutil/trace.go:171","msg":"trace[1282499474] transaction","detail":"{read_only:false; response_revision:19053; number_of_response:1; }","duration":"362.558781ms","start":"2026-09-04T03:54:56.299217Z","end":"2026-09-04T03:54:56.661775Z","steps":["trace[1282499474] 'process raft request' (duration: 362.023346ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:54:56.661994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.707535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-04T03:54:56.662058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:54:56.299177Z","time spent":"362.761987ms","remote":"127.0.0.1:38780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T03:54:56.66206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.563796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-04T03:54:56.662097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.859426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-04T03:54:56.662104Z","caller":"traceutil/trace.go:171","msg":"trace[1687622486] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:19053; }","duration":"178.847229ms","start":"2026-09-04T03:54:56.483209Z","end":"2026-09-04T03:54:56.662056Z","steps":["trace[1687622486] 'agreement among raft nodes before linearized reading' (duration: 178.690355ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:54:56.662125Z","caller":"traceutil/trace.go:171","msg":"trace[2114051361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19053; }","duration":"229.652921ms","start":"2026-09-04T03:54:56.432457Z","end":"2026-09-04T03:54:56.66211Z","steps":["trace[2114051361] 'agreement among raft nodes before linearized reading' (duration: 229.427744ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:54:56.66215Z","caller":"traceutil/trace.go:171","msg":"trace[58648174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19053; }","duration":"228.93081ms","start":"2026-09-04T03:54:56.433205Z","end":"2026-09-04T03:54:56.662136Z","steps":["trace[58648174] 'agreement among raft nodes before linearized reading' (duration: 228.726923ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:54:56.66227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.704529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-09-04T03:54:56.662454Z","caller":"traceutil/trace.go:171","msg":"trace[444208400] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19053; }","duration":"244.929965ms","start":"2026-09-04T03:54:56.417502Z","end":"2026-09-04T03:54:56.662432Z","steps":["trace[444208400] 'agreement among raft nodes before linearized reading' (duration: 244.357918ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:54:57.280322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.456687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-04T03:54:57.280517Z","caller":"traceutil/trace.go:171","msg":"trace[975803473] transaction","detail":"{read_only:false; response_revision:19056; number_of_response:1; }","duration":"460.883265ms","start":"2026-09-04T03:54:56.819616Z","end":"2026-09-04T03:54:57.280499Z","steps":["trace[975803473] 'process raft request' (duration: 460.817453ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:54:57.280609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:54:56.819583Z","time spent":"460.966277ms","remote":"127.0.0.1:38780","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-04T03:54:57.280842Z","caller":"traceutil/trace.go:171","msg":"trace[1108517376] transaction","detail":"{read_only:false; response_revision:19055; number_of_response:1; }","duration":"612.308586ms","start":"2026-09-04T03:54:56.66852Z","end":"2026-09-04T03:54:57.280829Z","steps":["trace[1108517376] 'process raft request' (duration: 350.252143ms)","trace[1108517376] 'compare' (duration: 261.22857ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:54:57.281162Z","caller":"traceutil/trace.go:171","msg":"trace[939515628] linearizableReadLoop","detail":"{readStateIndex:19705; appliedIndex:19703; }","duration":"557.562003ms","start":"2026-09-04T03:54:56.723558Z","end":"2026-09-04T03:54:57.28112Z","steps":["trace[939515628] 'read index received' (duration: 11.139754ms)","trace[939515628] 'applied index is now lower than readState.Index' (duration: 546.418518ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T03:54:57.281221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:54:56.66849Z","time spent":"612.373608ms","remote":"127.0.0.1:38780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T03:54:57.281338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.775318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-04T03:54:57.281751Z","caller":"traceutil/trace.go:171","msg":"trace[2049832206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19056; }","duration":"558.151489ms","start":"2026-09-04T03:54:56.723513Z","end":"2026-09-04T03:54:57.281665Z","steps":["trace[2049832206] 'agreement among raft nodes before linearized reading' (duration: 557.764178ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:54:57.281492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.23628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-04T03:54:57.281906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:54:56.723484Z","time spent":"558.382996ms","remote":"127.0.0.1:38468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-04T03:54:57.281944Z","caller":"traceutil/trace.go:171","msg":"trace[2034980787] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19056; }","duration":"445.713833ms","start":"2026-09-04T03:54:56.836208Z","end":"2026-09-04T03:54:57.281922Z","steps":["trace[2034980787] 'agreement among raft nodes before linearized reading' (duration: 445.183508ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:54:57.281987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T03:54:56.836186Z","time spent":"445.788656ms","remote":"127.0.0.1:38780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-04T03:55:05.61031Z","caller":"traceutil/trace.go:171","msg":"trace[418762617] transaction","detail":"{read_only:false; response_revision:19169; number_of_response:1; }","duration":"131.709276ms","start":"2026-09-04T03:55:05.478571Z","end":"2026-09-04T03:55:05.61028Z","steps":["trace[418762617] 'process raft request' (duration: 131.409398ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:55:05.610424Z","caller":"traceutil/trace.go:171","msg":"trace[403326976] transaction","detail":"{read_only:false; response_revision:19170; number_of_response:1; }","duration":"128.07365ms","start":"2026-09-04T03:55:05.48229Z","end":"2026-09-04T03:55:05.610364Z","steps":["trace[403326976] 'process raft request' (duration: 127.890845ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:55:05.610445Z","caller":"traceutil/trace.go:171","msg":"trace[962350766] transaction","detail":"{read_only:false; response_revision:19171; number_of_response:1; }","duration":"127.901385ms","start":"2026-09-04T03:55:05.482514Z","end":"2026-09-04T03:55:05.610416Z","steps":["trace[962350766] 'process raft request' (duration: 127.738261ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:55:05.61037Z","caller":"traceutil/trace.go:171","msg":"trace[39069227] linearizableReadLoop","detail":"{readStateIndex:19829; appliedIndex:19825; }","duration":"103.572856ms","start":"2026-09-04T03:55:05.506778Z","end":"2026-09-04T03:55:05.610351Z","steps":["trace[39069227] 'read index received' (duration: 103.121584ms)","trace[39069227] 'applied index is now lower than readState.Index' (duration: 450.472µs)"],"step_count":2} {"level":"info","ts":"2026-09-04T03:55:05.611007Z","caller":"traceutil/trace.go:171","msg":"trace[1321737879] transaction","detail":"{read_only:false; response_revision:19172; number_of_response:1; }","duration":"126.463774ms","start":"2026-09-04T03:55:05.484528Z","end":"2026-09-04T03:55:05.610992Z","steps":["trace[1321737879] 'process raft request' (duration: 125.779153ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T03:55:05.615325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.152257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-09-04T03:55:05.615723Z","caller":"traceutil/trace.go:171","msg":"trace[68122061] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19172; }","duration":"136.936149ms","start":"2026-09-04T03:55:05.478464Z","end":"2026-09-04T03:55:05.6154Z","steps":["trace[68122061] 'agreement among raft nodes before linearized reading' (duration: 131.959244ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T03:55:16.352562Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16996} {"level":"info","ts":"2026-09-04T03:55:16.47367Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16996,"took":"115.933657ms","hash":18271437,"current-db-size-bytes":52011008,"current-db-size":"52 MB","current-db-size-in-use-bytes":31797248,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-04T03:55:16.47379Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":18271437,"revision":16996,"compact-revision":14571} {"level":"info","ts":"2026-09-04T03:55:21.575429Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db28a37eabf8270","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-04T03:55:21.585621Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-04T03:55:21.585784Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-04T04:00:16.365931Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19292} {"level":"info","ts":"2026-09-04T04:00:16.475897Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19292,"took":"105.231125ms","hash":2226565736,"current-db-size-bytes":52011008,"current-db-size":"52 MB","current-db-size-in-use-bytes":34775040,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-09-04T04:00:16.475981Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2226565736,"revision":19292,"compact-revision":16996} {"level":"info","ts":"2026-09-04T04:02:16.767101Z","caller":"traceutil/trace.go:171","msg":"trace[1167655291] transaction","detail":"{read_only:false; response_revision:22390; number_of_response:1; }","duration":"125.419432ms","start":"2026-09-04T04:02:16.641622Z","end":"2026-09-04T04:02:16.767042Z","steps":["trace[1167655291] 'process raft request' (duration: 125.232447ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T04:02:16.961484Z","caller":"traceutil/trace.go:171","msg":"trace[847815549] transaction","detail":"{read_only:false; response_revision:22391; number_of_response:1; }","duration":"189.459865ms","start":"2026-09-04T04:02:16.771987Z","end":"2026-09-04T04:02:16.961446Z","steps":["trace[847815549] 'process raft request' (duration: 164.960822ms)","trace[847815549] 'compare' (duration: 23.963618ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T04:02:16.961675Z","caller":"traceutil/trace.go:171","msg":"trace[1265574679] transaction","detail":"{read_only:false; response_revision:22392; number_of_response:1; }","duration":"170.05173ms","start":"2026-09-04T04:02:16.791586Z","end":"2026-09-04T04:02:16.961638Z","steps":["trace[1265574679] 'process raft request' (duration: 169.756021ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T04:02:33.432784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.118284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-04T04:02:33.432903Z","caller":"traceutil/trace.go:171","msg":"trace[1907904469] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:22473; }","duration":"123.279929ms","start":"2026-09-04T04:02:33.309597Z","end":"2026-09-04T04:02:33.432877Z","steps":["trace[1907904469] 'count revisions from in-memory index tree' (duration: 122.982301ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T04:02:33.610314Z","caller":"traceutil/trace.go:171","msg":"trace[521903365] transaction","detail":"{read_only:false; response_revision:22475; number_of_response:1; }","duration":"168.28438ms","start":"2026-09-04T04:02:33.441995Z","end":"2026-09-04T04:02:33.610279Z","steps":["trace[521903365] 'process raft request' (duration: 151.272415ms)","trace[521903365] 'compare' (duration: 16.790308ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T04:02:33.610574Z","caller":"traceutil/trace.go:171","msg":"trace[1904047972] linearizableReadLoop","detail":"{readStateIndex:23239; appliedIndex:23237; }","duration":"131.331673ms","start":"2026-09-04T04:02:33.479183Z","end":"2026-09-04T04:02:33.610515Z","steps":["trace[1904047972] 'read index received' (duration: 114.192225ms)","trace[1904047972] 'applied index is now lower than readState.Index' (duration: 17.136658ms)"],"step_count":2} {"level":"info","ts":"2026-09-04T04:02:33.610615Z","caller":"traceutil/trace.go:171","msg":"trace[1383428352] transaction","detail":"{read_only:false; response_revision:22476; number_of_response:1; }","duration":"164.447628ms","start":"2026-09-04T04:02:33.446125Z","end":"2026-09-04T04:02:33.610572Z","steps":["trace[1383428352] 'process raft request' (duration: 164.099378ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T04:02:33.61072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.518496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-09-04T04:02:33.610782Z","caller":"traceutil/trace.go:171","msg":"trace[682929279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22476; }","duration":"189.65571ms","start":"2026-09-04T04:02:33.421109Z","end":"2026-09-04T04:02:33.610764Z","steps":["trace[682929279] 'agreement among raft nodes before linearized reading' (duration: 189.453155ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T04:02:33.610913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.012154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-04T04:02:33.610962Z","caller":"traceutil/trace.go:171","msg":"trace[1100980320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22476; }","duration":"210.107667ms","start":"2026-09-04T04:02:33.400837Z","end":"2026-09-04T04:02:33.610944Z","steps":["trace[1100980320] 'agreement among raft nodes before linearized reading' (duration: 209.86281ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T04:02:33.676649Z","caller":"traceutil/trace.go:171","msg":"trace[540798377] transaction","detail":"{read_only:false; response_revision:22477; number_of_response:1; }","duration":"188.805287ms","start":"2026-09-04T04:02:33.487812Z","end":"2026-09-04T04:02:33.676618Z","steps":["trace[540798377] 'process raft request' (duration: 188.622561ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T04:02:33.954036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.088749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-04T04:02:33.954774Z","caller":"traceutil/trace.go:171","msg":"trace[835474794] transaction","detail":"{read_only:false; response_revision:22479; number_of_response:1; }","duration":"338.391452ms","start":"2026-09-04T04:02:33.616352Z","end":"2026-09-04T04:02:33.954743Z","steps":["trace[835474794] 'process raft request' (duration: 337.842705ms)"],"step_count":1} {"level":"warn","ts":"2026-09-04T04:02:33.954875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T04:02:33.616331Z","time spent":"338.503325ms","remote":"127.0.0.1:38780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-04T04:02:33.954862Z","caller":"traceutil/trace.go:171","msg":"trace[1521576117] transaction","detail":"{read_only:false; response_revision:22478; number_of_response:1; }","duration":"339.276608ms","start":"2026-09-04T04:02:33.615548Z","end":"2026-09-04T04:02:33.954825Z","steps":["trace[1521576117] 'process raft request' (duration: 199.275382ms)","trace[1521576117] 'compare' (duration: 138.867913ms)"],"step_count":2} {"level":"warn","ts":"2026-09-04T04:02:33.955668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-04T04:02:33.615522Z","time spent":"339.422392ms","remote":"127.0.0.1:38780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-04T04:02:34.233531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.284783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-04T04:02:34.233614Z","caller":"traceutil/trace.go:171","msg":"trace[1765078586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22480; }","duration":"152.434358ms","start":"2026-09-04T04:02:34.081159Z","end":"2026-09-04T04:02:34.233594Z","steps":["trace[1765078586] 'range keys from in-memory index tree' (duration: 152.067777ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T04:05:16.373594Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21664} {"level":"info","ts":"2026-09-04T04:05:16.4986Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21664,"took":"119.594982ms","hash":971390710,"current-db-size-bytes":52011008,"current-db-size":"52 MB","current-db-size-in-use-bytes":29999104,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-04T04:05:16.498699Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":971390710,"revision":21664,"compact-revision":19292} {"level":"info","ts":"2026-09-04T04:06:51.982042Z","caller":"traceutil/trace.go:171","msg":"trace[2088720387] transaction","detail":"{read_only:false; response_revision:25077; number_of_response:1; }","duration":"114.830022ms","start":"2026-09-04T04:06:51.867177Z","end":"2026-09-04T04:06:51.982007Z","steps":["trace[2088720387] 'process raft request' (duration: 114.625446ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T04:06:52.348639Z","caller":"traceutil/trace.go:171","msg":"trace[813734645] transaction","detail":"{read_only:false; response_revision:25078; number_of_response:1; }","duration":"114.514943ms","start":"2026-09-04T04:06:52.234063Z","end":"2026-09-04T04:06:52.348578Z","steps":["trace[813734645] 'process raft request' (duration: 114.316917ms)"],"step_count":1} {"level":"info","ts":"2026-09-04T04:08:24.866489Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006918.wal"} {"level":"info","ts":"2026-09-04T04:10:16.381537Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23765} {"level":"info","ts":"2026-09-04T04:10:16.516074Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23765,"took":"129.060327ms","hash":504819348,"current-db-size-bytes":52432896,"current-db-size":"52 MB","current-db-size-in-use-bytes":42844160,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-09-04T04:10:16.516165Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":504819348,"revision":23765,"compact-revision":21664} {"level":"info","ts":"2026-09-04T04:15:06.916511Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"db28a37eabf8270","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-04T04:15:06.924052Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-04T04:15:06.924345Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-04T04:15:16.388973Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26911} {"level":"info","ts":"2026-09-04T04:15:16.564Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26911,"took":"166.904911ms","hash":3185814406,"current-db-size-bytes":52432896,"current-db-size":"52 MB","current-db-size-in-use-bytes":30449664,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-04T04:15:16.564075Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3185814406,"revision":26911,"compact-revision":23765}