{"level":"warn","ts":"2026-10-26T16:40:32.877143Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T16:40:32.877394Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.237: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.237:2380","--initial-cluster=instance=https://199.204.45.237:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.237:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.237: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-10-26T16:40:32.877592Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T16:40:32.877633Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.237:2380"]} {"level":"info","ts":"2026-10-26T16:40:32.877712Z","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-10-26T16:40:32.879265Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.237:2379"]} {"level":"info","ts":"2026-10-26T16:40:32.879445Z","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.237:2380"],"listen-peer-urls":["https://199.204.45.237:2380"],"advertise-client-urls":["https://199.204.45.237:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.237:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.237: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-10-26T16:40:32.886759Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.799027ms"} {"level":"info","ts":"2026-10-26T16:40:32.895865Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"208757c2a41558c1","cluster-id":"14128f4624e10873"} {"level":"info","ts":"2026-10-26T16:40:32.895967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"208757c2a41558c1 switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T16:40:32.896013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"208757c2a41558c1 became follower at term 0"} {"level":"info","ts":"2026-10-26T16:40:32.896045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 208757c2a41558c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T16:40:32.89607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"208757c2a41558c1 became follower at term 1"} {"level":"info","ts":"2026-10-26T16:40:32.896165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"208757c2a41558c1 switched to configuration voters=(2343938624557766849)"} {"level":"warn","ts":"2026-10-26T16:40:32.905038Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T16:40:32.911415Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T16:40:32.914029Z","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-10-26T16:40:32.921373Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"208757c2a41558c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T16:40:32.921591Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"208757c2a41558c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T16:40:32.921749Z","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-10-26T16:40:32.921887Z","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-10-26T16:40:32.921904Z","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-10-26T16:40:32.923063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"208757c2a41558c1 switched to configuration voters=(2343938624557766849)"} {"level":"info","ts":"2026-10-26T16:40:32.923383Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14128f4624e10873","local-member-id":"208757c2a41558c1","added-peer-id":"208757c2a41558c1","added-peer-peer-urls":["https://199.204.45.237:2380"]} {"level":"info","ts":"2026-10-26T16:40:32.924228Z","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-10-26T16:40:32.924489Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"208757c2a41558c1","initial-advertise-peer-urls":["https://199.204.45.237:2380"],"listen-peer-urls":["https://199.204.45.237:2380"],"advertise-client-urls":["https://199.204.45.237:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.237:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T16:40:32.924518Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T16:40:32.924582Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.237:2380"} {"level":"info","ts":"2026-10-26T16:40:32.924611Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.237:2380"} {"level":"info","ts":"2026-10-26T16:40:33.897185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"208757c2a41558c1 is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T16:40:33.897488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"208757c2a41558c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T16:40:33.897537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"208757c2a41558c1 received MsgPreVoteResp from 208757c2a41558c1 at term 1"} {"level":"info","ts":"2026-10-26T16:40:33.897557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"208757c2a41558c1 became candidate at term 2"} {"level":"info","ts":"2026-10-26T16:40:33.897679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"208757c2a41558c1 received MsgVoteResp from 208757c2a41558c1 at term 2"} {"level":"info","ts":"2026-10-26T16:40:33.897696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"208757c2a41558c1 became leader at term 2"} {"level":"info","ts":"2026-10-26T16:40:33.89771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 208757c2a41558c1 elected leader 208757c2a41558c1 at term 2"} {"level":"info","ts":"2026-10-26T16:40:33.904867Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T16:40:33.916015Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"208757c2a41558c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.237:2379]}","request-path":"/0/members/208757c2a41558c1/attributes","cluster-id":"14128f4624e10873","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T16:40:33.916094Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T16:40:33.916187Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T16:40:33.916361Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T16:40:33.916406Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T16:40:33.919561Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T16:40:33.920324Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.237:2379"} {"level":"info","ts":"2026-10-26T16:40:33.921096Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14128f4624e10873","local-member-id":"208757c2a41558c1","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T16:40:33.921251Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T16:40:33.921327Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-10-26T16:41:24.256035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.387027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7hz2t\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-26T16:41:24.256152Z","caller":"traceutil/trace.go:171","msg":"trace[1237153344] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7hz2t; range_end:; response_count:1; response_revision:650; }","duration":"114.544951ms","start":"2026-10-26T16:41:24.141587Z","end":"2026-10-26T16:41:24.256132Z","steps":["trace[1237153344] 'range keys from in-memory index tree' (duration: 114.234434ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:41:24.546814Z","caller":"traceutil/trace.go:171","msg":"trace[2013129678] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"270.991291ms","start":"2026-10-26T16:41:24.27579Z","end":"2026-10-26T16:41:24.546782Z","steps":["trace[2013129678] 'process raft request' (duration: 270.772296ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:41:24.593005Z","caller":"traceutil/trace.go:171","msg":"trace[1645144547] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"214.597689ms","start":"2026-10-26T16:41:24.378379Z","end":"2026-10-26T16:41:24.592977Z","steps":["trace[1645144547] 'process raft request' (duration: 214.444195ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:41:25.515595Z","caller":"traceutil/trace.go:171","msg":"trace[77338514] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"163.431291ms","start":"2026-10-26T16:41:25.352136Z","end":"2026-10-26T16:41:25.515567Z","steps":["trace[77338514] 'read index received' (duration: 162.752223ms)","trace[77338514] 'applied index is now lower than readState.Index' (duration: 678.238µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:41:25.515708Z","caller":"traceutil/trace.go:171","msg":"trace[1126666505] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"461.607892ms","start":"2026-10-26T16:41:25.054064Z","end":"2026-10-26T16:41:25.515672Z","steps":["trace[1126666505] 'process raft request' (duration: 460.971446ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:41:25.515819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.668546ms","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-10-26T16:41:25.515855Z","caller":"traceutil/trace.go:171","msg":"trace[2113842360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"163.757079ms","start":"2026-10-26T16:41:25.352087Z","end":"2026-10-26T16:41:25.515844Z","steps":["trace[2113842360] 'agreement among raft nodes before linearized reading' (duration: 163.607065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:41:25.516402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:41:25.054033Z","time spent":"461.755506ms","remote":"127.0.0.1:42610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T16:41:28.348183Z","caller":"traceutil/trace.go:171","msg":"trace[855751993] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"159.587481ms","start":"2026-10-26T16:41:28.188565Z","end":"2026-10-26T16:41:28.348152Z","steps":["trace[855751993] 'process raft request' (duration: 124.745974ms)","trace[855751993] 'compare' (duration: 34.689953ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:41:41.351084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.681988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-h8wbp\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-10-26T16:41:41.351171Z","caller":"traceutil/trace.go:171","msg":"trace[1177067543] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-h8wbp; range_end:; response_count:1; response_revision:822; }","duration":"106.822181ms","start":"2026-10-26T16:41:41.244323Z","end":"2026-10-26T16:41:41.351146Z","steps":["trace[1177067543] 'range keys from in-memory index tree' (duration: 106.413501ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:41:41.585128Z","caller":"traceutil/trace.go:171","msg":"trace[481090520] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"226.232699ms","start":"2026-10-26T16:41:41.358872Z","end":"2026-10-26T16:41:41.585104Z","steps":["trace[481090520] 'process raft request' (duration: 226.040773ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:42:11.195124Z","caller":"traceutil/trace.go:171","msg":"trace[2001583526] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"215.409999ms","start":"2026-10-26T16:42:10.979674Z","end":"2026-10-26T16:42:11.195084Z","steps":["trace[2001583526] 'process raft request' (duration: 215.144512ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:02.109223Z","caller":"traceutil/trace.go:171","msg":"trace[1712892082] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1983; }","duration":"350.323299ms","start":"2026-10-26T16:43:01.758877Z","end":"2026-10-26T16:43:02.1092Z","steps":["trace[1712892082] 'read index received' (duration: 350.165504ms)","trace[1712892082] 'applied index is now lower than readState.Index' (duration: 157.045µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:43:02.109281Z","caller":"traceutil/trace.go:171","msg":"trace[533809911] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"421.746334ms","start":"2026-10-26T16:43:01.687515Z","end":"2026-10-26T16:43:02.109261Z","steps":["trace[533809911] 'process raft request' (duration: 421.532858ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:02.109407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:43:01.68749Z","time spent":"421.839267ms","remote":"127.0.0.1:42698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11469,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T16:43:02.109968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.083817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T16:43:02.110049Z","caller":"traceutil/trace.go:171","msg":"trace[1816832184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1909; }","duration":"351.20453ms","start":"2026-10-26T16:43:01.758827Z","end":"2026-10-26T16:43:02.110031Z","steps":["trace[1816832184] 'agreement among raft nodes before linearized reading' (duration: 350.493543ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:02.110081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:43:01.758811Z","time spent":"351.262522ms","remote":"127.0.0.1:60422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-26T16:43:02.228973Z","caller":"traceutil/trace.go:171","msg":"trace[244272906] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"112.194961ms","start":"2026-10-26T16:43:02.116748Z","end":"2026-10-26T16:43:02.228943Z","steps":["trace[244272906] 'process raft request' (duration: 111.765889ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:02.229114Z","caller":"traceutil/trace.go:171","msg":"trace[1670685995] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"112.041317ms","start":"2026-10-26T16:43:02.117036Z","end":"2026-10-26T16:43:02.229077Z","steps":["trace[1670685995] 'process raft request' (duration: 111.889913ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:02.229149Z","caller":"traceutil/trace.go:171","msg":"trace[1380778327] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"112.15436ms","start":"2026-10-26T16:43:02.116933Z","end":"2026-10-26T16:43:02.229087Z","steps":["trace[1380778327] 'process raft request' (duration: 111.890304ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:02.229469Z","caller":"traceutil/trace.go:171","msg":"trace[1473555234] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"110.586989ms","start":"2026-10-26T16:43:02.118846Z","end":"2026-10-26T16:43:02.229433Z","steps":["trace[1473555234] 'process raft request' (duration: 110.168188ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:02.229746Z","caller":"traceutil/trace.go:171","msg":"trace[897050934] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"108.378422ms","start":"2026-10-26T16:43:02.121352Z","end":"2026-10-26T16:43:02.229731Z","steps":["trace[897050934] 'process raft request' (duration: 108.034613ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:02.599876Z","caller":"traceutil/trace.go:171","msg":"trace[1466109547] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"227.153393ms","start":"2026-10-26T16:43:02.372686Z","end":"2026-10-26T16:43:02.599839Z","steps":["trace[1466109547] 'process raft request' (duration: 226.985299ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:02.60136Z","caller":"traceutil/trace.go:171","msg":"trace[998066744] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"205.677788ms","start":"2026-10-26T16:43:02.395643Z","end":"2026-10-26T16:43:02.601321Z","steps":["trace[998066744] 'process raft request' (duration: 205.507414ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:08.980175Z","caller":"traceutil/trace.go:171","msg":"trace[1434345632] transaction","detail":"{read_only:false; response_revision:1986; number_of_response:1; }","duration":"161.008643ms","start":"2026-10-26T16:43:08.819136Z","end":"2026-10-26T16:43:08.980144Z","steps":["trace[1434345632] 'process raft request' (duration: 139.947339ms)","trace[1434345632] 'compare' (duration: 20.92819ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:43:15.691887Z","caller":"traceutil/trace.go:171","msg":"trace[655312562] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"106.526355ms","start":"2026-10-26T16:43:15.585338Z","end":"2026-10-26T16:43:15.691864Z","steps":["trace[655312562] 'process raft request' (duration: 106.391351ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:46.532784Z","caller":"traceutil/trace.go:171","msg":"trace[117577463] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"106.480316ms","start":"2026-10-26T16:43:46.42628Z","end":"2026-10-26T16:43:46.53276Z","steps":["trace[117577463] 'process raft request' (duration: 106.254161ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:53.207393Z","caller":"traceutil/trace.go:171","msg":"trace[1990586662] linearizableReadLoop","detail":"{readStateIndex:2325; appliedIndex:2324; }","duration":"447.404042ms","start":"2026-10-26T16:43:52.759954Z","end":"2026-10-26T16:43:53.207358Z","steps":["trace[1990586662] 'read index received' (duration: 378.454414ms)","trace[1990586662] 'applied index is now lower than readState.Index' (duration: 68.946318ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:43:53.20768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.70605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T16:43:53.207453Z","caller":"traceutil/trace.go:171","msg":"trace[2138990220] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"394.192351ms","start":"2026-10-26T16:43:52.813236Z","end":"2026-10-26T16:43:53.207428Z","steps":["trace[2138990220] 'process raft request' (duration: 394.123568ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:53.207801Z","caller":"traceutil/trace.go:171","msg":"trace[655817713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2240; }","duration":"447.878335ms","start":"2026-10-26T16:43:52.759904Z","end":"2026-10-26T16:43:53.207782Z","steps":["trace[655817713] 'agreement among raft nodes before linearized reading' (duration: 447.68252ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:53.207513Z","caller":"traceutil/trace.go:171","msg":"trace[1075736493] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"405.0442ms","start":"2026-10-26T16:43:52.80246Z","end":"2026-10-26T16:43:53.207504Z","steps":["trace[1075736493] 'process raft request' (duration: 404.69727ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:53.207925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:43:52.813223Z","time spent":"394.606081ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T16:43:53.207958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:43:52.759877Z","time spent":"448.061409ms","remote":"127.0.0.1:60422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T16:43:53.208057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:43:52.802435Z","time spent":"405.548602ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T16:43:53.256585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.999194ms","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-10-26T16:43:53.256631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.162525ms","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-10-26T16:43:53.256666Z","caller":"traceutil/trace.go:171","msg":"trace[687899297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2241; }","duration":"344.114007ms","start":"2026-10-26T16:43:52.912535Z","end":"2026-10-26T16:43:53.256649Z","steps":["trace[687899297] 'agreement among raft nodes before linearized reading' (duration: 343.958203ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:53.25668Z","caller":"traceutil/trace.go:171","msg":"trace[2092391517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2241; }","duration":"290.259258ms","start":"2026-10-26T16:43:52.966408Z","end":"2026-10-26T16:43:53.256667Z","steps":["trace[2092391517] 'agreement among raft nodes before linearized reading' (duration: 290.050553ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:53.256688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.788297ms","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":"info","ts":"2026-10-26T16:43:53.256724Z","caller":"traceutil/trace.go:171","msg":"trace[1239685588] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:2241; }","duration":"329.847569ms","start":"2026-10-26T16:43:52.926864Z","end":"2026-10-26T16:43:53.256712Z","steps":["trace[1239685588] 'agreement among raft nodes before linearized reading' (duration: 329.787968ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:53.256754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:43:52.926851Z","time spent":"329.89468ms","remote":"127.0.0.1:59202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-10-26T16:43:53.256701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:43:52.912517Z","time spent":"344.175069ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-26T16:43:53.256908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.207581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5700"} {"level":"warn","ts":"2026-10-26T16:43:53.256591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.820944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-26T16:43:53.256992Z","caller":"traceutil/trace.go:171","msg":"trace[1275422063] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2241; }","duration":"256.314032ms","start":"2026-10-26T16:43:53.000645Z","end":"2026-10-26T16:43:53.256959Z","steps":["trace[1275422063] 'agreement among raft nodes before linearized reading' (duration: 256.080156ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:43:53.257012Z","caller":"traceutil/trace.go:171","msg":"trace[1112712184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2241; }","duration":"312.273176ms","start":"2026-10-26T16:43:52.944709Z","end":"2026-10-26T16:43:53.256982Z","steps":["trace[1112712184] 'agreement among raft nodes before linearized reading' (duration: 311.753012ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:43:53.257127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:43:52.944686Z","time spent":"312.40736ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-26T16:43:58.713396Z","caller":"traceutil/trace.go:171","msg":"trace[163160500] transaction","detail":"{read_only:false; response_revision:2268; number_of_response:1; }","duration":"103.962592ms","start":"2026-10-26T16:43:58.609406Z","end":"2026-10-26T16:43:58.713368Z","steps":["trace[163160500] 'process raft request' (duration: 103.802258ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:44:41.527986Z","caller":"traceutil/trace.go:171","msg":"trace[1344068439] transaction","detail":"{read_only:false; response_revision:2668; number_of_response:1; }","duration":"107.495054ms","start":"2026-10-26T16:44:41.420464Z","end":"2026-10-26T16:44:41.527959Z","steps":["trace[1344068439] 'process raft request' (duration: 106.041857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:44:45.150514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.517302ms","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-10-26T16:44:45.150654Z","caller":"traceutil/trace.go:171","msg":"trace[1711235814] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2691; }","duration":"208.705577ms","start":"2026-10-26T16:44:44.941913Z","end":"2026-10-26T16:44:45.150619Z","steps":["trace[1711235814] 'range keys from in-memory index tree' (duration: 208.308047ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:44:45.150514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.45314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:8132"} {"level":"info","ts":"2026-10-26T16:44:45.150881Z","caller":"traceutil/trace.go:171","msg":"trace[1676100136] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2691; }","duration":"263.820779ms","start":"2026-10-26T16:44:44.887Z","end":"2026-10-26T16:44:45.15082Z","steps":["trace[1676100136] 'range keys from in-memory index tree' (duration: 263.275125ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:45:17.890887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.54413ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T16:45:17.891203Z","caller":"traceutil/trace.go:171","msg":"trace[388760468] transaction","detail":"{read_only:false; response_revision:2891; number_of_response:1; }","duration":"234.750895ms","start":"2026-10-26T16:45:17.656345Z","end":"2026-10-26T16:45:17.891096Z","steps":["trace[388760468] 'process raft request' (duration: 77.67387ms)","trace[388760468] 'compare' (duration: 156.355706ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:45:17.891646Z","caller":"traceutil/trace.go:171","msg":"trace[682213974] linearizableReadLoop","detail":"{readStateIndex:3005; appliedIndex:3003; }","duration":"180.735882ms","start":"2026-10-26T16:45:17.710888Z","end":"2026-10-26T16:45:17.891624Z","steps":["trace[682213974] 'read index received' (duration: 23.123703ms)","trace[682213974] 'applied index is now lower than readState.Index' (duration: 157.611039ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:45:17.891749Z","caller":"traceutil/trace.go:171","msg":"trace[154961978] transaction","detail":"{read_only:false; response_revision:2892; number_of_response:1; }","duration":"228.917275ms","start":"2026-10-26T16:45:17.662792Z","end":"2026-10-26T16:45:17.89171Z","steps":["trace[154961978] 'process raft request' (duration: 228.230777ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:45:17.891818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.960601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T16:45:17.891871Z","caller":"traceutil/trace.go:171","msg":"trace[513532962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2892; }","duration":"132.066194ms","start":"2026-10-26T16:45:17.759791Z","end":"2026-10-26T16:45:17.891857Z","steps":["trace[513532962] 'agreement among raft nodes before linearized reading' (duration: 131.966412ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:45:17.891883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.116578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-26T16:45:17.891943Z","caller":"traceutil/trace.go:171","msg":"trace[1967556514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2892; }","duration":"168.22562ms","start":"2026-10-26T16:45:17.723693Z","end":"2026-10-26T16:45:17.891918Z","steps":["trace[1967556514] 'agreement among raft nodes before linearized reading' (duration: 168.067466ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:45:17.891955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.023378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-10-26T16:45:17.891978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.627954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T16:45:17.892065Z","caller":"traceutil/trace.go:171","msg":"trace[815293584] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2892; }","duration":"181.192533ms","start":"2026-10-26T16:45:17.710843Z","end":"2026-10-26T16:45:17.892035Z","steps":["trace[815293584] 'agreement among raft nodes before linearized reading' (duration: 180.860454ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:45:17.892111Z","caller":"traceutil/trace.go:171","msg":"trace[187456631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2892; }","duration":"174.766717ms","start":"2026-10-26T16:45:17.717292Z","end":"2026-10-26T16:45:17.892059Z","steps":["trace[187456631] 'agreement among raft nodes before linearized reading' (duration: 174.46481ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:45:18.011219Z","caller":"traceutil/trace.go:171","msg":"trace[146263024] transaction","detail":"{read_only:false; response_revision:2895; number_of_response:1; }","duration":"113.903628ms","start":"2026-10-26T16:45:17.897288Z","end":"2026-10-26T16:45:18.011192Z","steps":["trace[146263024] 'process raft request' (duration: 113.826636ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:45:18.011502Z","caller":"traceutil/trace.go:171","msg":"trace[1858531740] transaction","detail":"{read_only:false; response_revision:2894; number_of_response:1; }","duration":"114.647518ms","start":"2026-10-26T16:45:17.896809Z","end":"2026-10-26T16:45:18.011457Z","steps":["trace[1858531740] 'process raft request' (duration: 107.635568ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:45:18.372194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.483499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T16:45:18.372342Z","caller":"traceutil/trace.go:171","msg":"trace[1270929302] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2895; }","duration":"140.680355ms","start":"2026-10-26T16:45:18.23164Z","end":"2026-10-26T16:45:18.37232Z","steps":["trace[1270929302] 'count revisions from in-memory index tree' (duration: 140.368606ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:45:23.627132Z","caller":"traceutil/trace.go:171","msg":"trace[1129941688] transaction","detail":"{read_only:false; response_revision:2922; number_of_response:1; }","duration":"143.748843ms","start":"2026-10-26T16:45:23.483353Z","end":"2026-10-26T16:45:23.627102Z","steps":["trace[1129941688] 'process raft request' (duration: 143.564588ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:48:35.918525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.214931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T16:48:35.918617Z","caller":"traceutil/trace.go:171","msg":"trace[2096132106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4134; }","duration":"160.368306ms","start":"2026-10-26T16:48:35.758229Z","end":"2026-10-26T16:48:35.918597Z","steps":["trace[2096132106] 'range keys from in-memory index tree' (duration: 160.14348ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:48:35.918903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.7853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12428"} {"level":"info","ts":"2026-10-26T16:48:35.918992Z","caller":"traceutil/trace.go:171","msg":"trace[968178421] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4134; }","duration":"131.918323ms","start":"2026-10-26T16:48:35.787054Z","end":"2026-10-26T16:48:35.918972Z","steps":["trace[968178421] 'range keys from in-memory index tree' (duration: 131.588405ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:48:35.919055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.913788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T16:48:35.919096Z","caller":"traceutil/trace.go:171","msg":"trace[772996305] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:4134; }","duration":"200.99006ms","start":"2026-10-26T16:48:35.718095Z","end":"2026-10-26T16:48:35.919085Z","steps":["trace[772996305] 'count revisions from in-memory index tree' (duration: 200.834966ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:50:14.331683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.401552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3ae9c224-a1a0-499c-bc04-fde059e7a5a5\" ","response":"range_response_count:1 size:3601"} {"level":"warn","ts":"2026-10-26T16:50:14.331756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.973388ms","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-10-26T16:50:14.331805Z","caller":"traceutil/trace.go:171","msg":"trace[1705135392] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3ae9c224-a1a0-499c-bc04-fde059e7a5a5; range_end:; response_count:1; response_revision:5377; }","duration":"232.612816ms","start":"2026-10-26T16:50:14.099168Z","end":"2026-10-26T16:50:14.331781Z","steps":["trace[1705135392] 'range keys from in-memory index tree' (duration: 232.032921ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:14.331874Z","caller":"traceutil/trace.go:171","msg":"trace[786226674] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5377; }","duration":"107.167053ms","start":"2026-10-26T16:50:14.224681Z","end":"2026-10-26T16:50:14.331848Z","steps":["trace[786226674] 'range keys from in-memory index tree' (duration: 106.540487ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:50:14.332179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.112859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:787380"} {"level":"info","ts":"2026-10-26T16:50:14.332305Z","caller":"traceutil/trace.go:171","msg":"trace[574701974] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5377; }","duration":"160.287813ms","start":"2026-10-26T16:50:14.171995Z","end":"2026-10-26T16:50:14.332283Z","steps":["trace[574701974] 'range keys from in-memory index tree' (duration: 159.290157ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:14.513607Z","caller":"traceutil/trace.go:171","msg":"trace[1954116720] transaction","detail":"{read_only:false; response_revision:5378; number_of_response:1; }","duration":"176.949881ms","start":"2026-10-26T16:50:14.33662Z","end":"2026-10-26T16:50:14.513569Z","steps":["trace[1954116720] 'process raft request' (duration: 176.698625ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:14.633344Z","caller":"traceutil/trace.go:171","msg":"trace[1972697604] transaction","detail":"{read_only:false; response_revision:5379; number_of_response:1; }","duration":"292.057319ms","start":"2026-10-26T16:50:14.34124Z","end":"2026-10-26T16:50:14.633298Z","steps":["trace[1972697604] 'process raft request' (duration: 291.767851ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:19.900809Z","caller":"traceutil/trace.go:171","msg":"trace[1196883026] transaction","detail":"{read_only:false; response_revision:5493; number_of_response:1; }","duration":"104.398462ms","start":"2026-10-26T16:50:19.796342Z","end":"2026-10-26T16:50:19.900741Z","steps":["trace[1196883026] 'process raft request' (duration: 64.878663ms)","trace[1196883026] 'compare' (duration: 39.084448ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:50:29.811652Z","caller":"traceutil/trace.go:171","msg":"trace[1379356505] transaction","detail":"{read_only:false; response_revision:5647; number_of_response:1; }","duration":"101.2429ms","start":"2026-10-26T16:50:29.710375Z","end":"2026-10-26T16:50:29.811618Z","steps":["trace[1379356505] 'process raft request' (duration: 100.991834ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:33.954062Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2979} {"level":"info","ts":"2026-10-26T16:50:34.044758Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2979,"took":"89.353754ms","hash":1750313109,"current-db-size-bytes":56721408,"current-db-size":"57 MB","current-db-size-in-use-bytes":31391744,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T16:50:34.044851Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1750313109,"revision":2979,"compact-revision":-1} {"level":"info","ts":"2026-10-26T16:50:38.503081Z","caller":"traceutil/trace.go:171","msg":"trace[2027070973] transaction","detail":"{read_only:false; response_revision:5814; number_of_response:1; }","duration":"109.969546ms","start":"2026-10-26T16:50:38.393072Z","end":"2026-10-26T16:50:38.503042Z","steps":["trace[2027070973] 'process raft request' (duration: 109.73339ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:38.776635Z","caller":"traceutil/trace.go:171","msg":"trace[2065319990] transaction","detail":"{read_only:false; response_revision:5815; number_of_response:1; }","duration":"265.867184ms","start":"2026-10-26T16:50:38.510725Z","end":"2026-10-26T16:50:38.776592Z","steps":["trace[2065319990] 'process raft request' (duration: 237.186415ms)","trace[2065319990] 'compare' (duration: 28.170057ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:50:38.945514Z","caller":"traceutil/trace.go:171","msg":"trace[1857837294] transaction","detail":"{read_only:false; response_revision:5816; number_of_response:1; }","duration":"434.632886ms","start":"2026-10-26T16:50:38.510844Z","end":"2026-10-26T16:50:38.945477Z","steps":["trace[1857837294] 'process raft request' (duration: 421.869056ms)","trace[1857837294] 'compare' (duration: 12.541924ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:50:38.945619Z","caller":"traceutil/trace.go:171","msg":"trace[1427676624] transaction","detail":"{read_only:false; response_revision:5818; number_of_response:1; }","duration":"434.03213ms","start":"2026-10-26T16:50:38.511557Z","end":"2026-10-26T16:50:38.945589Z","steps":["trace[1427676624] 'process raft request' (duration: 433.917417ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:50:38.945805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:50:38.511549Z","time spent":"434.156302ms","remote":"127.0.0.1:42762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1394,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T16:50:38.945848Z","caller":"traceutil/trace.go:171","msg":"trace[738743774] transaction","detail":"{read_only:false; response_revision:5817; number_of_response:1; }","duration":"434.353967ms","start":"2026-10-26T16:50:38.511425Z","end":"2026-10-26T16:50:38.945779Z","steps":["trace[738743774] 'process raft request' (duration: 433.956177ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:50:38.946014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:50:38.510808Z","time spent":"434.79825ms","remote":"127.0.0.1:42676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":967,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T16:50:38.94606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:50:38.511395Z","time spent":"434.563714ms","remote":"127.0.0.1:42762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1498,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T16:50:38.946259Z","caller":"traceutil/trace.go:171","msg":"trace[830032935] transaction","detail":"{read_only:false; response_revision:5819; number_of_response:1; }","duration":"434.495073ms","start":"2026-10-26T16:50:38.511738Z","end":"2026-10-26T16:50:38.946233Z","steps":["trace[830032935] 'process raft request' (duration: 433.795594ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:50:38.946358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:50:38.511724Z","time spent":"434.588494ms","remote":"127.0.0.1:42676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T16:50:38.948412Z","caller":"traceutil/trace.go:171","msg":"trace[707846503] transaction","detail":"{read_only:false; response_revision:5821; number_of_response:1; }","duration":"433.717091ms","start":"2026-10-26T16:50:38.514667Z","end":"2026-10-26T16:50:38.948384Z","steps":["trace[707846503] 'process raft request' (duration: 433.489995ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:50:38.948436Z","caller":"traceutil/trace.go:171","msg":"trace[748430811] transaction","detail":"{read_only:false; response_revision:5820; number_of_response:1; }","duration":"436.141724ms","start":"2026-10-26T16:50:38.512272Z","end":"2026-10-26T16:50:38.948413Z","steps":["trace[748430811] 'process raft request' (duration: 433.581768ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:50:38.94859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:50:38.512261Z","time spent":"436.270177ms","remote":"127.0.0.1:42676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T16:50:38.948598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.491675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T16:50:38.94839Z","caller":"traceutil/trace.go:171","msg":"trace[1454358951] linearizableReadLoop","detail":"{readStateIndex:6043; appliedIndex:6036; }","duration":"362.253379ms","start":"2026-10-26T16:50:38.586107Z","end":"2026-10-26T16:50:38.94836Z","steps":["trace[1454358951] 'read index received' (duration: 161.827401ms)","trace[1454358951] 'applied index is now lower than readState.Index' (duration: 200.424048ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:50:38.948657Z","caller":"traceutil/trace.go:171","msg":"trace[644915111] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:5821; }","duration":"362.579607ms","start":"2026-10-26T16:50:38.586063Z","end":"2026-10-26T16:50:38.948642Z","steps":["trace[644915111] 'agreement among raft nodes before linearized reading' (duration: 362.437083ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:50:38.948712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:50:38.58605Z","time spent":"362.648898ms","remote":"127.0.0.1:42676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":50,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-10-26T16:50:38.948903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:50:38.514634Z","time spent":"433.889795ms","remote":"127.0.0.1:42698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T16:50:38.948946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.794472ms","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-10-26T16:50:38.949053Z","caller":"traceutil/trace.go:171","msg":"trace[466698048] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5821; }","duration":"270.979046ms","start":"2026-10-26T16:50:38.67806Z","end":"2026-10-26T16:50:38.949039Z","steps":["trace[466698048] 'agreement among raft nodes before linearized reading' (duration: 270.7348ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:55:33.966345Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5690} {"level":"info","ts":"2026-10-26T16:55:34.070551Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5690,"took":"101.2467ms","hash":2836887637,"current-db-size-bytes":56721408,"current-db-size":"57 MB","current-db-size-in-use-bytes":27369472,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-10-26T16:55:34.070634Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2836887637,"revision":5690,"compact-revision":2979} {"level":"info","ts":"2026-10-26T16:57:07.008309Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002368.wal"} {"level":"info","ts":"2026-10-26T16:57:11.714816Z","caller":"traceutil/trace.go:171","msg":"trace[254952545] linearizableReadLoop","detail":"{readStateIndex:9095; appliedIndex:9094; }","duration":"107.931809ms","start":"2026-10-26T16:57:11.606854Z","end":"2026-10-26T16:57:11.714786Z","steps":["trace[254952545] 'read index received' (duration: 107.739504ms)","trace[254952545] 'applied index is now lower than readState.Index' (duration: 191.285µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:57:11.715039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.171035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-g9v2j\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-26T16:57:11.715072Z","caller":"traceutil/trace.go:171","msg":"trace[1054485957] range","detail":"{range_begin:/registry/pods/openstack/keepalived-g9v2j; range_end:; response_count:1; response_revision:8779; }","duration":"108.267197ms","start":"2026-10-26T16:57:11.606793Z","end":"2026-10-26T16:57:11.71506Z","steps":["trace[1054485957] 'agreement among raft nodes before linearized reading' (duration: 108.102653ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:57:11.715164Z","caller":"traceutil/trace.go:171","msg":"trace[878040006] transaction","detail":"{read_only:false; response_revision:8779; number_of_response:1; }","duration":"394.638036ms","start":"2026-10-26T16:57:11.320483Z","end":"2026-10-26T16:57:11.715121Z","steps":["trace[878040006] 'process raft request' (duration: 394.119202ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:57:11.715417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:57:11.320459Z","time spent":"394.81416ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T16:57:11.924344Z","caller":"traceutil/trace.go:171","msg":"trace[1859692148] linearizableReadLoop","detail":"{readStateIndex:9096; appliedIndex:9095; }","duration":"165.411801ms","start":"2026-10-26T16:57:11.758893Z","end":"2026-10-26T16:57:11.924305Z","steps":["trace[1859692148] 'read index received' (duration: 144.491458ms)","trace[1859692148] 'applied index is now lower than readState.Index' (duration: 20.918983ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:57:11.924441Z","caller":"traceutil/trace.go:171","msg":"trace[390790661] transaction","detail":"{read_only:false; response_revision:8780; number_of_response:1; }","duration":"201.467606ms","start":"2026-10-26T16:57:11.722937Z","end":"2026-10-26T16:57:11.924404Z","steps":["trace[390790661] 'process raft request' (duration: 180.44355ms)","trace[390790661] 'compare' (duration: 20.719797ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:57:11.924532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.627326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T16:57:11.924634Z","caller":"traceutil/trace.go:171","msg":"trace[1895379134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8780; }","duration":"165.78006ms","start":"2026-10-26T16:57:11.758833Z","end":"2026-10-26T16:57:11.924613Z","steps":["trace[1895379134] 'agreement among raft nodes before linearized reading' (duration: 165.614926ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:57:22.651664Z","caller":"traceutil/trace.go:171","msg":"trace[476774370] transaction","detail":"{read_only:false; response_revision:8833; number_of_response:1; }","duration":"212.43884ms","start":"2026-10-26T16:57:22.439195Z","end":"2026-10-26T16:57:22.651633Z","steps":["trace[476774370] 'process raft request' (duration: 212.236685ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:57:22.805588Z","caller":"traceutil/trace.go:171","msg":"trace[890867113] transaction","detail":"{read_only:false; response_revision:8834; number_of_response:1; }","duration":"366.274519ms","start":"2026-10-26T16:57:22.439285Z","end":"2026-10-26T16:57:22.805559Z","steps":["trace[890867113] 'process raft request' (duration: 366.034213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:57:22.80579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:57:22.43926Z","time spent":"366.420443ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T16:57:23.132645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.091134ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-10-26T16:57:23.13304Z","caller":"traceutil/trace.go:171","msg":"trace[1195615643] transaction","detail":"{read_only:false; response_revision:8835; number_of_response:1; }","duration":"347.688928ms","start":"2026-10-26T16:57:22.785318Z","end":"2026-10-26T16:57:23.133007Z","steps":["trace[1195615643] 'process raft request' (duration: 347.456772ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:57:23.133018Z","caller":"traceutil/trace.go:171","msg":"trace[1881996831] linearizableReadLoop","detail":"{readStateIndex:9152; appliedIndex:9150; }","duration":"691.177557ms","start":"2026-10-26T16:57:22.441776Z","end":"2026-10-26T16:57:23.132954Z","steps":["trace[1881996831] 'read index received' (duration: 209.710279ms)","trace[1881996831] 'applied index is now lower than readState.Index' (duration: 481.458278ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:57:23.133207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:57:22.785282Z","time spent":"347.837492ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T16:57:23.181583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.794108ms","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-10-26T16:57:23.181653Z","caller":"traceutil/trace.go:171","msg":"trace[387644787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8835; }","duration":"739.89284ms","start":"2026-10-26T16:57:22.441733Z","end":"2026-10-26T16:57:23.181626Z","steps":["trace[387644787] 'agreement among raft nodes before linearized reading' (duration: 691.29377ms)","trace[387644787] 'range keys from in-memory index tree' (duration: 48.410636ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T16:57:23.181789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:57:22.44172Z","time spent":"740.022624ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-26T16:57:23.190766Z","caller":"traceutil/trace.go:171","msg":"trace[1868331828] transaction","detail":"{read_only:false; response_revision:8836; number_of_response:1; }","duration":"307.766632ms","start":"2026-10-26T16:57:22.882978Z","end":"2026-10-26T16:57:23.190744Z","steps":["trace[1868331828] 'process raft request' (duration: 307.552147ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:57:23.190918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:57:22.882946Z","time spent":"307.895566ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T16:57:23.191193Z","caller":"traceutil/trace.go:171","msg":"trace[839155477] transaction","detail":"{read_only:false; response_revision:8837; number_of_response:1; }","duration":"273.196116ms","start":"2026-10-26T16:57:22.917962Z","end":"2026-10-26T16:57:23.191159Z","steps":["trace[839155477] 'process raft request' (duration: 272.743044ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:57:23.191313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.135879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T16:57:23.191402Z","caller":"traceutil/trace.go:171","msg":"trace[1362314815] transaction","detail":"{read_only:false; response_revision:8839; number_of_response:1; }","duration":"100.054195ms","start":"2026-10-26T16:57:23.091327Z","end":"2026-10-26T16:57:23.191381Z","steps":["trace[1362314815] 'process raft request' (duration: 99.87259ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:57:23.191442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.030066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T16:57:23.191486Z","caller":"traceutil/trace.go:171","msg":"trace[1444947591] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:8839; }","duration":"208.131208ms","start":"2026-10-26T16:57:22.983344Z","end":"2026-10-26T16:57:23.191475Z","steps":["trace[1444947591] 'agreement among raft nodes before linearized reading' (duration: 208.035116ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:57:23.191518Z","caller":"traceutil/trace.go:171","msg":"trace[1048843450] transaction","detail":"{read_only:false; response_revision:8838; number_of_response:1; }","duration":"115.986028ms","start":"2026-10-26T16:57:23.075522Z","end":"2026-10-26T16:57:23.191508Z","steps":["trace[1048843450] 'process raft request' (duration: 115.527257ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:57:23.191443Z","caller":"traceutil/trace.go:171","msg":"trace[1502103802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8839; }","duration":"432.338293ms","start":"2026-10-26T16:57:22.75908Z","end":"2026-10-26T16:57:23.191418Z","steps":["trace[1502103802] 'agreement among raft nodes before linearized reading' (duration: 432.174869ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:57:23.191618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.031378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T16:57:23.191663Z","caller":"traceutil/trace.go:171","msg":"trace[1889165748] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:8839; }","duration":"249.116451ms","start":"2026-10-26T16:57:22.942536Z","end":"2026-10-26T16:57:23.191653Z","steps":["trace[1889165748] 'agreement among raft nodes before linearized reading' (duration: 249.042559ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:57:23.191673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:57:22.759048Z","time spent":"432.6111ms","remote":"127.0.0.1:60422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T16:57:52.05237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.880398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/rook-ceph/csi-nfsplugin-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T16:57:52.052476Z","caller":"traceutil/trace.go:171","msg":"trace[1531831474] range","detail":"{range_begin:/registry/services/specs/rook-ceph/csi-nfsplugin-metrics; range_end:; response_count:0; response_revision:9044; }","duration":"113.108553ms","start":"2026-10-26T16:57:51.939344Z","end":"2026-10-26T16:57:52.052452Z","steps":["trace[1531831474] 'range keys from in-memory index tree' (duration: 112.808195ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:57:55.029086Z","caller":"traceutil/trace.go:171","msg":"trace[1204832938] transaction","detail":"{read_only:false; response_revision:9059; number_of_response:1; }","duration":"124.14738ms","start":"2026-10-26T16:57:54.904909Z","end":"2026-10-26T16:57:55.029056Z","steps":["trace[1204832938] 'process raft request' (duration: 123.39755ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:58:08.299197Z","caller":"traceutil/trace.go:171","msg":"trace[1280211777] transaction","detail":"{read_only:false; response_revision:9133; number_of_response:1; }","duration":"120.895476ms","start":"2026-10-26T16:58:08.178278Z","end":"2026-10-26T16:58:08.299173Z","steps":["trace[1280211777] 'process raft request' (duration: 120.67815ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:58:08.565103Z","caller":"traceutil/trace.go:171","msg":"trace[1498688931] transaction","detail":"{read_only:false; response_revision:9135; number_of_response:1; }","duration":"257.652463ms","start":"2026-10-26T16:58:08.307407Z","end":"2026-10-26T16:58:08.565059Z","steps":["trace[1498688931] 'process raft request' (duration: 199.884385ms)","trace[1498688931] 'compare' (duration: 57.606054ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:58:09.449046Z","caller":"traceutil/trace.go:171","msg":"trace[1566844746] linearizableReadLoop","detail":"{readStateIndex:9467; appliedIndex:9466; }","duration":"214.72509ms","start":"2026-10-26T16:58:09.234289Z","end":"2026-10-26T16:58:09.449014Z","steps":["trace[1566844746] 'read index received' (duration: 214.469073ms)","trace[1566844746] 'applied index is now lower than readState.Index' (duration: 254.676µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:58:09.44953Z","caller":"traceutil/trace.go:171","msg":"trace[2106750556] transaction","detail":"{read_only:false; response_revision:9136; number_of_response:1; }","duration":"295.756751ms","start":"2026-10-26T16:58:09.15376Z","end":"2026-10-26T16:58:09.449516Z","steps":["trace[2106750556] 'process raft request' (duration: 295.081433ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:58:09.449867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.568921ms","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-10-26T16:58:09.449902Z","caller":"traceutil/trace.go:171","msg":"trace[354084828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9136; }","duration":"215.659783ms","start":"2026-10-26T16:58:09.234232Z","end":"2026-10-26T16:58:09.449891Z","steps":["trace[354084828] 'agreement among raft nodes before linearized reading' (duration: 215.51848ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:58:09.450021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.261631ms","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":"info","ts":"2026-10-26T16:58:09.450098Z","caller":"traceutil/trace.go:171","msg":"trace[297190866] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9136; }","duration":"213.379504ms","start":"2026-10-26T16:58:09.2367Z","end":"2026-10-26T16:58:09.450079Z","steps":["trace[297190866] 'agreement among raft nodes before linearized reading' (duration: 213.23469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:58:09.450237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.196763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T16:58:09.450277Z","caller":"traceutil/trace.go:171","msg":"trace[1518369274] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:9136; }","duration":"163.265195ms","start":"2026-10-26T16:58:09.287002Z","end":"2026-10-26T16:58:09.450268Z","steps":["trace[1518369274] 'agreement among raft nodes before linearized reading' (duration: 163.198412ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:59:07.33679Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"208757c2a41558c1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T16:59:07.429009Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-26T16:59:07.429175Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-26T16:59:42.008467Z","caller":"traceutil/trace.go:171","msg":"trace[310246470] transaction","detail":"{read_only:false; response_revision:10011; number_of_response:1; }","duration":"107.706405ms","start":"2026-10-26T16:59:41.900731Z","end":"2026-10-26T16:59:42.008438Z","steps":["trace[310246470] 'process raft request' (duration: 107.443808ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:59:42.344753Z","caller":"traceutil/trace.go:171","msg":"trace[1647628278] transaction","detail":"{read_only:false; response_revision:10012; number_of_response:1; }","duration":"377.956592ms","start":"2026-10-26T16:59:41.966756Z","end":"2026-10-26T16:59:42.344712Z","steps":["trace[1647628278] 'process raft request' (duration: 377.690495ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:59:42.344998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:59:41.966718Z","time spent":"378.145626ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T16:59:42.345259Z","caller":"traceutil/trace.go:171","msg":"trace[570329978] transaction","detail":"{read_only:false; response_revision:10013; number_of_response:1; }","duration":"373.105835ms","start":"2026-10-26T16:59:41.97211Z","end":"2026-10-26T16:59:42.345216Z","steps":["trace[570329978] 'process raft request' (duration: 372.556311ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:59:42.345278Z","caller":"traceutil/trace.go:171","msg":"trace[1675090209] linearizableReadLoop","detail":"{readStateIndex:10369; appliedIndex:10365; }","duration":"342.227161ms","start":"2026-10-26T16:59:42.00299Z","end":"2026-10-26T16:59:42.345217Z","steps":["trace[1675090209] 'read index received' (duration: 5.229546ms)","trace[1675090209] 'applied index is now lower than readState.Index' (duration: 336.995645ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:59:42.345374Z","caller":"traceutil/trace.go:171","msg":"trace[957097527] transaction","detail":"{read_only:false; response_revision:10014; number_of_response:1; }","duration":"347.821508ms","start":"2026-10-26T16:59:41.997537Z","end":"2026-10-26T16:59:42.345359Z","steps":["trace[957097527] 'process raft request' (duration: 347.480358ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:59:42.345458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:59:41.997522Z","time spent":"347.899659ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T16:59:42.345584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:59:41.972096Z","time spent":"373.26257ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T16:59:42.345672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.649902ms","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-10-26T16:59:42.345746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.886148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T16:59:42.3458Z","caller":"traceutil/trace.go:171","msg":"trace[1348259567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:10014; }","duration":"337.97624ms","start":"2026-10-26T16:59:42.007807Z","end":"2026-10-26T16:59:42.345783Z","steps":["trace[1348259567] 'agreement among raft nodes before linearized reading' (duration: 337.761305ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:59:42.345838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:59:42.007792Z","time spent":"338.037802ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T16:59:42.346001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.193683ms","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-10-26T16:59:42.346077Z","caller":"traceutil/trace.go:171","msg":"trace[494120718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:10014; }","duration":"215.313966ms","start":"2026-10-26T16:59:42.130747Z","end":"2026-10-26T16:59:42.34606Z","steps":["trace[494120718] 'agreement among raft nodes before linearized reading' (duration: 215.05069ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:59:42.346448Z","caller":"traceutil/trace.go:171","msg":"trace[388009285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10014; }","duration":"342.792016ms","start":"2026-10-26T16:59:42.002922Z","end":"2026-10-26T16:59:42.345715Z","steps":["trace[388009285] 'agreement among raft nodes before linearized reading' (duration: 342.545219ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T16:59:42.346647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T16:59:42.00289Z","time spent":"343.71891ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-26T16:59:42.511619Z","caller":"traceutil/trace.go:171","msg":"trace[1538376339] transaction","detail":"{read_only:false; response_revision:10015; number_of_response:1; }","duration":"160.010787ms","start":"2026-10-26T16:59:42.351573Z","end":"2026-10-26T16:59:42.511584Z","steps":["trace[1538376339] 'process raft request' (duration: 94.540451ms)","trace[1538376339] 'compare' (duration: 65.205168ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T16:59:42.511882Z","caller":"traceutil/trace.go:171","msg":"trace[69634050] transaction","detail":"{read_only:false; response_revision:10016; number_of_response:1; }","duration":"159.843122ms","start":"2026-10-26T16:59:42.352014Z","end":"2026-10-26T16:59:42.511857Z","steps":["trace[69634050] 'process raft request' (duration: 159.522734ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T16:59:42.512196Z","caller":"traceutil/trace.go:171","msg":"trace[1759279736] transaction","detail":"{read_only:false; response_revision:10017; number_of_response:1; }","duration":"158.987769ms","start":"2026-10-26T16:59:42.353168Z","end":"2026-10-26T16:59:42.512156Z","steps":["trace[1759279736] 'process raft request' (duration: 158.589729ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:00:33.978698Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8028} {"level":"info","ts":"2026-10-26T17:00:34.137513Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8028,"took":"155.814007ms","hash":1452952676,"current-db-size-bytes":56721408,"current-db-size":"57 MB","current-db-size-in-use-bytes":25223168,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-10-26T17:00:34.137601Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1452952676,"revision":8028,"compact-revision":5690} {"level":"info","ts":"2026-10-26T17:05:25.171396Z","caller":"traceutil/trace.go:171","msg":"trace[468517383] transaction","detail":"{read_only:false; response_revision:12483; number_of_response:1; }","duration":"146.546538ms","start":"2026-10-26T17:05:25.024812Z","end":"2026-10-26T17:05:25.171359Z","steps":["trace[468517383] 'process raft request' (duration: 115.358617ms)","trace[468517383] 'compare' (duration: 30.998547ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:05:25.991595Z","caller":"traceutil/trace.go:171","msg":"trace[1865185783] transaction","detail":"{read_only:false; response_revision:12487; number_of_response:1; }","duration":"119.079124ms","start":"2026-10-26T17:05:25.872492Z","end":"2026-10-26T17:05:25.991571Z","steps":["trace[1865185783] 'process raft request' (duration: 118.881599ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:05:26.083111Z","caller":"traceutil/trace.go:171","msg":"trace[1155449636] transaction","detail":"{read_only:false; response_revision:12488; number_of_response:1; }","duration":"209.489243ms","start":"2026-10-26T17:05:25.873588Z","end":"2026-10-26T17:05:26.083077Z","steps":["trace[1155449636] 'process raft request' (duration: 151.470896ms)","trace[1155449636] 'compare' (duration: 57.794262ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:05:26.083246Z","caller":"traceutil/trace.go:171","msg":"trace[985852412] linearizableReadLoop","detail":"{readStateIndex:12924; appliedIndex:12921; }","duration":"163.451118ms","start":"2026-10-26T17:05:25.919772Z","end":"2026-10-26T17:05:26.083223Z","steps":["trace[985852412] 'read index received' (duration: 71.639152ms)","trace[985852412] 'applied index is now lower than readState.Index' (duration: 91.810396ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:05:26.083297Z","caller":"traceutil/trace.go:171","msg":"trace[472341769] transaction","detail":"{read_only:false; response_revision:12489; number_of_response:1; }","duration":"208.673772ms","start":"2026-10-26T17:05:25.874595Z","end":"2026-10-26T17:05:26.083269Z","steps":["trace[472341769] 'process raft request' (duration: 208.413026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:05:26.083505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.711155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-g9v2j\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-10-26T17:05:26.083565Z","caller":"traceutil/trace.go:171","msg":"trace[227031347] range","detail":"{range_begin:/registry/pods/openstack/keepalived-g9v2j; range_end:; response_count:1; response_revision:12489; }","duration":"163.809578ms","start":"2026-10-26T17:05:25.919724Z","end":"2026-10-26T17:05:26.083534Z","steps":["trace[227031347] 'agreement among raft nodes before linearized reading' (duration: 163.575522ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:05:33.98876Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10371} {"level":"info","ts":"2026-10-26T17:05:34.094451Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10371,"took":"102.502324ms","hash":564086830,"current-db-size-bytes":56721408,"current-db-size":"57 MB","current-db-size-in-use-bytes":25808896,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-26T17:05:34.094689Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":564086830,"revision":10371,"compact-revision":8028} {"level":"info","ts":"2026-10-26T17:10:33.999679Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12552} {"level":"info","ts":"2026-10-26T17:10:34.107368Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12552,"took":"104.681815ms","hash":4069850049,"current-db-size-bytes":56721408,"current-db-size":"57 MB","current-db-size-in-use-bytes":27815936,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-26T17:10:34.107539Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4069850049,"revision":12552,"compact-revision":10371} {"level":"info","ts":"2026-10-26T17:11:45.804148Z","caller":"traceutil/trace.go:171","msg":"trace[747004114] transaction","detail":"{read_only:false; response_revision:15053; number_of_response:1; }","duration":"103.72483ms","start":"2026-10-26T17:11:45.700398Z","end":"2026-10-26T17:11:45.804123Z","steps":["trace[747004114] 'process raft request' (duration: 103.588866ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:12:38.804819Z","caller":"traceutil/trace.go:171","msg":"trace[1236606185] transaction","detail":"{read_only:false; response_revision:15339; number_of_response:1; }","duration":"143.689387ms","start":"2026-10-26T17:12:38.661103Z","end":"2026-10-26T17:12:38.804792Z","steps":["trace[1236606185] 'process raft request' (duration: 143.477632ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:12:49.302855Z","caller":"traceutil/trace.go:171","msg":"trace[1486836233] transaction","detail":"{read_only:false; response_revision:15508; number_of_response:1; }","duration":"158.878542ms","start":"2026-10-26T17:12:49.143939Z","end":"2026-10-26T17:12:49.302817Z","steps":["trace[1486836233] 'process raft request' (duration: 158.594184ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:12:49.478582Z","caller":"traceutil/trace.go:171","msg":"trace[86187756] transaction","detail":"{read_only:false; response_revision:15509; number_of_response:1; }","duration":"169.759233ms","start":"2026-10-26T17:12:49.308781Z","end":"2026-10-26T17:12:49.47854Z","steps":["trace[86187756] 'process raft request' (duration: 169.463895ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:12:49.724713Z","caller":"traceutil/trace.go:171","msg":"trace[580673624] transaction","detail":"{read_only:false; response_revision:15510; number_of_response:1; }","duration":"239.792769ms","start":"2026-10-26T17:12:49.484883Z","end":"2026-10-26T17:12:49.724676Z","steps":["trace[580673624] 'process raft request' (duration: 199.474473ms)","trace[580673624] 'compare' (duration: 40.004478ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:12:49.724847Z","caller":"traceutil/trace.go:171","msg":"trace[1379263867] transaction","detail":"{read_only:false; response_revision:15511; number_of_response:1; }","duration":"239.350379ms","start":"2026-10-26T17:12:49.485467Z","end":"2026-10-26T17:12:49.724818Z","steps":["trace[1379263867] 'process raft request' (duration: 239.129652ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:14:32.363076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.446603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5524"} {"level":"info","ts":"2026-10-26T17:14:32.363151Z","caller":"traceutil/trace.go:171","msg":"trace[1768628982] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16610; }","duration":"185.570137ms","start":"2026-10-26T17:14:32.177566Z","end":"2026-10-26T17:14:32.363136Z","steps":["trace[1768628982] 'range keys from in-memory index tree' (duration: 185.256128ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:14:32.363402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.367626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-26T17:14:32.3635Z","caller":"traceutil/trace.go:171","msg":"trace[522767321] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16610; }","duration":"201.51221ms","start":"2026-10-26T17:14:32.161963Z","end":"2026-10-26T17:14:32.363475Z","steps":["trace[522767321] 'range keys from in-memory index tree' (duration: 201.178031ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:14:32.363805Z","caller":"traceutil/trace.go:171","msg":"trace[1524368781] transaction","detail":"{read_only:false; response_revision:16611; number_of_response:1; }","duration":"140.924826ms","start":"2026-10-26T17:14:32.22283Z","end":"2026-10-26T17:14:32.363755Z","steps":["trace[1524368781] 'process raft request' (duration: 106.655787ms)","trace[1524368781] 'compare' (duration: 33.678754ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:14:32.71185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.591505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T17:14:32.712023Z","caller":"traceutil/trace.go:171","msg":"trace[1684192782] transaction","detail":"{read_only:false; response_revision:16612; number_of_response:1; }","duration":"342.61085ms","start":"2026-10-26T17:14:32.369396Z","end":"2026-10-26T17:14:32.712007Z","steps":["trace[1684192782] 'process raft request' (duration: 168.78832ms)","trace[1684192782] 'compare' (duration: 173.130173ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:14:32.712166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:14:32.369379Z","time spent":"342.759956ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:14:33.067529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.293184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T17:14:33.068043Z","caller":"traceutil/trace.go:171","msg":"trace[1977110481] transaction","detail":"{read_only:false; response_revision:16613; number_of_response:1; }","duration":"698.599019ms","start":"2026-10-26T17:14:32.369403Z","end":"2026-10-26T17:14:33.068002Z","steps":["trace[1977110481] 'process raft request' (duration: 511.731649ms)","trace[1977110481] 'compare' (duration: 186.183372ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:14:33.068185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:14:32.369389Z","time spent":"698.737134ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:14:33.23775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.045153ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-26T17:14:33.238138Z","caller":"traceutil/trace.go:171","msg":"trace[284269949] transaction","detail":"{read_only:false; response_revision:16616; number_of_response:1; }","duration":"698.815574ms","start":"2026-10-26T17:14:32.539304Z","end":"2026-10-26T17:14:33.238119Z","steps":["trace[284269949] 'process raft request' (duration: 698.769033ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:14:33.238255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:14:32.539263Z","time spent":"698.924818ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:14:33.238566Z","caller":"traceutil/trace.go:171","msg":"trace[186096614] transaction","detail":"{read_only:false; response_revision:16614; number_of_response:1; }","duration":"722.335225ms","start":"2026-10-26T17:14:32.516181Z","end":"2026-10-26T17:14:33.238517Z","steps":["trace[186096614] 'process raft request' (duration: 721.682999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:14:33.23881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.013671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:14:33.238859Z","caller":"traceutil/trace.go:171","msg":"trace[1516157004] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:16616; }","duration":"867.106112ms","start":"2026-10-26T17:14:32.371741Z","end":"2026-10-26T17:14:33.238847Z","steps":["trace[1516157004] 'agreement among raft nodes before linearized reading' (duration: 867.00109ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:14:33.239029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:14:32.516151Z","time spent":"722.619182ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:14:33.238599Z","caller":"traceutil/trace.go:171","msg":"trace[227885549] transaction","detail":"{read_only:false; response_revision:16615; number_of_response:1; }","duration":"703.512116ms","start":"2026-10-26T17:14:32.535047Z","end":"2026-10-26T17:14:33.238559Z","steps":["trace[227885549] 'process raft request' (duration: 702.977843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:14:33.239726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:14:32.535029Z","time spent":"704.473243ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:14:33.238723Z","caller":"traceutil/trace.go:171","msg":"trace[566335315] linearizableReadLoop","detail":"{readStateIndex:17185; appliedIndex:17183; }","duration":"866.938359ms","start":"2026-10-26T17:14:32.371768Z","end":"2026-10-26T17:14:33.238706Z","steps":["trace[566335315] 'read index received' (duration: 166.434169ms)","trace[566335315] 'applied index is now lower than readState.Index' (duration: 700.50252ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:14:33.238895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:14:32.371731Z","time spent":"867.154834ms","remote":"127.0.0.1:41156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-10-26T17:14:33.24039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.434313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:14:33.240434Z","caller":"traceutil/trace.go:171","msg":"trace[1321735799] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:16616; }","duration":"834.520075ms","start":"2026-10-26T17:14:32.405902Z","end":"2026-10-26T17:14:33.240422Z","steps":["trace[1321735799] 'agreement among raft nodes before linearized reading' (duration: 834.448564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:14:33.240465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:14:32.405879Z","time spent":"834.576178ms","remote":"127.0.0.1:41480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true "} {"level":"warn","ts":"2026-10-26T17:14:33.240797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.259724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-fcvpd\" ","response":"range_response_count:1 size:9792"} {"level":"info","ts":"2026-10-26T17:14:33.240833Z","caller":"traceutil/trace.go:171","msg":"trace[543220667] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-fcvpd; range_end:; response_count:1; response_revision:16616; }","duration":"460.326146ms","start":"2026-10-26T17:14:32.780498Z","end":"2026-10-26T17:14:33.240824Z","steps":["trace[543220667] 'agreement among raft nodes before linearized reading' (duration: 460.201563ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:14:33.240859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:14:32.78048Z","time spent":"460.371097ms","remote":"127.0.0.1:42698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9814,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-fcvpd\" "} {"level":"warn","ts":"2026-10-26T17:14:33.241028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.28688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:14:33.241062Z","caller":"traceutil/trace.go:171","msg":"trace[974111546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16616; }","duration":"476.369782ms","start":"2026-10-26T17:14:32.764684Z","end":"2026-10-26T17:14:33.241053Z","steps":["trace[974111546] 'agreement among raft nodes before linearized reading' (duration: 476.318261ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:14:33.241085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:14:32.764609Z","time spent":"476.469545ms","remote":"127.0.0.1:60430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-10-26T17:14:33.242139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.795804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-26T17:14:33.242176Z","caller":"traceutil/trace.go:171","msg":"trace[171951060] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:16616; }","duration":"582.890096ms","start":"2026-10-26T17:14:32.659278Z","end":"2026-10-26T17:14:33.242168Z","steps":["trace[171951060] 'agreement among raft nodes before linearized reading' (duration: 581.925291ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:14:33.2422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:14:32.659252Z","time spent":"582.941658ms","remote":"127.0.0.1:42588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":1607,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-10-26T17:14:33.242384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.781175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-10-26T17:14:33.24241Z","caller":"traceutil/trace.go:171","msg":"trace[975148603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16616; }","duration":"678.856208ms","start":"2026-10-26T17:14:32.563544Z","end":"2026-10-26T17:14:33.242401Z","steps":["trace[975148603] 'agreement among raft nodes before linearized reading' (duration: 678.776106ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:14:33.242432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:14:32.563512Z","time spent":"678.914939ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-26T17:14:33.242581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.412356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-26T17:14:33.242603Z","caller":"traceutil/trace.go:171","msg":"trace[88394789] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16616; }","duration":"688.506149ms","start":"2026-10-26T17:14:32.55409Z","end":"2026-10-26T17:14:33.242596Z","steps":["trace[88394789] 'agreement among raft nodes before linearized reading' (duration: 688.434037ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:14:33.242646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:14:32.554065Z","time spent":"688.554039ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-26T17:14:33.242803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.540204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5524"} {"level":"info","ts":"2026-10-26T17:14:33.242829Z","caller":"traceutil/trace.go:171","msg":"trace[1265479542] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16616; }","duration":"709.592464ms","start":"2026-10-26T17:14:32.533227Z","end":"2026-10-26T17:14:33.24282Z","steps":["trace[1265479542] 'agreement among raft nodes before linearized reading' (duration: 709.514322ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:14:33.242854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:14:32.533217Z","time spent":"709.630016ms","remote":"127.0.0.1:42734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5546,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"warn","ts":"2026-10-26T17:14:33.243032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.017635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-10-26T17:14:33.243057Z","caller":"traceutil/trace.go:171","msg":"trace[381721111] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16616; }","duration":"710.080527ms","start":"2026-10-26T17:14:32.532969Z","end":"2026-10-26T17:14:33.24305Z","steps":["trace[381721111] 'agreement among raft nodes before linearized reading' (duration: 709.999825ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:14:33.243079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:14:32.532951Z","time spent":"710.122367ms","remote":"127.0.0.1:42676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":690,"request content":"key:\"/registry/services/endpoints/openstack/nova-api\" "} {"level":"warn","ts":"2026-10-26T17:14:33.243226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.924817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:14:33.243249Z","caller":"traceutil/trace.go:171","msg":"trace[1715982091] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16616; }","duration":"718.9817ms","start":"2026-10-26T17:14:32.52426Z","end":"2026-10-26T17:14:33.243242Z","steps":["trace[1715982091] 'agreement among raft nodes before linearized reading' (duration: 718.941028ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:14:33.243271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:14:32.524222Z","time spent":"719.042921ms","remote":"127.0.0.1:42734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"info","ts":"2026-10-26T17:14:33.425563Z","caller":"traceutil/trace.go:171","msg":"trace[1041335424] linearizableReadLoop","detail":"{readStateIndex:17191; appliedIndex:17189; }","duration":"168.656907ms","start":"2026-10-26T17:14:33.256881Z","end":"2026-10-26T17:14:33.425538Z","steps":["trace[1041335424] 'read index received' (duration: 106.984797ms)","trace[1041335424] 'applied index is now lower than readState.Index' (duration: 61.67113ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:14:33.425871Z","caller":"traceutil/trace.go:171","msg":"trace[1886212960] transaction","detail":"{read_only:false; response_revision:16617; number_of_response:1; }","duration":"172.748333ms","start":"2026-10-26T17:14:33.253109Z","end":"2026-10-26T17:14:33.425857Z","steps":["trace[1886212960] 'process raft request' (duration: 110.735455ms)","trace[1886212960] 'compare' (duration: 61.356171ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:14:33.426063Z","caller":"traceutil/trace.go:171","msg":"trace[791268990] transaction","detail":"{read_only:false; response_revision:16618; number_of_response:1; }","duration":"170.520285ms","start":"2026-10-26T17:14:33.255533Z","end":"2026-10-26T17:14:33.426053Z","steps":["trace[791268990] 'process raft request' (duration: 169.950751ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:14:33.426922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.018702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:80 size:779641"} {"level":"info","ts":"2026-10-26T17:14:33.426967Z","caller":"traceutil/trace.go:171","msg":"trace[2043002986] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:80; response_revision:16618; }","duration":"170.102204ms","start":"2026-10-26T17:14:33.256853Z","end":"2026-10-26T17:14:33.426955Z","steps":["trace[2043002986] 'agreement among raft nodes before linearized reading' (duration: 169.317764ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:14:33.427315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.929426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:14:33.427349Z","caller":"traceutil/trace.go:171","msg":"trace[447549097] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:16618; }","duration":"129.000048ms","start":"2026-10-26T17:14:33.29834Z","end":"2026-10-26T17:14:33.42734Z","steps":["trace[447549097] 'agreement among raft nodes before linearized reading' (duration: 128.943527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:14:33.428372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.748879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-dccgf\" ","response":"range_response_count:1 size:20785"} {"level":"info","ts":"2026-10-26T17:14:33.428411Z","caller":"traceutil/trace.go:171","msg":"trace[421581069] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-dccgf; range_end:; response_count:1; response_revision:16618; }","duration":"104.79028ms","start":"2026-10-26T17:14:33.323612Z","end":"2026-10-26T17:14:33.428402Z","steps":["trace[421581069] 'agreement among raft nodes before linearized reading' (duration: 104.700487ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:15:34.007405Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14577} {"level":"info","ts":"2026-10-26T17:15:34.113488Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14577,"took":"99.974644ms","hash":3115581363,"current-db-size-bytes":56721408,"current-db-size":"57 MB","current-db-size-in-use-bytes":30748672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T17:15:34.113561Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3115581363,"revision":14577,"compact-revision":12552} {"level":"info","ts":"2026-10-26T17:20:08.3378Z","caller":"traceutil/trace.go:171","msg":"trace[1085245943] transaction","detail":"{read_only:false; response_revision:19019; number_of_response:1; }","duration":"253.048097ms","start":"2026-10-26T17:20:08.084727Z","end":"2026-10-26T17:20:08.337775Z","steps":["trace[1085245943] 'process raft request' (duration: 252.590805ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:20:15.280306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.913619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:96 size:950404"} {"level":"info","ts":"2026-10-26T17:20:15.280403Z","caller":"traceutil/trace.go:171","msg":"trace[1037131098] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:96; response_revision:19056; }","duration":"106.034932ms","start":"2026-10-26T17:20:15.17435Z","end":"2026-10-26T17:20:15.280385Z","steps":["trace[1037131098] 'range keys from in-memory index tree' (duration: 105.055177ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:20:15.894813Z","caller":"traceutil/trace.go:171","msg":"trace[187078016] linearizableReadLoop","detail":"{readStateIndex:19713; appliedIndex:19712; }","duration":"106.459193ms","start":"2026-10-26T17:20:15.788326Z","end":"2026-10-26T17:20:15.894785Z","steps":["trace[187078016] 'read index received' (duration: 106.299729ms)","trace[187078016] 'applied index is now lower than readState.Index' (duration: 158.224µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:20:15.895077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.7277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-dccgf\" ","response":"range_response_count:1 size:20887"} {"level":"info","ts":"2026-10-26T17:20:15.895124Z","caller":"traceutil/trace.go:171","msg":"trace[1387321700] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-dccgf; range_end:; response_count:1; response_revision:19059; }","duration":"106.815762ms","start":"2026-10-26T17:20:15.788292Z","end":"2026-10-26T17:20:15.895108Z","steps":["trace[1387321700] 'agreement among raft nodes before linearized reading' (duration: 106.599807ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:20:15.895221Z","caller":"traceutil/trace.go:171","msg":"trace[470991094] transaction","detail":"{read_only:false; response_revision:19059; number_of_response:1; }","duration":"112.614922ms","start":"2026-10-26T17:20:15.782577Z","end":"2026-10-26T17:20:15.895192Z","steps":["trace[470991094] 'process raft request' (duration: 112.036658ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:20:16.337422Z","caller":"traceutil/trace.go:171","msg":"trace[448923978] transaction","detail":"{read_only:false; response_revision:19061; number_of_response:1; }","duration":"113.787553ms","start":"2026-10-26T17:20:16.223602Z","end":"2026-10-26T17:20:16.337389Z","steps":["trace[448923978] 'process raft request' (duration: 113.475036ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:20:16.77067Z","caller":"traceutil/trace.go:171","msg":"trace[668683109] transaction","detail":"{read_only:false; response_revision:19062; number_of_response:1; }","duration":"391.523012ms","start":"2026-10-26T17:20:16.379097Z","end":"2026-10-26T17:20:16.77062Z","steps":["trace[668683109] 'process raft request' (duration: 378.975107ms)","trace[668683109] 'compare' (duration: 12.028201ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:20:16.770836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:20:16.379068Z","time spent":"391.676236ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:20:16.770885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.02037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-fcvpd\" ","response":"range_response_count:1 size:9792"} {"level":"info","ts":"2026-10-26T17:20:16.770983Z","caller":"traceutil/trace.go:171","msg":"trace[1249038155] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-fcvpd; range_end:; response_count:1; response_revision:19062; }","duration":"314.162453ms","start":"2026-10-26T17:20:16.456795Z","end":"2026-10-26T17:20:16.770957Z","steps":["trace[1249038155] 'agreement among raft nodes before linearized reading' (duration: 313.901297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:20:16.771043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:20:16.456772Z","time spent":"314.257116ms","remote":"127.0.0.1:42698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9814,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-fcvpd\" "} {"level":"info","ts":"2026-10-26T17:20:16.77059Z","caller":"traceutil/trace.go:171","msg":"trace[1407327242] linearizableReadLoop","detail":"{readStateIndex:19716; appliedIndex:19715; }","duration":"313.717943ms","start":"2026-10-26T17:20:16.456846Z","end":"2026-10-26T17:20:16.770564Z","steps":["trace[1407327242] 'read index received' (duration: 301.157576ms)","trace[1407327242] 'applied index is now lower than readState.Index' (duration: 12.559347ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:20:16.771532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.076048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-10-26T17:20:16.771592Z","caller":"traceutil/trace.go:171","msg":"trace[1380522166] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19062; }","duration":"278.13961ms","start":"2026-10-26T17:20:16.493436Z","end":"2026-10-26T17:20:16.771576Z","steps":["trace[1380522166] 'agreement among raft nodes before linearized reading' (duration: 277.975166ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:20:16.771624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.919319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-10-26T17:20:16.771731Z","caller":"traceutil/trace.go:171","msg":"trace[980178860] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:19062; }","duration":"178.085852ms","start":"2026-10-26T17:20:16.593621Z","end":"2026-10-26T17:20:16.771707Z","steps":["trace[980178860] 'agreement among raft nodes before linearized reading' (duration: 177.839826ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:20:17.039892Z","caller":"traceutil/trace.go:171","msg":"trace[1520891194] transaction","detail":"{read_only:false; response_revision:19064; number_of_response:1; }","duration":"236.365925ms","start":"2026-10-26T17:20:16.803496Z","end":"2026-10-26T17:20:17.039862Z","steps":["trace[1520891194] 'process raft request' (duration: 205.683959ms)","trace[1520891194] 'compare' (duration: 30.309517ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:20:17.040334Z","caller":"traceutil/trace.go:171","msg":"trace[200329491] transaction","detail":"{read_only:false; response_revision:19065; number_of_response:1; }","duration":"233.277155ms","start":"2026-10-26T17:20:16.807029Z","end":"2026-10-26T17:20:17.040306Z","steps":["trace[200329491] 'process raft request' (duration: 232.739301ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:20:17.041566Z","caller":"traceutil/trace.go:171","msg":"trace[480799132] linearizableReadLoop","detail":"{readStateIndex:19720; appliedIndex:19716; }","duration":"219.141438ms","start":"2026-10-26T17:20:16.822394Z","end":"2026-10-26T17:20:17.041536Z","steps":["trace[480799132] 'read index received' (duration: 33.284894ms)","trace[480799132] 'applied index is now lower than readState.Index' (duration: 185.855584ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:20:17.041699Z","caller":"traceutil/trace.go:171","msg":"trace[1990920745] transaction","detail":"{read_only:false; response_revision:19066; number_of_response:1; }","duration":"227.751291ms","start":"2026-10-26T17:20:16.813927Z","end":"2026-10-26T17:20:17.041678Z","steps":["trace[1990920745] 'process raft request' (duration: 227.465114ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:20:17.041945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.554009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7382"} {"level":"info","ts":"2026-10-26T17:20:17.042014Z","caller":"traceutil/trace.go:171","msg":"trace[2082530684] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:19066; }","duration":"219.711543ms","start":"2026-10-26T17:20:16.822286Z","end":"2026-10-26T17:20:17.041997Z","steps":["trace[2082530684] 'agreement among raft nodes before linearized reading' (duration: 219.518047ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:20:17.044592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.716225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/neutron-ks-user\" ","response":"range_response_count:1 size:7382"} {"level":"info","ts":"2026-10-26T17:20:17.044692Z","caller":"traceutil/trace.go:171","msg":"trace[2074032660] range","detail":"{range_begin:/registry/jobs/openstack/neutron-ks-user; range_end:; response_count:1; response_revision:19066; }","duration":"112.85964ms","start":"2026-10-26T17:20:16.931814Z","end":"2026-10-26T17:20:17.044673Z","steps":["trace[2074032660] 'agreement among raft nodes before linearized reading' (duration: 112.626453ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:20:25.773195Z","caller":"traceutil/trace.go:171","msg":"trace[1741232860] linearizableReadLoop","detail":"{readStateIndex:19797; appliedIndex:19794; }","duration":"119.144212ms","start":"2026-10-26T17:20:25.654028Z","end":"2026-10-26T17:20:25.773172Z","steps":["trace[1741232860] 'read index received' (duration: 30.100402ms)","trace[1741232860] 'applied index is now lower than readState.Index' (duration: 89.04225ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:20:25.773243Z","caller":"traceutil/trace.go:171","msg":"trace[615113003] transaction","detail":"{read_only:false; response_revision:19140; number_of_response:1; }","duration":"130.409315ms","start":"2026-10-26T17:20:25.6428Z","end":"2026-10-26T17:20:25.773209Z","steps":["trace[615113003] 'process raft request' (duration: 130.2024ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:20:25.773453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.40169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/ingress-nginx/ingress-nginx-controller-sjxg7.18e221f960b772b5\" ","response":"range_response_count:1 size:767"} {"level":"info","ts":"2026-10-26T17:20:25.773517Z","caller":"traceutil/trace.go:171","msg":"trace[1056462012] range","detail":"{range_begin:/registry/events/ingress-nginx/ingress-nginx-controller-sjxg7.18e221f960b772b5; range_end:; response_count:1; response_revision:19140; }","duration":"119.519163ms","start":"2026-10-26T17:20:25.65398Z","end":"2026-10-26T17:20:25.773499Z","steps":["trace[1056462012] 'agreement among raft nodes before linearized reading' (duration: 119.301287ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:20:34.016448Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16993} {"level":"info","ts":"2026-10-26T17:20:34.145697Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16993,"took":"125.881337ms","hash":2616125868,"current-db-size-bytes":56721408,"current-db-size":"57 MB","current-db-size-in-use-bytes":31920128,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T17:20:34.145772Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2616125868,"revision":16993,"compact-revision":14577} {"level":"info","ts":"2026-10-26T17:20:41.441052Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"208757c2a41558c1","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T17:20:41.454098Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-26T17:20:41.454289Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-26T17:25:34.025706Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19285} {"level":"info","ts":"2026-10-26T17:25:34.159662Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19285,"took":"128.629319ms","hash":1785979010,"current-db-size-bytes":56721408,"current-db-size":"57 MB","current-db-size-in-use-bytes":34312192,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-26T17:25:34.159749Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1785979010,"revision":19285,"compact-revision":16993} {"level":"info","ts":"2026-10-26T17:26:56.230441Z","caller":"traceutil/trace.go:171","msg":"trace[751029239] linearizableReadLoop","detail":"{readStateIndex:22879; appliedIndex:22878; }","duration":"119.222467ms","start":"2026-10-26T17:26:56.111187Z","end":"2026-10-26T17:26:56.23041Z","steps":["trace[751029239] 'read index received' (duration: 118.980271ms)","trace[751029239] 'applied index is now lower than readState.Index' (duration: 240.786µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:26:56.230508Z","caller":"traceutil/trace.go:171","msg":"trace[646701866] transaction","detail":"{read_only:false; response_revision:22127; number_of_response:1; }","duration":"147.264525ms","start":"2026-10-26T17:26:56.083206Z","end":"2026-10-26T17:26:56.230471Z","steps":["trace[646701866] 'process raft request' (duration: 147.030818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:26:56.230801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.602077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-10-26T17:26:56.23096Z","caller":"traceutil/trace.go:171","msg":"trace[138982010] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:22127; }","duration":"119.828563ms","start":"2026-10-26T17:26:56.111105Z","end":"2026-10-26T17:26:56.230934Z","steps":["trace[138982010] 'agreement among raft nodes before linearized reading' (duration: 119.501295ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:27:12.54678Z","caller":"traceutil/trace.go:171","msg":"trace[428882360] transaction","detail":"{read_only:false; response_revision:22208; number_of_response:1; }","duration":"221.625876ms","start":"2026-10-26T17:27:12.325115Z","end":"2026-10-26T17:27:12.546741Z","steps":["trace[428882360] 'process raft request' (duration: 221.36772ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:27:12.901853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.990518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:27:12.901947Z","caller":"traceutil/trace.go:171","msg":"trace[787920608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22208; }","duration":"142.154493ms","start":"2026-10-26T17:27:12.759772Z","end":"2026-10-26T17:27:12.901926Z","steps":["trace[787920608] 'range keys from in-memory index tree' (duration: 141.806393ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:27:13.044475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.534319ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-26T17:27:13.044566Z","caller":"traceutil/trace.go:171","msg":"trace[1930870127] linearizableReadLoop","detail":"{readStateIndex:22967; appliedIndex:22964; }","duration":"141.067925ms","start":"2026-10-26T17:27:12.903485Z","end":"2026-10-26T17:27:13.044553Z","steps":["trace[1930870127] 'read index received' (duration: 12.216548ms)","trace[1930870127] 'applied index is now lower than readState.Index' (duration: 128.850356ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:27:13.044637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.131586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:27:13.044658Z","caller":"traceutil/trace.go:171","msg":"trace[1015684528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22211; }","duration":"141.210848ms","start":"2026-10-26T17:27:12.903438Z","end":"2026-10-26T17:27:13.044649Z","steps":["trace[1015684528] 'agreement among raft nodes before linearized reading' (duration: 141.147406ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:27:13.642306Z","caller":"traceutil/trace.go:171","msg":"trace[1048494001] linearizableReadLoop","detail":"{readStateIndex:22969; appliedIndex:22968; }","duration":"333.927235ms","start":"2026-10-26T17:27:13.308347Z","end":"2026-10-26T17:27:13.642275Z","steps":["trace[1048494001] 'read index received' (duration: 333.668518ms)","trace[1048494001] 'applied index is now lower than readState.Index' (duration: 257.556µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:27:13.642663Z","caller":"traceutil/trace.go:171","msg":"trace[400555575] transaction","detail":"{read_only:false; response_revision:22213; number_of_response:1; }","duration":"359.585211ms","start":"2026-10-26T17:27:13.283065Z","end":"2026-10-26T17:27:13.642651Z","steps":["trace[400555575] 'process raft request' (duration: 359.056048ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:27:13.642775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:27:13.283036Z","time spent":"359.662032ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:27:13.642977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.609252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-26T17:27:13.643053Z","caller":"traceutil/trace.go:171","msg":"trace[1610243373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:22213; }","duration":"334.737545ms","start":"2026-10-26T17:27:13.308297Z","end":"2026-10-26T17:27:13.643034Z","steps":["trace[1610243373] 'agreement among raft nodes before linearized reading' (duration: 334.548071ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:27:13.643086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:27:13.308226Z","time spent":"334.850538ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-26T17:27:13.86234Z","caller":"traceutil/trace.go:171","msg":"trace[1954867045] transaction","detail":"{read_only:false; response_revision:22214; number_of_response:1; }","duration":"215.393865ms","start":"2026-10-26T17:27:13.646912Z","end":"2026-10-26T17:27:13.862305Z","steps":["trace[1954867045] 'process raft request' (duration: 193.102806ms)","trace[1954867045] 'compare' (duration: 22.164555ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:27:13.865674Z","caller":"traceutil/trace.go:171","msg":"trace[1752222636] transaction","detail":"{read_only:false; response_revision:22216; number_of_response:1; }","duration":"135.746375ms","start":"2026-10-26T17:27:13.729861Z","end":"2026-10-26T17:27:13.865607Z","steps":["trace[1752222636] 'process raft request' (duration: 135.504929ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:27:13.865662Z","caller":"traceutil/trace.go:171","msg":"trace[2013482062] linearizableReadLoop","detail":"{readStateIndex:22971; appliedIndex:22969; }","duration":"135.776856ms","start":"2026-10-26T17:27:13.72982Z","end":"2026-10-26T17:27:13.865597Z","steps":["trace[2013482062] 'read index received' (duration: 110.205222ms)","trace[2013482062] 'applied index is now lower than readState.Index' (duration: 25.569214ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:27:13.865668Z","caller":"traceutil/trace.go:171","msg":"trace[2051246807] transaction","detail":"{read_only:false; response_revision:22215; number_of_response:1; }","duration":"218.371422ms","start":"2026-10-26T17:27:13.647039Z","end":"2026-10-26T17:27:13.86541Z","steps":["trace[2051246807] 'process raft request' (duration: 218.184168ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:27:13.865828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.978372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-26T17:27:13.86634Z","caller":"traceutil/trace.go:171","msg":"trace[1294568320] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22216; }","duration":"136.554887ms","start":"2026-10-26T17:27:13.729764Z","end":"2026-10-26T17:27:13.866319Z","steps":["trace[1294568320] 'agreement among raft nodes before linearized reading' (duration: 135.966271ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:30:34.04121Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21565} {"level":"info","ts":"2026-10-26T17:30:34.177488Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21565,"took":"129.883744ms","hash":2120445750,"current-db-size-bytes":56721408,"current-db-size":"57 MB","current-db-size-in-use-bytes":30572544,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T17:30:34.177563Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2120445750,"revision":21565,"compact-revision":19285} {"level":"info","ts":"2026-10-26T17:31:39.023992Z","caller":"traceutil/trace.go:171","msg":"trace[453057422] transaction","detail":"{read_only:false; response_revision:24813; number_of_response:1; }","duration":"123.005595ms","start":"2026-10-26T17:31:38.900931Z","end":"2026-10-26T17:31:39.023936Z","steps":["trace[453057422] 'process raft request' (duration: 122.745689ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:31:39.428886Z","caller":"traceutil/trace.go:171","msg":"trace[199344817] linearizableReadLoop","detail":"{readStateIndex:25650; appliedIndex:25649; }","duration":"249.521842ms","start":"2026-10-26T17:31:39.179334Z","end":"2026-10-26T17:31:39.428855Z","steps":["trace[199344817] 'read index received' (duration: 249.210904ms)","trace[199344817] 'applied index is now lower than readState.Index' (duration: 309.958µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:31:39.429241Z","caller":"traceutil/trace.go:171","msg":"trace[382386918] transaction","detail":"{read_only:false; response_revision:24815; number_of_response:1; }","duration":"329.954641ms","start":"2026-10-26T17:31:39.09927Z","end":"2026-10-26T17:31:39.429224Z","steps":["trace[382386918] 'process raft request' (duration: 329.404896ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:31:39.42938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:31:39.099237Z","time spent":"330.038442ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:31:39.429884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.528618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-api-56df495fc9-c99th.18e224470a055b86\" ","response":"range_response_count:1 size:907"} {"level":"info","ts":"2026-10-26T17:31:39.429985Z","caller":"traceutil/trace.go:171","msg":"trace[1604054256] range","detail":"{range_begin:/registry/events/openstack/heat-api-56df495fc9-c99th.18e224470a055b86; range_end:; response_count:1; response_revision:24815; }","duration":"250.706423ms","start":"2026-10-26T17:31:39.179252Z","end":"2026-10-26T17:31:39.429959Z","steps":["trace[1604054256] 'agreement among raft nodes before linearized reading' (duration: 250.404524ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:33:06.690115Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067e7.wal"} {"level":"info","ts":"2026-10-26T17:35:25.612566Z","caller":"traceutil/trace.go:171","msg":"trace[1485199975] transaction","detail":"{read_only:false; response_revision:27002; number_of_response:1; }","duration":"100.871072ms","start":"2026-10-26T17:35:25.511668Z","end":"2026-10-26T17:35:25.612539Z","steps":["trace[1485199975] 'process raft request' (duration: 100.717898ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:35:26.382416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.163002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T17:35:26.382559Z","caller":"traceutil/trace.go:171","msg":"trace[33373575] transaction","detail":"{read_only:false; response_revision:27005; number_of_response:1; }","duration":"430.482011ms","start":"2026-10-26T17:35:25.952042Z","end":"2026-10-26T17:35:26.382524Z","steps":["trace[33373575] 'process raft request' (duration: 227.130794ms)","trace[33373575] 'compare' (duration: 203.017638ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:35:26.382599Z","caller":"traceutil/trace.go:171","msg":"trace[531361232] linearizableReadLoop","detail":"{readStateIndex:27895; appliedIndex:27893; }","duration":"372.399301ms","start":"2026-10-26T17:35:26.010184Z","end":"2026-10-26T17:35:26.382583Z","steps":["trace[531361232] 'read index received' (duration: 5.636957ms)","trace[531361232] 'applied index is now lower than readState.Index' (duration: 366.760694ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:35:26.382729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.284414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T17:35:26.382735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.550795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-26T17:35:26.382736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:35:25.952015Z","time spent":"430.615854ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:35:26.382757Z","caller":"traceutil/trace.go:171","msg":"trace[311465513] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:27006; }","duration":"338.349165ms","start":"2026-10-26T17:35:26.044399Z","end":"2026-10-26T17:35:26.382748Z","steps":["trace[311465513] 'agreement among raft nodes before linearized reading' (duration: 338.296764ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:35:26.382768Z","caller":"traceutil/trace.go:171","msg":"trace[1449479277] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:27006; }","duration":"372.623857ms","start":"2026-10-26T17:35:26.010134Z","end":"2026-10-26T17:35:26.382758Z","steps":["trace[1449479277] 'agreement among raft nodes before linearized reading' (duration: 372.567875ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:35:26.382594Z","caller":"traceutil/trace.go:171","msg":"trace[1879632420] transaction","detail":"{read_only:false; response_revision:27006; number_of_response:1; }","duration":"304.884605ms","start":"2026-10-26T17:35:26.077695Z","end":"2026-10-26T17:35:26.382579Z","steps":["trace[1879632420] 'process raft request' (duration: 304.821294ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:35:26.382793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:35:26.010112Z","time spent":"372.671368ms","remote":"127.0.0.1:60202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"warn","ts":"2026-10-26T17:35:26.382813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.773629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-26T17:35:26.382837Z","caller":"traceutil/trace.go:171","msg":"trace[869433747] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:27006; }","duration":"195.832821ms","start":"2026-10-26T17:35:26.186999Z","end":"2026-10-26T17:35:26.382832Z","steps":["trace[869433747] 'agreement among raft nodes before linearized reading' (duration: 195.79499ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:35:26.382778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:35:26.04438Z","time spent":"338.392256ms","remote":"127.0.0.1:42416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":124,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true "} {"level":"warn","ts":"2026-10-26T17:35:26.382955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:35:26.077669Z","time spent":"305.160141ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:35:34.050098Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23730} {"level":"info","ts":"2026-10-26T17:35:34.229493Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23730,"took":"173.961832ms","hash":2842230493,"current-db-size-bytes":56721408,"current-db-size":"57 MB","current-db-size-in-use-bytes":44855296,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-10-26T17:35:34.229572Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2842230493,"revision":23730,"compact-revision":21565} {"level":"info","ts":"2026-10-26T17:36:23.767754Z","caller":"traceutil/trace.go:171","msg":"trace[2013331499] transaction","detail":"{read_only:false; response_revision:27530; number_of_response:1; }","duration":"124.943107ms","start":"2026-10-26T17:36:23.642786Z","end":"2026-10-26T17:36:23.767729Z","steps":["trace[2013331499] 'process raft request' (duration: 124.747302ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:36:29.2534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.002052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T17:36:29.253544Z","caller":"traceutil/trace.go:171","msg":"trace[43416847] linearizableReadLoop","detail":"{readStateIndex:28472; appliedIndex:28470; }","duration":"355.586264ms","start":"2026-10-26T17:36:28.897936Z","end":"2026-10-26T17:36:29.253522Z","steps":["trace[43416847] 'read index received' (duration: 13.944422ms)","trace[43416847] 'applied index is now lower than readState.Index' (duration: 341.639962ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:36:29.25363Z","caller":"traceutil/trace.go:171","msg":"trace[664646351] transaction","detail":"{read_only:false; response_revision:27566; number_of_response:1; }","duration":"433.14085ms","start":"2026-10-26T17:36:28.820475Z","end":"2026-10-26T17:36:29.253616Z","steps":["trace[664646351] 'process raft request' (duration: 280.81805ms)","trace[664646351] 'compare' (duration: 151.789767ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:36:29.253742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:36:28.820449Z","time spent":"433.250834ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:36:29.253855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.903653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-26T17:36:29.253955Z","caller":"traceutil/trace.go:171","msg":"trace[461967750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27566; }","duration":"356.044096ms","start":"2026-10-26T17:36:28.897888Z","end":"2026-10-26T17:36:29.253932Z","steps":["trace[461967750] 'agreement among raft nodes before linearized reading' (duration: 355.831651ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:36:29.254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:36:28.897817Z","time spent":"356.17662ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-26T17:36:29.256978Z","caller":"traceutil/trace.go:171","msg":"trace[874824237] transaction","detail":"{read_only:false; response_revision:27567; number_of_response:1; }","duration":"118.759228ms","start":"2026-10-26T17:36:29.13817Z","end":"2026-10-26T17:36:29.256929Z","steps":["trace[874824237] 'process raft request' (duration: 118.582603ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:36:29.676908Z","caller":"traceutil/trace.go:171","msg":"trace[1402296732] transaction","detail":"{read_only:false; response_revision:27571; number_of_response:1; }","duration":"365.526743ms","start":"2026-10-26T17:36:29.311348Z","end":"2026-10-26T17:36:29.676875Z","steps":["trace[1402296732] 'process raft request' (duration: 365.477581ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:36:29.677122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:36:29.311321Z","time spent":"365.686727ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:36:29.677469Z","caller":"traceutil/trace.go:171","msg":"trace[809571918] transaction","detail":"{read_only:false; response_revision:27570; number_of_response:1; }","duration":"380.44788ms","start":"2026-10-26T17:36:29.296994Z","end":"2026-10-26T17:36:29.677442Z","steps":["trace[809571918] 'process raft request' (duration: 379.785053ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:36:29.677565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:36:29.296969Z","time spent":"380.550493ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T17:36:29.677686Z","caller":"traceutil/trace.go:171","msg":"trace[851196884] transaction","detail":"{read_only:false; response_revision:27569; number_of_response:1; }","duration":"416.284641ms","start":"2026-10-26T17:36:29.261391Z","end":"2026-10-26T17:36:29.677675Z","steps":["trace[851196884] 'process raft request' (duration: 415.130311ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:36:29.677744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:36:29.261383Z","time spent":"416.330172ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:36:30.136381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.64733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-26T17:36:30.136588Z","caller":"traceutil/trace.go:171","msg":"trace[1005183973] transaction","detail":"{read_only:false; response_revision:27573; number_of_response:1; }","duration":"219.517687ms","start":"2026-10-26T17:36:29.917052Z","end":"2026-10-26T17:36:30.13657Z","steps":["trace[1005183973] 'process raft request' (duration: 219.439705ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:36:30.136633Z","caller":"traceutil/trace.go:171","msg":"trace[23216424] linearizableReadLoop","detail":"{readStateIndex:28478; appliedIndex:28474; }","duration":"660.655954ms","start":"2026-10-26T17:36:29.475938Z","end":"2026-10-26T17:36:30.136594Z","steps":["trace[23216424] 'read index received' (duration: 200.769559ms)","trace[23216424] 'applied index is now lower than readState.Index' (duration: 459.883805ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:36:30.136851Z","caller":"traceutil/trace.go:171","msg":"trace[1712021183] transaction","detail":"{read_only:false; response_revision:27572; number_of_response:1; }","duration":"822.389658ms","start":"2026-10-26T17:36:29.314439Z","end":"2026-10-26T17:36:30.136829Z","steps":["trace[1712021183] 'process raft request' (duration: 542.190694ms)","trace[1712021183] 'compare' (duration: 279.321872ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:36:30.13702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:36:29.314426Z","time spent":"822.463921ms","remote":"127.0.0.1:42750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T17:36:30.137162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.222509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-10-26T17:36:30.137219Z","caller":"traceutil/trace.go:171","msg":"trace[1595570539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27573; }","duration":"661.326111ms","start":"2026-10-26T17:36:29.475877Z","end":"2026-10-26T17:36:30.137204Z","steps":["trace[1595570539] 'agreement among raft nodes before linearized reading' (duration: 661.163327ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:36:30.137257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:36:29.475853Z","time spent":"661.393355ms","remote":"127.0.0.1:42750","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-10-26T17:36:30.13749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.440919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-26T17:36:30.137587Z","caller":"traceutil/trace.go:171","msg":"trace[1917982335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27573; }","duration":"655.591142ms","start":"2026-10-26T17:36:29.481969Z","end":"2026-10-26T17:36:30.13756Z","steps":["trace[1917982335] 'agreement among raft nodes before linearized reading' (duration: 655.118121ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:36:30.137638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.917891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12429"} {"level":"warn","ts":"2026-10-26T17:36:30.1377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:36:29.481946Z","time spent":"655.687915ms","remote":"127.0.0.1:42750","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-10-26T17:36:30.13773Z","caller":"traceutil/trace.go:171","msg":"trace[1817107829] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27573; }","duration":"369.077534ms","start":"2026-10-26T17:36:29.768636Z","end":"2026-10-26T17:36:30.137713Z","steps":["trace[1817107829] 'agreement among raft nodes before linearized reading' (duration: 368.91741ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T17:36:30.137715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.599682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-10-26T17:36:30.137766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T17:36:29.768612Z","time spent":"369.146106ms","remote":"127.0.0.1:42698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12451,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-26T17:36:30.137823Z","caller":"traceutil/trace.go:171","msg":"trace[2084123036] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27573; }","duration":"158.772508ms","start":"2026-10-26T17:36:29.979025Z","end":"2026-10-26T17:36:30.137797Z","steps":["trace[2084123036] 'agreement among raft nodes before linearized reading' (duration: 158.438959ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:36:30.289507Z","caller":"traceutil/trace.go:171","msg":"trace[891254438] linearizableReadLoop","detail":"{readStateIndex:28480; appliedIndex:28479; }","duration":"145.940464ms","start":"2026-10-26T17:36:30.143537Z","end":"2026-10-26T17:36:30.289478Z","steps":["trace[891254438] 'read index received' (duration: 135.285937ms)","trace[891254438] 'applied index is now lower than readState.Index' (duration: 10.653367ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:36:30.289581Z","caller":"traceutil/trace.go:171","msg":"trace[1130782591] transaction","detail":"{read_only:false; response_revision:27574; number_of_response:1; }","duration":"146.063996ms","start":"2026-10-26T17:36:30.143476Z","end":"2026-10-26T17:36:30.28954Z","steps":["trace[1130782591] 'process raft request' (duration: 135.373169ms)","trace[1130782591] 'compare' (duration: 10.455961ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T17:36:30.289766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.20044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18748"} {"level":"info","ts":"2026-10-26T17:36:30.289807Z","caller":"traceutil/trace.go:171","msg":"trace[703093595] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27574; }","duration":"146.304593ms","start":"2026-10-26T17:36:30.143491Z","end":"2026-10-26T17:36:30.289795Z","steps":["trace[703093595] 'agreement among raft nodes before linearized reading' (duration: 146.093937ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:36:30.295023Z","caller":"traceutil/trace.go:171","msg":"trace[1956691611] transaction","detail":"{read_only:false; response_revision:27575; number_of_response:1; }","duration":"150.613215ms","start":"2026-10-26T17:36:30.14438Z","end":"2026-10-26T17:36:30.294994Z","steps":["trace[1956691611] 'process raft request' (duration: 150.42005ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:36:30.295177Z","caller":"traceutil/trace.go:171","msg":"trace[169680652] transaction","detail":"{read_only:false; response_revision:27576; number_of_response:1; }","duration":"150.313557ms","start":"2026-10-26T17:36:30.144841Z","end":"2026-10-26T17:36:30.295155Z","steps":["trace[169680652] 'process raft request' (duration: 150.098222ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:39:12.646183Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"208757c2a41558c1","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T17:39:12.653804Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-26T17:39:12.653961Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-26T17:40:11.149273Z","caller":"traceutil/trace.go:171","msg":"trace[360041728] transaction","detail":"{read_only:false; response_revision:29721; number_of_response:1; }","duration":"105.268676ms","start":"2026-10-26T17:40:11.043945Z","end":"2026-10-26T17:40:11.149213Z","steps":["trace[360041728] 'process raft request' (duration: 88.402658ms)","trace[360041728] 'compare' (duration: 16.401727ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T17:40:11.149895Z","caller":"traceutil/trace.go:171","msg":"trace[2054968489] transaction","detail":"{read_only:false; number_of_response:1; response_revision:29722; }","duration":"105.176275ms","start":"2026-10-26T17:40:11.044648Z","end":"2026-10-26T17:40:11.149824Z","steps":["trace[2054968489] 'process raft request' (duration: 104.417615ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T17:40:34.063411Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27061} {"level":"info","ts":"2026-10-26T17:40:34.261821Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27061,"took":"194.575489ms","hash":3040364654,"current-db-size-bytes":57942016,"current-db-size":"58 MB","current-db-size-in-use-bytes":36106240,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-26T17:40:34.261952Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3040364654,"revision":27061,"compact-revision":23730} {"level":"info","ts":"2026-10-26T17:45:34.100493Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29968} {"level":"info","ts":"2026-10-26T17:45:34.253729Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29968,"took":"149.72927ms","hash":3230965917,"current-db-size-bytes":57942016,"current-db-size":"58 MB","current-db-size-in-use-bytes":32165888,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T17:45:34.2538Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3230965917,"revision":29968,"compact-revision":27061} {"level":"info","ts":"2026-10-26T17:50:34.1106Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32287} {"level":"info","ts":"2026-10-26T17:50:34.281045Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32287,"took":"166.907756ms","hash":4144057530,"current-db-size-bytes":58986496,"current-db-size":"59 MB","current-db-size-in-use-bytes":31793152,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T17:50:34.281105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4144057530,"revision":32287,"compact-revision":29968} {"level":"info","ts":"2026-10-26T17:55:34.119552Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34513} {"level":"info","ts":"2026-10-26T17:55:34.259126Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34513,"took":"135.716905ms","hash":232942446,"current-db-size-bytes":58986496,"current-db-size":"59 MB","current-db-size-in-use-bytes":31879168,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-26T17:55:34.259194Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":232942446,"revision":34513,"compact-revision":32287} {"level":"info","ts":"2026-10-26T17:59:58.655515Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"208757c2a41558c1","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-26T17:59:58.661941Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-26T17:59:58.662198Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-26T18:00:34.127407Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":36745} {"level":"info","ts":"2026-10-26T18:00:34.287022Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":36745,"took":"155.024807ms","hash":3213848764,"current-db-size-bytes":58986496,"current-db-size":"59 MB","current-db-size-in-use-bytes":33542144,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-26T18:00:34.287093Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3213848764,"revision":36745,"compact-revision":34513} {"level":"info","ts":"2026-10-26T18:05:34.137366Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39147} {"level":"info","ts":"2026-10-26T18:05:34.285207Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39147,"took":"143.241463ms","hash":135772929,"current-db-size-bytes":58986496,"current-db-size":"59 MB","current-db-size-in-use-bytes":32755712,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-26T18:05:34.285284Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":135772929,"revision":39147,"compact-revision":36745} {"level":"info","ts":"2026-10-26T18:10:34.145171Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41413} {"level":"info","ts":"2026-10-26T18:10:34.288226Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41413,"took":"136.244621ms","hash":935065972,"current-db-size-bytes":59412480,"current-db-size":"59 MB","current-db-size-in-use-bytes":31223808,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-26T18:10:34.288332Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":935065972,"revision":41413,"compact-revision":39147}