{"level":"warn","ts":"2026-03-25T21:04:57.479009Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T21:04:57.479118Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.78: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.78:2380","--initial-cluster=instance=https://199.204.45.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.78: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-03-25T21:04:57.479215Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T21:04:57.479233Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-03-25T21:04:57.47925Z","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-03-25T21:04:57.479914Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"]} {"level":"info","ts":"2026-03-25T21:04:57.47999Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.78: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-03-25T21:04:57.485862Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.212872ms"} {"level":"info","ts":"2026-03-25T21:04:57.499261Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6eb8766b1ec86e92","cluster-id":"9bbfe9e5d45d3718"} {"level":"info","ts":"2026-03-25T21:04:57.499367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=()"} {"level":"info","ts":"2026-03-25T21:04:57.499398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 0"} {"level":"info","ts":"2026-03-25T21:04:57.499416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6eb8766b1ec86e92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-25T21:04:57.499426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 1"} {"level":"info","ts":"2026-03-25T21:04:57.499473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"warn","ts":"2026-03-25T21:04:57.509132Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-25T21:04:57.51282Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-25T21:04:57.514812Z","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-03-25T21:04:57.51879Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6eb8766b1ec86e92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-25T21:04:57.519795Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6eb8766b1ec86e92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-25T21:04:57.519815Z","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-03-25T21:04:57.519934Z","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-03-25T21:04:57.520025Z","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-03-25T21:04:57.521714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"info","ts":"2026-03-25T21:04:57.522084Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","added-peer-id":"6eb8766b1ec86e92","added-peer-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-03-25T21:04:57.5234Z","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-03-25T21:04:57.523493Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-03-25T21:04:57.523543Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-03-25T21:04:57.523602Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6eb8766b1ec86e92","initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-25T21:04:57.523656Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-25T21:04:58.400476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 is starting a new election at term 1"} {"level":"info","ts":"2026-03-25T21:04:58.400519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-25T21:04:58.400541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgPreVoteResp from 6eb8766b1ec86e92 at term 1"} {"level":"info","ts":"2026-03-25T21:04:58.400552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became candidate at term 2"} {"level":"info","ts":"2026-03-25T21:04:58.400557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgVoteResp from 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-03-25T21:04:58.400566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became leader at term 2"} {"level":"info","ts":"2026-03-25T21:04:58.400572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6eb8766b1ec86e92 elected leader 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-03-25T21:04:58.401582Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6eb8766b1ec86e92","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.78:2379]}","request-path":"/0/members/6eb8766b1ec86e92/attributes","cluster-id":"9bbfe9e5d45d3718","publish-timeout":"7s"} {"level":"info","ts":"2026-03-25T21:04:58.401657Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:04:58.401721Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T21:04:58.40167Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T21:04:58.401887Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-25T21:04:58.401984Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-25T21:04:58.402911Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:04:58.403096Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:04:58.403141Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:04:58.405824Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-25T21:04:58.406029Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.78:2379"} {"level":"warn","ts":"2026-03-25T21:05:18.661637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.859983ms","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-03-25T21:05:18.661775Z","caller":"traceutil/trace.go:171","msg":"trace[902440635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:412; }","duration":"169.020157ms","start":"2026-03-25T21:05:18.492735Z","end":"2026-03-25T21:05:18.661756Z","steps":["trace[902440635] 'range keys from in-memory index tree' (duration: 168.74284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:18.661781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.973611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:18.661885Z","caller":"traceutil/trace.go:171","msg":"trace[551741455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"312.086815ms","start":"2026-03-25T21:05:18.349772Z","end":"2026-03-25T21:05:18.661858Z","steps":["trace[551741455] 'range keys from in-memory index tree' (duration: 311.884268ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:18.661988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:18.349708Z","time spent":"312.265029ms","remote":"127.0.0.1:57488","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-03-25T21:05:24.205169Z","caller":"traceutil/trace.go:171","msg":"trace[1544653217] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"105.92403ms","start":"2026-03-25T21:05:24.09922Z","end":"2026-03-25T21:05:24.205144Z","steps":["trace[1544653217] 'process raft request' (duration: 105.792106ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:27.246107Z","caller":"traceutil/trace.go:171","msg":"trace[29039328] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"170.779355ms","start":"2026-03-25T21:05:27.075306Z","end":"2026-03-25T21:05:27.246085Z","steps":["trace[29039328] 'process raft request' (duration: 170.59217ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:27.254508Z","caller":"traceutil/trace.go:171","msg":"trace[1729294222] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"161.901882ms","start":"2026-03-25T21:05:27.092575Z","end":"2026-03-25T21:05:27.254477Z","steps":["trace[1729294222] 'process raft request' (duration: 161.678646ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:27.254529Z","caller":"traceutil/trace.go:171","msg":"trace[698492516] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"154.662455ms","start":"2026-03-25T21:05:27.099842Z","end":"2026-03-25T21:05:27.254504Z","steps":["trace[698492516] 'process raft request' (duration: 154.505421ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:27.254701Z","caller":"traceutil/trace.go:171","msg":"trace[1784630874] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"113.821295ms","start":"2026-03-25T21:05:27.140857Z","end":"2026-03-25T21:05:27.254678Z","steps":["trace[1784630874] 'process raft request' (duration: 113.717993ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:27.254784Z","caller":"traceutil/trace.go:171","msg":"trace[1968071538] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"138.644986ms","start":"2026-03-25T21:05:27.116091Z","end":"2026-03-25T21:05:27.254736Z","steps":["trace[1968071538] 'process raft request' (duration: 138.354188ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:27.254879Z","caller":"traceutil/trace.go:171","msg":"trace[1446339781] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"113.58096ms","start":"2026-03-25T21:05:27.141257Z","end":"2026-03-25T21:05:27.254838Z","steps":["trace[1446339781] 'process raft request' (duration: 113.372314ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:27.254608Z","caller":"traceutil/trace.go:171","msg":"trace[1747410860] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"138.505073ms","start":"2026-03-25T21:05:27.116034Z","end":"2026-03-25T21:05:27.254539Z","steps":["trace[1747410860] 'process raft request' (duration: 138.356359ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:27.254968Z","caller":"traceutil/trace.go:171","msg":"trace[597273618] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"112.077538ms","start":"2026-03-25T21:05:27.142871Z","end":"2026-03-25T21:05:27.254948Z","steps":["trace[597273618] 'process raft request' (duration: 111.80743ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:27.254848Z","caller":"traceutil/trace.go:171","msg":"trace[1146380403] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"138.562063ms","start":"2026-03-25T21:05:27.116112Z","end":"2026-03-25T21:05:27.254674Z","steps":["trace[1146380403] 'process raft request' (duration: 138.391239ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:27.255398Z","caller":"traceutil/trace.go:171","msg":"trace[1347630725] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"103.36225ms","start":"2026-03-25T21:05:27.152023Z","end":"2026-03-25T21:05:27.255385Z","steps":["trace[1347630725] 'process raft request' (duration: 102.865137ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:27.255653Z","caller":"traceutil/trace.go:171","msg":"trace[1316775976] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"102.869976ms","start":"2026-03-25T21:05:27.15277Z","end":"2026-03-25T21:05:27.25564Z","steps":["trace[1316775976] 'process raft request' (duration: 102.530286ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:27.548633Z","caller":"traceutil/trace.go:171","msg":"trace[1026588194] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"141.658948ms","start":"2026-03-25T21:05:27.406942Z","end":"2026-03-25T21:05:27.548601Z","steps":["trace[1026588194] 'process raft request' (duration: 82.574521ms)","trace[1026588194] 'compare' (duration: 58.614575ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:05:27.548729Z","caller":"traceutil/trace.go:171","msg":"trace[1606278497] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"132.356544ms","start":"2026-03-25T21:05:27.41634Z","end":"2026-03-25T21:05:27.548697Z","steps":["trace[1606278497] 'process raft request' (duration: 131.971134ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:27.54885Z","caller":"traceutil/trace.go:171","msg":"trace[1314674132] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"127.47463ms","start":"2026-03-25T21:05:27.42136Z","end":"2026-03-25T21:05:27.548834Z","steps":["trace[1314674132] 'process raft request' (duration: 127.218482ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:27.549049Z","caller":"traceutil/trace.go:171","msg":"trace[2127914741] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"104.812559ms","start":"2026-03-25T21:05:27.444221Z","end":"2026-03-25T21:05:27.549034Z","steps":["trace[2127914741] 'process raft request' (duration: 104.553552ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:33.730352Z","caller":"traceutil/trace.go:171","msg":"trace[1512203562] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"248.641398ms","start":"2026-03-25T21:05:33.481686Z","end":"2026-03-25T21:05:33.730327Z","steps":["trace[1512203562] 'process raft request' (duration: 248.089882ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:05:34.195717Z","caller":"traceutil/trace.go:171","msg":"trace[1564464171] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"399.759384ms","start":"2026-03-25T21:05:33.795933Z","end":"2026-03-25T21:05:34.195692Z","steps":["trace[1564464171] 'process raft request' (duration: 323.513107ms)","trace[1564464171] 'compare' (duration: 76.123334ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:05:34.196125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:33.79591Z","time spent":"399.872668ms","remote":"127.0.0.1:57954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T21:05:34.517233Z","caller":"traceutil/trace.go:171","msg":"trace[2079137057] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"307.770166ms","start":"2026-03-25T21:05:34.209441Z","end":"2026-03-25T21:05:34.517211Z","steps":["trace[2079137057] 'read index received' (duration: 305.850563ms)","trace[2079137057] 'applied index is now lower than readState.Index' (duration: 1.918493ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:05:34.517317Z","caller":"traceutil/trace.go:171","msg":"trace[1276147513] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"311.795266ms","start":"2026-03-25T21:05:34.205508Z","end":"2026-03-25T21:05:34.517303Z","steps":["trace[1276147513] 'process raft request' (duration: 309.866493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:34.517376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.91728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:34.517423Z","caller":"traceutil/trace.go:171","msg":"trace[1944473733] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:580; }","duration":"307.998472ms","start":"2026-03-25T21:05:34.209408Z","end":"2026-03-25T21:05:34.517406Z","steps":["trace[1944473733] 'agreement among raft nodes before linearized reading' (duration: 307.927471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:34.517465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:34.209392Z","time spent":"308.062194ms","remote":"127.0.0.1:57726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-03-25T21:05:34.517535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:05:34.205488Z","time spent":"311.858457ms","remote":"127.0.0.1:57674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:05:34.517549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.511603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:05:34.517619Z","caller":"traceutil/trace.go:171","msg":"trace[458107229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"168.623166ms","start":"2026-03-25T21:05:34.348977Z","end":"2026-03-25T21:05:34.5176Z","steps":["trace[458107229] 'agreement among raft nodes before linearized reading' (duration: 168.501092ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:05:34.517649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.977455ms","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-03-25T21:05:34.517751Z","caller":"traceutil/trace.go:171","msg":"trace[386247327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"257.12709ms","start":"2026-03-25T21:05:34.260599Z","end":"2026-03-25T21:05:34.517726Z","steps":["trace[386247327] 'agreement among raft nodes before linearized reading' (duration: 256.853232ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:06:17.632868Z","caller":"traceutil/trace.go:171","msg":"trace[1761960898] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"137.788001ms","start":"2026-03-25T21:06:17.495049Z","end":"2026-03-25T21:06:17.632837Z","steps":["trace[1761960898] 'process raft request' (duration: 137.592116ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:01.698454Z","caller":"traceutil/trace.go:171","msg":"trace[551223553] linearizableReadLoop","detail":"{readStateIndex:1856; appliedIndex:1855; }","duration":"214.588792ms","start":"2026-03-25T21:07:01.483833Z","end":"2026-03-25T21:07:01.698422Z","steps":["trace[551223553] 'read index received' (duration: 159.586028ms)","trace[551223553] 'applied index is now lower than readState.Index' (duration: 55.001264ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:01.69854Z","caller":"traceutil/trace.go:171","msg":"trace[1132140141] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"397.654503ms","start":"2026-03-25T21:07:01.300814Z","end":"2026-03-25T21:07:01.698469Z","steps":["trace[1132140141] 'process raft request' (duration: 342.559046ms)","trace[1132140141] 'compare' (duration: 54.680646ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:07:01.698648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.805217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-25T21:07:01.698743Z","caller":"traceutil/trace.go:171","msg":"trace[718896416] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:1789; }","duration":"214.961301ms","start":"2026-03-25T21:07:01.483765Z","end":"2026-03-25T21:07:01.698726Z","steps":["trace[718896416] 'agreement among raft nodes before linearized reading' (duration: 214.733976ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:01.698792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:01.300793Z","time spent":"397.870059ms","remote":"127.0.0.1:57746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:07:01.698807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.502197ms","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-03-25T21:07:01.698857Z","caller":"traceutil/trace.go:171","msg":"trace[1118687833] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1789; }","duration":"114.60518ms","start":"2026-03-25T21:07:01.584242Z","end":"2026-03-25T21:07:01.698848Z","steps":["trace[1118687833] 'agreement among raft nodes before linearized reading' (duration: 114.340383ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:01.879152Z","caller":"traceutil/trace.go:171","msg":"trace[2012985448] linearizableReadLoop","detail":"{readStateIndex:1857; appliedIndex:1856; }","duration":"103.674039ms","start":"2026-03-25T21:07:01.775447Z","end":"2026-03-25T21:07:01.879121Z","steps":["trace[2012985448] 'read index received' (duration: 60.491229ms)","trace[2012985448] 'applied index is now lower than readState.Index' (duration: 43.18084ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:07:01.879616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.126961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9b1bb2f5-4226-4947-aba3-e872f60e3951\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-03-25T21:07:01.879746Z","caller":"traceutil/trace.go:171","msg":"trace[378769996] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9b1bb2f5-4226-4947-aba3-e872f60e3951; range_end:; response_count:1; response_revision:1789; }","duration":"104.327737ms","start":"2026-03-25T21:07:01.775378Z","end":"2026-03-25T21:07:01.879706Z","steps":["trace[378769996] 'agreement among raft nodes before linearized reading' (duration: 103.851634ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:02.175184Z","caller":"traceutil/trace.go:171","msg":"trace[407289694] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"198.243247ms","start":"2026-03-25T21:07:01.976876Z","end":"2026-03-25T21:07:02.17512Z","steps":["trace[407289694] 'process raft request' (duration: 98.275276ms)","trace[407289694] 'compare' (duration: 99.688373ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:14.572186Z","caller":"traceutil/trace.go:171","msg":"trace[1958975459] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1963; }","duration":"224.938386ms","start":"2026-03-25T21:07:14.347217Z","end":"2026-03-25T21:07:14.572155Z","steps":["trace[1958975459] 'read index received' (duration: 224.789292ms)","trace[1958975459] 'applied index is now lower than readState.Index' (duration: 147.584µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:14.572308Z","caller":"traceutil/trace.go:171","msg":"trace[38543278] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"233.698119ms","start":"2026-03-25T21:07:14.338571Z","end":"2026-03-25T21:07:14.572269Z","steps":["trace[38543278] 'process raft request' (duration: 233.411882ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:14.57241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.117481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:07:14.572521Z","caller":"traceutil/trace.go:171","msg":"trace[1789777292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1893; }","duration":"225.274506ms","start":"2026-03-25T21:07:14.347188Z","end":"2026-03-25T21:07:14.572463Z","steps":["trace[1789777292] 'agreement among raft nodes before linearized reading' (duration: 225.119351ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:14.582597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.511421ms","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-03-25T21:07:14.582698Z","caller":"traceutil/trace.go:171","msg":"trace[1557253919] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1894; }","duration":"107.655735ms","start":"2026-03-25T21:07:14.475015Z","end":"2026-03-25T21:07:14.582671Z","steps":["trace[1557253919] 'agreement among raft nodes before linearized reading' (duration: 107.431819ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:41.300974Z","caller":"traceutil/trace.go:171","msg":"trace[1930755311] linearizableReadLoop","detail":"{readStateIndex:2130; appliedIndex:2129; }","duration":"104.897172ms","start":"2026-03-25T21:07:41.196046Z","end":"2026-03-25T21:07:41.300943Z","steps":["trace[1930755311] 'read index received' (duration: 104.605415ms)","trace[1930755311] 'applied index is now lower than readState.Index' (duration: 290.317µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:41.301068Z","caller":"traceutil/trace.go:171","msg":"trace[287073999] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"124.221626ms","start":"2026-03-25T21:07:41.176797Z","end":"2026-03-25T21:07:41.301019Z","steps":["trace[287073999] 'process raft request' (duration: 123.924739ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:41.301288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.218331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-03-25T21:07:41.301343Z","caller":"traceutil/trace.go:171","msg":"trace[1507530129] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2054; }","duration":"105.371945ms","start":"2026-03-25T21:07:41.195954Z","end":"2026-03-25T21:07:41.301326Z","steps":["trace[1507530129] 'agreement among raft nodes before linearized reading' (duration: 105.161908ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:46.862574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.481773ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-25T21:07:46.863094Z","caller":"traceutil/trace.go:171","msg":"trace[61978044] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"144.398403ms","start":"2026-03-25T21:07:46.718629Z","end":"2026-03-25T21:07:46.863027Z","steps":["trace[61978044] 'process raft request' (duration: 144.069664ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:46.863223Z","caller":"traceutil/trace.go:171","msg":"trace[2081124460] linearizableReadLoop","detail":"{readStateIndex:2159; appliedIndex:2157; }","duration":"104.254534ms","start":"2026-03-25T21:07:46.758938Z","end":"2026-03-25T21:07:46.863193Z","steps":["trace[2081124460] 'read index received' (duration: 94.053034ms)","trace[2081124460] 'applied index is now lower than readState.Index' (duration: 10.19858ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:07:46.864252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.278693ms","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-03-25T21:07:46.864344Z","caller":"traceutil/trace.go:171","msg":"trace[502343940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2082; }","duration":"105.411856ms","start":"2026-03-25T21:07:46.758897Z","end":"2026-03-25T21:07:46.864309Z","steps":["trace[502343940] 'agreement among raft nodes before linearized reading' (duration: 104.392938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:47.14874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.221635ms","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-03-25T21:07:47.148843Z","caller":"traceutil/trace.go:171","msg":"trace[219950131] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2084; }","duration":"103.399739ms","start":"2026-03-25T21:07:47.045417Z","end":"2026-03-25T21:07:47.148817Z","steps":["trace[219950131] 'range keys from in-memory index tree' (duration: 102.925556ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:47.452887Z","caller":"traceutil/trace.go:171","msg":"trace[1706835711] linearizableReadLoop","detail":"{readStateIndex:2163; appliedIndex:2162; }","duration":"115.322001ms","start":"2026-03-25T21:07:47.337529Z","end":"2026-03-25T21:07:47.452851Z","steps":["trace[1706835711] 'read index received' (duration: 115.013964ms)","trace[1706835711] 'applied index is now lower than readState.Index' (duration: 306.307µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:47.453075Z","caller":"traceutil/trace.go:171","msg":"trace[1837943845] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"130.133932ms","start":"2026-03-25T21:07:47.322888Z","end":"2026-03-25T21:07:47.453022Z","steps":["trace[1837943845] 'process raft request' (duration: 129.767172ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:47.453215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.657881ms","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-03-25T21:07:47.453218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.81138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:07:47.453285Z","caller":"traceutil/trace.go:171","msg":"trace[512671898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2085; }","duration":"115.783404ms","start":"2026-03-25T21:07:47.337484Z","end":"2026-03-25T21:07:47.453268Z","steps":["trace[512671898] 'agreement among raft nodes before linearized reading' (duration: 115.552468ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:47.453298Z","caller":"traceutil/trace.go:171","msg":"trace[2135558010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2085; }","duration":"103.921852ms","start":"2026-03-25T21:07:47.349356Z","end":"2026-03-25T21:07:47.453278Z","steps":["trace[2135558010] 'agreement among raft nodes before linearized reading' (duration: 103.757908ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:47.722966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.179783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:07:47.723144Z","caller":"traceutil/trace.go:171","msg":"trace[1277717959] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"264.441033ms","start":"2026-03-25T21:07:47.458673Z","end":"2026-03-25T21:07:47.723114Z","steps":["trace[1277717959] 'process raft request' (duration: 150.022185ms)","trace[1277717959] 'compare' (duration: 114.017638ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:47.792068Z","caller":"traceutil/trace.go:171","msg":"trace[1411688334] linearizableReadLoop","detail":"{readStateIndex:2165; appliedIndex:2163; }","duration":"236.762886ms","start":"2026-03-25T21:07:47.555274Z","end":"2026-03-25T21:07:47.792037Z","steps":["trace[1411688334] 'read index received' (duration: 53.392194ms)","trace[1411688334] 'applied index is now lower than readState.Index' (duration: 183.369312ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:47.792115Z","caller":"traceutil/trace.go:171","msg":"trace[146472086] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"332.932875ms","start":"2026-03-25T21:07:47.459143Z","end":"2026-03-25T21:07:47.792076Z","steps":["trace[146472086] 'process raft request' (duration: 332.75244ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:47.792252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.970821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T21:07:47.792296Z","caller":"traceutil/trace.go:171","msg":"trace[39072420] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2087; }","duration":"237.033623ms","start":"2026-03-25T21:07:47.555248Z","end":"2026-03-25T21:07:47.792282Z","steps":["trace[39072420] 'agreement among raft nodes before linearized reading' (duration: 236.93704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:47.792291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:47.459111Z","time spent":"333.082199ms","remote":"127.0.0.1:57746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:07:47.792501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.620204ms","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-03-25T21:07:47.792613Z","caller":"traceutil/trace.go:171","msg":"trace[1710867238] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2087; }","duration":"200.80337ms","start":"2026-03-25T21:07:47.591784Z","end":"2026-03-25T21:07:47.792587Z","steps":["trace[1710867238] 'agreement among raft nodes before linearized reading' (duration: 200.462461ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:52.998922Z","caller":"traceutil/trace.go:171","msg":"trace[648814080] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"107.398904ms","start":"2026-03-25T21:07:52.891478Z","end":"2026-03-25T21:07:52.998877Z","steps":["trace[648814080] 'process raft request' (duration: 107.197769ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:41.422197Z","caller":"traceutil/trace.go:171","msg":"trace[908147552] transaction","detail":"{read_only:false; response_revision:2546; number_of_response:1; }","duration":"270.700538ms","start":"2026-03-25T21:08:41.151467Z","end":"2026-03-25T21:08:41.422168Z","steps":["trace[908147552] 'process raft request' (duration: 270.521934ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:41.426224Z","caller":"traceutil/trace.go:171","msg":"trace[1820052886] linearizableReadLoop","detail":"{readStateIndex:2642; appliedIndex:2640; }","duration":"180.178149ms","start":"2026-03-25T21:08:41.246013Z","end":"2026-03-25T21:08:41.426191Z","steps":["trace[1820052886] 'read index received' (duration: 175.980018ms)","trace[1820052886] 'applied index is now lower than readState.Index' (duration: 4.197001ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:08:41.426412Z","caller":"traceutil/trace.go:171","msg":"trace[1851360156] transaction","detail":"{read_only:false; response_revision:2547; number_of_response:1; }","duration":"211.834161ms","start":"2026-03-25T21:08:41.214525Z","end":"2026-03-25T21:08:41.426359Z","steps":["trace[1851360156] 'process raft request' (duration: 211.496971ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:41.426499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.465435ms","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-03-25T21:08:41.426541Z","caller":"traceutil/trace.go:171","msg":"trace[849193420] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2547; }","duration":"180.555598ms","start":"2026-03-25T21:08:41.245973Z","end":"2026-03-25T21:08:41.426529Z","steps":["trace[849193420] 'agreement among raft nodes before linearized reading' (duration: 180.367753ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:15.451814Z","caller":"traceutil/trace.go:171","msg":"trace[1058555378] linearizableReadLoop","detail":"{readStateIndex:2854; appliedIndex:2853; }","duration":"102.102574ms","start":"2026-03-25T21:09:15.349678Z","end":"2026-03-25T21:09:15.451781Z","steps":["trace[1058555378] 'read index received' (duration: 101.92244ms)","trace[1058555378] 'applied index is now lower than readState.Index' (duration: 179.154µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:09:15.452017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.320859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:09:15.452074Z","caller":"traceutil/trace.go:171","msg":"trace[1222402553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2751; }","duration":"102.447224ms","start":"2026-03-25T21:09:15.349611Z","end":"2026-03-25T21:09:15.452058Z","steps":["trace[1222402553] 'agreement among raft nodes before linearized reading' (duration: 102.3091ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:15.452042Z","caller":"traceutil/trace.go:171","msg":"trace[1980489591] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"211.327874ms","start":"2026-03-25T21:09:15.240674Z","end":"2026-03-25T21:09:15.452002Z","steps":["trace[1980489591] 'process raft request' (duration: 210.893883ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:15.678788Z","caller":"traceutil/trace.go:171","msg":"trace[1082722174] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"117.055326ms","start":"2026-03-25T21:09:15.561695Z","end":"2026-03-25T21:09:15.67875Z","steps":["trace[1082722174] 'process raft request' (duration: 116.763679ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:15.679661Z","caller":"traceutil/trace.go:171","msg":"trace[701378784] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"116.232805ms","start":"2026-03-25T21:09:15.563264Z","end":"2026-03-25T21:09:15.679497Z","steps":["trace[701378784] 'process raft request' (duration: 115.993459ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:46.551308Z","caller":"traceutil/trace.go:171","msg":"trace[334316040] linearizableReadLoop","detail":"{readStateIndex:3031; appliedIndex:3030; }","duration":"119.270704ms","start":"2026-03-25T21:09:46.432006Z","end":"2026-03-25T21:09:46.551277Z","steps":["trace[334316040] 'read index received' (duration: 119.045908ms)","trace[334316040] 'applied index is now lower than readState.Index' (duration: 223.436µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:09:46.551372Z","caller":"traceutil/trace.go:171","msg":"trace[755900545] transaction","detail":"{read_only:false; response_revision:2922; number_of_response:1; }","duration":"122.300614ms","start":"2026-03-25T21:09:46.429031Z","end":"2026-03-25T21:09:46.551331Z","steps":["trace[755900545] 'process raft request' (duration: 122.069689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:46.551621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.586432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.18a031be441f84c3\" ","response":"range_response_count:1 size:768"} {"level":"info","ts":"2026-03-25T21:09:46.551697Z","caller":"traceutil/trace.go:171","msg":"trace[1375529171] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.18a031be441f84c3; range_end:; response_count:1; response_revision:2922; }","duration":"119.708846ms","start":"2026-03-25T21:09:46.431969Z","end":"2026-03-25T21:09:46.551678Z","steps":["trace[1375529171] 'agreement among raft nodes before linearized reading' (duration: 119.481129ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:11:46.567604Z","caller":"traceutil/trace.go:171","msg":"trace[1897967716] linearizableReadLoop","detail":"{readStateIndex:3887; appliedIndex:3886; }","duration":"120.684563ms","start":"2026-03-25T21:11:46.446884Z","end":"2026-03-25T21:11:46.567568Z","steps":["trace[1897967716] 'read index received' (duration: 120.431925ms)","trace[1897967716] 'applied index is now lower than readState.Index' (duration: 251.228µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:11:46.568099Z","caller":"traceutil/trace.go:171","msg":"trace[1156579266] transaction","detail":"{read_only:false; response_revision:3748; number_of_response:1; }","duration":"184.602663ms","start":"2026-03-25T21:11:46.38348Z","end":"2026-03-25T21:11:46.568082Z","steps":["trace[1156579266] 'process raft request' (duration: 183.863573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:11:46.568509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.605406ms","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-03-25T21:11:46.56856Z","caller":"traceutil/trace.go:171","msg":"trace[462481194] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3748; }","duration":"121.73164ms","start":"2026-03-25T21:11:46.446818Z","end":"2026-03-25T21:11:46.568549Z","steps":["trace[462481194] 'agreement among raft nodes before linearized reading' (duration: 121.539695ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:01.002511Z","caller":"traceutil/trace.go:171","msg":"trace[930336372] transaction","detail":"{read_only:false; response_revision:4250; number_of_response:1; }","duration":"110.418319ms","start":"2026-03-25T21:13:00.892061Z","end":"2026-03-25T21:13:01.002479Z","steps":["trace[930336372] 'process raft request' (duration: 110.138011ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:08.672705Z","caller":"traceutil/trace.go:171","msg":"trace[165546150] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"153.10517ms","start":"2026-03-25T21:13:08.519571Z","end":"2026-03-25T21:13:08.672676Z","steps":["trace[165546150] 'process raft request' (duration: 152.945496ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:08.678184Z","caller":"traceutil/trace.go:171","msg":"trace[831945365] transaction","detail":"{read_only:false; response_revision:4694; number_of_response:1; }","duration":"127.836707ms","start":"2026-03-25T21:13:08.550322Z","end":"2026-03-25T21:13:08.678159Z","steps":["trace[831945365] 'process raft request' (duration: 127.561169ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:08.678223Z","caller":"traceutil/trace.go:171","msg":"trace[1130333164] transaction","detail":"{read_only:false; response_revision:4695; number_of_response:1; }","duration":"126.44156ms","start":"2026-03-25T21:13:08.551756Z","end":"2026-03-25T21:13:08.678198Z","steps":["trace[1130333164] 'process raft request' (duration: 126.295847ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:10.970085Z","caller":"traceutil/trace.go:171","msg":"trace[1397887471] transaction","detail":"{read_only:false; response_revision:4725; number_of_response:1; }","duration":"157.222131ms","start":"2026-03-25T21:13:10.812836Z","end":"2026-03-25T21:13:10.970058Z","steps":["trace[1397887471] 'process raft request' (duration: 156.976045ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:11.014823Z","caller":"traceutil/trace.go:171","msg":"trace[819740406] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"120.277905ms","start":"2026-03-25T21:13:10.894525Z","end":"2026-03-25T21:13:11.014803Z","steps":["trace[819740406] 'process raft request' (duration: 119.746221ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:11.202845Z","caller":"traceutil/trace.go:171","msg":"trace[1935168782] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"180.837678ms","start":"2026-03-25T21:13:11.021969Z","end":"2026-03-25T21:13:11.202806Z","steps":["trace[1935168782] 'process raft request' (duration: 178.067763ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:11.207876Z","caller":"traceutil/trace.go:171","msg":"trace[1128247161] linearizableReadLoop","detail":"{readStateIndex:4906; appliedIndex:4903; }","duration":"118.591809ms","start":"2026-03-25T21:13:11.089269Z","end":"2026-03-25T21:13:11.207861Z","steps":["trace[1128247161] 'read index received' (duration: 110.790069ms)","trace[1128247161] 'applied index is now lower than readState.Index' (duration: 7.80031ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:11.207943Z","caller":"traceutil/trace.go:171","msg":"trace[291445648] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"146.083361ms","start":"2026-03-25T21:13:11.061826Z","end":"2026-03-25T21:13:11.20791Z","steps":["trace[291445648] 'process raft request' (duration: 145.850255ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:11.208091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.775923ms","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-03-25T21:13:11.208128Z","caller":"traceutil/trace.go:171","msg":"trace[1059490006] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4729; }","duration":"118.892896ms","start":"2026-03-25T21:13:11.089227Z","end":"2026-03-25T21:13:11.20812Z","steps":["trace[1059490006] 'agreement among raft nodes before linearized reading' (duration: 118.712902ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:11.208198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.862989ms","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-03-25T21:13:11.208266Z","caller":"traceutil/trace.go:171","msg":"trace[519037511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4729; }","duration":"117.966763ms","start":"2026-03-25T21:13:11.090285Z","end":"2026-03-25T21:13:11.208252Z","steps":["trace[519037511] 'agreement among raft nodes before linearized reading' (duration: 117.725456ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:11.208458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.137839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pcrl6\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-25T21:13:11.208609Z","caller":"traceutil/trace.go:171","msg":"trace[1142389865] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pcrl6; range_end:; response_count:1; response_revision:4729; }","duration":"104.302273ms","start":"2026-03-25T21:13:11.10428Z","end":"2026-03-25T21:13:11.208582Z","steps":["trace[1142389865] 'agreement among raft nodes before linearized reading' (duration: 103.783689ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:11.207748Z","caller":"traceutil/trace.go:171","msg":"trace[616376965] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"182.785911ms","start":"2026-03-25T21:13:11.024933Z","end":"2026-03-25T21:13:11.207719Z","steps":["trace[616376965] 'process raft request' (duration: 182.605327ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:14.238368Z","caller":"traceutil/trace.go:171","msg":"trace[1112262515] linearizableReadLoop","detail":"{readStateIndex:5003; appliedIndex:5002; }","duration":"151.909068ms","start":"2026-03-25T21:13:14.086436Z","end":"2026-03-25T21:13:14.238345Z","steps":["trace[1112262515] 'read index received' (duration: 151.653371ms)","trace[1112262515] 'applied index is now lower than readState.Index' (duration: 254.487µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:14.238432Z","caller":"traceutil/trace.go:171","msg":"trace[1689294300] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"154.779095ms","start":"2026-03-25T21:13:14.08361Z","end":"2026-03-25T21:13:14.238389Z","steps":["trace[1689294300] 'process raft request' (duration: 154.58921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:14.239324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.870783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2196036"} {"level":"info","ts":"2026-03-25T21:13:14.239361Z","caller":"traceutil/trace.go:171","msg":"trace[2133250352] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4822; }","duration":"152.972196ms","start":"2026-03-25T21:13:14.08638Z","end":"2026-03-25T21:13:14.239352Z","steps":["trace[2133250352] 'agreement among raft nodes before linearized reading' (duration: 152.072792ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:14.529519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.100593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-03-25T21:13:14.529619Z","caller":"traceutil/trace.go:171","msg":"trace[1703558193] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4823; }","duration":"142.287209ms","start":"2026-03-25T21:13:14.387302Z","end":"2026-03-25T21:13:14.52959Z","steps":["trace[1703558193] 'range keys from in-memory index tree' (duration: 141.906358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:18.987979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.182419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16451"} {"level":"info","ts":"2026-03-25T21:13:18.988088Z","caller":"traceutil/trace.go:171","msg":"trace[1601150894] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4949; }","duration":"300.356823ms","start":"2026-03-25T21:13:18.687708Z","end":"2026-03-25T21:13:18.988065Z","steps":["trace[1601150894] 'range keys from in-memory index tree' (duration: 299.937582ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:18.98815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:18.687681Z","time spent":"300.458326ms","remote":"127.0.0.1:57674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":16474,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-25T21:13:19.227468Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967603480805689143,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-25T21:13:19.305098Z","caller":"traceutil/trace.go:171","msg":"trace[540991860] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"585.122695ms","start":"2026-03-25T21:13:18.719946Z","end":"2026-03-25T21:13:19.305069Z","steps":["trace[540991860] 'process raft request' (duration: 584.92402ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:19.305286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:18.719921Z","time spent":"585.260259ms","remote":"127.0.0.1:57746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:13:19.542107Z","caller":"traceutil/trace.go:171","msg":"trace[879411246] linearizableReadLoop","detail":"{readStateIndex:5135; appliedIndex:5133; }","duration":"814.942186ms","start":"2026-03-25T21:13:18.727136Z","end":"2026-03-25T21:13:19.542079Z","steps":["trace[879411246] 'read index received' (duration: 577.731917ms)","trace[879411246] 'applied index is now lower than readState.Index' (duration: 237.208879ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:19.542438Z","caller":"traceutil/trace.go:171","msg":"trace[2016385163] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"822.233262ms","start":"2026-03-25T21:13:18.720184Z","end":"2026-03-25T21:13:19.542417Z","steps":["trace[2016385163] 'process raft request' (duration: 792.099619ms)","trace[2016385163] 'compare' (duration: 29.295901ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:19.542562Z","caller":"traceutil/trace.go:171","msg":"trace[1434452746] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"307.101775ms","start":"2026-03-25T21:13:19.235364Z","end":"2026-03-25T21:13:19.542466Z","steps":["trace[1434452746] 'process raft request' (duration: 306.659443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:19.542635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.432449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-25T21:13:19.542707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.821599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-25T21:13:19.542765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.567785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:13:19.542735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.403622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4ad8d9c3-9c1b-4e06-b079-517d388f95ee\" ","response":"range_response_count:1 size:3600"} {"level":"warn","ts":"2026-03-25T21:13:19.542786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:19.235304Z","time spent":"307.358991ms","remote":"127.0.0.1:57746","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-03-25T21:13:19.542806Z","caller":"traceutil/trace.go:171","msg":"trace[1710362888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4952; }","duration":"192.643407ms","start":"2026-03-25T21:13:19.350152Z","end":"2026-03-25T21:13:19.542795Z","steps":["trace[1710362888] 'agreement among raft nodes before linearized reading' (duration: 192.592446ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:19.542796Z","caller":"traceutil/trace.go:171","msg":"trace[1243399380] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4952; }","duration":"269.985243ms","start":"2026-03-25T21:13:19.272789Z","end":"2026-03-25T21:13:19.542774Z","steps":["trace[1243399380] 'agreement among raft nodes before linearized reading' (duration: 269.665315ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:19.542792Z","caller":"traceutil/trace.go:171","msg":"trace[999273112] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4952; }","duration":"815.650674ms","start":"2026-03-25T21:13:18.727102Z","end":"2026-03-25T21:13:19.542753Z","steps":["trace[999273112] 'agreement among raft nodes before linearized reading' (duration: 815.318785ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:19.542843Z","caller":"traceutil/trace.go:171","msg":"trace[795330454] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4ad8d9c3-9c1b-4e06-b079-517d388f95ee; range_end:; response_count:1; response_revision:4952; }","duration":"767.562388ms","start":"2026-03-25T21:13:18.77526Z","end":"2026-03-25T21:13:19.542823Z","steps":["trace[795330454] 'agreement among raft nodes before linearized reading' (duration: 767.174407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:19.542691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.427699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:13:19.542908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.7816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4ad8d9c3-9c1b-4e06-b079-517d388f95ee\" ","response":"range_response_count:1 size:3600"} {"level":"warn","ts":"2026-03-25T21:13:19.54295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.962762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pcrl6\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-03-25T21:13:19.542955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.808933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16451"} {"level":"info","ts":"2026-03-25T21:13:19.543004Z","caller":"traceutil/trace.go:171","msg":"trace[50479441] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4ad8d9c3-9c1b-4e06-b079-517d388f95ee; range_end:; response_count:1; response_revision:4952; }","duration":"652.968766ms","start":"2026-03-25T21:13:18.890018Z","end":"2026-03-25T21:13:19.542987Z","steps":["trace[50479441] 'agreement among raft nodes before linearized reading' (duration: 652.598975ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:19.543011Z","caller":"traceutil/trace.go:171","msg":"trace[1645068784] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pcrl6; range_end:; response_count:1; response_revision:4952; }","duration":"257.075245ms","start":"2026-03-25T21:13:19.285921Z","end":"2026-03-25T21:13:19.542996Z","steps":["trace[1645068784] 'agreement among raft nodes before linearized reading' (duration: 256.827648ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:19.543005Z","caller":"traceutil/trace.go:171","msg":"trace[1365133537] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:4952; }","duration":"722.760279ms","start":"2026-03-25T21:13:18.820194Z","end":"2026-03-25T21:13:19.542954Z","steps":["trace[1365133537] 'agreement among raft nodes before linearized reading' (duration: 722.45315ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:19.543062Z","caller":"traceutil/trace.go:171","msg":"trace[880705978] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4952; }","duration":"549.940066ms","start":"2026-03-25T21:13:18.993098Z","end":"2026-03-25T21:13:19.543038Z","steps":["trace[880705978] 'agreement among raft nodes before linearized reading' (duration: 549.599796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:19.543073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:18.889989Z","time spent":"653.067038ms","remote":"127.0.0.1:57678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4ad8d9c3-9c1b-4e06-b079-517d388f95ee\" "} {"level":"warn","ts":"2026-03-25T21:13:19.543107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:18.820162Z","time spent":"722.911393ms","remote":"127.0.0.1:57700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/monitoring/vector\" "} {"level":"warn","ts":"2026-03-25T21:13:19.542895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:18.77522Z","time spent":"767.66624ms","remote":"127.0.0.1:57678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4ad8d9c3-9c1b-4e06-b079-517d388f95ee\" "} {"level":"warn","ts":"2026-03-25T21:13:19.54312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:18.993015Z","time spent":"550.093851ms","remote":"127.0.0.1:57674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":16474,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-25T21:13:19.543115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.42775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-25T21:13:19.542894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:18.727092Z","time spent":"815.780309ms","remote":"127.0.0.1:39640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-03-25T21:13:19.542598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:18.720176Z","time spent":"822.311094ms","remote":"127.0.0.1:57746","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-03-25T21:13:19.543241Z","caller":"traceutil/trace.go:171","msg":"trace[1683796405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4952; }","duration":"259.615964ms","start":"2026-03-25T21:13:19.283587Z","end":"2026-03-25T21:13:19.543203Z","steps":["trace[1683796405] 'agreement among raft nodes before linearized reading' (duration: 259.219663ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:19.876327Z","caller":"traceutil/trace.go:171","msg":"trace[347758836] transaction","detail":"{read_only:false; response_revision:4958; number_of_response:1; }","duration":"155.747562ms","start":"2026-03-25T21:13:19.720528Z","end":"2026-03-25T21:13:19.876276Z","steps":["trace[347758836] 'process raft request' (duration: 56.395231ms)","trace[347758836] 'compare' (duration: 98.682773ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:19.876344Z","caller":"traceutil/trace.go:171","msg":"trace[384710784] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"155.634428ms","start":"2026-03-25T21:13:19.720655Z","end":"2026-03-25T21:13:19.87629Z","steps":["trace[384710784] 'process raft request' (duration: 155.410952ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:31.274008Z","caller":"traceutil/trace.go:171","msg":"trace[1246607192] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"106.018979ms","start":"2026-03-25T21:13:31.167904Z","end":"2026-03-25T21:13:31.273923Z","steps":["trace[1246607192] 'process raft request' (duration: 58.214ms)","trace[1246607192] 'compare' (duration: 47.620765ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:31.29249Z","caller":"traceutil/trace.go:171","msg":"trace[523083598] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"123.659625ms","start":"2026-03-25T21:13:31.168773Z","end":"2026-03-25T21:13:31.292432Z","steps":["trace[523083598] 'process raft request' (duration: 123.411399ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:31.292848Z","caller":"traceutil/trace.go:171","msg":"trace[1787173244] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"123.994345ms","start":"2026-03-25T21:13:31.16883Z","end":"2026-03-25T21:13:31.292824Z","steps":["trace[1787173244] 'process raft request' (duration: 123.528542ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:31.29283Z","caller":"traceutil/trace.go:171","msg":"trace[789609063] linearizableReadLoop","detail":"{readStateIndex:5385; appliedIndex:5381; }","duration":"118.130126ms","start":"2026-03-25T21:13:31.174671Z","end":"2026-03-25T21:13:31.292801Z","steps":["trace[789609063] 'read index received' (duration: 51.701954ms)","trace[789609063] 'applied index is now lower than readState.Index' (duration: 66.427312ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:31.29327Z","caller":"traceutil/trace.go:171","msg":"trace[1126944021] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"124.137009ms","start":"2026-03-25T21:13:31.168905Z","end":"2026-03-25T21:13:31.293042Z","steps":["trace[1126944021] 'process raft request' (duration: 123.750139ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:31.293512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.807316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-25T21:13:31.293571Z","caller":"traceutil/trace.go:171","msg":"trace[1045365180] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5194; }","duration":"118.925488ms","start":"2026-03-25T21:13:31.174628Z","end":"2026-03-25T21:13:31.293553Z","steps":["trace[1045365180] 'agreement among raft nodes before linearized reading' (duration: 118.26126ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:32.085421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.772098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:13:32.085554Z","caller":"traceutil/trace.go:171","msg":"trace[456697238] linearizableReadLoop","detail":"{readStateIndex:5392; appliedIndex:5391; }","duration":"165.553656ms","start":"2026-03-25T21:13:31.919982Z","end":"2026-03-25T21:13:32.085535Z","steps":["trace[456697238] 'read index received' (duration: 64.912µs)","trace[456697238] 'applied index is now lower than readState.Index' (duration: 165.487524ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:32.085613Z","caller":"traceutil/trace.go:171","msg":"trace[1201735012] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"444.567863ms","start":"2026-03-25T21:13:31.641001Z","end":"2026-03-25T21:13:32.085569Z","steps":["trace[1201735012] 'process raft request' (duration: 32.540668ms)","trace[1201735012] 'compare' (duration: 411.647935ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:13:32.085667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.697049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:13:32.086636Z","caller":"traceutil/trace.go:171","msg":"trace[334424163] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:5200; }","duration":"166.645876ms","start":"2026-03-25T21:13:31.9199Z","end":"2026-03-25T21:13:32.086546Z","steps":["trace[334424163] 'agreement among raft nodes before linearized reading' (duration: 165.72306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:32.08677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:31.640965Z","time spent":"445.374165ms","remote":"127.0.0.1:57746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:13:39.602576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.675345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:13:39.602715Z","caller":"traceutil/trace.go:171","msg":"trace[1902282279] transaction","detail":"{read_only:false; response_revision:5340; number_of_response:1; }","duration":"144.769076ms","start":"2026-03-25T21:13:39.457922Z","end":"2026-03-25T21:13:39.602691Z","steps":["trace[1902282279] 'process raft request' (duration: 33.858033ms)","trace[1902282279] 'compare' (duration: 110.394648ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:39.61413Z","caller":"traceutil/trace.go:171","msg":"trace[1951741427] transaction","detail":"{read_only:false; response_revision:5341; number_of_response:1; }","duration":"125.056974ms","start":"2026-03-25T21:13:39.489029Z","end":"2026-03-25T21:13:39.614086Z","steps":["trace[1951741427] 'process raft request' (duration: 124.749395ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:39.813321Z","caller":"traceutil/trace.go:171","msg":"trace[2122824862] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"138.759843ms","start":"2026-03-25T21:13:39.67453Z","end":"2026-03-25T21:13:39.81329Z","steps":["trace[2122824862] 'process raft request' (duration: 138.710322ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:39.813543Z","caller":"traceutil/trace.go:171","msg":"trace[1164211600] transaction","detail":"{read_only:false; response_revision:5342; number_of_response:1; }","duration":"193.798748ms","start":"2026-03-25T21:13:39.619593Z","end":"2026-03-25T21:13:39.813392Z","steps":["trace[1164211600] 'process raft request' (duration: 157.994191ms)","trace[1164211600] 'compare' (duration: 35.364825ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:39.813629Z","caller":"traceutil/trace.go:171","msg":"trace[994755957] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"180.433738ms","start":"2026-03-25T21:13:39.633147Z","end":"2026-03-25T21:13:39.813581Z","steps":["trace[994755957] 'process raft request' (duration: 180.010126ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:39.813824Z","caller":"traceutil/trace.go:171","msg":"trace[735609787] linearizableReadLoop","detail":"{readStateIndex:5536; appliedIndex:5535; }","duration":"191.584948ms","start":"2026-03-25T21:13:39.622212Z","end":"2026-03-25T21:13:39.813797Z","steps":["trace[735609787] 'read index received' (duration: 155.392312ms)","trace[735609787] 'applied index is now lower than readState.Index' (duration: 36.191156ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:13:39.814148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.905079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-03-25T21:13:39.814203Z","caller":"traceutil/trace.go:171","msg":"trace[1101814899] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5344; }","duration":"191.99132ms","start":"2026-03-25T21:13:39.622196Z","end":"2026-03-25T21:13:39.814187Z","steps":["trace[1101814899] 'agreement among raft nodes before linearized reading' (duration: 191.772854ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:39.814232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.542505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pcrl6\" ","response":"range_response_count:1 size:6527"} {"level":"warn","ts":"2026-03-25T21:13:39.814238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.931871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-acb08739-2f9e-4b05-8946-348405034dac\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-03-25T21:13:39.814281Z","caller":"traceutil/trace.go:171","msg":"trace[865846259] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pcrl6; range_end:; response_count:1; response_revision:5344; }","duration":"129.666238ms","start":"2026-03-25T21:13:39.684604Z","end":"2026-03-25T21:13:39.81427Z","steps":["trace[865846259] 'agreement among raft nodes before linearized reading' (duration: 129.432891ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:39.814295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.867938ms","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-03-25T21:13:39.814383Z","caller":"traceutil/trace.go:171","msg":"trace[567849262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5344; }","duration":"131.991471ms","start":"2026-03-25T21:13:39.682374Z","end":"2026-03-25T21:13:39.814366Z","steps":["trace[567849262] 'agreement among raft nodes before linearized reading' (duration: 131.779616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:39.814395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.54416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2264"} {"level":"info","ts":"2026-03-25T21:13:39.814304Z","caller":"traceutil/trace.go:171","msg":"trace[1081465596] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-acb08739-2f9e-4b05-8946-348405034dac; range_end:; response_count:1; response_revision:5344; }","duration":"191.064635ms","start":"2026-03-25T21:13:39.623221Z","end":"2026-03-25T21:13:39.814285Z","steps":["trace[1081465596] 'agreement among raft nodes before linearized reading' (duration: 190.770666ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:39.814478Z","caller":"traceutil/trace.go:171","msg":"trace[117663463] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5344; }","duration":"190.637953ms","start":"2026-03-25T21:13:39.623818Z","end":"2026-03-25T21:13:39.814456Z","steps":["trace[117663463] 'agreement among raft nodes before linearized reading' (duration: 190.413068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:40.349507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.151091ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:13:40.349624Z","caller":"traceutil/trace.go:171","msg":"trace[1834340374] linearizableReadLoop","detail":"{readStateIndex:5540; appliedIndex:5539; }","duration":"480.716827ms","start":"2026-03-25T21:13:39.868889Z","end":"2026-03-25T21:13:40.349606Z","steps":["trace[1834340374] 'read index received' (duration: 158.320691ms)","trace[1834340374] 'applied index is now lower than readState.Index' (duration: 322.394516ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:40.349872Z","caller":"traceutil/trace.go:171","msg":"trace[56181998] transaction","detail":"{read_only:false; response_revision:5346; number_of_response:1; }","duration":"521.056487ms","start":"2026-03-25T21:13:39.828771Z","end":"2026-03-25T21:13:40.349827Z","steps":["trace[56181998] 'process raft request' (duration: 198.464794ms)","trace[56181998] 'compare' (duration: 321.914825ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:13:40.35006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.68637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-25T21:13:40.350061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.903529ms","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-03-25T21:13:40.350153Z","caller":"traceutil/trace.go:171","msg":"trace[1824130426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5346; }","duration":"211.859295ms","start":"2026-03-25T21:13:40.138272Z","end":"2026-03-25T21:13:40.350131Z","steps":["trace[1824130426] 'agreement among raft nodes before linearized reading' (duration: 211.549537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:40.349967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.94897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80095"} {"level":"warn","ts":"2026-03-25T21:13:40.350153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.410572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-25T21:13:40.35016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.787947ms","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-03-25T21:13:40.3502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.892133ms","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-03-25T21:13:40.350254Z","caller":"traceutil/trace.go:171","msg":"trace[1811408562] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:5346; }","duration":"506.577515ms","start":"2026-03-25T21:13:39.843653Z","end":"2026-03-25T21:13:40.35023Z","steps":["trace[1811408562] 'agreement among raft nodes before linearized reading' (duration: 506.406531ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:40.350287Z","caller":"traceutil/trace.go:171","msg":"trace[2019817964] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5346; }","duration":"226.058158ms","start":"2026-03-25T21:13:40.124208Z","end":"2026-03-25T21:13:40.350266Z","steps":["trace[2019817964] 'agreement among raft nodes before linearized reading' (duration: 225.774621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:40.350095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.084694ms","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-03-25T21:13:40.350255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.186995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:13:40.350312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:39.84362Z","time spent":"506.676038ms","remote":"127.0.0.1:57770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-03-25T21:13:40.35034Z","caller":"traceutil/trace.go:171","msg":"trace[1230796362] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5346; }","duration":"211.343291ms","start":"2026-03-25T21:13:40.138984Z","end":"2026-03-25T21:13:40.350327Z","steps":["trace[1230796362] 'agreement among raft nodes before linearized reading' (duration: 211.019373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:40.350155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:39.828745Z","time spent":"521.280743ms","remote":"127.0.0.1:45308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2576,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:13:40.350183Z","caller":"traceutil/trace.go:171","msg":"trace[1752440805] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5346; }","duration":"214.071375ms","start":"2026-03-25T21:13:40.136077Z","end":"2026-03-25T21:13:40.350148Z","steps":["trace[1752440805] 'agreement among raft nodes before linearized reading' (duration: 213.765268ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:40.350486Z","caller":"traceutil/trace.go:171","msg":"trace[1576365389] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5346; }","duration":"529.461464ms","start":"2026-03-25T21:13:39.820957Z","end":"2026-03-25T21:13:40.350418Z","steps":["trace[1576365389] 'agreement among raft nodes before linearized reading' (duration: 528.745765ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:40.35029Z","caller":"traceutil/trace.go:171","msg":"trace[1667257416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5346; }","duration":"213.944521ms","start":"2026-03-25T21:13:40.136314Z","end":"2026-03-25T21:13:40.350259Z","steps":["trace[1667257416] 'agreement among raft nodes before linearized reading' (duration: 213.598922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:40.350621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:39.820939Z","time spent":"529.634278ms","remote":"127.0.0.1:57678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":9,"response size":80118,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-03-25T21:13:40.350673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.32902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210982"} {"level":"info","ts":"2026-03-25T21:13:40.350769Z","caller":"traceutil/trace.go:171","msg":"trace[781359275] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5346; }","duration":"496.470153ms","start":"2026-03-25T21:13:39.854274Z","end":"2026-03-25T21:13:40.350745Z","steps":["trace[781359275] 'agreement among raft nodes before linearized reading' (duration: 495.347042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:40.350402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.174987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1131"} {"level":"info","ts":"2026-03-25T21:13:40.350701Z","caller":"traceutil/trace.go:171","msg":"trace[861205663] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:5346; }","duration":"513.761499ms","start":"2026-03-25T21:13:39.836577Z","end":"2026-03-25T21:13:40.350338Z","steps":["trace[861205663] 'agreement among raft nodes before linearized reading' (duration: 513.114143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:40.350831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:39.854258Z","time spent":"496.558145ms","remote":"127.0.0.1:57614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2211005,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-03-25T21:13:40.35087Z","caller":"traceutil/trace.go:171","msg":"trace[742702591] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:5346; }","duration":"476.675599ms","start":"2026-03-25T21:13:39.874174Z","end":"2026-03-25T21:13:40.350849Z","steps":["trace[742702591] 'agreement among raft nodes before linearized reading' (duration: 475.58071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:40.350934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:39.874153Z","time spent":"476.765841ms","remote":"127.0.0.1:57598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":1,"response size":1154,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" "} {"level":"warn","ts":"2026-03-25T21:13:40.350435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.405948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-acb08739-2f9e-4b05-8946-348405034dac\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-03-25T21:13:40.351083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:39.836522Z","time spent":"514.340795ms","remote":"127.0.0.1:59000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"info","ts":"2026-03-25T21:13:40.35118Z","caller":"traceutil/trace.go:171","msg":"trace[1285484819] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-acb08739-2f9e-4b05-8946-348405034dac; range_end:; response_count:1; response_revision:5346; }","duration":"207.257731ms","start":"2026-03-25T21:13:40.143866Z","end":"2026-03-25T21:13:40.351124Z","steps":["trace[1285484819] 'agreement among raft nodes before linearized reading' (duration: 206.010987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:40.690042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.123565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:13:40.690232Z","caller":"traceutil/trace.go:171","msg":"trace[1699854676] transaction","detail":"{read_only:false; response_revision:5347; number_of_response:1; }","duration":"333.113107ms","start":"2026-03-25T21:13:40.357096Z","end":"2026-03-25T21:13:40.690209Z","steps":["trace[1699854676] 'process raft request' (duration: 104.687234ms)","trace[1699854676] 'compare' (duration: 227.98532ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:13:40.690306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:40.357073Z","time spent":"333.200449ms","remote":"127.0.0.1:57746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:13:40.690502Z","caller":"traceutil/trace.go:171","msg":"trace[931577784] transaction","detail":"{read_only:false; response_revision:5348; number_of_response:1; }","duration":"333.062665ms","start":"2026-03-25T21:13:40.357422Z","end":"2026-03-25T21:13:40.690484Z","steps":["trace[931577784] 'process raft request' (duration: 332.734216ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:40.690597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:40.357413Z","time spent":"333.141347ms","remote":"127.0.0.1:57746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:13:40.6907Z","caller":"traceutil/trace.go:171","msg":"trace[2105937225] transaction","detail":"{read_only:false; response_revision:5351; number_of_response:1; }","duration":"332.644274ms","start":"2026-03-25T21:13:40.358046Z","end":"2026-03-25T21:13:40.69069Z","steps":["trace[2105937225] 'process raft request' (duration: 332.553402ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:40.690698Z","caller":"traceutil/trace.go:171","msg":"trace[2121894077] transaction","detail":"{read_only:false; response_revision:5349; number_of_response:1; }","duration":"333.121857ms","start":"2026-03-25T21:13:40.357534Z","end":"2026-03-25T21:13:40.690655Z","steps":["trace[2121894077] 'process raft request' (duration: 332.88758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:40.690758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:40.358016Z","time spent":"332.714267ms","remote":"127.0.0.1:57746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:13:40.690867Z","caller":"traceutil/trace.go:171","msg":"trace[1728856141] transaction","detail":"{read_only:false; response_revision:5353; number_of_response:1; }","duration":"326.352454ms","start":"2026-03-25T21:13:40.364505Z","end":"2026-03-25T21:13:40.690857Z","steps":["trace[1728856141] 'process raft request' (duration: 326.1922ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:13:40.690833Z","caller":"traceutil/trace.go:171","msg":"trace[228638575] transaction","detail":"{read_only:false; response_revision:5352; number_of_response:1; }","duration":"329.757586ms","start":"2026-03-25T21:13:40.361026Z","end":"2026-03-25T21:13:40.690783Z","steps":["trace[228638575] 'process raft request' (duration: 329.614783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:40.690922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:40.364497Z","time spent":"326.403195ms","remote":"127.0.0.1:45346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:13:40.690942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:40.357511Z","time spent":"333.288161ms","remote":"127.0.0.1:57746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:13:40.690866Z","caller":"traceutil/trace.go:171","msg":"trace[1157347776] linearizableReadLoop","detail":"{readStateIndex:5545; appliedIndex:5540; }","duration":"331.676388ms","start":"2026-03-25T21:13:40.359176Z","end":"2026-03-25T21:13:40.690853Z","steps":["trace[1157347776] 'read index received' (duration: 102.620009ms)","trace[1157347776] 'applied index is now lower than readState.Index' (duration: 229.055289ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:13:40.691038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.855393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"warn","ts":"2026-03-25T21:13:40.691036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:40.361014Z","time spent":"329.9183ms","remote":"127.0.0.1:57678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:13:40.691073Z","caller":"traceutil/trace.go:171","msg":"trace[210454350] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5353; }","duration":"331.923475ms","start":"2026-03-25T21:13:40.35914Z","end":"2026-03-25T21:13:40.691063Z","steps":["trace[210454350] 'agreement among raft nodes before linearized reading' (duration: 331.75337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:40.69112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:40.35913Z","time spent":"331.980036ms","remote":"127.0.0.1:57598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":673,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" "} {"level":"info","ts":"2026-03-25T21:13:40.690939Z","caller":"traceutil/trace.go:171","msg":"trace[784789795] transaction","detail":"{read_only:false; response_revision:5350; number_of_response:1; }","duration":"332.823499ms","start":"2026-03-25T21:13:40.357879Z","end":"2026-03-25T21:13:40.690703Z","steps":["trace[784789795] 'process raft request' (duration: 332.667184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:40.691255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.230093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-03-25T21:13:40.691314Z","caller":"traceutil/trace.go:171","msg":"trace[233441964] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5353; }","duration":"322.305925ms","start":"2026-03-25T21:13:40.368992Z","end":"2026-03-25T21:13:40.691298Z","steps":["trace[233441964] 'agreement among raft nodes before linearized reading' (duration: 322.11205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:40.69135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:40.368985Z","time spent":"322.357197ms","remote":"127.0.0.1:57694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2013,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-03-25T21:13:40.691391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.228571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-03-25T21:13:40.691456Z","caller":"traceutil/trace.go:171","msg":"trace[1765879914] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5353; }","duration":"303.301763ms","start":"2026-03-25T21:13:40.388135Z","end":"2026-03-25T21:13:40.691436Z","steps":["trace[1765879914] 'agreement among raft nodes before linearized reading' (duration: 303.145958ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:40.691507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:40.388127Z","time spent":"303.368405ms","remote":"127.0.0.1:57598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":1844,"request content":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" "} {"level":"warn","ts":"2026-03-25T21:13:40.691641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:40.357867Z","time spent":"333.489057ms","remote":"127.0.0.1:57746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:13:52.465272Z","caller":"traceutil/trace.go:171","msg":"trace[1569100090] linearizableReadLoop","detail":"{readStateIndex:5724; appliedIndex:5723; }","duration":"115.234308ms","start":"2026-03-25T21:13:52.35001Z","end":"2026-03-25T21:13:52.465244Z","steps":["trace[1569100090] 'read index received' (duration: 115.028233ms)","trace[1569100090] 'applied index is now lower than readState.Index' (duration: 204.645µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:52.465424Z","caller":"traceutil/trace.go:171","msg":"trace[1113780991] transaction","detail":"{read_only:false; response_revision:5526; number_of_response:1; }","duration":"133.921383ms","start":"2026-03-25T21:13:52.331472Z","end":"2026-03-25T21:13:52.465393Z","steps":["trace[1113780991] 'process raft request' (duration: 133.590294ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:52.465621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.594448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:13:52.465762Z","caller":"traceutil/trace.go:171","msg":"trace[375832537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5526; }","duration":"115.781044ms","start":"2026-03-25T21:13:52.349962Z","end":"2026-03-25T21:13:52.465743Z","steps":["trace[375832537] 'agreement among raft nodes before linearized reading' (duration: 115.590569ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:52.465798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.632455ms","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-03-25T21:13:52.465843Z","caller":"traceutil/trace.go:171","msg":"trace[1341622439] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5526; }","duration":"103.694037ms","start":"2026-03-25T21:13:52.362136Z","end":"2026-03-25T21:13:52.46583Z","steps":["trace[1341622439] 'agreement among raft nodes before linearized reading' (duration: 103.477501ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:14:58.426646Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2989} {"level":"info","ts":"2026-03-25T21:14:58.504088Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2989,"took":"75.251312ms","hash":3180816662,"current-db-size-bytes":58286080,"current-db-size":"58 MB","current-db-size-in-use-bytes":33988608,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-03-25T21:14:58.504163Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3180816662,"revision":2989,"compact-revision":-1} {"level":"info","ts":"2026-03-25T21:19:32.602206Z","caller":"traceutil/trace.go:171","msg":"trace[2140201628] transaction","detail":"{read_only:false; response_revision:8115; number_of_response:1; }","duration":"109.805966ms","start":"2026-03-25T21:19:32.492355Z","end":"2026-03-25T21:19:32.602161Z","steps":["trace[2140201628] 'process raft request' (duration: 80.891609ms)","trace[2140201628] 'compare' (duration: 28.617129ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:19:32.602609Z","caller":"traceutil/trace.go:171","msg":"trace[1173799083] transaction","detail":"{read_only:false; response_revision:8116; number_of_response:1; }","duration":"110.184146ms","start":"2026-03-25T21:19:32.492395Z","end":"2026-03-25T21:19:32.602579Z","steps":["trace[1173799083] 'process raft request' (duration: 109.639502ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:19:32.602945Z","caller":"traceutil/trace.go:171","msg":"trace[1169695264] transaction","detail":"{read_only:false; response_revision:8117; number_of_response:1; }","duration":"110.441273ms","start":"2026-03-25T21:19:32.492439Z","end":"2026-03-25T21:19:32.60288Z","steps":["trace[1169695264] 'process raft request' (duration: 109.912469ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:19:48.545857Z","caller":"traceutil/trace.go:171","msg":"trace[421710625] transaction","detail":"{read_only:false; response_revision:8203; number_of_response:1; }","duration":"143.494179ms","start":"2026-03-25T21:19:48.402329Z","end":"2026-03-25T21:19:48.545823Z","steps":["trace[421710625] 'process raft request' (duration: 143.271434ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:19:48.784487Z","caller":"traceutil/trace.go:171","msg":"trace[972050272] linearizableReadLoop","detail":"{readStateIndex:8488; appliedIndex:8487; }","duration":"164.8834ms","start":"2026-03-25T21:19:48.619573Z","end":"2026-03-25T21:19:48.784456Z","steps":["trace[972050272] 'read index received' (duration: 164.634463ms)","trace[972050272] 'applied index is now lower than readState.Index' (duration: 247.727µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:19:48.784652Z","caller":"traceutil/trace.go:171","msg":"trace[595897223] transaction","detail":"{read_only:false; response_revision:8205; number_of_response:1; }","duration":"177.737797ms","start":"2026-03-25T21:19:48.6069Z","end":"2026-03-25T21:19:48.784638Z","steps":["trace[595897223] 'process raft request' (duration: 177.380638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:19:48.784952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.363942ms","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-03-25T21:19:48.785262Z","caller":"traceutil/trace.go:171","msg":"trace[1208483719] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8205; }","duration":"165.699931ms","start":"2026-03-25T21:19:48.61954Z","end":"2026-03-25T21:19:48.78524Z","steps":["trace[1208483719] 'agreement among raft nodes before linearized reading' (duration: 165.336921ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:19:50.091785Z","caller":"traceutil/trace.go:171","msg":"trace[2123658562] transaction","detail":"{read_only:false; response_revision:8210; number_of_response:1; }","duration":"135.204142ms","start":"2026-03-25T21:19:49.956554Z","end":"2026-03-25T21:19:50.091759Z","steps":["trace[2123658562] 'process raft request' (duration: 135.031117ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:19:50.137476Z","caller":"traceutil/trace.go:171","msg":"trace[920569233] transaction","detail":"{read_only:false; response_revision:8212; number_of_response:1; }","duration":"171.715729ms","start":"2026-03-25T21:19:49.96574Z","end":"2026-03-25T21:19:50.137456Z","steps":["trace[920569233] 'process raft request' (duration: 171.665198ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:19:50.137565Z","caller":"traceutil/trace.go:171","msg":"trace[2062224244] transaction","detail":"{read_only:false; response_revision:8211; number_of_response:1; }","duration":"173.423232ms","start":"2026-03-25T21:19:49.964097Z","end":"2026-03-25T21:19:50.13752Z","steps":["trace[2062224244] 'process raft request' (duration: 164.735615ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:19:50.830554Z","caller":"traceutil/trace.go:171","msg":"trace[1469216815] transaction","detail":"{read_only:false; response_revision:8213; number_of_response:1; }","duration":"202.044942ms","start":"2026-03-25T21:19:50.628428Z","end":"2026-03-25T21:19:50.830473Z","steps":["trace[1469216815] 'process raft request' (duration: 201.778156ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:19:58.433492Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6079} {"level":"info","ts":"2026-03-25T21:19:58.533626Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6079,"took":"97.717229ms","hash":2544534655,"current-db-size-bytes":58286080,"current-db-size":"58 MB","current-db-size-in-use-bytes":23293952,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-25T21:19:58.533712Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2544534655,"revision":6079,"compact-revision":2989} {"level":"info","ts":"2026-03-25T21:20:17.34078Z","caller":"traceutil/trace.go:171","msg":"trace[561130773] transaction","detail":"{read_only:false; response_revision:8407; number_of_response:1; }","duration":"359.21585ms","start":"2026-03-25T21:20:16.981542Z","end":"2026-03-25T21:20:17.340758Z","steps":["trace[561130773] 'process raft request' (duration: 358.955074ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:17.34075Z","caller":"traceutil/trace.go:171","msg":"trace[1363916936] linearizableReadLoop","detail":"{readStateIndex:8699; appliedIndex:8698; }","duration":"357.67022ms","start":"2026-03-25T21:20:16.983046Z","end":"2026-03-25T21:20:17.340717Z","steps":["trace[1363916936] 'read index received' (duration: 357.523096ms)","trace[1363916936] 'applied index is now lower than readState.Index' (duration: 145.574µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:20:17.341004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:20:16.981519Z","time spent":"359.380255ms","remote":"127.0.0.1:57746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:20:17.341042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.952977ms","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-03-25T21:20:17.341149Z","caller":"traceutil/trace.go:171","msg":"trace[574481257] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8407; }","duration":"358.101052ms","start":"2026-03-25T21:20:16.98302Z","end":"2026-03-25T21:20:17.341121Z","steps":["trace[574481257] 'agreement among raft nodes before linearized reading' (duration: 357.804934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:17.341203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:20:16.983006Z","time spent":"358.188474ms","remote":"127.0.0.1:57746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-03-25T21:20:17.641064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.102926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:20:17.641224Z","caller":"traceutil/trace.go:171","msg":"trace[112107237] linearizableReadLoop","detail":"{readStateIndex:8700; appliedIndex:8699; }","duration":"300.180884ms","start":"2026-03-25T21:20:17.34102Z","end":"2026-03-25T21:20:17.641201Z","steps":["trace[112107237] 'read index received' (duration: 66.910843ms)","trace[112107237] 'applied index is now lower than readState.Index' (duration: 233.268901ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:20:17.641434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.451914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-25T21:20:17.64139Z","caller":"traceutil/trace.go:171","msg":"trace[1407899415] transaction","detail":"{read_only:false; response_revision:8408; number_of_response:1; }","duration":"657.521395ms","start":"2026-03-25T21:20:16.983815Z","end":"2026-03-25T21:20:17.641336Z","steps":["trace[1407899415] 'process raft request' (duration: 424.024409ms)","trace[1407899415] 'compare' (duration: 232.89584ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:20:17.641446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.947666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-25T21:20:17.641481Z","caller":"traceutil/trace.go:171","msg":"trace[228924689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8408; }","duration":"654.502616ms","start":"2026-03-25T21:20:16.986962Z","end":"2026-03-25T21:20:17.641464Z","steps":["trace[228924689] 'agreement among raft nodes before linearized reading' (duration: 654.329891ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:17.641536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:20:16.986954Z","time spent":"654.572518ms","remote":"127.0.0.1:57746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-25T21:20:17.64153Z","caller":"traceutil/trace.go:171","msg":"trace[1805215474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8408; }","duration":"658.058959ms","start":"2026-03-25T21:20:16.983449Z","end":"2026-03-25T21:20:17.641507Z","steps":["trace[1805215474] 'agreement among raft nodes before linearized reading' (duration: 657.849733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:17.641516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.534515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:20:17.641589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:20:16.98344Z","time spent":"658.138171ms","remote":"127.0.0.1:57746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-25T21:20:17.641445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.798539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-25T21:20:17.641626Z","caller":"traceutil/trace.go:171","msg":"trace[1142815642] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8408; }","duration":"444.716652ms","start":"2026-03-25T21:20:17.196877Z","end":"2026-03-25T21:20:17.641594Z","steps":["trace[1142815642] 'agreement among raft nodes before linearized reading' (duration: 444.492505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:17.641615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:20:16.9838Z","time spent":"657.69338ms","remote":"127.0.0.1:57746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:20:17.641675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:20:17.196853Z","time spent":"444.809473ms","remote":"127.0.0.1:46744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-03-25T21:20:17.641845Z","caller":"traceutil/trace.go:171","msg":"trace[338371663] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8408; }","duration":"656.085268ms","start":"2026-03-25T21:20:16.985562Z","end":"2026-03-25T21:20:17.641648Z","steps":["trace[338371663] 'agreement among raft nodes before linearized reading' (duration: 655.735388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:17.643135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:20:16.985536Z","time spent":"656.366375ms","remote":"127.0.0.1:57746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-25T21:20:17.64417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.991321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pcrl6\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-03-25T21:20:17.644386Z","caller":"traceutil/trace.go:171","msg":"trace[1365692564] transaction","detail":"{read_only:false; response_revision:8409; number_of_response:1; }","duration":"297.068453ms","start":"2026-03-25T21:20:17.347291Z","end":"2026-03-25T21:20:17.644359Z","steps":["trace[1365692564] 'process raft request' (duration: 296.345954ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:17.650149Z","caller":"traceutil/trace.go:171","msg":"trace[1443193453] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pcrl6; range_end:; response_count:1; response_revision:8408; }","duration":"644.103944ms","start":"2026-03-25T21:20:17.000129Z","end":"2026-03-25T21:20:17.644233Z","steps":["trace[1443193453] 'agreement among raft nodes before linearized reading' (duration: 641.085695ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:17.65023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:20:17.000112Z","time spent":"650.084951ms","remote":"127.0.0.1:57678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-pcrl6\" "} {"level":"info","ts":"2026-03-25T21:20:30.76504Z","caller":"traceutil/trace.go:171","msg":"trace[1843223158] transaction","detail":"{read_only:false; response_revision:8518; number_of_response:1; }","duration":"398.332245ms","start":"2026-03-25T21:20:30.366645Z","end":"2026-03-25T21:20:30.764977Z","steps":["trace[1843223158] 'process raft request' (duration: 309.632891ms)","trace[1843223158] 'compare' (duration: 88.210161ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:20:30.765287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:20:30.366623Z","time spent":"398.531591ms","remote":"127.0.0.1:57678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4629,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:20:30.785641Z","caller":"traceutil/trace.go:171","msg":"trace[375974001] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8519; }","duration":"392.3986ms","start":"2026-03-25T21:20:30.393216Z","end":"2026-03-25T21:20:30.785614Z","steps":["trace[375974001] 'process raft request' (duration: 392.111233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:30.7859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:20:30.393193Z","time spent":"392.541754ms","remote":"127.0.0.1:46606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-03-25T21:20:30.786127Z","caller":"traceutil/trace.go:171","msg":"trace[1829851195] transaction","detail":"{read_only:false; response_revision:8520; number_of_response:1; }","duration":"344.816164ms","start":"2026-03-25T21:20:30.441258Z","end":"2026-03-25T21:20:30.786075Z","steps":["trace[1829851195] 'process raft request' (duration: 344.27951ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:30.786207Z","caller":"traceutil/trace.go:171","msg":"trace[1170537200] transaction","detail":"{read_only:false; response_revision:8521; number_of_response:1; }","duration":"341.863806ms","start":"2026-03-25T21:20:30.4443Z","end":"2026-03-25T21:20:30.786163Z","steps":["trace[1170537200] 'process raft request' (duration: 341.649981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:20:30.786368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:20:30.441239Z","time spent":"344.997548ms","remote":"127.0.0.1:57746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:20:30.786426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:20:30.444292Z","time spent":"342.00932ms","remote":"127.0.0.1:57746","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-03-25T21:20:31.454715Z","caller":"traceutil/trace.go:171","msg":"trace[279891252] transaction","detail":"{read_only:false; response_revision:8524; number_of_response:1; }","duration":"102.992808ms","start":"2026-03-25T21:20:31.351689Z","end":"2026-03-25T21:20:31.454682Z","steps":["trace[279891252] 'process raft request' (duration: 102.782943ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:31.744059Z","caller":"traceutil/trace.go:171","msg":"trace[793988349] linearizableReadLoop","detail":"{readStateIndex:8821; appliedIndex:8820; }","duration":"248.549352ms","start":"2026-03-25T21:20:31.495471Z","end":"2026-03-25T21:20:31.74402Z","steps":["trace[793988349] 'read index received' (duration: 215.085146ms)","trace[793988349] 'applied index is now lower than readState.Index' (duration: 33.461976ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:20:31.744099Z","caller":"traceutil/trace.go:171","msg":"trace[1873990441] transaction","detail":"{read_only:false; response_revision:8525; number_of_response:1; }","duration":"256.475209ms","start":"2026-03-25T21:20:31.487575Z","end":"2026-03-25T21:20:31.74405Z","steps":["trace[1873990441] 'process raft request' (duration: 223.149546ms)","trace[1873990441] 'compare' (duration: 33.077437ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:20:31.744335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.825839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5146"} {"level":"warn","ts":"2026-03-25T21:20:31.744417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.744812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-25T21:20:31.744454Z","caller":"traceutil/trace.go:171","msg":"trace[1428625001] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:8525; }","duration":"249.021454ms","start":"2026-03-25T21:20:31.495407Z","end":"2026-03-25T21:20:31.744429Z","steps":["trace[1428625001] 'agreement among raft nodes before linearized reading' (duration: 248.733636ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:31.744479Z","caller":"traceutil/trace.go:171","msg":"trace[577043650] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:8525; }","duration":"218.883505ms","start":"2026-03-25T21:20:31.525583Z","end":"2026-03-25T21:20:31.744467Z","steps":["trace[577043650] 'agreement among raft nodes before linearized reading' (duration: 218.638039ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:33.055131Z","caller":"traceutil/trace.go:171","msg":"trace[2052157854] transaction","detail":"{read_only:false; response_revision:8536; number_of_response:1; }","duration":"262.912039ms","start":"2026-03-25T21:20:32.79218Z","end":"2026-03-25T21:20:33.055092Z","steps":["trace[2052157854] 'process raft request' (duration: 262.664412ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:20:33.077793Z","caller":"traceutil/trace.go:171","msg":"trace[2132924239] transaction","detail":"{read_only:false; response_revision:8537; number_of_response:1; }","duration":"284.664647ms","start":"2026-03-25T21:20:32.7931Z","end":"2026-03-25T21:20:33.077764Z","steps":["trace[2132924239] 'process raft request' (duration: 284.501623ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:21:18.247987Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002424.wal"} {"level":"info","ts":"2026-03-25T21:22:36.433469Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"6eb8766b1ec86e92","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-25T21:22:36.43821Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-25T21:22:36.438343Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-25T21:24:58.440738Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8262} {"level":"info","ts":"2026-03-25T21:24:58.523683Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8262,"took":"80.057552ms","hash":4135956889,"current-db-size-bytes":58286080,"current-db-size":"58 MB","current-db-size-in-use-bytes":25169920,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-25T21:24:58.523765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4135956889,"revision":8262,"compact-revision":6079} {"level":"info","ts":"2026-03-25T21:29:58.449953Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10685} {"level":"info","ts":"2026-03-25T21:29:58.532665Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10685,"took":"80.09303ms","hash":4216772761,"current-db-size-bytes":58286080,"current-db-size":"58 MB","current-db-size-in-use-bytes":20361216,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-03-25T21:29:58.532736Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4216772761,"revision":10685,"compact-revision":8262}