{"level":"warn","ts":"2026-07-15T21:39:53.66961Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T21:39:53.669687Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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://199.204.45.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-07-15T21:39:53.669747Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T21:39:53.669754Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-07-15T21:39:53.66977Z","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-07-15T21:39:53.670366Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-07-15T21:39:53.670444Z","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://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-07-15T21:39:53.675719Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.807613ms"} {"level":"info","ts":"2026-07-15T21:39:53.680918Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-07-15T21:39:53.681004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-07-15T21:39:53.681025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-07-15T21:39:53.681048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-15T21:39:53.681064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-07-15T21:39:53.681101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-07-15T21:39:53.689665Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-15T21:39:53.692081Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-15T21:39:53.693484Z","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-07-15T21:39:53.695822Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-15T21:39:53.695951Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-15T21:39:53.696111Z","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-07-15T21:39:53.696174Z","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-07-15T21:39:53.696184Z","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-07-15T21:39:53.697461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-07-15T21:39:53.697628Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-07-15T21:39:53.699578Z","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-07-15T21:39:53.699675Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-07-15T21:39:53.699809Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-15T21:39:53.699841Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-15T21:39:53.699707Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-07-15T21:39:53.881946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-07-15T21:39:53.882017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-15T21:39:53.882166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-07-15T21:39:53.8822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-07-15T21:39:53.882278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-07-15T21:39:53.882298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-07-15T21:39:53.882378Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-07-15T21:39:53.883579Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-07-15T21:39:53.883702Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T21:39:53.883885Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T21:39:53.88497Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T21:39:53.885121Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T21:39:53.88516Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T21:39:53.885204Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T21:39:53.887118Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-07-15T21:39:53.888027Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-15T21:39:53.900016Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-15T21:39:53.900039Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-15T21:40:30.193306Z","caller":"traceutil/trace.go:171","msg":"trace[1089544079] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"266.466007ms","start":"2026-07-15T21:40:29.92681Z","end":"2026-07-15T21:40:30.193276Z","steps":["trace[1089544079] 'process raft request' (duration: 266.321859ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:30.197734Z","caller":"traceutil/trace.go:171","msg":"trace[2021180612] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:604; }","duration":"200.213778ms","start":"2026-07-15T21:40:29.997497Z","end":"2026-07-15T21:40:30.197711Z","steps":["trace[2021180612] 'read index received' (duration: 196.107437ms)","trace[2021180612] 'applied index is now lower than readState.Index' (duration: 4.105631ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:40:30.197852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.341497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:40:30.197901Z","caller":"traceutil/trace.go:171","msg":"trace[310783851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"200.428706ms","start":"2026-07-15T21:40:29.997466Z","end":"2026-07-15T21:40:30.197895Z","steps":["trace[310783851] 'agreement among raft nodes before linearized reading' (duration: 200.323077ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:30.198078Z","caller":"traceutil/trace.go:171","msg":"trace[1281176303] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"236.817434ms","start":"2026-07-15T21:40:29.961253Z","end":"2026-07-15T21:40:30.19807Z","steps":["trace[1281176303] 'process raft request' (duration: 236.31034ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:30.198238Z","caller":"traceutil/trace.go:171","msg":"trace[1207647706] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"254.647999ms","start":"2026-07-15T21:40:29.943515Z","end":"2026-07-15T21:40:30.198163Z","steps":["trace[1207647706] 'process raft request' (duration: 253.896998ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:30.695884Z","caller":"traceutil/trace.go:171","msg":"trace[1352382260] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"159.221174ms","start":"2026-07-15T21:40:30.536628Z","end":"2026-07-15T21:40:30.695849Z","steps":["trace[1352382260] 'process raft request' (duration: 158.615861ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:36.323556Z","caller":"traceutil/trace.go:171","msg":"trace[41725699] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"223.003594ms","start":"2026-07-15T21:40:36.10049Z","end":"2026-07-15T21:40:36.323494Z","steps":["trace[41725699] 'process raft request' (duration: 222.090299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:36.764563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.472037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-15T21:40:36.764656Z","caller":"traceutil/trace.go:171","msg":"trace[1842264338] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"222.425147ms","start":"2026-07-15T21:40:36.542216Z","end":"2026-07-15T21:40:36.764641Z","steps":["trace[1842264338] 'read index received' (duration: 118.423052ms)","trace[1842264338] 'applied index is now lower than readState.Index' (duration: 104.001135ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:40:36.764718Z","caller":"traceutil/trace.go:171","msg":"trace[240468792] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"293.4927ms","start":"2026-07-15T21:40:36.471188Z","end":"2026-07-15T21:40:36.76468Z","steps":["trace[240468792] 'process raft request' (duration: 189.502505ms)","trace[240468792] 'compare' (duration: 103.337588ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:40:36.764802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.581416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-c8mxp\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-15T21:40:36.764822Z","caller":"traceutil/trace.go:171","msg":"trace[757416714] range","detail":"{range_begin:/registry/pods/kube-system/cilium-c8mxp; range_end:; response_count:1; response_revision:634; }","duration":"222.643616ms","start":"2026-07-15T21:40:36.542172Z","end":"2026-07-15T21:40:36.764816Z","steps":["trace[757416714] 'agreement among raft nodes before linearized reading' (duration: 222.504607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:37.350773Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305497416943982201,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-15T21:40:37.695777Z","caller":"traceutil/trace.go:171","msg":"trace[918220030] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"926.87429ms","start":"2026-07-15T21:40:36.76887Z","end":"2026-07-15T21:40:37.695744Z","steps":["trace[918220030] 'process raft request' (duration: 926.69553ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:37.695843Z","caller":"traceutil/trace.go:171","msg":"trace[1108827786] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"926.427032ms","start":"2026-07-15T21:40:36.769393Z","end":"2026-07-15T21:40:37.69582Z","steps":["trace[1108827786] 'process raft request' (duration: 926.311303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:37.695909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:36.768862Z","time spent":"926.982809ms","remote":"127.0.0.1:38360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-15T21:40:37.696244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:36.769378Z","time spent":"926.578773ms","remote":"127.0.0.1:38472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T21:40:37.696287Z","caller":"traceutil/trace.go:171","msg":"trace[520468927] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"926.397333ms","start":"2026-07-15T21:40:36.769854Z","end":"2026-07-15T21:40:37.696252Z","steps":["trace[520468927] 'process raft request' (duration: 925.922686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:37.696483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:36.769844Z","time spent":"926.541373ms","remote":"127.0.0.1:38530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T21:40:37.696561Z","caller":"traceutil/trace.go:171","msg":"trace[1829953619] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"920.591527ms","start":"2026-07-15T21:40:36.775935Z","end":"2026-07-15T21:40:37.696527Z","steps":["trace[1829953619] 'process raft request' (duration: 920.180949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:37.696717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:36.775926Z","time spent":"920.718216ms","remote":"127.0.0.1:38472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T21:40:37.696502Z","caller":"traceutil/trace.go:171","msg":"trace[738873201] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:659; }","duration":"846.416711ms","start":"2026-07-15T21:40:36.850054Z","end":"2026-07-15T21:40:37.696471Z","steps":["trace[738873201] 'read index received' (duration: 845.403986ms)","trace[738873201] 'applied index is now lower than readState.Index' (duration: 1.010595ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:40:37.696895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.182978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:40:37.696936Z","caller":"traceutil/trace.go:171","msg":"trace[1680693825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"702.221148ms","start":"2026-07-15T21:40:36.994703Z","end":"2026-07-15T21:40:37.696924Z","steps":["trace[1680693825] 'agreement among raft nodes before linearized reading' (duration: 702.161468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:37.696891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.67868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-15T21:40:37.697046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.483009ms","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-07-15T21:40:37.697043Z","caller":"traceutil/trace.go:171","msg":"trace[840414840] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:639; }","duration":"847.032499ms","start":"2026-07-15T21:40:36.849995Z","end":"2026-07-15T21:40:37.697027Z","steps":["trace[840414840] 'agreement among raft nodes before linearized reading' (duration: 846.6849ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:37.697147Z","caller":"traceutil/trace.go:171","msg":"trace[1611110899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"322.554549ms","start":"2026-07-15T21:40:37.374518Z","end":"2026-07-15T21:40:37.697073Z","steps":["trace[1611110899] 'agreement among raft nodes before linearized reading' (duration: 322.496689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:37.697189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:37.374495Z","time spent":"322.688287ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T21:40:37.697264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:36.849976Z","time spent":"847.250297ms","remote":"127.0.0.1:38318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.210\" "} {"level":"warn","ts":"2026-07-15T21:40:37.696987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:36.994642Z","time spent":"702.316986ms","remote":"127.0.0.1:38298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T21:40:56.864257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.520253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kflhw\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-15T21:40:56.864414Z","caller":"traceutil/trace.go:171","msg":"trace[1882923916] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-kflhw; range_end:; response_count:1; response_revision:801; }","duration":"201.697495ms","start":"2026-07-15T21:40:56.662693Z","end":"2026-07-15T21:40:56.864391Z","steps":["trace[1882923916] 'range keys from in-memory index tree' (duration: 201.340742ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:57.30572Z","caller":"traceutil/trace.go:171","msg":"trace[46010787] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"407.366509ms","start":"2026-07-15T21:40:56.898328Z","end":"2026-07-15T21:40:57.305694Z","steps":["trace[46010787] 'read index received' (duration: 262.135516ms)","trace[46010787] 'applied index is now lower than readState.Index' (duration: 145.230173ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:40:57.305849Z","caller":"traceutil/trace.go:171","msg":"trace[1076828397] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"435.150703ms","start":"2026-07-15T21:40:56.870686Z","end":"2026-07-15T21:40:57.305836Z","steps":["trace[1076828397] 'process raft request' (duration: 434.84663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:57.306021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:56.870661Z","time spent":"435.221515ms","remote":"127.0.0.1:38472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T21:40:57.306057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.715193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:40:57.306106Z","caller":"traceutil/trace.go:171","msg":"trace[1403088249] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:802; }","duration":"407.811634ms","start":"2026-07-15T21:40:56.898282Z","end":"2026-07-15T21:40:57.306094Z","steps":["trace[1403088249] 'agreement among raft nodes before linearized reading' (duration: 407.701653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:57.306141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:56.898269Z","time spent":"407.862735ms","remote":"127.0.0.1:38414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-07-15T21:40:57.307618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.810527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:40:57.307601Z","caller":"traceutil/trace.go:171","msg":"trace[2096290975] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"145.999932ms","start":"2026-07-15T21:40:57.161582Z","end":"2026-07-15T21:40:57.307582Z","steps":["trace[2096290975] 'process raft request' (duration: 145.85837ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:40:57.30768Z","caller":"traceutil/trace.go:171","msg":"trace[759075455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"307.92025ms","start":"2026-07-15T21:40:56.999751Z","end":"2026-07-15T21:40:57.307672Z","steps":["trace[759075455] 'agreement among raft nodes before linearized reading' (duration: 307.801497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:40:57.307702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:40:56.999734Z","time spent":"307.96149ms","remote":"127.0.0.1:38298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T21:40:57.307942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.628325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-15T21:40:57.308Z","caller":"traceutil/trace.go:171","msg":"trace[468531507] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:803; }","duration":"115.860357ms","start":"2026-07-15T21:40:57.192122Z","end":"2026-07-15T21:40:57.307982Z","steps":["trace[468531507] 'agreement among raft nodes before linearized reading' (duration: 115.521113ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:41:19.911313Z","caller":"traceutil/trace.go:171","msg":"trace[11619429] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1196; }","duration":"433.869072ms","start":"2026-07-15T21:41:19.477411Z","end":"2026-07-15T21:41:19.91128Z","steps":["trace[11619429] 'read index received' (duration: 433.816891ms)","trace[11619429] 'applied index is now lower than readState.Index' (duration: 50.511µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:41:19.911471Z","caller":"traceutil/trace.go:171","msg":"trace[1849218625] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"484.652142ms","start":"2026-07-15T21:41:19.426805Z","end":"2026-07-15T21:41:19.911457Z","steps":["trace[1849218625] 'process raft request' (duration: 484.224651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:19.91177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:19.426764Z","time spent":"484.742155ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T21:41:19.911863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.431318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T21:41:19.911962Z","caller":"traceutil/trace.go:171","msg":"trace[880793354] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1160; }","duration":"434.595321ms","start":"2026-07-15T21:41:19.477347Z","end":"2026-07-15T21:41:19.911942Z","steps":["trace[880793354] 'agreement among raft nodes before linearized reading' (duration: 434.419517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:19.912012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:19.477326Z","time spent":"434.676934ms","remote":"127.0.0.1:38382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":18,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-07-15T21:41:21.36413Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305497416943983222,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T21:41:21.864856Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305497416943983222,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-15T21:41:22.16214Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.372421761s","expected-duration":"1s"} {"level":"info","ts":"2026-07-15T21:41:22.16295Z","caller":"traceutil/trace.go:171","msg":"trace[1258302944] linearizableReadLoop","detail":"{readStateIndex:1210; appliedIndex:1209; }","duration":"1.299519333s","start":"2026-07-15T21:41:20.863394Z","end":"2026-07-15T21:41:22.162913Z","steps":["trace[1258302944] 'read index received' (duration: 1.299107001s)","trace[1258302944] 'applied index is now lower than readState.Index' (duration: 410.762µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:41:22.163025Z","caller":"traceutil/trace.go:171","msg":"trace[2076826637] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"1.37458647s","start":"2026-07-15T21:41:20.788397Z","end":"2026-07-15T21:41:22.162984Z","steps":["trace[2076826637] 'process raft request' (duration: 1.37423063s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:22.163196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:20.788382Z","time spent":"1.374714524s","remote":"127.0.0.1:38472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4602,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T21:41:22.163302Z","caller":"traceutil/trace.go:171","msg":"trace[303603761] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"239.15907ms","start":"2026-07-15T21:41:21.924109Z","end":"2026-07-15T21:41:22.163268Z","steps":["trace[303603761] 'process raft request' (duration: 238.929514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:22.163436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.300001225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:41:22.163494Z","caller":"traceutil/trace.go:171","msg":"trace[1332043187] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1174; }","duration":"1.300124209s","start":"2026-07-15T21:41:20.863354Z","end":"2026-07-15T21:41:22.163478Z","steps":["trace[1332043187] 'agreement among raft nodes before linearized reading' (duration: 1.300000665s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:22.163527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16606135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T21:41:22.163537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:20.863337Z","time spent":"1.3001895s","remote":"127.0.0.1:38576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-07-15T21:41:22.163579Z","caller":"traceutil/trace.go:171","msg":"trace[1043733879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1174; }","duration":"1.166162892s","start":"2026-07-15T21:41:20.997401Z","end":"2026-07-15T21:41:22.163563Z","steps":["trace[1043733879] 'agreement among raft nodes before linearized reading' (duration: 1.16608704s)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:22.163623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:20.99737Z","time spent":"1.166244495s","remote":"127.0.0.1:38298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-15T21:41:22.163771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.055135ms","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-07-15T21:41:22.163831Z","caller":"traceutil/trace.go:171","msg":"trace[53961795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1174; }","duration":"187.153329ms","start":"2026-07-15T21:41:21.976663Z","end":"2026-07-15T21:41:22.163816Z","steps":["trace[53961795] 'agreement among raft nodes before linearized reading' (duration: 186.955953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:22.163835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.673154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T21:41:22.163866Z","caller":"traceutil/trace.go:171","msg":"trace[2093407807] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1174; }","duration":"937.743806ms","start":"2026-07-15T21:41:21.226112Z","end":"2026-07-15T21:41:22.163855Z","steps":["trace[2093407807] 'agreement among raft nodes before linearized reading' (duration: 937.698834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:22.163895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:21.226093Z","time spent":"937.796036ms","remote":"127.0.0.1:38454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-15T21:41:22.163901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099788406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-15T21:41:22.163947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.219122ms","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-07-15T21:41:22.163976Z","caller":"traceutil/trace.go:171","msg":"trace[373803871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1174; }","duration":"183.287083ms","start":"2026-07-15T21:41:21.980681Z","end":"2026-07-15T21:41:22.163968Z","steps":["trace[373803871] 'agreement among raft nodes before linearized reading' (duration: 183.219742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:22.163772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.457215ms","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-07-15T21:41:22.164041Z","caller":"traceutil/trace.go:171","msg":"trace[190349268] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1174; }","duration":"164.756393ms","start":"2026-07-15T21:41:21.999277Z","end":"2026-07-15T21:41:22.164034Z","steps":["trace[190349268] 'agreement among raft nodes before linearized reading' (duration: 164.398494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:22.164067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.937367ms","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-07-15T21:41:22.163944Z","caller":"traceutil/trace.go:171","msg":"trace[1520820900] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1174; }","duration":"1.099846377s","start":"2026-07-15T21:41:21.064082Z","end":"2026-07-15T21:41:22.163928Z","steps":["trace[1520820900] 'agreement among raft nodes before linearized reading' (duration: 1.099733615s)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:41:22.164094Z","caller":"traceutil/trace.go:171","msg":"trace[1121998334] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1174; }","duration":"248.002389ms","start":"2026-07-15T21:41:21.916085Z","end":"2026-07-15T21:41:22.164087Z","steps":["trace[1121998334] 'agreement among raft nodes before linearized reading' (duration: 247.930357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:22.164116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:21.064071Z","time spent":"1.100033803s","remote":"127.0.0.1:38638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":64,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-15T21:41:22.164477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.576504ms","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-07-15T21:41:22.164527Z","caller":"traceutil/trace.go:171","msg":"trace[1498280169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1174; }","duration":"182.706848ms","start":"2026-07-15T21:41:21.981807Z","end":"2026-07-15T21:41:22.164514Z","steps":["trace[1498280169] 'agreement among raft nodes before linearized reading' (duration: 181.805503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:32.823289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305497416943983318,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-15T21:41:32.851367Z","caller":"traceutil/trace.go:171","msg":"trace[1521116198] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1259; }","duration":"528.517773ms","start":"2026-07-15T21:41:32.322815Z","end":"2026-07-15T21:41:32.851333Z","steps":["trace[1521116198] 'read index received' (duration: 528.172031ms)","trace[1521116198] 'applied index is now lower than readState.Index' (duration: 344.072µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:41:32.851692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.295393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-15T21:41:32.85169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.854663ms","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-07-15T21:41:32.851758Z","caller":"traceutil/trace.go:171","msg":"trace[122292589] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1220; }","duration":"517.385036ms","start":"2026-07-15T21:41:32.334356Z","end":"2026-07-15T21:41:32.851741Z","steps":["trace[122292589] 'agreement among raft nodes before linearized reading' (duration: 517.276313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:32.851763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.648735ms","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-07-15T21:41:32.851793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:32.334346Z","time spent":"517.438008ms","remote":"127.0.0.1:38774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":30,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-15T21:41:32.851791Z","caller":"traceutil/trace.go:171","msg":"trace[1929893857] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1220; }","duration":"529.015258ms","start":"2026-07-15T21:41:32.322755Z","end":"2026-07-15T21:41:32.85177Z","steps":["trace[1929893857] 'agreement among raft nodes before linearized reading' (duration: 528.705909ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:41:32.851824Z","caller":"traceutil/trace.go:171","msg":"trace[936259073] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1220; }","duration":"527.783649ms","start":"2026-07-15T21:41:32.324027Z","end":"2026-07-15T21:41:32.851811Z","steps":["trace[936259073] 'agreement among raft nodes before linearized reading' (duration: 527.508681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:32.851846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:32.322734Z","time spent":"529.10284ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-15T21:41:32.851872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:32.32401Z","time spent":"527.853131ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T21:41:32.85206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.546352ms","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-07-15T21:41:32.852097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.186947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-15T21:41:32.852148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.412513ms","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-07-15T21:41:32.85216Z","caller":"traceutil/trace.go:171","msg":"trace[1261266994] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1220; }","duration":"525.259699ms","start":"2026-07-15T21:41:32.326882Z","end":"2026-07-15T21:41:32.852142Z","steps":["trace[1261266994] 'agreement among raft nodes before linearized reading' (duration: 525.099764ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:41:32.85218Z","caller":"traceutil/trace.go:171","msg":"trace[797454044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1220; }","duration":"525.455804ms","start":"2026-07-15T21:41:32.326714Z","end":"2026-07-15T21:41:32.85217Z","steps":["trace[797454044] 'agreement among raft nodes before linearized reading' (duration: 525.359501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:32.852183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.932597ms","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-07-15T21:41:32.85216Z","caller":"traceutil/trace.go:171","msg":"trace[458024204] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1220; }","duration":"274.735767ms","start":"2026-07-15T21:41:32.577397Z","end":"2026-07-15T21:41:32.852133Z","steps":["trace[458024204] 'agreement among raft nodes before linearized reading' (duration: 274.414676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:32.85221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:32.326878Z","time spent":"525.32291ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-15T21:41:32.852217Z","caller":"traceutil/trace.go:171","msg":"trace[1473245544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1220; }","duration":"525.004179ms","start":"2026-07-15T21:41:32.327202Z","end":"2026-07-15T21:41:32.852206Z","steps":["trace[1473245544] 'agreement among raft nodes before linearized reading' (duration: 524.880186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:32.852252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:32.327183Z","time spent":"525.063632ms","remote":"127.0.0.1:38548","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":"info","ts":"2026-07-15T21:41:32.851578Z","caller":"traceutil/trace.go:171","msg":"trace[566423472] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"535.679622ms","start":"2026-07-15T21:41:32.315751Z","end":"2026-07-15T21:41:32.851431Z","steps":["trace[566423472] 'process raft request' (duration: 535.351701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:32.85221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:32.326708Z","time spent":"525.495306ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T21:41:32.85242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:32.315728Z","time spent":"536.605151ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-15T21:41:33.385868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.00211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:41:33.385952Z","caller":"traceutil/trace.go:171","msg":"trace[422781408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1225; }","duration":"387.120104ms","start":"2026-07-15T21:41:32.998813Z","end":"2026-07-15T21:41:33.385933Z","steps":["trace[422781408] 'range keys from in-memory index tree' (duration: 386.944969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:33.38595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.09457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T21:41:33.385994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:32.998805Z","time spent":"387.180206ms","remote":"127.0.0.1:38302","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-07-15T21:41:33.386068Z","caller":"traceutil/trace.go:171","msg":"trace[2024582882] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1225; }","duration":"200.244995ms","start":"2026-07-15T21:41:33.185805Z","end":"2026-07-15T21:41:33.38605Z","steps":["trace[2024582882] 'count revisions from in-memory index tree' (duration: 200.045638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:33.385871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.718711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-15T21:41:33.386248Z","caller":"traceutil/trace.go:171","msg":"trace[334464500] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1225; }","duration":"308.156926ms","start":"2026-07-15T21:41:33.078079Z","end":"2026-07-15T21:41:33.386236Z","steps":["trace[334464500] 'range keys from in-memory index tree' (duration: 307.535954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:41:33.386284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:41:33.078065Z","time spent":"308.207957ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":580,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-07-15T21:42:13.118083Z","caller":"traceutil/trace.go:171","msg":"trace[412618804] linearizableReadLoop","detail":"{readStateIndex:1836; appliedIndex:1835; }","duration":"119.205438ms","start":"2026-07-15T21:42:12.998854Z","end":"2026-07-15T21:42:13.11806Z","steps":["trace[412618804] 'read index received' (duration: 119.055891ms)","trace[412618804] 'applied index is now lower than readState.Index' (duration: 148.157µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:42:13.118145Z","caller":"traceutil/trace.go:171","msg":"trace[371778811] transaction","detail":"{read_only:false; response_revision:1762; number_of_response:1; }","duration":"193.739324ms","start":"2026-07-15T21:42:12.924368Z","end":"2026-07-15T21:42:13.118107Z","steps":["trace[371778811] 'process raft request' (duration: 193.510304ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:42:13.118284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.403717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:42:13.119576Z","caller":"traceutil/trace.go:171","msg":"trace[1698914349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1762; }","duration":"120.752376ms","start":"2026-07-15T21:42:12.998802Z","end":"2026-07-15T21:42:13.119554Z","steps":["trace[1698914349] 'agreement among raft nodes before linearized reading' (duration: 119.383115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:42:19.456371Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305497416943984616,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-15T21:42:19.470959Z","caller":"traceutil/trace.go:171","msg":"trace[686194191] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"614.077733ms","start":"2026-07-15T21:42:18.856629Z","end":"2026-07-15T21:42:19.470706Z","steps":["trace[686194191] 'process raft request' (duration: 613.868703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:42:19.471099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:42:18.856604Z","time spent":"614.421019ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T21:42:19.489726Z","caller":"traceutil/trace.go:171","msg":"trace[1128777519] linearizableReadLoop","detail":"{readStateIndex:1946; appliedIndex:1944; }","duration":"534.054025ms","start":"2026-07-15T21:42:18.955611Z","end":"2026-07-15T21:42:19.489665Z","steps":["trace[1128777519] 'read index received' (duration: 514.917946ms)","trace[1128777519] 'applied index is now lower than readState.Index' (duration: 19.135029ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T21:42:19.490026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.817578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-15T21:42:19.490061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.63417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-15T21:42:19.489848Z","caller":"traceutil/trace.go:171","msg":"trace[1745919] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"334.345596ms","start":"2026-07-15T21:42:19.155485Z","end":"2026-07-15T21:42:19.489831Z","steps":["trace[1745919] 'process raft request' (duration: 334.040512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:42:19.490124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.190465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-15T21:42:19.49014Z","caller":"traceutil/trace.go:171","msg":"trace[489463217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1871; }","duration":"309.752726ms","start":"2026-07-15T21:42:19.180375Z","end":"2026-07-15T21:42:19.490127Z","steps":["trace[489463217] 'agreement among raft nodes before linearized reading' (duration: 309.578638ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:42:19.490171Z","caller":"traceutil/trace.go:171","msg":"trace[78648867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1871; }","duration":"313.254988ms","start":"2026-07-15T21:42:19.1769Z","end":"2026-07-15T21:42:19.490155Z","steps":["trace[78648867] 'agreement among raft nodes before linearized reading' (duration: 313.171223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:42:19.490266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.511491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-15T21:42:19.490311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:42:19.155464Z","time spent":"334.695134ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T21:42:19.49037Z","caller":"traceutil/trace.go:171","msg":"trace[958609118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1871; }","duration":"320.71317ms","start":"2026-07-15T21:42:19.169631Z","end":"2026-07-15T21:42:19.490344Z","steps":["trace[958609118] 'agreement among raft nodes before linearized reading' (duration: 320.388004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:42:19.490186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:42:19.18036Z","time spent":"309.81719ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-15T21:42:19.490428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:42:19.169615Z","time spent":"320.803623ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-15T21:42:19.490091Z","caller":"traceutil/trace.go:171","msg":"trace[1373259695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1871; }","duration":"309.921433ms","start":"2026-07-15T21:42:19.180151Z","end":"2026-07-15T21:42:19.490072Z","steps":["trace[1373259695] 'agreement among raft nodes before linearized reading' (duration: 309.761206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:42:19.490569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:42:19.180131Z","time spent":"310.420566ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-15T21:42:19.490027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.035392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18c2948d5ab70f06\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-07-15T21:42:19.490657Z","caller":"traceutil/trace.go:171","msg":"trace[365941236] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18c2948d5ab70f06; range_end:; response_count:1; response_revision:1871; }","duration":"413.707483ms","start":"2026-07-15T21:42:19.076933Z","end":"2026-07-15T21:42:19.49064Z","steps":["trace[365941236] 'agreement among raft nodes before linearized reading' (duration: 412.97641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:42:19.490712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:42:19.076909Z","time spent":"413.789567ms","remote":"127.0.0.1:38360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1005,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18c2948d5ab70f06\" "} {"level":"warn","ts":"2026-07-15T21:42:19.49019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.502488ms","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-07-15T21:42:19.490766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.150625ms","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-07-15T21:42:19.490882Z","caller":"traceutil/trace.go:171","msg":"trace[173513048] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1871; }","duration":"535.263141ms","start":"2026-07-15T21:42:18.955571Z","end":"2026-07-15T21:42:19.490834Z","steps":["trace[173513048] 'agreement among raft nodes before linearized reading' (duration: 534.260214ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:42:19.490836Z","caller":"traceutil/trace.go:171","msg":"trace[705125118] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1871; }","duration":"319.21369ms","start":"2026-07-15T21:42:19.171598Z","end":"2026-07-15T21:42:19.490812Z","steps":["trace[705125118] 'agreement among raft nodes before linearized reading' (duration: 318.388282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:42:19.490946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:42:19.171588Z","time spent":"319.342127ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-15T21:42:19.49034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:42:19.176889Z","time spent":"313.434085ms","remote":"127.0.0.1:38548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-15T21:42:19.49027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.763538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fcdf3da6-d6ea-4c81-b867-0e8ec38f891f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-15T21:42:19.491125Z","caller":"traceutil/trace.go:171","msg":"trace[408042975] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fcdf3da6-d6ea-4c81-b867-0e8ec38f891f; range_end:; response_count:1; response_revision:1871; }","duration":"228.882789ms","start":"2026-07-15T21:42:19.262228Z","end":"2026-07-15T21:42:19.491111Z","steps":["trace[408042975] 'agreement among raft nodes before linearized reading' (duration: 227.705335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:42:19.491197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T21:42:18.955557Z","time spent":"535.609377ms","remote":"127.0.0.1:38374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-15T21:42:19.7079Z","caller":"traceutil/trace.go:171","msg":"trace[2117777407] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"211.94586ms","start":"2026-07-15T21:42:19.495929Z","end":"2026-07-15T21:42:19.707875Z","steps":["trace[2117777407] 'process raft request' (duration: 211.866646ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:42:19.70792Z","caller":"traceutil/trace.go:171","msg":"trace[1774311688] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"212.656943ms","start":"2026-07-15T21:42:19.495219Z","end":"2026-07-15T21:42:19.707876Z","steps":["trace[1774311688] 'process raft request' (duration: 212.425462ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:42:19.708191Z","caller":"traceutil/trace.go:171","msg":"trace[552602782] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"211.521821ms","start":"2026-07-15T21:42:19.496653Z","end":"2026-07-15T21:42:19.708175Z","steps":["trace[552602782] 'process raft request' (duration: 211.188405ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:42:19.708228Z","caller":"traceutil/trace.go:171","msg":"trace[1222895356] linearizableReadLoop","detail":"{readStateIndex:1952; appliedIndex:1947; }","duration":"189.723939ms","start":"2026-07-15T21:42:19.518486Z","end":"2026-07-15T21:42:19.70821Z","steps":["trace[1222895356] 'read index received' (duration: 188.591447ms)","trace[1222895356] 'applied index is now lower than readState.Index' (duration: 1.131652ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:42:19.708284Z","caller":"traceutil/trace.go:171","msg":"trace[1768332506] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"210.880091ms","start":"2026-07-15T21:42:19.497381Z","end":"2026-07-15T21:42:19.708262Z","steps":["trace[1768332506] 'process raft request' (duration: 210.791097ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:42:19.708284Z","caller":"traceutil/trace.go:171","msg":"trace[873297122] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"211.268999ms","start":"2026-07-15T21:42:19.496988Z","end":"2026-07-15T21:42:19.708257Z","steps":["trace[873297122] 'process raft request' (duration: 211.027248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:42:19.708435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.947839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fcdf3da6-d6ea-4c81-b867-0e8ec38f891f\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-15T21:42:19.708467Z","caller":"traceutil/trace.go:171","msg":"trace[1491198274] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fcdf3da6-d6ea-4c81-b867-0e8ec38f891f; range_end:; response_count:1; response_revision:1877; }","duration":"190.042283ms","start":"2026-07-15T21:42:19.518416Z","end":"2026-07-15T21:42:19.708458Z","steps":["trace[1491198274] 'agreement among raft nodes before linearized reading' (duration: 189.883496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:42:19.708801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.20825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-15T21:42:19.708967Z","caller":"traceutil/trace.go:171","msg":"trace[1564014536] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1877; }","duration":"131.470622ms","start":"2026-07-15T21:42:19.577476Z","end":"2026-07-15T21:42:19.708947Z","steps":["trace[1564014536] 'agreement among raft nodes before linearized reading' (duration: 131.095215ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:42:19.952669Z","caller":"traceutil/trace.go:171","msg":"trace[1153236675] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"236.016627ms","start":"2026-07-15T21:42:19.716625Z","end":"2026-07-15T21:42:19.952642Z","steps":["trace[1153236675] 'process raft request' (duration: 229.34167ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:42:54.803152Z","caller":"traceutil/trace.go:171","msg":"trace[1563855424] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"202.523461ms","start":"2026-07-15T21:42:54.600585Z","end":"2026-07-15T21:42:54.803109Z","steps":["trace[1563855424] 'process raft request' (duration: 202.108449ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:42:55.183655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.776156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T21:42:55.183738Z","caller":"traceutil/trace.go:171","msg":"trace[813512404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2124; }","duration":"182.908473ms","start":"2026-07-15T21:42:55.000807Z","end":"2026-07-15T21:42:55.183715Z","steps":["trace[813512404] 'range keys from in-memory index tree' (duration: 182.635319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:43:58.377613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.645016ms","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-07-15T21:43:58.378299Z","caller":"traceutil/trace.go:171","msg":"trace[411140110] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2560; }","duration":"104.417659ms","start":"2026-07-15T21:43:58.273853Z","end":"2026-07-15T21:43:58.37827Z","steps":["trace[411140110] 'range keys from in-memory index tree' (duration: 103.378349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:43:58.378364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.416469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-15T21:43:58.378434Z","caller":"traceutil/trace.go:171","msg":"trace[539041804] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2560; }","duration":"109.533485ms","start":"2026-07-15T21:43:58.268885Z","end":"2026-07-15T21:43:58.378418Z","steps":["trace[539041804] 'range keys from in-memory index tree' (duration: 109.314113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:44:37.130442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.314432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-15T21:44:37.130505Z","caller":"traceutil/trace.go:171","msg":"trace[2018546416] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:2777; }","duration":"268.422279ms","start":"2026-07-15T21:44:36.862068Z","end":"2026-07-15T21:44:37.130491Z","steps":["trace[2018546416] 'range keys from in-memory index tree' (duration: 268.175004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:44:37.131026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.691766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c294a80b7be524\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-07-15T21:44:37.131109Z","caller":"traceutil/trace.go:171","msg":"trace[272502662] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c294a80b7be524; range_end:; response_count:1; response_revision:2777; }","duration":"268.799935ms","start":"2026-07-15T21:44:36.862292Z","end":"2026-07-15T21:44:37.131092Z","steps":["trace[272502662] 'range keys from in-memory index tree' (duration: 268.219982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:48:16.449096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.052605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-07-15T21:48:16.449173Z","caller":"traceutil/trace.go:171","msg":"trace[442073274] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4548; }","duration":"115.168769ms","start":"2026-07-15T21:48:16.333988Z","end":"2026-07-15T21:48:16.449156Z","steps":["trace[442073274] 'range keys from in-memory index tree' (duration: 114.873659ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:48:16.467112Z","caller":"traceutil/trace.go:171","msg":"trace[997548605] transaction","detail":"{read_only:false; response_revision:4549; number_of_response:1; }","duration":"117.283491ms","start":"2026-07-15T21:48:16.349807Z","end":"2026-07-15T21:48:16.467091Z","steps":["trace[997548605] 'process raft request' (duration: 117.172627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:48:26.051465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.671879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-15T21:48:26.051581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.733406ms","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-07-15T21:48:26.051505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.621622ms","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-07-15T21:48:26.051665Z","caller":"traceutil/trace.go:171","msg":"trace[973716306] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4739; }","duration":"218.83497ms","start":"2026-07-15T21:48:25.832821Z","end":"2026-07-15T21:48:26.051656Z","steps":["trace[973716306] 'range keys from in-memory index tree' (duration: 218.419465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:48:26.051679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.416152ms","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-07-15T21:48:26.051718Z","caller":"traceutil/trace.go:171","msg":"trace[383207618] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4739; }","duration":"202.469044ms","start":"2026-07-15T21:48:25.849236Z","end":"2026-07-15T21:48:26.051705Z","steps":["trace[383207618] 'range keys from in-memory index tree' (duration: 202.334559ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:48:26.051669Z","caller":"traceutil/trace.go:171","msg":"trace[514902692] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4739; }","duration":"218.844371ms","start":"2026-07-15T21:48:25.832805Z","end":"2026-07-15T21:48:26.05165Z","steps":["trace[514902692] 'range keys from in-memory index tree' (duration: 218.586751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:48:26.051598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.627058ms","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-07-15T21:48:26.051918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.689531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1362f8a0-b60d-49e5-8ee4-bdd9b32705f1\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-15T21:48:26.051934Z","caller":"traceutil/trace.go:171","msg":"trace[1539376987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4739; }","duration":"207.998802ms","start":"2026-07-15T21:48:25.843928Z","end":"2026-07-15T21:48:26.051927Z","steps":["trace[1539376987] 'range keys from in-memory index tree' (duration: 207.445491ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:48:26.051959Z","caller":"traceutil/trace.go:171","msg":"trace[2121569237] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1362f8a0-b60d-49e5-8ee4-bdd9b32705f1; range_end:; response_count:1; response_revision:4739; }","duration":"186.751973ms","start":"2026-07-15T21:48:25.865197Z","end":"2026-07-15T21:48:26.051949Z","steps":["trace[2121569237] 'range keys from in-memory index tree' (duration: 186.585657ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:48:26.051607Z","caller":"traceutil/trace.go:171","msg":"trace[1365946449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4739; }","duration":"209.868226ms","start":"2026-07-15T21:48:25.841721Z","end":"2026-07-15T21:48:26.05159Z","steps":["trace[1365946449] 'range keys from in-memory index tree' (duration: 209.510962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:48:26.052062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.99167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-15T21:48:26.052083Z","caller":"traceutil/trace.go:171","msg":"trace[1526042431] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4739; }","duration":"214.049462ms","start":"2026-07-15T21:48:25.838024Z","end":"2026-07-15T21:48:26.052074Z","steps":["trace[1526042431] 'range keys from in-memory index tree' (duration: 213.815853ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:48:26.215204Z","caller":"traceutil/trace.go:171","msg":"trace[1363727507] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"156.663854ms","start":"2026-07-15T21:48:26.058517Z","end":"2026-07-15T21:48:26.215181Z","steps":["trace[1363727507] 'process raft request' (duration: 156.507838ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:48:26.29446Z","caller":"traceutil/trace.go:171","msg":"trace[321348377] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"235.87502ms","start":"2026-07-15T21:48:26.058563Z","end":"2026-07-15T21:48:26.294438Z","steps":["trace[321348377] 'process raft request' (duration: 235.62676ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:48:26.294539Z","caller":"traceutil/trace.go:171","msg":"trace[1005048304] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"235.89044ms","start":"2026-07-15T21:48:26.058639Z","end":"2026-07-15T21:48:26.29453Z","steps":["trace[1005048304] 'process raft request' (duration: 235.702703ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:48:26.294495Z","caller":"traceutil/trace.go:171","msg":"trace[120280046] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"233.172472ms","start":"2026-07-15T21:48:26.061306Z","end":"2026-07-15T21:48:26.294478Z","steps":["trace[120280046] 'process raft request' (duration: 233.080919ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:48:26.29474Z","caller":"traceutil/trace.go:171","msg":"trace[270941776] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"233.186234ms","start":"2026-07-15T21:48:26.061533Z","end":"2026-07-15T21:48:26.294719Z","steps":["trace[270941776] 'process raft request' (duration: 232.892042ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:48:26.294997Z","caller":"traceutil/trace.go:171","msg":"trace[36654109] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"232.238876ms","start":"2026-07-15T21:48:26.062749Z","end":"2026-07-15T21:48:26.294987Z","steps":["trace[36654109] 'process raft request' (duration: 231.900362ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:48:26.295295Z","caller":"traceutil/trace.go:171","msg":"trace[776151500] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"225.79131ms","start":"2026-07-15T21:48:26.069492Z","end":"2026-07-15T21:48:26.295283Z","steps":["trace[776151500] 'process raft request' (duration: 225.457817ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:48:26.295551Z","caller":"traceutil/trace.go:171","msg":"trace[654514654] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"222.266562ms","start":"2026-07-15T21:48:26.073248Z","end":"2026-07-15T21:48:26.295515Z","steps":["trace[654514654] 'process raft request' (duration: 221.902907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:48:29.413351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.326521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1362f8a0-b60d-49e5-8ee4-bdd9b32705f1\" ","response":"range_response_count:1 size:3949"} {"level":"info","ts":"2026-07-15T21:48:29.413428Z","caller":"traceutil/trace.go:171","msg":"trace[1177730819] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1362f8a0-b60d-49e5-8ee4-bdd9b32705f1; range_end:; response_count:1; response_revision:4786; }","duration":"145.511178ms","start":"2026-07-15T21:48:29.267899Z","end":"2026-07-15T21:48:29.41341Z","steps":["trace[1177730819] 'range keys from in-memory index tree' (duration: 145.155775ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:48:34.531799Z","caller":"traceutil/trace.go:171","msg":"trace[371646672] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"109.712466ms","start":"2026-07-15T21:48:34.422061Z","end":"2026-07-15T21:48:34.531774Z","steps":["trace[371646672] 'process raft request' (duration: 109.5612ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:48:38.452457Z","caller":"traceutil/trace.go:171","msg":"trace[179042204] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"104.57017ms","start":"2026-07-15T21:48:38.347863Z","end":"2026-07-15T21:48:38.452433Z","steps":["trace[179042204] 'process raft request' (duration: 104.173274ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:48:38.452632Z","caller":"traceutil/trace.go:171","msg":"trace[630169701] linearizableReadLoop","detail":"{readStateIndex:5094; appliedIndex:5091; }","duration":"103.348722ms","start":"2026-07-15T21:48:38.349265Z","end":"2026-07-15T21:48:38.452614Z","steps":["trace[630169701] 'read index received' (duration: 72.056603ms)","trace[630169701] 'applied index is now lower than readState.Index' (duration: 31.284578ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:48:38.452667Z","caller":"traceutil/trace.go:171","msg":"trace[400218219] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"103.475246ms","start":"2026-07-15T21:48:38.349172Z","end":"2026-07-15T21:48:38.452648Z","steps":["trace[400218219] 'process raft request' (duration: 103.204395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:48:38.452792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.503888ms","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-07-15T21:48:38.452813Z","caller":"traceutil/trace.go:171","msg":"trace[1412858845] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4904; }","duration":"103.55812ms","start":"2026-07-15T21:48:38.349249Z","end":"2026-07-15T21:48:38.452807Z","steps":["trace[1412858845] 'agreement among raft nodes before linearized reading' (duration: 103.442835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:48:38.5017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.623068ms","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-07-15T21:48:38.501772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.490222ms","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-07-15T21:48:38.501782Z","caller":"traceutil/trace.go:171","msg":"trace[1435823668] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4908; }","duration":"149.729152ms","start":"2026-07-15T21:48:38.352034Z","end":"2026-07-15T21:48:38.501763Z","steps":["trace[1435823668] 'agreement among raft nodes before linearized reading' (duration: 149.526194ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:48:38.50182Z","caller":"traceutil/trace.go:171","msg":"trace[1070451050] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4908; }","duration":"148.557226ms","start":"2026-07-15T21:48:38.353247Z","end":"2026-07-15T21:48:38.501804Z","steps":["trace[1070451050] 'agreement among raft nodes before linearized reading' (duration: 148.440581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:48:38.502155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.040005ms","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-07-15T21:48:38.502221Z","caller":"traceutil/trace.go:171","msg":"trace[2113923221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4908; }","duration":"149.127479ms","start":"2026-07-15T21:48:38.353075Z","end":"2026-07-15T21:48:38.502203Z","steps":["trace[2113923221] 'agreement among raft nodes before linearized reading' (duration: 148.929661ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:48:38.741041Z","caller":"traceutil/trace.go:171","msg":"trace[177822187] transaction","detail":"{read_only:false; response_revision:4911; number_of_response:1; }","duration":"234.026992ms","start":"2026-07-15T21:48:38.506991Z","end":"2026-07-15T21:48:38.741018Z","steps":["trace[177822187] 'process raft request' (duration: 233.972909ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:48:38.741196Z","caller":"traceutil/trace.go:171","msg":"trace[829513320] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"235.207959ms","start":"2026-07-15T21:48:38.505977Z","end":"2026-07-15T21:48:38.741185Z","steps":["trace[829513320] 'process raft request' (duration: 220.901759ms)","trace[829513320] 'compare' (duration: 13.692946ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:49:54.015245Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2864} {"level":"info","ts":"2026-07-15T21:49:54.091776Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2864,"took":"74.901516ms","hash":2344630355,"current-db-size-bytes":54493184,"current-db-size":"54 MB","current-db-size-in-use-bytes":29982720,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-15T21:49:54.091834Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2344630355,"revision":2864,"compact-revision":-1} {"level":"info","ts":"2026-07-15T21:52:01.205212Z","caller":"traceutil/trace.go:171","msg":"trace[1591336968] transaction","detail":"{read_only:false; response_revision:6428; number_of_response:1; }","duration":"100.716926ms","start":"2026-07-15T21:52:01.104456Z","end":"2026-07-15T21:52:01.205173Z","steps":["trace[1591336968] 'process raft request' (duration: 100.504786ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:52:26.026555Z","caller":"traceutil/trace.go:171","msg":"trace[885890105] linearizableReadLoop","detail":"{readStateIndex:6860; appliedIndex:6859; }","duration":"126.003961ms","start":"2026-07-15T21:52:25.900525Z","end":"2026-07-15T21:52:26.026529Z","steps":["trace[885890105] 'read index received' (duration: 125.870114ms)","trace[885890105] 'applied index is now lower than readState.Index' (duration: 132.947µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T21:52:26.027092Z","caller":"traceutil/trace.go:171","msg":"trace[1550748233] transaction","detail":"{read_only:false; response_revision:6614; number_of_response:1; }","duration":"191.487386ms","start":"2026-07-15T21:52:25.83559Z","end":"2026-07-15T21:52:26.027078Z","steps":["trace[1550748233] 'process raft request' (duration: 190.783212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:52:26.027529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.979858ms","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-07-15T21:52:26.02761Z","caller":"traceutil/trace.go:171","msg":"trace[694305352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6614; }","duration":"127.153656ms","start":"2026-07-15T21:52:25.900444Z","end":"2026-07-15T21:52:26.027598Z","steps":["trace[694305352] 'agreement among raft nodes before linearized reading' (duration: 126.942506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T21:52:26.027913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.293094ms","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-07-15T21:52:26.02801Z","caller":"traceutil/trace.go:171","msg":"trace[1025584358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6614; }","duration":"127.406279ms","start":"2026-07-15T21:52:25.900594Z","end":"2026-07-15T21:52:26.028Z","steps":["trace[1025584358] 'agreement among raft nodes before linearized reading' (duration: 127.252802ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T21:54:54.021969Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5567} {"level":"info","ts":"2026-07-15T21:54:54.091293Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5567,"took":"68.110961ms","hash":1738110681,"current-db-size-bytes":54493184,"current-db-size":"54 MB","current-db-size-in-use-bytes":18567168,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-15T21:54:54.091343Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1738110681,"revision":5567,"compact-revision":2864}