{"level":"warn","ts":"2026-08-28T04:05:04.852621Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-28T04:05:04.852722Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.31: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.31:2380","--initial-cluster=instance=https://162.253.55.31:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.31:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.31: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-08-28T04:05:04.85293Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-28T04:05:04.852943Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.31:2380"]} {"level":"info","ts":"2026-08-28T04:05:04.852966Z","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-08-28T04:05:04.853969Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.31:2379"]} {"level":"info","ts":"2026-08-28T04:05:04.854093Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.31:2380"],"listen-peer-urls":["https://162.253.55.31:2380"],"advertise-client-urls":["https://162.253.55.31:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.31:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.31: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-08-28T04:05:04.858993Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.368317ms"} {"level":"info","ts":"2026-08-28T04:05:04.873446Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"de05d1e64231de42","cluster-id":"4f2999739a68ff64"} {"level":"info","ts":"2026-08-28T04:05:04.873534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 switched to configuration voters=()"} {"level":"info","ts":"2026-08-28T04:05:04.873558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became follower at term 0"} {"level":"info","ts":"2026-08-28T04:05:04.873573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft de05d1e64231de42 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-28T04:05:04.873584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became follower at term 1"} {"level":"info","ts":"2026-08-28T04:05:04.87362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 switched to configuration voters=(15998424038186802754)"} {"level":"warn","ts":"2026-08-28T04:05:04.881721Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-28T04:05:04.883204Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-28T04:05:04.884443Z","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-08-28T04:05:04.88664Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"de05d1e64231de42","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-28T04:05:04.886892Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"de05d1e64231de42","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-28T04:05:04.887067Z","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-08-28T04:05:04.88711Z","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-08-28T04:05:04.887119Z","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-08-28T04:05:04.887595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 switched to configuration voters=(15998424038186802754)"} {"level":"info","ts":"2026-08-28T04:05:04.887726Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4f2999739a68ff64","local-member-id":"de05d1e64231de42","added-peer-id":"de05d1e64231de42","added-peer-peer-urls":["https://162.253.55.31:2380"]} {"level":"info","ts":"2026-08-28T04:05:04.892753Z","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-08-28T04:05:04.892879Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.31:2380"} {"level":"info","ts":"2026-08-28T04:05:04.892924Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.31:2380"} {"level":"info","ts":"2026-08-28T04:05:04.893115Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"de05d1e64231de42","initial-advertise-peer-urls":["https://162.253.55.31:2380"],"listen-peer-urls":["https://162.253.55.31:2380"],"advertise-client-urls":["https://162.253.55.31:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.31:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-28T04:05:04.893166Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-28T04:05:05.774094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 is starting a new election at term 1"} {"level":"info","ts":"2026-08-28T04:05:05.774173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-28T04:05:05.774217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 received MsgPreVoteResp from de05d1e64231de42 at term 1"} {"level":"info","ts":"2026-08-28T04:05:05.774236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became candidate at term 2"} {"level":"info","ts":"2026-08-28T04:05:05.774248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 received MsgVoteResp from de05d1e64231de42 at term 2"} {"level":"info","ts":"2026-08-28T04:05:05.77427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de05d1e64231de42 became leader at term 2"} {"level":"info","ts":"2026-08-28T04:05:05.774296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: de05d1e64231de42 elected leader de05d1e64231de42 at term 2"} {"level":"info","ts":"2026-08-28T04:05:05.775755Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T04:05:05.776873Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"de05d1e64231de42","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.31:2379]}","request-path":"/0/members/de05d1e64231de42/attributes","cluster-id":"4f2999739a68ff64","publish-timeout":"7s"} {"level":"info","ts":"2026-08-28T04:05:05.776895Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-28T04:05:05.776958Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-28T04:05:05.777167Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-28T04:05:05.777201Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-28T04:05:05.777449Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4f2999739a68ff64","local-member-id":"de05d1e64231de42","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T04:05:05.777577Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T04:05:05.777611Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T04:05:05.780116Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-28T04:05:05.780943Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.31:2379"} {"level":"info","ts":"2026-08-28T04:05:29.826053Z","caller":"traceutil/trace.go:171","msg":"trace[1415878006] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"162.209007ms","start":"2026-08-28T04:05:29.663821Z","end":"2026-08-28T04:05:29.82603Z","steps":["trace[1415878006] 'read index received' (duration: 162.00702ms)","trace[1415878006] 'applied index is now lower than readState.Index' (duration: 200.947µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:05:29.826216Z","caller":"traceutil/trace.go:171","msg":"trace[59371901] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"171.970583ms","start":"2026-08-28T04:05:29.654217Z","end":"2026-08-28T04:05:29.826188Z","steps":["trace[59371901] 'process raft request' (duration: 171.679248ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:05:29.826345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.491302ms","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-08-28T04:05:29.826548Z","caller":"traceutil/trace.go:171","msg":"trace[932329544] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:425; }","duration":"162.706308ms","start":"2026-08-28T04:05:29.663798Z","end":"2026-08-28T04:05:29.826504Z","steps":["trace[932329544] 'agreement among raft nodes before linearized reading' (duration: 162.431573ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:05:45.278597Z","caller":"traceutil/trace.go:171","msg":"trace[1940564964] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"109.771993ms","start":"2026-08-28T04:05:45.168798Z","end":"2026-08-28T04:05:45.27857Z","steps":["trace[1940564964] 'process raft request' (duration: 96.963985ms)","trace[1940564964] 'compare' (duration: 12.705778ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:05:45.283978Z","caller":"traceutil/trace.go:171","msg":"trace[1998236204] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"113.89712ms","start":"2026-08-28T04:05:45.170037Z","end":"2026-08-28T04:05:45.283935Z","steps":["trace[1998236204] 'process raft request' (duration: 113.624709ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:05:45.284312Z","caller":"traceutil/trace.go:171","msg":"trace[2027152331] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"109.508301ms","start":"2026-08-28T04:05:45.17479Z","end":"2026-08-28T04:05:45.284298Z","steps":["trace[2027152331] 'process raft request' (duration: 109.326761ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:05:45.284418Z","caller":"traceutil/trace.go:171","msg":"trace[504759742] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"109.675952ms","start":"2026-08-28T04:05:45.174733Z","end":"2026-08-28T04:05:45.284409Z","steps":["trace[504759742] 'process raft request' (duration: 109.31274ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:05:45.284452Z","caller":"traceutil/trace.go:171","msg":"trace[993696532] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"109.918663ms","start":"2026-08-28T04:05:45.174523Z","end":"2026-08-28T04:05:45.284442Z","steps":["trace[993696532] 'process raft request' (duration: 109.214539ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:05:45.284969Z","caller":"traceutil/trace.go:171","msg":"trace[601098882] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"110.130514ms","start":"2026-08-28T04:05:45.174809Z","end":"2026-08-28T04:05:45.28494Z","steps":["trace[601098882] 'process raft request' (duration: 109.447471ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:05:45.285096Z","caller":"traceutil/trace.go:171","msg":"trace[642408997] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"108.053324ms","start":"2026-08-28T04:05:45.177034Z","end":"2026-08-28T04:05:45.285087Z","steps":["trace[642408997] 'process raft request' (duration: 107.525632ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:05:45.285194Z","caller":"traceutil/trace.go:171","msg":"trace[132157325] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"108.116605ms","start":"2026-08-28T04:05:45.177069Z","end":"2026-08-28T04:05:45.285186Z","steps":["trace[132157325] 'process raft request' (duration: 107.527402ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:05:45.285255Z","caller":"traceutil/trace.go:171","msg":"trace[158296004] transaction","detail":"{read_only:false; number_of_response:1; response_revision:513; }","duration":"108.147824ms","start":"2026-08-28T04:05:45.177101Z","end":"2026-08-28T04:05:45.285248Z","steps":["trace[158296004] 'process raft request' (duration: 107.533092ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:05:45.285354Z","caller":"traceutil/trace.go:171","msg":"trace[1955307176] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"105.01054ms","start":"2026-08-28T04:05:45.180335Z","end":"2026-08-28T04:05:45.285345Z","steps":["trace[1955307176] 'process raft request' (duration: 104.355448ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:05:45.285429Z","caller":"traceutil/trace.go:171","msg":"trace[1389961055] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"103.493172ms","start":"2026-08-28T04:05:45.181922Z","end":"2026-08-28T04:05:45.285415Z","steps":["trace[1389961055] 'process raft request' (duration: 102.81444ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:05:53.192744Z","caller":"traceutil/trace.go:171","msg":"trace[133871183] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"138.368569ms","start":"2026-08-28T04:05:53.054345Z","end":"2026-08-28T04:05:53.192714Z","steps":["trace[133871183] 'read index received' (duration: 137.005401ms)","trace[133871183] 'applied index is now lower than readState.Index' (duration: 1.362008ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:05:53.19284Z","caller":"traceutil/trace.go:171","msg":"trace[436615348] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"288.708764ms","start":"2026-08-28T04:05:52.904084Z","end":"2026-08-28T04:05:53.192793Z","steps":["trace[436615348] 'process raft request' (duration: 287.755951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:05:53.193121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.756984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fdjcr\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-28T04:05:53.193196Z","caller":"traceutil/trace.go:171","msg":"trace[1033894311] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fdjcr; range_end:; response_count:1; response_revision:599; }","duration":"138.880246ms","start":"2026-08-28T04:05:53.054295Z","end":"2026-08-28T04:05:53.193175Z","steps":["trace[1033894311] 'agreement among raft nodes before linearized reading' (duration: 138.614063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:05:53.806523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.506264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:05:53.806906Z","caller":"traceutil/trace.go:171","msg":"trace[678524597] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:623; }","duration":"229.284358ms","start":"2026-08-28T04:05:53.577539Z","end":"2026-08-28T04:05:53.806823Z","steps":["trace[678524597] 'read index received' (duration: 56.914592ms)","trace[678524597] 'applied index is now lower than readState.Index' (duration: 172.368386ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:05:53.806913Z","caller":"traceutil/trace.go:171","msg":"trace[147090196] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"595.689238ms","start":"2026-08-28T04:05:53.211149Z","end":"2026-08-28T04:05:53.806838Z","steps":["trace[147090196] 'process raft request' (duration: 322.992934ms)","trace[147090196] 'compare' (duration: 270.272991ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:05:53.806994Z","caller":"traceutil/trace.go:171","msg":"trace[1836906563] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"547.496076ms","start":"2026-08-28T04:05:53.259455Z","end":"2026-08-28T04:05:53.806951Z","steps":["trace[1836906563] 'process raft request' (duration: 547.206102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:05:53.807137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:05:53.259416Z","time spent":"547.649787ms","remote":"127.0.0.1:40596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:05:53.807194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:05:53.211124Z","time spent":"595.909921ms","remote":"127.0.0.1:40440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T04:05:53.808189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.641476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-28T04:05:53.808244Z","caller":"traceutil/trace.go:171","msg":"trace[313370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"230.738797ms","start":"2026-08-28T04:05:53.577491Z","end":"2026-08-28T04:05:53.80823Z","steps":["trace[313370] 'agreement among raft nodes before linearized reading' (duration: 229.565191ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:06:10.347988Z","caller":"traceutil/trace.go:171","msg":"trace[304702200] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:805; }","duration":"284.950511ms","start":"2026-08-28T04:06:10.063017Z","end":"2026-08-28T04:06:10.347968Z","steps":["trace[304702200] 'read index received' (duration: 284.757724ms)","trace[304702200] 'applied index is now lower than readState.Index' (duration: 191.827µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:06:10.348105Z","caller":"traceutil/trace.go:171","msg":"trace[1480597076] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"285.536618ms","start":"2026-08-28T04:06:10.062545Z","end":"2026-08-28T04:06:10.348081Z","steps":["trace[1480597076] 'process raft request' (duration: 285.25243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:06:10.348177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.650789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-28T04:06:10.348202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.177547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-28T04:06:10.348241Z","caller":"traceutil/trace.go:171","msg":"trace[979544776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"204.742121ms","start":"2026-08-28T04:06:10.143479Z","end":"2026-08-28T04:06:10.348221Z","steps":["trace[979544776] 'agreement among raft nodes before linearized reading' (duration: 204.640658ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:06:10.348247Z","caller":"traceutil/trace.go:171","msg":"trace[97147663] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:777; }","duration":"285.247349ms","start":"2026-08-28T04:06:10.062986Z","end":"2026-08-28T04:06:10.348233Z","steps":["trace[97147663] 'agreement among raft nodes before linearized reading' (duration: 285.109385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:06:10.348371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.357502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ck6bz\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-08-28T04:06:10.348412Z","caller":"traceutil/trace.go:171","msg":"trace[666810673] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ck6bz; range_end:; response_count:1; response_revision:777; }","duration":"187.423664ms","start":"2026-08-28T04:06:10.160976Z","end":"2026-08-28T04:06:10.3484Z","steps":["trace[666810673] 'agreement among raft nodes before linearized reading' (duration: 187.332822ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:06:10.467827Z","caller":"traceutil/trace.go:171","msg":"trace[1724390160] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"114.658679ms","start":"2026-08-28T04:06:10.353131Z","end":"2026-08-28T04:06:10.467789Z","steps":["trace[1724390160] 'process raft request' (duration: 75.843028ms)","trace[1724390160] 'compare' (duration: 38.642886ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:06:10.470487Z","caller":"traceutil/trace.go:171","msg":"trace[392405443] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"117.315168ms","start":"2026-08-28T04:06:10.353153Z","end":"2026-08-28T04:06:10.470468Z","steps":["trace[392405443] 'process raft request' (duration: 117.177034ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:06:10.470639Z","caller":"traceutil/trace.go:171","msg":"trace[1072993758] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"112.73477ms","start":"2026-08-28T04:06:10.357874Z","end":"2026-08-28T04:06:10.470609Z","steps":["trace[1072993758] 'process raft request' (duration: 112.548394ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:06:44.749989Z","caller":"traceutil/trace.go:171","msg":"trace[502672440] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1236; }","duration":"233.886121ms","start":"2026-08-28T04:06:44.516078Z","end":"2026-08-28T04:06:44.749964Z","steps":["trace[502672440] 'read index received' (duration: 225.699017ms)","trace[502672440] 'applied index is now lower than readState.Index' (duration: 8.185294ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:06:44.750217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.086292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-28T04:06:44.750303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.711157ms","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-08-28T04:06:44.750343Z","caller":"traceutil/trace.go:171","msg":"trace[1406199831] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1198; }","duration":"230.77592ms","start":"2026-08-28T04:06:44.519555Z","end":"2026-08-28T04:06:44.750331Z","steps":["trace[1406199831] 'agreement among raft nodes before linearized reading' (duration: 230.594881ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:06:44.750341Z","caller":"traceutil/trace.go:171","msg":"trace[19499902] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1198; }","duration":"234.329125ms","start":"2026-08-28T04:06:44.515982Z","end":"2026-08-28T04:06:44.750312Z","steps":["trace[19499902] 'agreement among raft nodes before linearized reading' (duration: 234.087873ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:07:35.134668Z","caller":"traceutil/trace.go:171","msg":"trace[1200510793] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"290.568766ms","start":"2026-08-28T04:07:34.84407Z","end":"2026-08-28T04:07:35.134639Z","steps":["trace[1200510793] 'process raft request' (duration: 290.254343ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:07:35.13503Z","caller":"traceutil/trace.go:171","msg":"trace[1281627027] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1947; }","duration":"136.344194ms","start":"2026-08-28T04:07:34.998604Z","end":"2026-08-28T04:07:35.134949Z","steps":["trace[1281627027] 'read index received' (duration: 135.57164ms)","trace[1281627027] 'applied index is now lower than readState.Index' (duration: 771.004µs)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:07:35.135196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.534426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:07:35.135285Z","caller":"traceutil/trace.go:171","msg":"trace[2041474954] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1873; }","duration":"136.731211ms","start":"2026-08-28T04:07:34.998533Z","end":"2026-08-28T04:07:35.135264Z","steps":["trace[2041474954] 'agreement among raft nodes before linearized reading' (duration: 136.522307ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:07:46.064016Z","caller":"traceutil/trace.go:171","msg":"trace[1365173108] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"111.622442ms","start":"2026-08-28T04:07:45.952333Z","end":"2026-08-28T04:07:46.063955Z","steps":["trace[1365173108] 'process raft request' (duration: 111.210593ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:07:47.414598Z","caller":"traceutil/trace.go:171","msg":"trace[1740381437] linearizableReadLoop","detail":"{readStateIndex:2050; appliedIndex:2049; }","duration":"168.343036ms","start":"2026-08-28T04:07:47.246224Z","end":"2026-08-28T04:07:47.414567Z","steps":["trace[1740381437] 'read index received' (duration: 168.12239ms)","trace[1740381437] 'applied index is now lower than readState.Index' (duration: 218.605µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:07:47.414675Z","caller":"traceutil/trace.go:171","msg":"trace[2118632155] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"175.70449ms","start":"2026-08-28T04:07:47.238942Z","end":"2026-08-28T04:07:47.414647Z","steps":["trace[2118632155] 'process raft request' (duration: 175.433221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:07:47.414832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.828436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-28T04:07:47.414858Z","caller":"traceutil/trace.go:171","msg":"trace[460333048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1972; }","duration":"164.890971ms","start":"2026-08-28T04:07:47.249961Z","end":"2026-08-28T04:07:47.414852Z","steps":["trace[460333048] 'agreement among raft nodes before linearized reading' (duration: 164.790114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:07:47.414857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.617845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-28T04:07:47.414918Z","caller":"traceutil/trace.go:171","msg":"trace[1900291861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1972; }","duration":"168.764417ms","start":"2026-08-28T04:07:47.246136Z","end":"2026-08-28T04:07:47.414901Z","steps":["trace[1900291861] 'agreement among raft nodes before linearized reading' (duration: 168.555712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:07:47.414887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.453224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-28T04:07:47.414954Z","caller":"traceutil/trace.go:171","msg":"trace[1154884344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1972; }","duration":"168.562141ms","start":"2026-08-28T04:07:47.246381Z","end":"2026-08-28T04:07:47.414943Z","steps":["trace[1154884344] 'agreement among raft nodes before linearized reading' (duration: 168.349816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:08:09.265258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.999731ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-08-28T04:08:09.265375Z","caller":"traceutil/trace.go:171","msg":"trace[241506399] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2189; }","duration":"114.949692ms","start":"2026-08-28T04:08:09.150408Z","end":"2026-08-28T04:08:09.265358Z","steps":["trace[241506399] 'read index received' (duration: 816.181µs)","trace[241506399] 'applied index is now lower than readState.Index' (duration: 114.132031ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:08:09.265564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.139487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:08:09.265644Z","caller":"traceutil/trace.go:171","msg":"trace[1156883319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2107; }","duration":"115.249714ms","start":"2026-08-28T04:08:09.150383Z","end":"2026-08-28T04:08:09.265632Z","steps":["trace[1156883319] 'agreement among raft nodes before linearized reading' (duration: 115.018837ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:08:09.476142Z","caller":"traceutil/trace.go:171","msg":"trace[780440755] linearizableReadLoop","detail":"{readStateIndex:2191; appliedIndex:2190; }","duration":"209.314643ms","start":"2026-08-28T04:08:09.266803Z","end":"2026-08-28T04:08:09.476118Z","steps":["trace[780440755] 'read index received' (duration: 209.077105ms)","trace[780440755] 'applied index is now lower than readState.Index' (duration: 236.378µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:08:09.476298Z","caller":"traceutil/trace.go:171","msg":"trace[1454390231] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"209.55042ms","start":"2026-08-28T04:08:09.266664Z","end":"2026-08-28T04:08:09.476214Z","steps":["trace[1454390231] 'process raft request' (duration: 209.263969ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:08:09.476455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.564731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:08:09.476508Z","caller":"traceutil/trace.go:171","msg":"trace[693478091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2108; }","duration":"209.730303ms","start":"2026-08-28T04:08:09.266763Z","end":"2026-08-28T04:08:09.476493Z","steps":["trace[693478091] 'agreement among raft nodes before linearized reading' (duration: 209.441911ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:08:09.79332Z","caller":"traceutil/trace.go:171","msg":"trace[1413674968] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"269.241835ms","start":"2026-08-28T04:08:09.524046Z","end":"2026-08-28T04:08:09.793288Z","steps":["trace[1413674968] 'process raft request' (duration: 193.301959ms)","trace[1413674968] 'compare' (duration: 75.347393ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:08:09.793338Z","caller":"traceutil/trace.go:171","msg":"trace[1547932785] linearizableReadLoop","detail":"{readStateIndex:2192; appliedIndex:2191; }","duration":"262.45884ms","start":"2026-08-28T04:08:09.530646Z","end":"2026-08-28T04:08:09.793105Z","steps":["trace[1547932785] 'read index received' (duration: 186.692116ms)","trace[1547932785] 'applied index is now lower than readState.Index' (duration: 75.765834ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:08:09.793616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.953487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-28T04:08:09.793917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.849987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-28T04:08:09.79408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.893275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-28T04:08:09.794161Z","caller":"traceutil/trace.go:171","msg":"trace[29013672] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2109; }","duration":"172.017904ms","start":"2026-08-28T04:08:09.622122Z","end":"2026-08-28T04:08:09.79414Z","steps":["trace[29013672] 'agreement among raft nodes before linearized reading' (duration: 171.786757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:08:09.7942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.990924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-28T04:08:09.794117Z","caller":"traceutil/trace.go:171","msg":"trace[346710275] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2109; }","duration":"153.081653ms","start":"2026-08-28T04:08:09.641017Z","end":"2026-08-28T04:08:09.794099Z","steps":["trace[346710275] 'agreement among raft nodes before linearized reading' (duration: 152.851056ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:08:09.79398Z","caller":"traceutil/trace.go:171","msg":"trace[1072205978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2109; }","duration":"263.306054ms","start":"2026-08-28T04:08:09.53061Z","end":"2026-08-28T04:08:09.793916Z","steps":["trace[1072205978] 'agreement among raft nodes before linearized reading' (duration: 262.764783ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:08:09.794272Z","caller":"traceutil/trace.go:171","msg":"trace[1159234289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2109; }","duration":"257.104032ms","start":"2026-08-28T04:08:09.537131Z","end":"2026-08-28T04:08:09.794235Z","steps":["trace[1159234289] 'agreement among raft nodes before linearized reading' (duration: 256.889837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:08:09.794472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.984918ms","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-08-28T04:08:09.794588Z","caller":"traceutil/trace.go:171","msg":"trace[646424885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2109; }","duration":"258.467352ms","start":"2026-08-28T04:08:09.536079Z","end":"2026-08-28T04:08:09.794547Z","steps":["trace[646424885] 'agreement among raft nodes before linearized reading' (duration: 257.76497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:09:52.767736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.776661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-28T04:09:52.767955Z","caller":"traceutil/trace.go:171","msg":"trace[226555031] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2768; }","duration":"140.112764ms","start":"2026-08-28T04:09:52.627819Z","end":"2026-08-28T04:09:52.767932Z","steps":["trace[226555031] 'count revisions from in-memory index tree' (duration: 139.643884ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:09:53.229857Z","caller":"traceutil/trace.go:171","msg":"trace[691748313] transaction","detail":"{read_only:false; response_revision:2770; number_of_response:1; }","duration":"172.667036ms","start":"2026-08-28T04:09:53.057159Z","end":"2026-08-28T04:09:53.229826Z","steps":["trace[691748313] 'process raft request' (duration: 172.44854ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:09:53.332228Z","caller":"traceutil/trace.go:171","msg":"trace[502857933] linearizableReadLoop","detail":"{readStateIndex:2878; appliedIndex:2875; }","duration":"187.042252ms","start":"2026-08-28T04:09:53.145168Z","end":"2026-08-28T04:09:53.33221Z","steps":["trace[502857933] 'read index received' (duration: 84.465468ms)","trace[502857933] 'applied index is now lower than readState.Index' (duration: 102.576074ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:09:53.332475Z","caller":"traceutil/trace.go:171","msg":"trace[1214707157] transaction","detail":"{read_only:false; response_revision:2771; number_of_response:1; }","duration":"272.294995ms","start":"2026-08-28T04:09:53.060168Z","end":"2026-08-28T04:09:53.332463Z","steps":["trace[1214707157] 'process raft request' (duration: 271.899023ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:09:53.33265Z","caller":"traceutil/trace.go:171","msg":"trace[1068692568] transaction","detail":"{read_only:false; response_revision:2772; number_of_response:1; }","duration":"272.119568ms","start":"2026-08-28T04:09:53.06052Z","end":"2026-08-28T04:09:53.33264Z","steps":["trace[1068692568] 'process raft request' (duration: 271.653438ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:09:53.332893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.710147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:09:53.332941Z","caller":"traceutil/trace.go:171","msg":"trace[159792257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2772; }","duration":"187.797536ms","start":"2026-08-28T04:09:53.145133Z","end":"2026-08-28T04:09:53.332931Z","steps":["trace[159792257] 'agreement among raft nodes before linearized reading' (duration: 187.715718ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:09:53.333141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.634493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-08-28T04:09:53.333174Z","caller":"traceutil/trace.go:171","msg":"trace[38366718] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2772; }","duration":"135.706152ms","start":"2026-08-28T04:09:53.19746Z","end":"2026-08-28T04:09:53.333166Z","steps":["trace[38366718] 'agreement among raft nodes before linearized reading' (duration: 135.600554ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:15:05.850595Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2835} {"level":"info","ts":"2026-08-28T04:15:06.070858Z","caller":"traceutil/trace.go:171","msg":"trace[696410828] linearizableReadLoop","detail":"{readStateIndex:5387; appliedIndex:5386; }","duration":"118.967067ms","start":"2026-08-28T04:15:05.951867Z","end":"2026-08-28T04:15:06.070834Z","steps":["trace[696410828] 'read index received' (duration: 86.067343ms)","trace[696410828] 'applied index is now lower than readState.Index' (duration: 32.898604ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:15:06.070908Z","caller":"traceutil/trace.go:171","msg":"trace[618153917] transaction","detail":"{read_only:false; response_revision:5185; number_of_response:1; }","duration":"137.925611ms","start":"2026-08-28T04:15:05.932949Z","end":"2026-08-28T04:15:06.070874Z","steps":["trace[618153917] 'process raft request' (duration: 105.043278ms)","trace[618153917] 'compare' (duration: 18.256614ms)","trace[618153917] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/openstack/08db1feb.percona.com; req_size:506; } (duration: 14.46922ms)"],"step_count":3} {"level":"warn","ts":"2026-08-28T04:15:06.071084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.204952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8b9a85e7-74e8-442f-9d17-924c85de2581\" ","response":"range_response_count:1 size:3951"} {"level":"info","ts":"2026-08-28T04:15:06.071124Z","caller":"traceutil/trace.go:171","msg":"trace[1631286594] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8b9a85e7-74e8-442f-9d17-924c85de2581; range_end:; response_count:1; response_revision:5185; }","duration":"119.292448ms","start":"2026-08-28T04:15:05.951821Z","end":"2026-08-28T04:15:06.071113Z","steps":["trace[1631286594] 'agreement among raft nodes before linearized reading' (duration: 119.142759ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:15:06.197997Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2835,"took":"345.468384ms","hash":1010056232,"current-db-size-bytes":52297728,"current-db-size":"52 MB","current-db-size-in-use-bytes":27353088,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-28T04:15:06.198075Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1010056232,"revision":2835,"compact-revision":-1} {"level":"info","ts":"2026-08-28T04:15:18.227414Z","caller":"traceutil/trace.go:171","msg":"trace[951464856] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"360.730311ms","start":"2026-08-28T04:15:17.866655Z","end":"2026-08-28T04:15:18.227385Z","steps":["trace[951464856] 'process raft request' (duration: 360.534028ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:15:18.22759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:15:17.866635Z","time spent":"360.864819ms","remote":"127.0.0.1:40440","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:15:18.228844Z","caller":"traceutil/trace.go:171","msg":"trace[878143005] linearizableReadLoop","detail":"{readStateIndex:5552; appliedIndex:5550; }","duration":"296.565211ms","start":"2026-08-28T04:15:17.932254Z","end":"2026-08-28T04:15:18.228819Z","steps":["trace[878143005] 'read index received' (duration: 295.001343ms)","trace[878143005] 'applied index is now lower than readState.Index' (duration: 1.562738ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:15:18.228939Z","caller":"traceutil/trace.go:171","msg":"trace[968468714] transaction","detail":"{read_only:false; response_revision:5345; number_of_response:1; }","duration":"112.652461ms","start":"2026-08-28T04:15:18.116254Z","end":"2026-08-28T04:15:18.228907Z","steps":["trace[968468714] 'process raft request' (duration: 112.519093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:15:18.229154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.888811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-28T04:15:18.229223Z","caller":"traceutil/trace.go:171","msg":"trace[1886316577] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5345; }","duration":"297.02972ms","start":"2026-08-28T04:15:17.932173Z","end":"2026-08-28T04:15:18.229203Z","steps":["trace[1886316577] 'agreement among raft nodes before linearized reading' (duration: 296.769924ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:15:18.229258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.580372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-08-28T04:15:18.229345Z","caller":"traceutil/trace.go:171","msg":"trace[1668988874] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5345; }","duration":"164.715461ms","start":"2026-08-28T04:15:18.064571Z","end":"2026-08-28T04:15:18.229287Z","steps":["trace[1668988874] 'agreement among raft nodes before linearized reading' (duration: 164.389711ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:15:18.229367Z","caller":"traceutil/trace.go:171","msg":"trace[420931735] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"341.041357ms","start":"2026-08-28T04:15:17.888308Z","end":"2026-08-28T04:15:18.22935Z","steps":["trace[420931735] 'process raft request' (duration: 340.333742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:15:18.229485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:15:17.888283Z","time spent":"341.121942ms","remote":"127.0.0.1:40518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:16:04.296745Z","caller":"traceutil/trace.go:171","msg":"trace[774024939] transaction","detail":"{read_only:false; response_revision:5714; number_of_response:1; }","duration":"107.771019ms","start":"2026-08-28T04:16:04.188948Z","end":"2026-08-28T04:16:04.296719Z","steps":["trace[774024939] 'process raft request' (duration: 107.581276ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:20:05.857925Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5183} {"level":"info","ts":"2026-08-28T04:20:05.948098Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5183,"took":"88.762291ms","hash":49437835,"current-db-size-bytes":52297728,"current-db-size":"52 MB","current-db-size-in-use-bytes":19324928,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-28T04:20:05.94816Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":49437835,"revision":5183,"compact-revision":2835}