{"level":"warn","ts":"2026-06-10T12:55:08.300254Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T12:55:08.300365Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.177: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.177:2380","--initial-cluster=instance=https://199.204.45.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.177: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-06-10T12:55:08.300439Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T12:55:08.30046Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-06-10T12:55:08.300488Z","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-06-10T12:55:08.301226Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"]} {"level":"info","ts":"2026-06-10T12:55:08.301317Z","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.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.177: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-06-10T12:55:08.305241Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.685906ms"} {"level":"info","ts":"2026-06-10T12:55:08.311945Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7c53a18098324dc9","cluster-id":"4717e8aa221973c8"} {"level":"info","ts":"2026-06-10T12:55:08.31204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=()"} {"level":"info","ts":"2026-06-10T12:55:08.312105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 0"} {"level":"info","ts":"2026-06-10T12:55:08.312126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7c53a18098324dc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-10T12:55:08.312143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 1"} {"level":"info","ts":"2026-06-10T12:55:08.312202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"warn","ts":"2026-06-10T12:55:08.317179Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-10T12:55:08.31856Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-10T12:55:08.319802Z","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-06-10T12:55:08.322309Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7c53a18098324dc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-10T12:55:08.32261Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7c53a18098324dc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-10T12:55:08.322712Z","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-06-10T12:55:08.322855Z","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-06-10T12:55:08.322866Z","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-06-10T12:55:08.324764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"info","ts":"2026-06-10T12:55:08.324926Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","added-peer-id":"7c53a18098324dc9","added-peer-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-06-10T12:55:08.328158Z","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-06-10T12:55:08.328282Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-06-10T12:55:08.328363Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-06-10T12:55:08.328602Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7c53a18098324dc9","initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-10T12:55:08.328673Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-10T12:55:08.712423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 is starting a new election at term 1"} {"level":"info","ts":"2026-06-10T12:55:08.71249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-10T12:55:08.712528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgPreVoteResp from 7c53a18098324dc9 at term 1"} {"level":"info","ts":"2026-06-10T12:55:08.712551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became candidate at term 2"} {"level":"info","ts":"2026-06-10T12:55:08.712568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgVoteResp from 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-06-10T12:55:08.712594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became leader at term 2"} {"level":"info","ts":"2026-06-10T12:55:08.712613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7c53a18098324dc9 elected leader 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-06-10T12:55:08.713547Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T12:55:08.714218Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7c53a18098324dc9","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.177:2379]}","request-path":"/0/members/7c53a18098324dc9/attributes","cluster-id":"4717e8aa221973c8","publish-timeout":"7s"} {"level":"info","ts":"2026-06-10T12:55:08.714219Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T12:55:08.714535Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T12:55:08.71471Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T12:55:08.714893Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T12:55:08.714909Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-10T12:55:08.714931Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-10T12:55:08.714953Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T12:55:08.719057Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-10T12:55:08.719088Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.177:2379"} {"level":"info","ts":"2026-06-10T12:55:13.956525Z","caller":"traceutil/trace.go:171","msg":"trace[1952203777] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"125.566696ms","start":"2026-06-10T12:55:13.830905Z","end":"2026-06-10T12:55:13.956472Z","steps":["trace[1952203777] 'process raft request' (duration: 125.38873ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:55:34.442517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.978979ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T12:55:34.442794Z","caller":"traceutil/trace.go:171","msg":"trace[786142816] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"151.331339ms","start":"2026-06-10T12:55:34.291372Z","end":"2026-06-10T12:55:34.442703Z","steps":["trace[786142816] 'process raft request' (duration: 42.36492ms)","trace[786142816] 'compare' (duration: 107.792295ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T12:55:37.308406Z","caller":"traceutil/trace.go:171","msg":"trace[1093546588] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"115.531797ms","start":"2026-06-10T12:55:37.192836Z","end":"2026-06-10T12:55:37.308368Z","steps":["trace[1093546588] 'process raft request' (duration: 115.349932ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:55:50.577502Z","caller":"traceutil/trace.go:171","msg":"trace[1686151813] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"175.928787ms","start":"2026-06-10T12:55:50.401536Z","end":"2026-06-10T12:55:50.577465Z","steps":["trace[1686151813] 'process raft request' (duration: 175.68187ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:55:50.589136Z","caller":"traceutil/trace.go:171","msg":"trace[1591969110] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"162.526191ms","start":"2026-06-10T12:55:50.42659Z","end":"2026-06-10T12:55:50.589116Z","steps":["trace[1591969110] 'process raft request' (duration: 162.346265ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:55:50.589146Z","caller":"traceutil/trace.go:171","msg":"trace[1410548633] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"166.458373ms","start":"2026-06-10T12:55:50.422606Z","end":"2026-06-10T12:55:50.589065Z","steps":["trace[1410548633] 'process raft request' (duration: 166.180545ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:55:50.589276Z","caller":"traceutil/trace.go:171","msg":"trace[349032335] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"157.491406ms","start":"2026-06-10T12:55:50.431731Z","end":"2026-06-10T12:55:50.589222Z","steps":["trace[349032335] 'process raft request' (duration: 157.262539ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:55:50.589322Z","caller":"traceutil/trace.go:171","msg":"trace[1573349511] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"149.575677ms","start":"2026-06-10T12:55:50.439735Z","end":"2026-06-10T12:55:50.589311Z","steps":["trace[1573349511] 'process raft request' (duration: 149.467004ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:55:50.589355Z","caller":"traceutil/trace.go:171","msg":"trace[1112974718] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"150.774552ms","start":"2026-06-10T12:55:50.438512Z","end":"2026-06-10T12:55:50.589286Z","steps":["trace[1112974718] 'process raft request' (duration: 150.637558ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:55:50.589418Z","caller":"traceutil/trace.go:171","msg":"trace[463144423] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"149.252248ms","start":"2026-06-10T12:55:50.440156Z","end":"2026-06-10T12:55:50.589408Z","steps":["trace[463144423] 'process raft request' (duration: 149.100453ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:55:50.589515Z","caller":"traceutil/trace.go:171","msg":"trace[795880465] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"149.262719ms","start":"2026-06-10T12:55:50.440242Z","end":"2026-06-10T12:55:50.589505Z","steps":["trace[795880465] 'process raft request' (duration: 149.072165ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:55:50.589272Z","caller":"traceutil/trace.go:171","msg":"trace[1410867762] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"151.601976ms","start":"2026-06-10T12:55:50.437658Z","end":"2026-06-10T12:55:50.58926Z","steps":["trace[1410867762] 'process raft request' (duration: 151.37694ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:55:50.589626Z","caller":"traceutil/trace.go:171","msg":"trace[654989671] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"137.791948ms","start":"2026-06-10T12:55:50.451825Z","end":"2026-06-10T12:55:50.589617Z","steps":["trace[654989671] 'process raft request' (duration: 137.569952ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:55:50.589752Z","caller":"traceutil/trace.go:171","msg":"trace[1538282506] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"106.936879ms","start":"2026-06-10T12:55:50.482791Z","end":"2026-06-10T12:55:50.589728Z","steps":["trace[1538282506] 'process raft request' (duration: 106.712562ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:55:50.590366Z","caller":"traceutil/trace.go:171","msg":"trace[1932901440] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"101.00255ms","start":"2026-06-10T12:55:50.489333Z","end":"2026-06-10T12:55:50.590336Z","steps":["trace[1932901440] 'process raft request' (duration: 100.29805ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:55:56.97455Z","caller":"traceutil/trace.go:171","msg":"trace[460135399] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"305.097866ms","start":"2026-06-10T12:55:56.669422Z","end":"2026-06-10T12:55:56.97452Z","steps":["trace[460135399] 'process raft request' (duration: 208.037811ms)","trace[460135399] 'compare' (duration: 96.905591ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:55:56.974807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:55:56.669395Z","time spent":"305.301093ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T12:55:57.005064Z","caller":"traceutil/trace.go:171","msg":"trace[51859489] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"105.089007ms","start":"2026-06-10T12:55:56.899942Z","end":"2026-06-10T12:55:57.005031Z","steps":["trace[51859489] 'read index received' (duration: 104.86515ms)","trace[51859489] 'applied index is now lower than readState.Index' (duration: 222.287µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T12:55:57.005242Z","caller":"traceutil/trace.go:171","msg":"trace[1945055214] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"286.536922ms","start":"2026-06-10T12:55:56.718631Z","end":"2026-06-10T12:55:57.005168Z","steps":["trace[1945055214] 'process raft request' (duration: 286.259944ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:55:57.00548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.520699ms","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-06-10T12:55:57.00555Z","caller":"traceutil/trace.go:171","msg":"trace[1375136706] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:643; }","duration":"105.642722ms","start":"2026-06-10T12:55:56.899889Z","end":"2026-06-10T12:55:57.005532Z","steps":["trace[1375136706] 'agreement among raft nodes before linearized reading' (duration: 105.321953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:55:57.37959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.302581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kn4bt\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-10T12:55:57.37991Z","caller":"traceutil/trace.go:171","msg":"trace[1340147578] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kn4bt; range_end:; response_count:1; response_revision:644; }","duration":"298.672061ms","start":"2026-06-10T12:55:57.081216Z","end":"2026-06-10T12:55:57.379888Z","steps":["trace[1340147578] 'range keys from in-memory index tree' (duration: 298.130176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:55:57.512738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.614113ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-10T12:55:57.754021Z","caller":"traceutil/trace.go:171","msg":"trace[1267214594] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"355.57173ms","start":"2026-06-10T12:55:57.39842Z","end":"2026-06-10T12:55:57.753992Z","steps":["trace[1267214594] 'process raft request' (duration: 355.414916ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:55:57.7542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:55:57.398393Z","time spent":"355.715824ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T12:55:58.96384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.728761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T12:55:58.964Z","caller":"traceutil/trace.go:171","msg":"trace[1148172417] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:674; }","duration":"1.5229909s","start":"2026-06-10T12:55:57.440952Z","end":"2026-06-10T12:55:58.963943Z","steps":["trace[1148172417] 'read index received' (duration: 312.976644ms)","trace[1148172417] 'applied index is now lower than readState.Index' (duration: 1.210012706s)"],"step_count":2} {"level":"info","ts":"2026-06-10T12:55:58.964024Z","caller":"traceutil/trace.go:171","msg":"trace[1322096149] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.532342139s","start":"2026-06-10T12:55:57.431637Z","end":"2026-06-10T12:55:58.963979Z","steps":["trace[1322096149] 'process raft request' (duration: 1.049385051s)","trace[1322096149] 'compare' (duration: 482.285379ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:55:58.964147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.300276316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T12:55:58.964189Z","caller":"traceutil/trace.go:171","msg":"trace[610603907] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"1.300318007s","start":"2026-06-10T12:55:57.663858Z","end":"2026-06-10T12:55:58.964176Z","steps":["trace[610603907] 'agreement among raft nodes before linearized reading' (duration: 1.300249886s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:55:58.964217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.523254118s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-10T12:55:58.964234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:55:57.6638Z","time spent":"1.30042405s","remote":"127.0.0.1:53190","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-06-10T12:55:58.964269Z","caller":"traceutil/trace.go:171","msg":"trace[631336352] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"1.52333006s","start":"2026-06-10T12:55:57.440925Z","end":"2026-06-10T12:55:58.964255Z","steps":["trace[631336352] 'agreement among raft nodes before linearized reading' (duration: 1.523169986s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:55:58.964284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:55:57.431609Z","time spent":"1.532569895s","remote":"127.0.0.1:53250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T12:55:58.964302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:55:57.440913Z","time spent":"1.523381691s","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T12:55:58.964289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.792848ms","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-06-10T12:55:58.964376Z","caller":"traceutil/trace.go:171","msg":"trace[1535282476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:646; }","duration":"345.916661ms","start":"2026-06-10T12:55:58.618438Z","end":"2026-06-10T12:55:58.964355Z","steps":["trace[1535282476] 'agreement among raft nodes before linearized reading' (duration: 345.738336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:55:58.964421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:55:58.618423Z","time spent":"345.990303ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-10T12:56:00.521639Z","caller":"traceutil/trace.go:171","msg":"trace[1382078319] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"130.447516ms","start":"2026-06-10T12:56:00.391166Z","end":"2026-06-10T12:56:00.521613Z","steps":["trace[1382078319] 'process raft request' (duration: 130.323883ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:13.381657Z","caller":"traceutil/trace.go:171","msg":"trace[1863689449] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"313.097867ms","start":"2026-06-10T12:56:13.068525Z","end":"2026-06-10T12:56:13.381623Z","steps":["trace[1863689449] 'process raft request' (duration: 312.897381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:13.381858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:13.068498Z","time spent":"313.264441ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T12:56:13.383264Z","caller":"traceutil/trace.go:171","msg":"trace[1409698770] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:836; }","duration":"262.52355ms","start":"2026-06-10T12:56:13.120715Z","end":"2026-06-10T12:56:13.383239Z","steps":["trace[1409698770] 'read index received' (duration: 260.910424ms)","trace[1409698770] 'applied index is now lower than readState.Index' (duration: 1.612006ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T12:56:13.383424Z","caller":"traceutil/trace.go:171","msg":"trace[728587143] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"313.494788ms","start":"2026-06-10T12:56:13.069913Z","end":"2026-06-10T12:56:13.383407Z","steps":["trace[728587143] 'process raft request' (duration: 313.122927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:13.383987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.78701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nkwg4\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-06-10T12:56:13.3841Z","caller":"traceutil/trace.go:171","msg":"trace[699136712] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nkwg4; range_end:; response_count:1; response_revision:803; }","duration":"193.941495ms","start":"2026-06-10T12:56:13.190141Z","end":"2026-06-10T12:56:13.384083Z","steps":["trace[699136712] 'agreement among raft nodes before linearized reading' (duration: 193.713399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:13.38399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:13.0699Z","time spent":"313.92193ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T12:56:13.383608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.887741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-10T12:56:13.384494Z","caller":"traceutil/trace.go:171","msg":"trace[1006277761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:803; }","duration":"263.788927ms","start":"2026-06-10T12:56:13.120681Z","end":"2026-06-10T12:56:13.38447Z","steps":["trace[1006277761] 'agreement among raft nodes before linearized reading' (duration: 262.815979ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:13.383537Z","caller":"traceutil/trace.go:171","msg":"trace[2033650783] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"311.601684ms","start":"2026-06-10T12:56:13.071895Z","end":"2026-06-10T12:56:13.383496Z","steps":["trace[2033650783] 'process raft request' (duration: 311.295765ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:56:13.384767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:56:13.07188Z","time spent":"312.824199ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T12:56:15.395377Z","caller":"traceutil/trace.go:171","msg":"trace[1221537372] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"119.386049ms","start":"2026-06-10T12:56:15.275962Z","end":"2026-06-10T12:56:15.395349Z","steps":["trace[1221537372] 'process raft request' (duration: 119.206804ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:25.879357Z","caller":"traceutil/trace.go:171","msg":"trace[143459667] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"129.030916ms","start":"2026-06-10T12:56:25.750292Z","end":"2026-06-10T12:56:25.879322Z","steps":["trace[143459667] 'process raft request' (duration: 127.859413ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:25.879436Z","caller":"traceutil/trace.go:171","msg":"trace[2144683106] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"127.016347ms","start":"2026-06-10T12:56:25.752371Z","end":"2026-06-10T12:56:25.879387Z","steps":["trace[2144683106] 'process raft request' (duration: 126.872424ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:25.879595Z","caller":"traceutil/trace.go:171","msg":"trace[1713228617] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"126.896524ms","start":"2026-06-10T12:56:25.752619Z","end":"2026-06-10T12:56:25.879515Z","steps":["trace[1713228617] 'process raft request' (duration: 126.696798ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:56:48.75016Z","caller":"traceutil/trace.go:171","msg":"trace[272283700] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"150.381398ms","start":"2026-06-10T12:56:48.599728Z","end":"2026-06-10T12:56:48.75011Z","steps":["trace[272283700] 'process raft request' (duration: 150.077039ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:57:09.259Z","caller":"traceutil/trace.go:171","msg":"trace[572188620] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"132.26617ms","start":"2026-06-10T12:57:09.126673Z","end":"2026-06-10T12:57:09.258939Z","steps":["trace[572188620] 'process raft request' (duration: 129.707738ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:57:36.046087Z","caller":"traceutil/trace.go:171","msg":"trace[1556806314] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1979; }","duration":"499.660945ms","start":"2026-06-10T12:57:35.546387Z","end":"2026-06-10T12:57:36.046048Z","steps":["trace[1556806314] 'read index received' (duration: 444.234745ms)","trace[1556806314] 'applied index is now lower than readState.Index' (duration: 55.4249ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T12:57:36.046216Z","caller":"traceutil/trace.go:171","msg":"trace[1599132701] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"600.702735ms","start":"2026-06-10T12:57:35.445462Z","end":"2026-06-10T12:57:36.046165Z","steps":["trace[1599132701] 'process raft request' (duration: 545.169502ms)","trace[1599132701] 'compare' (duration: 55.121112ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:57:36.046283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.66362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T12:57:36.046358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.974921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-59a8c515-4ffe-4680-be11-0a9533a72413\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-10T12:57:36.046417Z","caller":"traceutil/trace.go:171","msg":"trace[1634245384] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-59a8c515-4ffe-4680-be11-0a9533a72413; range_end:; response_count:1; response_revision:1902; }","duration":"428.073214ms","start":"2026-06-10T12:57:35.61833Z","end":"2026-06-10T12:57:36.046404Z","steps":["trace[1634245384] 'agreement among raft nodes before linearized reading' (duration: 427.889099ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:57:36.046359Z","caller":"traceutil/trace.go:171","msg":"trace[708994844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1902; }","duration":"382.806993ms","start":"2026-06-10T12:57:35.663537Z","end":"2026-06-10T12:57:36.046344Z","steps":["trace[708994844] 'agreement among raft nodes before linearized reading' (duration: 382.6885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:57:36.046388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.999624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-59a8c515-4ffe-4680-be11-0a9533a72413\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-06-10T12:57:36.046461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:57:35.618301Z","time spent":"428.150255ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-59a8c515-4ffe-4680-be11-0a9533a72413\" "} {"level":"warn","ts":"2026-06-10T12:57:36.046447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:57:35.44543Z","time spent":"600.9173ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T12:57:36.046483Z","caller":"traceutil/trace.go:171","msg":"trace[1041523068] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-59a8c515-4ffe-4680-be11-0a9533a72413; range_end:; response_count:1; response_revision:1902; }","duration":"500.146119ms","start":"2026-06-10T12:57:35.546316Z","end":"2026-06-10T12:57:36.046462Z","steps":["trace[1041523068] 'agreement among raft nodes before linearized reading' (duration: 499.872711ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:57:36.046464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:57:35.663492Z","time spent":"382.963078ms","remote":"127.0.0.1:53190","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-06-10T12:57:36.046534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:57:35.546282Z","time spent":"500.238921ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-59a8c515-4ffe-4680-be11-0a9533a72413\" "} {"level":"info","ts":"2026-06-10T12:57:36.708686Z","caller":"traceutil/trace.go:171","msg":"trace[1232532354] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"172.099716ms","start":"2026-06-10T12:57:36.536559Z","end":"2026-06-10T12:57:36.708658Z","steps":["trace[1232532354] 'process raft request' (duration: 163.969805ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:57:49.018184Z","caller":"traceutil/trace.go:171","msg":"trace[185516313] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"259.304397ms","start":"2026-06-10T12:57:48.758861Z","end":"2026-06-10T12:57:49.018165Z","steps":["trace[185516313] 'process raft request' (duration: 259.182934ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:57:51.367231Z","caller":"traceutil/trace.go:171","msg":"trace[1881006027] linearizableReadLoop","detail":"{readStateIndex:2117; appliedIndex:2116; }","duration":"170.109514ms","start":"2026-06-10T12:57:51.197089Z","end":"2026-06-10T12:57:51.367198Z","steps":["trace[1881006027] 'read index received' (duration: 148.943785ms)","trace[1881006027] 'applied index is now lower than readState.Index' (duration: 21.164439ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:57:51.367498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.392943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-06-10T12:57:51.367553Z","caller":"traceutil/trace.go:171","msg":"trace[789245159] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2035; }","duration":"170.491605ms","start":"2026-06-10T12:57:51.197045Z","end":"2026-06-10T12:57:51.367537Z","steps":["trace[789245159] 'agreement among raft nodes before linearized reading' (duration: 170.274229ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:57:51.36765Z","caller":"traceutil/trace.go:171","msg":"trace[1881049411] transaction","detail":"{read_only:false; response_revision:2035; number_of_response:1; }","duration":"180.604186ms","start":"2026-06-10T12:57:51.187007Z","end":"2026-06-10T12:57:51.367611Z","steps":["trace[1881049411] 'process raft request' (duration: 159.026426ms)","trace[1881049411] 'compare' (duration: 20.985743ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T12:57:52.384862Z","caller":"traceutil/trace.go:171","msg":"trace[414394648] linearizableReadLoop","detail":"{readStateIndex:2119; appliedIndex:2118; }","duration":"196.012726ms","start":"2026-06-10T12:57:52.188623Z","end":"2026-06-10T12:57:52.384636Z","steps":["trace[414394648] 'read index received' (duration: 194.953306ms)","trace[414394648] 'applied index is now lower than readState.Index' (duration: 1.055659ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T12:57:52.384993Z","caller":"traceutil/trace.go:171","msg":"trace[713524784] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"215.988961ms","start":"2026-06-10T12:57:52.16894Z","end":"2026-06-10T12:57:52.384929Z","steps":["trace[713524784] 'process raft request' (duration: 214.634793ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:57:52.385202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.555081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-10T12:57:52.38525Z","caller":"traceutil/trace.go:171","msg":"trace[30517228] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2037; }","duration":"196.689484ms","start":"2026-06-10T12:57:52.188548Z","end":"2026-06-10T12:57:52.385238Z","steps":["trace[30517228] 'agreement among raft nodes before linearized reading' (duration: 196.469418ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:57:52.385321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.410444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-10T12:57:52.38539Z","caller":"traceutil/trace.go:171","msg":"trace[369383845] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:2037; }","duration":"151.531047ms","start":"2026-06-10T12:57:52.233841Z","end":"2026-06-10T12:57:52.385372Z","steps":["trace[369383845] 'agreement among raft nodes before linearized reading' (duration: 151.311321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:57:53.001336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.574554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T12:57:53.001448Z","caller":"traceutil/trace.go:171","msg":"trace[1603357704] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2042; }","duration":"181.750739ms","start":"2026-06-10T12:57:52.819678Z","end":"2026-06-10T12:57:53.001429Z","steps":["trace[1603357704] 'count revisions from in-memory index tree' (duration: 181.485121ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:58:25.689847Z","caller":"traceutil/trace.go:171","msg":"trace[645222282] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"206.552158ms","start":"2026-06-10T12:58:25.483256Z","end":"2026-06-10T12:58:25.689808Z","steps":["trace[645222282] 'process raft request' (duration: 206.366913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:31.814173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.86865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T12:58:31.814295Z","caller":"traceutil/trace.go:171","msg":"trace[1059604085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2252; }","duration":"151.067035ms","start":"2026-06-10T12:58:31.663202Z","end":"2026-06-10T12:58:31.81427Z","steps":["trace[1059604085] 'range keys from in-memory index tree' (duration: 150.772697ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:58:31.814542Z","caller":"traceutil/trace.go:171","msg":"trace[983082357] transaction","detail":"{read_only:false; response_revision:2253; number_of_response:1; }","duration":"106.70307ms","start":"2026-06-10T12:58:31.707782Z","end":"2026-06-10T12:58:31.814485Z","steps":["trace[983082357] 'process raft request' (duration: 82.632341ms)","trace[983082357] 'compare' (duration: 23.781001ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:58:32.387851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.840596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-10T12:58:32.387946Z","caller":"traceutil/trace.go:171","msg":"trace[1998795878] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:2253; }","duration":"151.992701ms","start":"2026-06-10T12:58:32.235935Z","end":"2026-06-10T12:58:32.387928Z","steps":["trace[1998795878] 'range keys from in-memory index tree' (duration: 151.632161ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:58:32.405187Z","caller":"traceutil/trace.go:171","msg":"trace[1030927060] transaction","detail":"{read_only:false; response_revision:2254; number_of_response:1; }","duration":"143.745532ms","start":"2026-06-10T12:58:32.26142Z","end":"2026-06-10T12:58:32.405165Z","steps":["trace[1030927060] 'process raft request' (duration: 143.579137ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:32.929239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.381812ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-06-10T12:58:32.929389Z","caller":"traceutil/trace.go:171","msg":"trace[45347031] linearizableReadLoop","detail":"{readStateIndex:2346; appliedIndex:2345; }","duration":"350.893995ms","start":"2026-06-10T12:58:32.578474Z","end":"2026-06-10T12:58:32.929368Z","steps":["trace[45347031] 'read index received' (duration: 33.325778ms)","trace[45347031] 'applied index is now lower than readState.Index' (duration: 317.566537ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:58:32.929418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:58:32.388883Z","time spent":"540.526714ms","remote":"127.0.0.1:53214","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-10T12:58:32.929678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.205484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-10T12:58:32.929713Z","caller":"traceutil/trace.go:171","msg":"trace[1075724951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2254; }","duration":"351.291806ms","start":"2026-06-10T12:58:32.578411Z","end":"2026-06-10T12:58:32.929702Z","steps":["trace[1075724951] 'agreement among raft nodes before linearized reading' (duration: 351.05582ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:32.929743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:58:32.578386Z","time spent":"351.348799ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T12:58:32.929753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.053551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-06-10T12:58:32.929787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.35091ms","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-06-10T12:58:32.929819Z","caller":"traceutil/trace.go:171","msg":"trace[624120905] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2254; }","duration":"106.148103ms","start":"2026-06-10T12:58:32.823653Z","end":"2026-06-10T12:58:32.929801Z","steps":["trace[624120905] 'agreement among raft nodes before linearized reading' (duration: 105.947307ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:32.929793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.558028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-10T12:58:32.929759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.383993ms","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-06-10T12:58:32.929961Z","caller":"traceutil/trace.go:171","msg":"trace[1961478542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2254; }","duration":"349.59998ms","start":"2026-06-10T12:58:32.580335Z","end":"2026-06-10T12:58:32.929935Z","steps":["trace[1961478542] 'agreement among raft nodes before linearized reading' (duration: 349.26197ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:58:32.929873Z","caller":"traceutil/trace.go:171","msg":"trace[83545483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2254; }","duration":"350.503545ms","start":"2026-06-10T12:58:32.57935Z","end":"2026-06-10T12:58:32.929853Z","steps":["trace[83545483] 'agreement among raft nodes before linearized reading' (duration: 350.218377ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:58:32.929953Z","caller":"traceutil/trace.go:171","msg":"trace[2133681756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2254; }","duration":"349.739043ms","start":"2026-06-10T12:58:32.58019Z","end":"2026-06-10T12:58:32.929929Z","steps":["trace[2133681756] 'agreement among raft nodes before linearized reading' (duration: 349.435215ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T12:58:32.930007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:58:32.580328Z","time spent":"349.667342ms","remote":"127.0.0.1:53420","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-06-10T12:58:32.93009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:58:32.58018Z","time spent":"349.893608ms","remote":"127.0.0.1:53420","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":"warn","ts":"2026-06-10T12:58:32.930074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T12:58:32.579326Z","time spent":"350.723492ms","remote":"127.0.0.1:53420","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-06-10T12:58:32.929909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.930354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T12:58:32.930241Z","caller":"traceutil/trace.go:171","msg":"trace[1007718919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2254; }","duration":"264.498801ms","start":"2026-06-10T12:58:32.665713Z","end":"2026-06-10T12:58:32.930212Z","steps":["trace[1007718919] 'agreement among raft nodes before linearized reading' (duration: 263.882763ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:59:08.469069Z","caller":"traceutil/trace.go:171","msg":"trace[951220889] linearizableReadLoop","detail":"{readStateIndex:2735; appliedIndex:2734; }","duration":"143.303149ms","start":"2026-06-10T12:59:08.325744Z","end":"2026-06-10T12:59:08.469047Z","steps":["trace[951220889] 'read index received' (duration: 143.162025ms)","trace[951220889] 'applied index is now lower than readState.Index' (duration: 140.394µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T12:59:08.46922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.460943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-06-10T12:59:08.469243Z","caller":"traceutil/trace.go:171","msg":"trace[821049585] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2628; }","duration":"143.517895ms","start":"2026-06-10T12:59:08.325717Z","end":"2026-06-10T12:59:08.469235Z","steps":["trace[821049585] 'agreement among raft nodes before linearized reading' (duration: 143.408142ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:59:08.469303Z","caller":"traceutil/trace.go:171","msg":"trace[1152846788] transaction","detail":"{read_only:false; response_revision:2628; number_of_response:1; }","duration":"163.873017ms","start":"2026-06-10T12:59:08.305407Z","end":"2026-06-10T12:59:08.46928Z","steps":["trace[1152846788] 'process raft request' (duration: 163.521698ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T12:59:40.857464Z","caller":"traceutil/trace.go:171","msg":"trace[1878131230] transaction","detail":"{read_only:false; response_revision:2824; number_of_response:1; }","duration":"137.265522ms","start":"2026-06-10T12:59:40.720169Z","end":"2026-06-10T12:59:40.857435Z","steps":["trace[1878131230] 'process raft request' (duration: 137.106348ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:02:28.556752Z","caller":"traceutil/trace.go:171","msg":"trace[264892361] transaction","detail":"{read_only:false; response_revision:3903; number_of_response:1; }","duration":"109.289295ms","start":"2026-06-10T13:02:28.447451Z","end":"2026-06-10T13:02:28.55674Z","steps":["trace[264892361] 'process raft request' (duration: 109.196463ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:02:28.890444Z","caller":"traceutil/trace.go:171","msg":"trace[1404329232] transaction","detail":"{read_only:false; response_revision:3904; number_of_response:1; }","duration":"432.85713ms","start":"2026-06-10T13:02:28.457557Z","end":"2026-06-10T13:02:28.890414Z","steps":["trace[1404329232] 'process raft request' (duration: 429.120955ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:02:28.890544Z","caller":"traceutil/trace.go:171","msg":"trace[27611414] linearizableReadLoop","detail":"{readStateIndex:4059; appliedIndex:4058; }","duration":"432.884331ms","start":"2026-06-10T13:02:28.457638Z","end":"2026-06-10T13:02:28.890523Z","steps":["trace[27611414] 'read index received' (duration: 99.097639ms)","trace[27611414] 'applied index is now lower than readState.Index' (duration: 333.785312ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:02:28.890587Z","caller":"traceutil/trace.go:171","msg":"trace[571746257] transaction","detail":"{read_only:false; response_revision:3905; number_of_response:1; }","duration":"379.01206ms","start":"2026-06-10T13:02:28.51154Z","end":"2026-06-10T13:02:28.890552Z","steps":["trace[571746257] 'process raft request' (duration: 378.818206ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:02:28.890628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:02:28.457534Z","time spent":"433.000744ms","remote":"127.0.0.1:53420","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-06-10T13:02:28.89074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.085737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-10T13:02:28.890781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:02:28.511531Z","time spent":"379.135934ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:02:28.890847Z","caller":"traceutil/trace.go:171","msg":"trace[1837684301] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3905; }","duration":"433.212221ms","start":"2026-06-10T13:02:28.457618Z","end":"2026-06-10T13:02:28.89083Z","steps":["trace[1837684301] 'agreement among raft nodes before linearized reading' (duration: 432.977394ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:02:28.890906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:02:28.457613Z","time spent":"433.279973ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-10T13:02:28.892472Z","caller":"traceutil/trace.go:171","msg":"trace[1255994022] transaction","detail":"{read_only:false; response_revision:3906; number_of_response:1; }","duration":"142.107566ms","start":"2026-06-10T13:02:28.750341Z","end":"2026-06-10T13:02:28.892448Z","steps":["trace[1255994022] 'process raft request' (duration: 141.91928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:02:28.892516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.028631ms","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-06-10T13:02:28.892596Z","caller":"traceutil/trace.go:171","msg":"trace[2108616399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3906; }","duration":"430.146024ms","start":"2026-06-10T13:02:28.462435Z","end":"2026-06-10T13:02:28.892581Z","steps":["trace[2108616399] 'agreement among raft nodes before linearized reading' (duration: 429.953899ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:02:28.892601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.309215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-10T13:02:28.892633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:02:28.462421Z","time spent":"430.206036ms","remote":"127.0.0.1:53420","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-06-10T13:02:28.892655Z","caller":"traceutil/trace.go:171","msg":"trace[1384972461] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3906; }","duration":"181.382177ms","start":"2026-06-10T13:02:28.711256Z","end":"2026-06-10T13:02:28.892638Z","steps":["trace[1384972461] 'agreement among raft nodes before linearized reading' (duration: 181.256273ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:02:28.892476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.626062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:02:28.892717Z","caller":"traceutil/trace.go:171","msg":"trace[1026956183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3906; }","duration":"228.888509ms","start":"2026-06-10T13:02:28.663813Z","end":"2026-06-10T13:02:28.892701Z","steps":["trace[1026956183] 'agreement among raft nodes before linearized reading' (duration: 228.586661ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:02:28.892788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.579475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T13:02:28.892864Z","caller":"traceutil/trace.go:171","msg":"trace[1061605624] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:3906; }","duration":"272.704879ms","start":"2026-06-10T13:02:28.620136Z","end":"2026-06-10T13:02:28.892841Z","steps":["trace[1061605624] 'agreement among raft nodes before linearized reading' (duration: 272.545654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:02:28.892934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.436892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-10T13:02:28.893057Z","caller":"traceutil/trace.go:171","msg":"trace[2076067909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3906; }","duration":"430.516745ms","start":"2026-06-10T13:02:28.462473Z","end":"2026-06-10T13:02:28.89299Z","steps":["trace[2076067909] 'agreement among raft nodes before linearized reading' (duration: 430.236777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:02:28.893135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:02:28.46247Z","time spent":"430.650049ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T13:02:28.89287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.834008ms","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-06-10T13:02:28.893076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.619857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-55kjf\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-06-10T13:02:28.893278Z","caller":"traceutil/trace.go:171","msg":"trace[1092900538] range","detail":"{range_begin:/registry/pods/openstack/keepalived-55kjf; range_end:; response_count:1; response_revision:3906; }","duration":"364.915954ms","start":"2026-06-10T13:02:28.52834Z","end":"2026-06-10T13:02:28.893256Z","steps":["trace[1092900538] 'agreement among raft nodes before linearized reading' (duration: 364.147693ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:02:28.8932Z","caller":"traceutil/trace.go:171","msg":"trace[825912138] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3906; }","duration":"429.176027ms","start":"2026-06-10T13:02:28.464007Z","end":"2026-06-10T13:02:28.893183Z","steps":["trace[825912138] 'agreement among raft nodes before linearized reading' (duration: 428.544599ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:02:28.893376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:02:28.464003Z","time spent":"429.360931ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T13:02:28.893467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:02:28.528335Z","time spent":"365.017477ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-55kjf\" "} {"level":"info","ts":"2026-06-10T13:03:59.346718Z","caller":"traceutil/trace.go:171","msg":"trace[130185997] transaction","detail":"{read_only:false; response_revision:5026; number_of_response:1; }","duration":"106.2591ms","start":"2026-06-10T13:03:59.240427Z","end":"2026-06-10T13:03:59.346686Z","steps":["trace[130185997] 'process raft request' (duration: 105.922961ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:03:59.347191Z","caller":"traceutil/trace.go:171","msg":"trace[1407968438] transaction","detail":"{read_only:false; response_revision:5027; number_of_response:1; }","duration":"104.694247ms","start":"2026-06-10T13:03:59.242482Z","end":"2026-06-10T13:03:59.347177Z","steps":["trace[1407968438] 'process raft request' (duration: 104.193093ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:02.060253Z","caller":"traceutil/trace.go:171","msg":"trace[795259355] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"261.313448ms","start":"2026-06-10T13:04:01.798894Z","end":"2026-06-10T13:04:02.060207Z","steps":["trace[795259355] 'process raft request' (duration: 261.00062ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:02.325074Z","caller":"traceutil/trace.go:171","msg":"trace[775669913] linearizableReadLoop","detail":"{readStateIndex:5353; appliedIndex:5352; }","duration":"242.177353ms","start":"2026-06-10T13:04:02.082872Z","end":"2026-06-10T13:04:02.325049Z","steps":["trace[775669913] 'read index received' (duration: 241.982727ms)","trace[775669913] 'applied index is now lower than readState.Index' (duration: 193.576µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:04:02.325353Z","caller":"traceutil/trace.go:171","msg":"trace[705974093] transaction","detail":"{read_only:false; response_revision:5153; number_of_response:1; }","duration":"256.888434ms","start":"2026-06-10T13:04:02.068452Z","end":"2026-06-10T13:04:02.32534Z","steps":["trace[705974093] 'process raft request' (duration: 256.462342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:02.32615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.261253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728899"} {"level":"info","ts":"2026-06-10T13:04:02.326217Z","caller":"traceutil/trace.go:171","msg":"trace[1272618230] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5153; }","duration":"243.366344ms","start":"2026-06-10T13:04:02.082833Z","end":"2026-06-10T13:04:02.326199Z","steps":["trace[1272618230] 'agreement among raft nodes before linearized reading' (duration: 242.669915ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:04.569755Z","caller":"traceutil/trace.go:171","msg":"trace[604027691] transaction","detail":"{read_only:false; response_revision:5187; number_of_response:1; }","duration":"161.040464ms","start":"2026-06-10T13:04:04.408693Z","end":"2026-06-10T13:04:04.569734Z","steps":["trace[604027691] 'process raft request' (duration: 160.907249ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:17.65275Z","caller":"traceutil/trace.go:171","msg":"trace[343518182] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"123.972007ms","start":"2026-06-10T13:04:17.528734Z","end":"2026-06-10T13:04:17.652706Z","steps":["trace[343518182] 'process raft request' (duration: 94.158364ms)","trace[343518182] 'compare' (duration: 29.473063ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:04:17.652883Z","caller":"traceutil/trace.go:171","msg":"trace[1328110210] transaction","detail":"{read_only:false; response_revision:5431; number_of_response:1; }","duration":"123.975997ms","start":"2026-06-10T13:04:17.528881Z","end":"2026-06-10T13:04:17.652857Z","steps":["trace[1328110210] 'process raft request' (duration: 123.7567ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:17.652923Z","caller":"traceutil/trace.go:171","msg":"trace[1930444671] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"110.452038ms","start":"2026-06-10T13:04:17.542454Z","end":"2026-06-10T13:04:17.652906Z","steps":["trace[1930444671] 'process raft request' (duration: 110.226822ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:17.653017Z","caller":"traceutil/trace.go:171","msg":"trace[1328603674] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"110.447628ms","start":"2026-06-10T13:04:17.542551Z","end":"2026-06-10T13:04:17.652999Z","steps":["trace[1328603674] 'process raft request' (duration: 110.166171ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:18.388989Z","caller":"traceutil/trace.go:171","msg":"trace[269727856] linearizableReadLoop","detail":"{readStateIndex:5648; appliedIndex:5647; }","duration":"213.226773ms","start":"2026-06-10T13:04:18.175738Z","end":"2026-06-10T13:04:18.388965Z","steps":["trace[269727856] 'read index received' (duration: 171.346041ms)","trace[269727856] 'applied index is now lower than readState.Index' (duration: 41.879762ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:04:18.389138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.377096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-10T13:04:18.389169Z","caller":"traceutil/trace.go:171","msg":"trace[486153870] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:5437; }","duration":"213.469719ms","start":"2026-06-10T13:04:18.175691Z","end":"2026-06-10T13:04:18.389161Z","steps":["trace[486153870] 'agreement among raft nodes before linearized reading' (duration: 213.358796ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:18.38957Z","caller":"traceutil/trace.go:171","msg":"trace[153706347] transaction","detail":"{read_only:false; response_revision:5437; number_of_response:1; }","duration":"282.352885ms","start":"2026-06-10T13:04:18.106988Z","end":"2026-06-10T13:04:18.389341Z","steps":["trace[153706347] 'process raft request' (duration: 240.067813ms)","trace[153706347] 'compare' (duration: 41.808409ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:04:19.316262Z","caller":"traceutil/trace.go:171","msg":"trace[160639733] transaction","detail":"{read_only:false; response_revision:5442; number_of_response:1; }","duration":"119.817521ms","start":"2026-06-10T13:04:19.196407Z","end":"2026-06-10T13:04:19.316224Z","steps":["trace[160639733] 'process raft request' (duration: 119.598195ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:19.449833Z","caller":"traceutil/trace.go:171","msg":"trace[363851655] transaction","detail":"{read_only:false; response_revision:5443; number_of_response:1; }","duration":"248.754715ms","start":"2026-06-10T13:04:19.201052Z","end":"2026-06-10T13:04:19.449806Z","steps":["trace[363851655] 'process raft request' (duration: 248.498838ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:26.859986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.61737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-63bd0a4a-7013-4ab4-ab98-099eef3c1270\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-10T13:04:26.860409Z","caller":"traceutil/trace.go:171","msg":"trace[1234331840] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-63bd0a4a-7013-4ab4-ab98-099eef3c1270; range_end:; response_count:1; response_revision:5609; }","duration":"333.101154ms","start":"2026-06-10T13:04:26.527286Z","end":"2026-06-10T13:04:26.860387Z","steps":["trace[1234331840] 'range keys from in-memory index tree' (duration: 332.426326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:26.860458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:26.527263Z","time spent":"333.180667ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-63bd0a4a-7013-4ab4-ab98-099eef3c1270\" "} {"level":"info","ts":"2026-06-10T13:04:27.150303Z","caller":"traceutil/trace.go:171","msg":"trace[1041781883] transaction","detail":"{read_only:false; response_revision:5610; number_of_response:1; }","duration":"166.195707ms","start":"2026-06-10T13:04:26.984077Z","end":"2026-06-10T13:04:27.150272Z","steps":["trace[1041781883] 'process raft request' (duration: 166.033913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:27.443384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.297454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-chunks-cache-0\" ","response":"range_response_count:1 size:4464"} {"level":"info","ts":"2026-06-10T13:04:27.443469Z","caller":"traceutil/trace.go:171","msg":"trace[1429862592] range","detail":"{range_begin:/registry/pods/monitoring/loki-chunks-cache-0; range_end:; response_count:1; response_revision:5610; }","duration":"144.420409ms","start":"2026-06-10T13:04:27.29903Z","end":"2026-06-10T13:04:27.443451Z","steps":["trace[1429862592] 'range keys from in-memory index tree' (duration: 144.13826ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:27.772685Z","caller":"traceutil/trace.go:171","msg":"trace[613263151] linearizableReadLoop","detail":"{readStateIndex:5828; appliedIndex:5826; }","duration":"226.36012ms","start":"2026-06-10T13:04:27.546305Z","end":"2026-06-10T13:04:27.772665Z","steps":["trace[613263151] 'read index received' (duration: 219.395325ms)","trace[613263151] 'applied index is now lower than readState.Index' (duration: 6.963485ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:04:27.772927Z","caller":"traceutil/trace.go:171","msg":"trace[801897708] transaction","detail":"{read_only:false; response_revision:5612; number_of_response:1; }","duration":"295.124842ms","start":"2026-06-10T13:04:27.477772Z","end":"2026-06-10T13:04:27.772897Z","steps":["trace[801897708] 'process raft request' (duration: 288.290871ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:27.773181Z","caller":"traceutil/trace.go:171","msg":"trace[13084546] transaction","detail":"{read_only:false; response_revision:5613; number_of_response:1; }","duration":"279.135646ms","start":"2026-06-10T13:04:27.494036Z","end":"2026-06-10T13:04:27.773172Z","steps":["trace[13084546] 'process raft request' (duration: 278.546249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:27.773527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.811364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-06-10T13:04:27.773573Z","caller":"traceutil/trace.go:171","msg":"trace[314445983] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5613; }","duration":"259.910258ms","start":"2026-06-10T13:04:27.51365Z","end":"2026-06-10T13:04:27.77356Z","steps":["trace[314445983] 'agreement among raft nodes before linearized reading' (duration: 259.580938ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:27.845427Z","caller":"traceutil/trace.go:171","msg":"trace[52391754] transaction","detail":"{read_only:false; response_revision:5614; number_of_response:1; }","duration":"292.474478ms","start":"2026-06-10T13:04:27.552903Z","end":"2026-06-10T13:04:27.845378Z","steps":["trace[52391754] 'process raft request' (duration: 291.683016ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:27.845709Z","caller":"traceutil/trace.go:171","msg":"trace[184596303] transaction","detail":"{read_only:false; response_revision:5615; number_of_response:1; }","duration":"292.16074ms","start":"2026-06-10T13:04:27.553509Z","end":"2026-06-10T13:04:27.84567Z","steps":["trace[184596303] 'process raft request' (duration: 291.363048ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:27.845745Z","caller":"traceutil/trace.go:171","msg":"trace[1808212190] transaction","detail":"{read_only:false; response_revision:5618; number_of_response:1; }","duration":"288.900239ms","start":"2026-06-10T13:04:27.556825Z","end":"2026-06-10T13:04:27.845725Z","steps":["trace[1808212190] 'process raft request' (duration: 288.163338ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:27.845802Z","caller":"traceutil/trace.go:171","msg":"trace[131665164] transaction","detail":"{read_only:false; response_revision:5617; number_of_response:1; }","duration":"291.025188ms","start":"2026-06-10T13:04:27.554735Z","end":"2026-06-10T13:04:27.84576Z","steps":["trace[131665164] 'process raft request' (duration: 290.220725ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:27.845831Z","caller":"traceutil/trace.go:171","msg":"trace[1304934230] transaction","detail":"{read_only:false; response_revision:5619; number_of_response:1; }","duration":"288.213089ms","start":"2026-06-10T13:04:27.557602Z","end":"2026-06-10T13:04:27.845815Z","steps":["trace[1304934230] 'process raft request' (duration: 287.411527ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:27.845931Z","caller":"traceutil/trace.go:171","msg":"trace[2011377572] transaction","detail":"{read_only:false; response_revision:5616; number_of_response:1; }","duration":"291.614254ms","start":"2026-06-10T13:04:27.554304Z","end":"2026-06-10T13:04:27.845918Z","steps":["trace[2011377572] 'process raft request' (duration: 290.606576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:27.869426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.12141ms","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-06-10T13:04:27.869491Z","caller":"traceutil/trace.go:171","msg":"trace[914304358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5619; }","duration":"173.200653ms","start":"2026-06-10T13:04:27.696278Z","end":"2026-06-10T13:04:27.869478Z","steps":["trace[914304358] 'agreement among raft nodes before linearized reading' (duration: 173.007347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:27.869507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.233151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:04:27.869542Z","caller":"traceutil/trace.go:171","msg":"trace[243184211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5619; }","duration":"204.331645ms","start":"2026-06-10T13:04:27.665199Z","end":"2026-06-10T13:04:27.869531Z","steps":["trace[243184211] 'agreement among raft nodes before linearized reading' (duration: 204.266482ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:27.869626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.482604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:4791"} {"level":"info","ts":"2026-06-10T13:04:27.869692Z","caller":"traceutil/trace.go:171","msg":"trace[1164530733] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5619; }","duration":"314.573786ms","start":"2026-06-10T13:04:27.555101Z","end":"2026-06-10T13:04:27.869675Z","steps":["trace[1164530733] 'agreement among raft nodes before linearized reading' (duration: 314.384061ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:27.869736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:27.555093Z","time spent":"314.633047ms","remote":"127.0.0.1:53250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":4813,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" "} {"level":"warn","ts":"2026-06-10T13:04:27.869748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.199116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-63bd0a4a-7013-4ab4-ab98-099eef3c1270\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-06-10T13:04:27.869862Z","caller":"traceutil/trace.go:171","msg":"trace[755461749] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-63bd0a4a-7013-4ab4-ab98-099eef3c1270; range_end:; response_count:1; response_revision:5619; }","duration":"319.401011ms","start":"2026-06-10T13:04:27.550436Z","end":"2026-06-10T13:04:27.869837Z","steps":["trace[755461749] 'agreement among raft nodes before linearized reading' (duration: 319.01832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:27.869925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:04:27.550408Z","time spent":"319.506555ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-63bd0a4a-7013-4ab4-ab98-099eef3c1270\" "} {"level":"warn","ts":"2026-06-10T13:04:27.869572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.316873ms","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-06-10T13:04:27.870025Z","caller":"traceutil/trace.go:171","msg":"trace[117334168] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5619; }","duration":"170.822296ms","start":"2026-06-10T13:04:27.699176Z","end":"2026-06-10T13:04:27.869999Z","steps":["trace[117334168] 'agreement among raft nodes before linearized reading' (duration: 170.119646ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:34.038855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.591268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-10T13:04:34.038922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.902674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-55kjf\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-06-10T13:04:34.038961Z","caller":"traceutil/trace.go:171","msg":"trace[694990504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5720; }","duration":"127.724502ms","start":"2026-06-10T13:04:33.911218Z","end":"2026-06-10T13:04:34.038943Z","steps":["trace[694990504] 'range keys from in-memory index tree' (duration: 127.415704ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:34.038986Z","caller":"traceutil/trace.go:171","msg":"trace[1690298969] range","detail":"{range_begin:/registry/pods/openstack/keepalived-55kjf; range_end:; response_count:1; response_revision:5720; }","duration":"134.015797ms","start":"2026-06-10T13:04:33.904955Z","end":"2026-06-10T13:04:34.038971Z","steps":["trace[1690298969] 'range keys from in-memory index tree' (duration: 133.75381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:04:34.038923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.286489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-10T13:04:34.039053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.773133ms","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-06-10T13:04:34.039252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.582577ms","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-06-10T13:04:34.039213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.875305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-06-10T13:04:34.039286Z","caller":"traceutil/trace.go:171","msg":"trace[238520479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5720; }","duration":"122.623679ms","start":"2026-06-10T13:04:33.916652Z","end":"2026-06-10T13:04:34.039275Z","steps":["trace[238520479] 'range keys from in-memory index tree' (duration: 122.410743ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:34.039342Z","caller":"traceutil/trace.go:171","msg":"trace[2045019177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5720; }","duration":"129.058548ms","start":"2026-06-10T13:04:33.910252Z","end":"2026-06-10T13:04:34.039311Z","steps":["trace[2045019177] 'range keys from in-memory index tree' (duration: 128.608257ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:34.03923Z","caller":"traceutil/trace.go:171","msg":"trace[1616566428] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5720; }","duration":"122.615058ms","start":"2026-06-10T13:04:33.916596Z","end":"2026-06-10T13:04:34.039211Z","steps":["trace[1616566428] 'range keys from in-memory index tree' (duration: 122.112234ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:34.039314Z","caller":"traceutil/trace.go:171","msg":"trace[738915206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5720; }","duration":"128.00308ms","start":"2026-06-10T13:04:33.911262Z","end":"2026-06-10T13:04:34.039265Z","steps":["trace[738915206] 'range keys from in-memory index tree' (duration: 127.705592ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:34.192192Z","caller":"traceutil/trace.go:171","msg":"trace[821366293] transaction","detail":"{read_only:false; response_revision:5721; number_of_response:1; }","duration":"148.239994ms","start":"2026-06-10T13:04:34.043921Z","end":"2026-06-10T13:04:34.192161Z","steps":["trace[821366293] 'process raft request' (duration: 148.05311ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:34.234135Z","caller":"traceutil/trace.go:171","msg":"trace[45345502] linearizableReadLoop","detail":"{readStateIndex:5942; appliedIndex:5937; }","duration":"189.822289ms","start":"2026-06-10T13:04:34.044292Z","end":"2026-06-10T13:04:34.234114Z","steps":["trace[45345502] 'read index received' (duration: 147.753142ms)","trace[45345502] 'applied index is now lower than readState.Index' (duration: 42.067957ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:04:34.234392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.080825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:81604"} {"level":"info","ts":"2026-06-10T13:04:34.234433Z","caller":"traceutil/trace.go:171","msg":"trace[24312959] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:5725; }","duration":"190.152368ms","start":"2026-06-10T13:04:34.044274Z","end":"2026-06-10T13:04:34.234426Z","steps":["trace[24312959] 'agreement among raft nodes before linearized reading' (duration: 189.924751ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:34.234765Z","caller":"traceutil/trace.go:171","msg":"trace[664431160] transaction","detail":"{read_only:false; response_revision:5725; number_of_response:1; }","duration":"190.494426ms","start":"2026-06-10T13:04:34.044241Z","end":"2026-06-10T13:04:34.234735Z","steps":["trace[664431160] 'process raft request' (duration: 189.844348ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:34.234839Z","caller":"traceutil/trace.go:171","msg":"trace[1060607749] transaction","detail":"{read_only:false; response_revision:5722; number_of_response:1; }","duration":"190.700253ms","start":"2026-06-10T13:04:34.044076Z","end":"2026-06-10T13:04:34.234776Z","steps":["trace[1060607749] 'process raft request' (duration: 189.835389ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:34.234889Z","caller":"traceutil/trace.go:171","msg":"trace[612337886] transaction","detail":"{read_only:false; response_revision:5724; number_of_response:1; }","duration":"190.578029ms","start":"2026-06-10T13:04:34.04415Z","end":"2026-06-10T13:04:34.234728Z","steps":["trace[612337886] 'process raft request' (duration: 189.90823ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:34.235181Z","caller":"traceutil/trace.go:171","msg":"trace[1320956116] transaction","detail":"{read_only:false; response_revision:5723; number_of_response:1; }","duration":"190.981781ms","start":"2026-06-10T13:04:34.044183Z","end":"2026-06-10T13:04:34.235165Z","steps":["trace[1320956116] 'process raft request' (duration: 189.843569ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:39.990521Z","caller":"traceutil/trace.go:171","msg":"trace[1034403179] transaction","detail":"{read_only:false; response_revision:5758; number_of_response:1; }","duration":"167.899635ms","start":"2026-06-10T13:04:39.822597Z","end":"2026-06-10T13:04:39.990497Z","steps":["trace[1034403179] 'process raft request' (duration: 167.767931ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:04:40.023596Z","caller":"traceutil/trace.go:171","msg":"trace[1609802313] transaction","detail":"{read_only:false; response_revision:5759; number_of_response:1; }","duration":"195.496227ms","start":"2026-06-10T13:04:39.828056Z","end":"2026-06-10T13:04:40.023553Z","steps":["trace[1609802313] 'process raft request' (duration: 195.156508ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:05:08.794969Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2978} {"level":"info","ts":"2026-06-10T13:05:08.876909Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2978,"took":"80.217143ms","hash":3560819413,"current-db-size-bytes":58437632,"current-db-size":"58 MB","current-db-size-in-use-bytes":33869824,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-10T13:05:08.876965Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3560819413,"revision":2978,"compact-revision":-1} {"level":"info","ts":"2026-06-10T13:10:08.804252Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6042} {"level":"info","ts":"2026-06-10T13:10:08.905199Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6042,"took":"98.67135ms","hash":3408243650,"current-db-size-bytes":58437632,"current-db-size":"58 MB","current-db-size-in-use-bytes":22024192,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-06-10T13:10:08.905308Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3408243650,"revision":6042,"compact-revision":2978} {"level":"info","ts":"2026-06-10T13:10:48.785961Z","caller":"traceutil/trace.go:171","msg":"trace[817654379] transaction","detail":"{read_only:false; response_revision:8507; number_of_response:1; }","duration":"432.40559ms","start":"2026-06-10T13:10:48.35352Z","end":"2026-06-10T13:10:48.785925Z","steps":["trace[817654379] 'process raft request' (duration: 431.516476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:10:48.786168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:10:48.353506Z","time spent":"432.555315ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:10:48.950534Z","caller":"traceutil/trace.go:171","msg":"trace[1624631417] transaction","detail":"{read_only:false; response_revision:8509; number_of_response:1; }","duration":"596.496539ms","start":"2026-06-10T13:10:48.354013Z","end":"2026-06-10T13:10:48.95051Z","steps":["trace[1624631417] 'process raft request' (duration: 596.410406ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:10:48.950538Z","caller":"traceutil/trace.go:171","msg":"trace[27089426] transaction","detail":"{read_only:false; response_revision:8508; number_of_response:1; }","duration":"596.762026ms","start":"2026-06-10T13:10:48.353733Z","end":"2026-06-10T13:10:48.950495Z","steps":["trace[27089426] 'process raft request' (duration: 547.401905ms)","trace[27089426] 'compare' (duration: 49.157856ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:10:48.950678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:10:48.353964Z","time spent":"596.644363ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:10:48.950697Z","caller":"traceutil/trace.go:171","msg":"trace[1426149193] transaction","detail":"{read_only:false; response_revision:8510; number_of_response:1; }","duration":"490.317918ms","start":"2026-06-10T13:10:48.460368Z","end":"2026-06-10T13:10:48.950686Z","steps":["trace[1426149193] 'process raft request' (duration: 490.104082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:10:48.950693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:10:48.353683Z","time spent":"596.936411ms","remote":"127.0.0.1:53420","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-06-10T13:10:48.950785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:10:48.460344Z","time spent":"490.393142ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:10:48.950827Z","caller":"traceutil/trace.go:171","msg":"trace[843829525] transaction","detail":"{read_only:false; response_revision:8511; number_of_response:1; }","duration":"399.425315ms","start":"2026-06-10T13:10:48.551388Z","end":"2026-06-10T13:10:48.950814Z","steps":["trace[843829525] 'process raft request' (duration: 399.25746ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:10:48.950877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:10:48.55137Z","time spent":"399.481536ms","remote":"127.0.0.1:53420","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-06-10T13:10:48.951019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.294381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-55kjf\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-06-10T13:10:48.951086Z","caller":"traceutil/trace.go:171","msg":"trace[386964407] range","detail":"{range_begin:/registry/pods/openstack/keepalived-55kjf; range_end:; response_count:1; response_revision:8511; }","duration":"417.412264ms","start":"2026-06-10T13:10:48.533661Z","end":"2026-06-10T13:10:48.951073Z","steps":["trace[386964407] 'agreement among raft nodes before linearized reading' (duration: 417.235859ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:10:48.951152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:10:48.533639Z","time spent":"417.483176ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-55kjf\" "} {"level":"info","ts":"2026-06-10T13:10:48.950861Z","caller":"traceutil/trace.go:171","msg":"trace[1723771777] linearizableReadLoop","detail":"{readStateIndex:8819; appliedIndex:8815; }","duration":"417.092675ms","start":"2026-06-10T13:10:48.533718Z","end":"2026-06-10T13:10:48.950811Z","steps":["trace[1723771777] 'read index received' (duration: 251.34248ms)","trace[1723771777] 'applied index is now lower than readState.Index' (duration: 165.748965ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:10:48.951736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.260821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:10:48.951779Z","caller":"traceutil/trace.go:171","msg":"trace[867887309] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:8511; }","duration":"412.351843ms","start":"2026-06-10T13:10:48.539416Z","end":"2026-06-10T13:10:48.951768Z","steps":["trace[867887309] 'agreement among raft nodes before linearized reading' (duration: 412.265171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:10:48.95181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:10:48.539395Z","time spent":"412.406176ms","remote":"127.0.0.1:57666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true "} {"level":"info","ts":"2026-06-10T13:10:59.657742Z","caller":"traceutil/trace.go:171","msg":"trace[140402841] transaction","detail":"{read_only:false; response_revision:8566; number_of_response:1; }","duration":"163.44982ms","start":"2026-06-10T13:10:59.494268Z","end":"2026-06-10T13:10:59.657718Z","steps":["trace[140402841] 'process raft request' (duration: 163.281695ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:10:59.670016Z","caller":"traceutil/trace.go:171","msg":"trace[687749576] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"170.039123ms","start":"2026-06-10T13:10:59.49995Z","end":"2026-06-10T13:10:59.669989Z","steps":["trace[687749576] 'process raft request' (duration: 169.761626ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:00.847823Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185696866330594,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T13:11:00.921245Z","caller":"traceutil/trace.go:171","msg":"trace[272877771] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"629.64045ms","start":"2026-06-10T13:11:00.291591Z","end":"2026-06-10T13:11:00.921231Z","steps":["trace[272877771] 'process raft request' (duration: 629.487615ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:00.921358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:11:00.291567Z","time spent":"629.728131ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T13:11:00.921393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.494017ms","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-06-10T13:11:00.921235Z","caller":"traceutil/trace.go:171","msg":"trace[808464222] linearizableReadLoop","detail":"{readStateIndex:8880; appliedIndex:8879; }","duration":"574.313962ms","start":"2026-06-10T13:11:00.346895Z","end":"2026-06-10T13:11:00.921209Z","steps":["trace[808464222] 'read index received' (duration: 574.163578ms)","trace[808464222] 'applied index is now lower than readState.Index' (duration: 148.784µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:11:00.921433Z","caller":"traceutil/trace.go:171","msg":"trace[341382253] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8569; }","duration":"574.58161ms","start":"2026-06-10T13:11:00.346838Z","end":"2026-06-10T13:11:00.921419Z","steps":["trace[341382253] 'agreement among raft nodes before linearized reading' (duration: 574.445536ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:00.921465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:11:00.346733Z","time spent":"574.725504ms","remote":"127.0.0.1:53420","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-06-10T13:11:02.03997Z","caller":"traceutil/trace.go:171","msg":"trace[1602710010] transaction","detail":"{read_only:false; response_revision:8576; number_of_response:1; }","duration":"374.871223ms","start":"2026-06-10T13:11:01.665063Z","end":"2026-06-10T13:11:02.039934Z","steps":["trace[1602710010] 'process raft request' (duration: 374.691348ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:02.040146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:11:01.665049Z","time spent":"375.007276ms","remote":"127.0.0.1:53420","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-06-10T13:11:02.088422Z","caller":"traceutil/trace.go:171","msg":"trace[965700959] transaction","detail":"{read_only:false; response_revision:8577; number_of_response:1; }","duration":"414.334419ms","start":"2026-06-10T13:11:01.674053Z","end":"2026-06-10T13:11:02.088387Z","steps":["trace[965700959] 'process raft request' (duration: 414.121093ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:02.088566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:11:01.674033Z","time spent":"414.455322ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:11:02.088656Z","caller":"traceutil/trace.go:171","msg":"trace[556178812] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"145.021278ms","start":"2026-06-10T13:11:01.943607Z","end":"2026-06-10T13:11:02.088629Z","steps":["trace[556178812] 'process raft request' (duration: 144.704759ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:11:03.460387Z","caller":"traceutil/trace.go:171","msg":"trace[1440764006] linearizableReadLoop","detail":"{readStateIndex:8898; appliedIndex:8897; }","duration":"311.169304ms","start":"2026-06-10T13:11:03.149186Z","end":"2026-06-10T13:11:03.460355Z","steps":["trace[1440764006] 'read index received' (duration: 310.921627ms)","trace[1440764006] 'applied index is now lower than readState.Index' (duration: 246.327µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:11:03.460471Z","caller":"traceutil/trace.go:171","msg":"trace[1979799621] transaction","detail":"{read_only:false; response_revision:8586; number_of_response:1; }","duration":"323.244159ms","start":"2026-06-10T13:11:03.137188Z","end":"2026-06-10T13:11:03.460432Z","steps":["trace[1979799621] 'process raft request' (duration: 322.954331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:03.460897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.692598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224139"} {"level":"info","ts":"2026-06-10T13:11:03.460978Z","caller":"traceutil/trace.go:171","msg":"trace[315225282] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8586; }","duration":"311.830781ms","start":"2026-06-10T13:11:03.149131Z","end":"2026-06-10T13:11:03.460962Z","steps":["trace[315225282] 'agreement among raft nodes before linearized reading' (duration: 311.367138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:03.46103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:11:03.149112Z","time spent":"311.908533ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":25,"response size":224161,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-06-10T13:11:03.461252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:11:03.137166Z","time spent":"323.784473ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T13:11:31.761345Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185696866331261,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T13:11:31.914658Z","caller":"traceutil/trace.go:171","msg":"trace[2082819202] linearizableReadLoop","detail":"{readStateIndex:9109; appliedIndex:9108; }","duration":"654.133549ms","start":"2026-06-10T13:11:31.260497Z","end":"2026-06-10T13:11:31.914631Z","steps":["trace[2082819202] 'read index received' (duration: 582.564281ms)","trace[2082819202] 'applied index is now lower than readState.Index' (duration: 71.568338ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:11:31.914852Z","caller":"traceutil/trace.go:171","msg":"trace[643404780] transaction","detail":"{read_only:false; response_revision:8789; number_of_response:1; }","duration":"710.495056ms","start":"2026-06-10T13:11:31.204339Z","end":"2026-06-10T13:11:31.914834Z","steps":["trace[643404780] 'process raft request' (duration: 638.711172ms)","trace[643404780] 'compare' (duration: 71.475185ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:11:31.914996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:11:31.204303Z","time spent":"710.610117ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T13:11:31.915138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.516176ms","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-06-10T13:11:31.915293Z","caller":"traceutil/trace.go:171","msg":"trace[2069052600] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8789; }","duration":"278.722842ms","start":"2026-06-10T13:11:31.636536Z","end":"2026-06-10T13:11:31.915259Z","steps":["trace[2069052600] 'agreement among raft nodes before linearized reading' (duration: 278.399553ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:31.915466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.964142ms","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-06-10T13:11:31.915531Z","caller":"traceutil/trace.go:171","msg":"trace[1962781268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8789; }","duration":"655.070575ms","start":"2026-06-10T13:11:31.260444Z","end":"2026-06-10T13:11:31.915515Z","steps":["trace[1962781268] 'agreement among raft nodes before linearized reading' (duration: 654.878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:31.915578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:11:31.26042Z","time spent":"655.148268ms","remote":"127.0.0.1:53420","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-06-10T13:11:31.915914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.588199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-55kjf\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-06-10T13:11:31.915983Z","caller":"traceutil/trace.go:171","msg":"trace[1348505545] range","detail":"{range_begin:/registry/pods/openstack/keepalived-55kjf; range_end:; response_count:1; response_revision:8789; }","duration":"278.702582ms","start":"2026-06-10T13:11:31.637265Z","end":"2026-06-10T13:11:31.915968Z","steps":["trace[1348505545] 'agreement among raft nodes before linearized reading' (duration: 278.350052ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:11:34.38473Z","caller":"traceutil/trace.go:171","msg":"trace[1579032511] transaction","detail":"{read_only:false; response_revision:8803; number_of_response:1; }","duration":"111.477127ms","start":"2026-06-10T13:11:34.273217Z","end":"2026-06-10T13:11:34.384694Z","steps":["trace[1579032511] 'process raft request' (duration: 111.000743ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:11:34.415582Z","caller":"traceutil/trace.go:171","msg":"trace[1559963891] transaction","detail":"{read_only:false; response_revision:8804; number_of_response:1; }","duration":"138.216129ms","start":"2026-06-10T13:11:34.277329Z","end":"2026-06-10T13:11:34.415545Z","steps":["trace[1559963891] 'process raft request' (duration: 137.967262ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:11:34.415764Z","caller":"traceutil/trace.go:171","msg":"trace[1771101495] transaction","detail":"{read_only:false; response_revision:8805; number_of_response:1; }","duration":"133.973521ms","start":"2026-06-10T13:11:34.281751Z","end":"2026-06-10T13:11:34.415725Z","steps":["trace[1771101495] 'process raft request' (duration: 133.738745ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:11:46.189251Z","caller":"traceutil/trace.go:171","msg":"trace[686248986] transaction","detail":"{read_only:false; response_revision:8911; number_of_response:1; }","duration":"120.819713ms","start":"2026-06-10T13:11:46.068401Z","end":"2026-06-10T13:11:46.18922Z","steps":["trace[686248986] 'process raft request' (duration: 46.504984ms)","trace[686248986] 'compare' (duration: 74.054412ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:11:46.208981Z","caller":"traceutil/trace.go:171","msg":"trace[1943349573] transaction","detail":"{read_only:false; response_revision:8912; number_of_response:1; }","duration":"140.169243ms","start":"2026-06-10T13:11:46.068775Z","end":"2026-06-10T13:11:46.208945Z","steps":["trace[1943349573] 'process raft request' (duration: 139.902885ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:11:46.209166Z","caller":"traceutil/trace.go:171","msg":"trace[1612900928] linearizableReadLoop","detail":"{readStateIndex:9239; appliedIndex:9237; }","duration":"139.624386ms","start":"2026-06-10T13:11:46.069521Z","end":"2026-06-10T13:11:46.209145Z","steps":["trace[1612900928] 'read index received' (duration: 45.399664ms)","trace[1612900928] 'applied index is now lower than readState.Index' (duration: 94.223412ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:11:46.209345Z","caller":"traceutil/trace.go:171","msg":"trace[275235020] transaction","detail":"{read_only:false; response_revision:8913; number_of_response:1; }","duration":"112.83096ms","start":"2026-06-10T13:11:46.096504Z","end":"2026-06-10T13:11:46.209335Z","steps":["trace[275235020] 'process raft request' (duration: 112.390928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:11:46.210497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.966154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228785"} {"level":"info","ts":"2026-06-10T13:11:46.210554Z","caller":"traceutil/trace.go:171","msg":"trace[1869423783] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8913; }","duration":"141.081417ms","start":"2026-06-10T13:11:46.069459Z","end":"2026-06-10T13:11:46.21054Z","steps":["trace[1869423783] 'agreement among raft nodes before linearized reading' (duration: 139.979677ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:11:55.358964Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000245f.wal"} {"level":"info","ts":"2026-06-10T13:13:01.726443Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7c53a18098324dc9","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T13:13:01.731436Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-10T13:13:01.731536Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-10T13:13:05.88719Z","caller":"traceutil/trace.go:171","msg":"trace[756478119] transaction","detail":"{read_only:false; response_revision:9679; number_of_response:1; }","duration":"143.107743ms","start":"2026-06-10T13:13:05.744057Z","end":"2026-06-10T13:13:05.887165Z","steps":["trace[756478119] 'process raft request' (duration: 143.00094ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:13:05.887205Z","caller":"traceutil/trace.go:171","msg":"trace[518477990] transaction","detail":"{read_only:false; response_revision:9678; number_of_response:1; }","duration":"144.188413ms","start":"2026-06-10T13:13:05.742984Z","end":"2026-06-10T13:13:05.887172Z","steps":["trace[518477990] 'process raft request' (duration: 134.853773ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:15:08.811411Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8126} {"level":"info","ts":"2026-06-10T13:15:08.87615Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8126,"took":"62.459441ms","hash":3448161134,"current-db-size-bytes":58437632,"current-db-size":"58 MB","current-db-size-in-use-bytes":28672000,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-10T13:15:08.876211Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3448161134,"revision":8126,"compact-revision":6042} {"level":"info","ts":"2026-06-10T13:17:13.973048Z","caller":"traceutil/trace.go:171","msg":"trace[698503054] transaction","detail":"{read_only:false; response_revision:11278; number_of_response:1; }","duration":"101.917079ms","start":"2026-06-10T13:17:13.871104Z","end":"2026-06-10T13:17:13.973021Z","steps":["trace[698503054] 'process raft request' (duration: 101.767275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:18:43.763908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.091591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T13:18:43.764068Z","caller":"traceutil/trace.go:171","msg":"trace[1489609611] transaction","detail":"{read_only:false; response_revision:12105; number_of_response:1; }","duration":"151.627971ms","start":"2026-06-10T13:18:43.612417Z","end":"2026-06-10T13:18:43.764044Z","steps":["trace[1489609611] 'compare' (duration: 141.930936ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:18:43.808905Z","caller":"traceutil/trace.go:171","msg":"trace[1551461645] transaction","detail":"{read_only:false; response_revision:12106; number_of_response:1; }","duration":"195.78261ms","start":"2026-06-10T13:18:43.613093Z","end":"2026-06-10T13:18:43.808876Z","steps":["trace[1551461645] 'process raft request' (duration: 195.623055ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:18:43.824621Z","caller":"traceutil/trace.go:171","msg":"trace[2052307455] linearizableReadLoop","detail":"{readStateIndex:12531; appliedIndex:12529; }","duration":"160.309887ms","start":"2026-06-10T13:18:43.664288Z","end":"2026-06-10T13:18:43.824597Z","steps":["trace[2052307455] 'read index received' (duration: 144.468729ms)","trace[2052307455] 'applied index is now lower than readState.Index' (duration: 15.840008ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:18:43.824686Z","caller":"traceutil/trace.go:171","msg":"trace[2117815270] transaction","detail":"{read_only:false; response_revision:12107; number_of_response:1; }","duration":"200.527885ms","start":"2026-06-10T13:18:43.624132Z","end":"2026-06-10T13:18:43.82466Z","steps":["trace[2117815270] 'process raft request' (duration: 200.33033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:18:43.824853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.447431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:18:43.824887Z","caller":"traceutil/trace.go:171","msg":"trace[713283385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12107; }","duration":"160.659066ms","start":"2026-06-10T13:18:43.66422Z","end":"2026-06-10T13:18:43.824879Z","steps":["trace[713283385] 'agreement among raft nodes before linearized reading' (duration: 160.465441ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:20:08.81922Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10529} {"level":"info","ts":"2026-06-10T13:20:08.906067Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10529,"took":"83.02148ms","hash":3794618820,"current-db-size-bytes":58437632,"current-db-size":"58 MB","current-db-size-in-use-bytes":26251264,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-10T13:20:08.906161Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3794618820,"revision":10529,"compact-revision":8126} {"level":"info","ts":"2026-06-10T13:24:33.81919Z","caller":"traceutil/trace.go:171","msg":"trace[1750966143] transaction","detail":"{read_only:false; response_revision:14455; number_of_response:1; }","duration":"133.199656ms","start":"2026-06-10T13:24:33.685969Z","end":"2026-06-10T13:24:33.819169Z","steps":["trace[1750966143] 'process raft request' (duration: 133.052911ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:24:33.819291Z","caller":"traceutil/trace.go:171","msg":"trace[18119860] linearizableReadLoop","detail":"{readStateIndex:14959; appliedIndex:14959; }","duration":"112.086746ms","start":"2026-06-10T13:24:33.707158Z","end":"2026-06-10T13:24:33.819245Z","steps":["trace[18119860] 'read index received' (duration: 112.073836ms)","trace[18119860] 'applied index is now lower than readState.Index' (duration: 10.45µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:24:33.819627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.438186ms","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-06-10T13:24:33.819664Z","caller":"traceutil/trace.go:171","msg":"trace[54154838] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14455; }","duration":"112.55236ms","start":"2026-06-10T13:24:33.707099Z","end":"2026-06-10T13:24:33.819651Z","steps":["trace[54154838] 'agreement among raft nodes before linearized reading' (duration: 112.299343ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:08.825474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12660} {"level":"info","ts":"2026-06-10T13:25:08.905906Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12660,"took":"78.066747ms","hash":2571021074,"current-db-size-bytes":58437632,"current-db-size":"58 MB","current-db-size-in-use-bytes":27746304,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-10T13:25:08.905961Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2571021074,"revision":12660,"compact-revision":10529} {"level":"info","ts":"2026-06-10T13:25:27.690577Z","caller":"traceutil/trace.go:171","msg":"trace[374441764] transaction","detail":"{read_only:false; response_revision:14795; number_of_response:1; }","duration":"338.139812ms","start":"2026-06-10T13:25:27.352414Z","end":"2026-06-10T13:25:27.690554Z","steps":["trace[374441764] 'process raft request' (duration: 338.013228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:27.69072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:27.35239Z","time spent":"338.260595ms","remote":"127.0.0.1:53420","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-06-10T13:25:27.955777Z","caller":"traceutil/trace.go:171","msg":"trace[1141166258] transaction","detail":"{read_only:false; response_revision:14797; number_of_response:1; }","duration":"288.861708ms","start":"2026-06-10T13:25:27.666898Z","end":"2026-06-10T13:25:27.95576Z","steps":["trace[1141166258] 'process raft request' (duration: 288.785105ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:27.955773Z","caller":"traceutil/trace.go:171","msg":"trace[1380997604] transaction","detail":"{read_only:false; response_revision:14796; number_of_response:1; }","duration":"319.570953ms","start":"2026-06-10T13:25:27.63616Z","end":"2026-06-10T13:25:27.955731Z","steps":["trace[1380997604] 'process raft request' (duration: 319.373229ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:27.956018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:27.636142Z","time spent":"319.799029ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:25:27.956247Z","caller":"traceutil/trace.go:171","msg":"trace[1118468199] transaction","detail":"{read_only:false; response_revision:14798; number_of_response:1; }","duration":"286.82364ms","start":"2026-06-10T13:25:27.669411Z","end":"2026-06-10T13:25:27.956234Z","steps":["trace[1118468199] 'process raft request' (duration: 286.320455ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:27.956243Z","caller":"traceutil/trace.go:171","msg":"trace[1300348230] transaction","detail":"{read_only:false; response_revision:14799; number_of_response:1; }","duration":"242.549175ms","start":"2026-06-10T13:25:27.713667Z","end":"2026-06-10T13:25:27.956216Z","steps":["trace[1300348230] 'process raft request' (duration: 242.37301ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:27.956387Z","caller":"traceutil/trace.go:171","msg":"trace[2053079246] linearizableReadLoop","detail":"{readStateIndex:15316; appliedIndex:15314; }","duration":"325.331923ms","start":"2026-06-10T13:25:27.63104Z","end":"2026-06-10T13:25:27.956372Z","steps":["trace[2053079246] 'read index received' (duration: 59.486459ms)","trace[2053079246] 'applied index is now lower than readState.Index' (duration: 265.844574ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:25:27.956492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.363812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:25:27.956517Z","caller":"traceutil/trace.go:171","msg":"trace[127833073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14799; }","duration":"293.392814ms","start":"2026-06-10T13:25:27.663117Z","end":"2026-06-10T13:25:27.95651Z","steps":["trace[127833073] 'agreement among raft nodes before linearized reading' (duration: 293.348762ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:27.956557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.361423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/openstack/ovn-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T13:25:27.956566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.52622ms","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-06-10T13:25:27.956591Z","caller":"traceutil/trace.go:171","msg":"trace[1753893590] range","detail":"{range_begin:/registry/daemonsets/openstack/ovn-controller; range_end:; response_count:0; response_revision:14799; }","duration":"259.436106ms","start":"2026-06-10T13:25:27.697146Z","end":"2026-06-10T13:25:27.956582Z","steps":["trace[1753893590] 'agreement among raft nodes before linearized reading' (duration: 259.365205ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:27.956618Z","caller":"traceutil/trace.go:171","msg":"trace[108050647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14799; }","duration":"325.618423ms","start":"2026-06-10T13:25:27.630984Z","end":"2026-06-10T13:25:27.956602Z","steps":["trace[108050647] 'agreement among raft nodes before linearized reading' (duration: 325.458268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:27.956668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:27.630961Z","time spent":"325.696085ms","remote":"127.0.0.1:53420","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-06-10T13:25:37.709673Z","caller":"traceutil/trace.go:171","msg":"trace[361346748] transaction","detail":"{read_only:false; response_revision:14965; number_of_response:1; }","duration":"304.276402ms","start":"2026-06-10T13:25:37.405306Z","end":"2026-06-10T13:25:37.709582Z","steps":["trace[361346748] 'process raft request' (duration: 241.661708ms)","trace[361346748] 'compare' (duration: 62.48551ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:25:37.709735Z","caller":"traceutil/trace.go:171","msg":"trace[1175433820] linearizableReadLoop","detail":"{readStateIndex:15488; appliedIndex:15487; }","duration":"128.230041ms","start":"2026-06-10T13:25:37.581472Z","end":"2026-06-10T13:25:37.709702Z","steps":["trace[1175433820] 'read index received' (duration: 65.490754ms)","trace[1175433820] 'applied index is now lower than readState.Index' (duration: 62.735687ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:25:37.709873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:37.405283Z","time spent":"304.488907ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3709,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T13:25:38.396027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.452064ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-10T13:25:38.396226Z","caller":"traceutil/trace.go:171","msg":"trace[1886093541] linearizableReadLoop","detail":"{readStateIndex:15489; appliedIndex:15488; }","duration":"686.400551ms","start":"2026-06-10T13:25:37.709808Z","end":"2026-06-10T13:25:38.396208Z","steps":["trace[1886093541] 'read index received' (duration: 55.471µs)","trace[1886093541] 'applied index is now lower than readState.Index' (duration: 686.34393ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:25:38.396289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.784806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:25:38.396363Z","caller":"traceutil/trace.go:171","msg":"trace[406940987] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:14965; }","duration":"814.92299ms","start":"2026-06-10T13:25:37.58142Z","end":"2026-06-10T13:25:38.396343Z","steps":["trace[406940987] 'agreement among raft nodes before linearized reading' (duration: 128.402045ms)","trace[406940987] 'count revisions from in-memory index tree' (duration: 686.384062ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:25:38.3964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:37.581397Z","time spent":"814.995612ms","remote":"127.0.0.1:57728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"warn","ts":"2026-06-10T13:25:38.396858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.746429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:25:38.396936Z","caller":"traceutil/trace.go:171","msg":"trace[1156318068] transaction","detail":"{read_only:false; response_revision:14966; number_of_response:1; }","duration":"647.196704ms","start":"2026-06-10T13:25:37.749695Z","end":"2026-06-10T13:25:38.396892Z","steps":["trace[1156318068] 'process raft request' (duration: 646.426993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:38.397098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:37.749657Z","time spent":"647.359048ms","remote":"127.0.0.1:53420","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-06-10T13:25:38.39696Z","caller":"traceutil/trace.go:171","msg":"trace[853265322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14965; }","duration":"732.845462ms","start":"2026-06-10T13:25:37.664093Z","end":"2026-06-10T13:25:38.396939Z","steps":["trace[853265322] 'agreement among raft nodes before linearized reading' (duration: 732.657817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:38.397526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:37.664031Z","time spent":"733.129379ms","remote":"127.0.0.1:53190","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-06-10T13:25:38.532806Z","caller":"traceutil/trace.go:171","msg":"trace[1236003910] transaction","detail":"{read_only:false; response_revision:14968; number_of_response:1; }","duration":"541.33997ms","start":"2026-06-10T13:25:37.991445Z","end":"2026-06-10T13:25:38.532785Z","steps":["trace[1236003910] 'process raft request' (duration: 541.236817ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:38.53284Z","caller":"traceutil/trace.go:171","msg":"trace[702448705] transaction","detail":"{read_only:false; response_revision:14967; number_of_response:1; }","duration":"542.179843ms","start":"2026-06-10T13:25:37.990619Z","end":"2026-06-10T13:25:38.532799Z","steps":["trace[702448705] 'process raft request' (duration: 541.585116ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:38.532881Z","caller":"traceutil/trace.go:171","msg":"trace[1631838930] transaction","detail":"{read_only:false; response_revision:14969; number_of_response:1; }","duration":"536.33612ms","start":"2026-06-10T13:25:37.996533Z","end":"2026-06-10T13:25:38.532869Z","steps":["trace[1631838930] 'process raft request' (duration: 536.219467ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:38.532926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:37.991436Z","time spent":"541.427932ms","remote":"127.0.0.1:53420","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-06-10T13:25:38.532973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:37.996524Z","time spent":"536.386732ms","remote":"127.0.0.1:53420","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-06-10T13:25:38.532973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:37.990598Z","time spent":"542.307387ms","remote":"127.0.0.1:53420","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-06-10T13:25:38.534369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.872082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-10T13:25:38.534403Z","caller":"traceutil/trace.go:171","msg":"trace[564707249] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14970; }","duration":"512.950544ms","start":"2026-06-10T13:25:38.021444Z","end":"2026-06-10T13:25:38.534395Z","steps":["trace[564707249] 'agreement among raft nodes before linearized reading' (duration: 512.82299ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:38.534426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.46613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-261b39ba-e037-46e4-b75b-de03e08a478e\" ","response":"range_response_count:1 size:3607"} {"level":"warn","ts":"2026-06-10T13:25:38.534502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.289713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T13:25:38.534513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.437916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-06-10T13:25:38.534423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:38.021421Z","time spent":"512.996227ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T13:25:38.534599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.019981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1771a3d3-7b4b-4d73-9863-f61e191bed26\" ","response":"range_response_count:1 size:3724"} {"level":"warn","ts":"2026-06-10T13:25:38.534629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.071921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/ovn-controller-default-zzrpk.18b7bb22eba93394\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-06-10T13:25:38.534659Z","caller":"traceutil/trace.go:171","msg":"trace[1946485319] range","detail":"{range_begin:/registry/events/openstack/ovn-controller-default-zzrpk.18b7bb22eba93394; range_end:; response_count:1; response_revision:14970; }","duration":"687.154793ms","start":"2026-06-10T13:25:37.847498Z","end":"2026-06-10T13:25:38.534652Z","steps":["trace[1946485319] 'agreement among raft nodes before linearized reading' (duration: 687.078001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:38.534677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.92937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:25:38.534706Z","caller":"traceutil/trace.go:171","msg":"trace[398177459] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:14970; }","duration":"555.013972ms","start":"2026-06-10T13:25:37.979685Z","end":"2026-06-10T13:25:38.534699Z","steps":["trace[398177459] 'agreement among raft nodes before linearized reading' (duration: 554.966081ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:38.534739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:37.979657Z","time spent":"555.074424ms","remote":"127.0.0.1:46128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-10T13:25:38.534786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.002223ms","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-06-10T13:25:38.534807Z","caller":"traceutil/trace.go:171","msg":"trace[1069298014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14970; }","duration":"394.062925ms","start":"2026-06-10T13:25:38.140739Z","end":"2026-06-10T13:25:38.534802Z","steps":["trace[1069298014] 'agreement among raft nodes before linearized reading' (duration: 393.957262ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:38.534549Z","caller":"traceutil/trace.go:171","msg":"trace[960328403] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:14970; }","duration":"243.383496ms","start":"2026-06-10T13:25:38.291153Z","end":"2026-06-10T13:25:38.534537Z","steps":["trace[960328403] 'agreement among raft nodes before linearized reading' (duration: 243.298573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:38.53483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:38.140713Z","time spent":"394.112667ms","remote":"127.0.0.1:53420","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":"warn","ts":"2026-06-10T13:25:38.53483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.553523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-06-10T13:25:38.535008Z","caller":"traceutil/trace.go:171","msg":"trace[1765617766] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:14970; }","duration":"558.842979ms","start":"2026-06-10T13:25:37.976143Z","end":"2026-06-10T13:25:38.534986Z","steps":["trace[1765617766] 'agreement among raft nodes before linearized reading' (duration: 558.352985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:38.53504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.203424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/ovn-northd-fc978744-gbghh\" ","response":"range_response_count:1 size:7032"} {"level":"info","ts":"2026-06-10T13:25:38.535064Z","caller":"traceutil/trace.go:171","msg":"trace[1036806987] range","detail":"{range_begin:/registry/pods/openstack/ovn-northd-fc978744-gbghh; range_end:; response_count:1; response_revision:14970; }","duration":"822.261786ms","start":"2026-06-10T13:25:37.712796Z","end":"2026-06-10T13:25:38.535058Z","steps":["trace[1036806987] 'agreement among raft nodes before linearized reading' (duration: 822.197153ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:38.535072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:37.976124Z","time spent":"558.934602ms","remote":"127.0.0.1:53310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1879,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" "} {"level":"warn","ts":"2026-06-10T13:25:38.535083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:37.712778Z","time spent":"822.299678ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7054,"request content":"key:\"/registry/pods/openstack/ovn-northd-fc978744-gbghh\" "} {"level":"info","ts":"2026-06-10T13:25:38.534559Z","caller":"traceutil/trace.go:171","msg":"trace[483166463] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14970; }","duration":"379.523688ms","start":"2026-06-10T13:25:38.155024Z","end":"2026-06-10T13:25:38.534548Z","steps":["trace[483166463] 'agreement among raft nodes before linearized reading' (duration: 379.441056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:38.535118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:38.155002Z","time spent":"380.107224ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T13:25:38.53468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:37.847466Z","time spent":"687.209494ms","remote":"127.0.0.1:53244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":805,"request content":"key:\"/registry/events/openstack/ovn-controller-default-zzrpk.18b7bb22eba93394\" "} {"level":"info","ts":"2026-06-10T13:25:38.534634Z","caller":"traceutil/trace.go:171","msg":"trace[172800558] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1771a3d3-7b4b-4d73-9863-f61e191bed26; range_end:; response_count:1; response_revision:14970; }","duration":"320.089875ms","start":"2026-06-10T13:25:38.214537Z","end":"2026-06-10T13:25:38.534627Z","steps":["trace[172800558] 'agreement among raft nodes before linearized reading' (duration: 320.007542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:38.535303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:38.214516Z","time spent":"320.770573ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3746,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1771a3d3-7b4b-4d73-9863-f61e191bed26\" "} {"level":"info","ts":"2026-06-10T13:25:38.534522Z","caller":"traceutil/trace.go:171","msg":"trace[4245625] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-261b39ba-e037-46e4-b75b-de03e08a478e; range_end:; response_count:1; response_revision:14970; }","duration":"322.626485ms","start":"2026-06-10T13:25:38.211873Z","end":"2026-06-10T13:25:38.5345Z","steps":["trace[4245625] 'agreement among raft nodes before linearized reading' (duration: 322.375728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:38.535366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:25:38.211848Z","time spent":"323.507958ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3629,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-261b39ba-e037-46e4-b75b-de03e08a478e\" "} {"level":"warn","ts":"2026-06-10T13:25:38.535383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.426599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:17 size:158270"} {"level":"info","ts":"2026-06-10T13:25:38.534842Z","caller":"traceutil/trace.go:171","msg":"trace[505796061] transaction","detail":"{read_only:false; response_revision:14970; number_of_response:1; }","duration":"178.388435ms","start":"2026-06-10T13:25:38.356418Z","end":"2026-06-10T13:25:38.534806Z","steps":["trace[505796061] 'process raft request' (duration: 177.611893ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:38.535129Z","caller":"traceutil/trace.go:171","msg":"trace[1517215339] linearizableReadLoop","detail":"{readStateIndex:15494; appliedIndex:15490; }","duration":"137.947563ms","start":"2026-06-10T13:25:38.396262Z","end":"2026-06-10T13:25:38.53421Z","steps":["trace[1517215339] 'read index received' (duration: 136.013269ms)","trace[1517215339] 'applied index is now lower than readState.Index' (duration: 1.933264ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:25:38.535414Z","caller":"traceutil/trace.go:171","msg":"trace[915489855] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:17; response_revision:14970; }","duration":"172.458199ms","start":"2026-06-10T13:25:38.362946Z","end":"2026-06-10T13:25:38.535404Z","steps":["trace[915489855] 'agreement among raft nodes before linearized reading' (duration: 171.366589ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:38.535681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.449441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:25:38.535711Z","caller":"traceutil/trace.go:171","msg":"trace[1651649584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14970; }","duration":"136.509004ms","start":"2026-06-10T13:25:38.399195Z","end":"2026-06-10T13:25:38.535704Z","steps":["trace[1651649584] 'agreement among raft nodes before linearized reading' (duration: 136.461292ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:40.666006Z","caller":"traceutil/trace.go:171","msg":"trace[847765702] transaction","detail":"{read_only:false; response_revision:14985; number_of_response:1; }","duration":"128.246872ms","start":"2026-06-10T13:25:40.537724Z","end":"2026-06-10T13:25:40.665971Z","steps":["trace[847765702] 'process raft request' (duration: 122.363458ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:40.670352Z","caller":"traceutil/trace.go:171","msg":"trace[459846791] linearizableReadLoop","detail":"{readStateIndex:15511; appliedIndex:15508; }","duration":"125.817105ms","start":"2026-06-10T13:25:40.54451Z","end":"2026-06-10T13:25:40.670327Z","steps":["trace[459846791] 'read index received' (duration: 115.410143ms)","trace[459846791] 'applied index is now lower than readState.Index' (duration: 10.405762ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:25:40.670455Z","caller":"traceutil/trace.go:171","msg":"trace[1720483299] transaction","detail":"{read_only:false; response_revision:14986; number_of_response:1; }","duration":"131.549574ms","start":"2026-06-10T13:25:40.538836Z","end":"2026-06-10T13:25:40.670386Z","steps":["trace[1720483299] 'process raft request' (duration: 131.337568ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:40.670436Z","caller":"traceutil/trace.go:171","msg":"trace[32675086] transaction","detail":"{read_only:false; response_revision:14987; number_of_response:1; }","duration":"130.064532ms","start":"2026-06-10T13:25:40.540344Z","end":"2026-06-10T13:25:40.670408Z","steps":["trace[32675086] 'process raft request' (duration: 129.930819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:40.670593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.156821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T13:25:40.670658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.824046ms","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-06-10T13:25:40.670662Z","caller":"traceutil/trace.go:171","msg":"trace[1350272966] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:14987; }","duration":"102.257455ms","start":"2026-06-10T13:25:40.568388Z","end":"2026-06-10T13:25:40.670645Z","steps":["trace[1350272966] 'agreement among raft nodes before linearized reading' (duration: 102.12448ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:40.670692Z","caller":"traceutil/trace.go:171","msg":"trace[981656697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14987; }","duration":"104.916528ms","start":"2026-06-10T13:25:40.565767Z","end":"2026-06-10T13:25:40.670683Z","steps":["trace[981656697] 'agreement among raft nodes before linearized reading' (duration: 104.805275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:40.670691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.009889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-261b39ba-e037-46e4-b75b-de03e08a478e\" ","response":"range_response_count:1 size:3913"} {"level":"warn","ts":"2026-06-10T13:25:40.670683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.147354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1771a3d3-7b4b-4d73-9863-f61e191bed26\" ","response":"range_response_count:1 size:3910"} {"level":"info","ts":"2026-06-10T13:25:40.670794Z","caller":"traceutil/trace.go:171","msg":"trace[2142448627] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-261b39ba-e037-46e4-b75b-de03e08a478e; range_end:; response_count:1; response_revision:14987; }","duration":"126.130632ms","start":"2026-06-10T13:25:40.544602Z","end":"2026-06-10T13:25:40.670733Z","steps":["trace[2142448627] 'agreement among raft nodes before linearized reading' (duration: 125.907046ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:25:40.67081Z","caller":"traceutil/trace.go:171","msg":"trace[290775133] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1771a3d3-7b4b-4d73-9863-f61e191bed26; range_end:; response_count:1; response_revision:14987; }","duration":"126.281797ms","start":"2026-06-10T13:25:40.544501Z","end":"2026-06-10T13:25:40.670783Z","steps":["trace[290775133] 'agreement among raft nodes before linearized reading' (duration: 125.983069ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:40.670831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.876158ms","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-06-10T13:25:40.670916Z","caller":"traceutil/trace.go:171","msg":"trace[1134134765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14987; }","duration":"105.00757ms","start":"2026-06-10T13:25:40.565895Z","end":"2026-06-10T13:25:40.670903Z","steps":["trace[1134134765] 'agreement among raft nodes before linearized reading' (duration: 104.732512ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:25:40.671037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.247827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-10T13:25:40.671124Z","caller":"traceutil/trace.go:171","msg":"trace[863712359] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14987; }","duration":"105.385452ms","start":"2026-06-10T13:25:40.565715Z","end":"2026-06-10T13:25:40.6711Z","steps":["trace[863712359] 'agreement among raft nodes before linearized reading' (duration: 104.895648ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:13.594492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.892582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-cvd7q\" ","response":"range_response_count:1 size:9791"} {"level":"info","ts":"2026-06-10T13:27:13.594564Z","caller":"traceutil/trace.go:171","msg":"trace[764585965] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-cvd7q; range_end:; response_count:1; response_revision:16035; }","duration":"198.007997ms","start":"2026-06-10T13:27:13.396542Z","end":"2026-06-10T13:27:13.59455Z","steps":["trace[764585965] 'range keys from in-memory index tree' (duration: 197.716898ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:13.595089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.050351ms","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-06-10T13:27:13.595133Z","caller":"traceutil/trace.go:171","msg":"trace[36553346] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:16035; }","duration":"117.135823ms","start":"2026-06-10T13:27:13.477985Z","end":"2026-06-10T13:27:13.595121Z","steps":["trace[36553346] 'count revisions from in-memory index tree' (duration: 116.980889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:15.535933Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185696866351773,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T13:27:15.947116Z","caller":"traceutil/trace.go:171","msg":"trace[1809667678] transaction","detail":"{read_only:false; response_revision:16037; number_of_response:1; }","duration":"931.769677ms","start":"2026-06-10T13:27:15.015323Z","end":"2026-06-10T13:27:15.947093Z","steps":["trace[1809667678] 'process raft request' (duration: 931.649833ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:15.947273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:15.015299Z","time spent":"931.887719ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:27:16.10264Z","caller":"traceutil/trace.go:171","msg":"trace[535286927] linearizableReadLoop","detail":"{readStateIndex:16596; appliedIndex:16593; }","duration":"1.067839565s","start":"2026-06-10T13:27:15.034776Z","end":"2026-06-10T13:27:16.102615Z","steps":["trace[535286927] 'read index received' (duration: 912.219051ms)","trace[535286927] 'applied index is now lower than readState.Index' (duration: 155.619484ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:27:16.103143Z","caller":"traceutil/trace.go:171","msg":"trace[1293981265] transaction","detail":"{read_only:false; response_revision:16039; number_of_response:1; }","duration":"1.053855127s","start":"2026-06-10T13:27:15.049254Z","end":"2026-06-10T13:27:16.10311Z","steps":["trace[1293981265] 'process raft request' (duration: 1.053315082s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:16.103225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067873097s","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-06-10T13:27:16.10327Z","caller":"traceutil/trace.go:171","msg":"trace[498941647] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16039; }","duration":"1.067953098s","start":"2026-06-10T13:27:15.035305Z","end":"2026-06-10T13:27:16.103258Z","steps":["trace[498941647] 'agreement among raft nodes before linearized reading' (duration: 1.067795054s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:16.103274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:15.049227Z","time spent":"1.0539785s","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T13:27:16.103305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:15.035293Z","time spent":"1.068002671s","remote":"127.0.0.1:53322","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-06-10T13:27:16.103314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.871406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"warn","ts":"2026-06-10T13:27:16.103301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.305416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T13:27:16.103364Z","caller":"traceutil/trace.go:171","msg":"trace[1939535546] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:16039; }","duration":"909.927607ms","start":"2026-06-10T13:27:15.193422Z","end":"2026-06-10T13:27:16.10335Z","steps":["trace[1939535546] 'agreement among raft nodes before linearized reading' (duration: 909.788234ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:16.103397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:15.193346Z","time spent":"910.043411ms","remote":"127.0.0.1:53322","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":"info","ts":"2026-06-10T13:27:16.103404Z","caller":"traceutil/trace.go:171","msg":"trace[2047633583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:16039; }","duration":"440.45807ms","start":"2026-06-10T13:27:15.662922Z","end":"2026-06-10T13:27:16.10338Z","steps":["trace[2047633583] 'agreement among raft nodes before linearized reading' (duration: 440.270534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:16.103472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:15.662899Z","time spent":"440.558012ms","remote":"127.0.0.1:53190","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-06-10T13:27:16.103533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.990319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-cvd7q\" ","response":"range_response_count:1 size:9791"} {"level":"info","ts":"2026-06-10T13:27:16.103561Z","caller":"traceutil/trace.go:171","msg":"trace[228697270] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-cvd7q; range_end:; response_count:1; response_revision:16039; }","duration":"485.054502ms","start":"2026-06-10T13:27:15.618498Z","end":"2026-06-10T13:27:16.103552Z","steps":["trace[228697270] 'agreement among raft nodes before linearized reading' (duration: 484.971439ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:16.103585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:15.618475Z","time spent":"485.105192ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9813,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-cvd7q\" "} {"level":"warn","ts":"2026-06-10T13:27:16.103583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.513568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-10T13:27:16.103617Z","caller":"traceutil/trace.go:171","msg":"trace[1037425893] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16039; }","duration":"945.56912ms","start":"2026-06-10T13:27:15.158037Z","end":"2026-06-10T13:27:16.103606Z","steps":["trace[1037425893] 'agreement among raft nodes before linearized reading' (duration: 945.475777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:16.103646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:15.158026Z","time spent":"945.612752ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T13:27:16.103651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.36329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-10T13:27:16.103691Z","caller":"traceutil/trace.go:171","msg":"trace[287073398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16039; }","duration":"826.436941ms","start":"2026-06-10T13:27:15.277243Z","end":"2026-06-10T13:27:16.10368Z","steps":["trace[287073398] 'agreement among raft nodes before linearized reading' (duration: 826.266247ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:16.103719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:15.277221Z","time spent":"826.491234ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T13:27:16.103792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069012818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T13:27:16.103841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.347164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-10T13:27:16.103845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.644845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-4tlp4\" ","response":"range_response_count:1 size:20747"} {"level":"info","ts":"2026-06-10T13:27:16.103862Z","caller":"traceutil/trace.go:171","msg":"trace[952861103] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16039; }","duration":"1.069116492s","start":"2026-06-10T13:27:15.034713Z","end":"2026-06-10T13:27:16.103829Z","steps":["trace[952861103] 'agreement among raft nodes before linearized reading' (duration: 1.069029699s)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:27:16.103891Z","caller":"traceutil/trace.go:171","msg":"trace[548867349] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-4tlp4; range_end:; response_count:1; response_revision:16039; }","duration":"860.707166ms","start":"2026-06-10T13:27:15.243161Z","end":"2026-06-10T13:27:16.103868Z","steps":["trace[548867349] 'agreement among raft nodes before linearized reading' (duration: 860.557642ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:27:16.103871Z","caller":"traceutil/trace.go:171","msg":"trace[647369563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16039; }","duration":"950.399875ms","start":"2026-06-10T13:27:15.153463Z","end":"2026-06-10T13:27:16.103863Z","steps":["trace[647369563] 'agreement among raft nodes before linearized reading' (duration: 950.304412ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:16.103908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.006436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-10T13:27:16.103915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:15.034698Z","time spent":"1.069205974s","remote":"127.0.0.1:53400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/nova-rabbit-init\" "} {"level":"warn","ts":"2026-06-10T13:27:16.103924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:15.243141Z","time spent":"860.777529ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20769,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-4tlp4\" "} {"level":"warn","ts":"2026-06-10T13:27:16.103926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:15.15345Z","time spent":"950.469277ms","remote":"127.0.0.1:53420","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":"warn","ts":"2026-06-10T13:27:16.104067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.072323ms","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-06-10T13:27:16.104091Z","caller":"traceutil/trace.go:171","msg":"trace[361832347] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16039; }","duration":"951.127605ms","start":"2026-06-10T13:27:15.152956Z","end":"2026-06-10T13:27:16.104084Z","steps":["trace[361832347] 'agreement among raft nodes before linearized reading' (duration: 951.055942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:16.104115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:15.152938Z","time spent":"951.171236ms","remote":"127.0.0.1:53420","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-06-10T13:27:16.104204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.711855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-10T13:27:16.104228Z","caller":"traceutil/trace.go:171","msg":"trace[823998119] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16039; }","duration":"142.773436ms","start":"2026-06-10T13:27:15.961448Z","end":"2026-06-10T13:27:16.104222Z","steps":["trace[823998119] 'agreement among raft nodes before linearized reading' (duration: 142.703185ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:16.104276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017674399s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-06-10T13:27:16.104312Z","caller":"traceutil/trace.go:171","msg":"trace[1490687621] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:16039; }","duration":"1.017746351s","start":"2026-06-10T13:27:15.08655Z","end":"2026-06-10T13:27:16.104297Z","steps":["trace[1490687621] 'agreement among raft nodes before linearized reading' (duration: 1.017682219s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:16.104338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:15.086528Z","time spent":"1.017803131s","remote":"127.0.0.1:53344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2010,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-06-10T13:27:16.10455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.226067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1857"} {"level":"info","ts":"2026-06-10T13:27:16.104607Z","caller":"traceutil/trace.go:171","msg":"trace[801436289] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:16039; }","duration":"172.31872ms","start":"2026-06-10T13:27:15.932269Z","end":"2026-06-10T13:27:16.104588Z","steps":["trace[801436289] 'agreement among raft nodes before linearized reading' (duration: 172.173585ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:16.104771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068558446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-06-10T13:27:16.104798Z","caller":"traceutil/trace.go:171","msg":"trace[1421046188] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16039; }","duration":"1.068599308s","start":"2026-06-10T13:27:15.036187Z","end":"2026-06-10T13:27:16.104787Z","steps":["trace[1421046188] 'agreement among raft nodes before linearized reading' (duration: 1.068537166s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:16.104822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:15.03618Z","time spent":"1.068634738s","remote":"127.0.0.1:53400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":5543,"request content":"key:\"/registry/jobs/openstack/nova-db-sync\" "} {"level":"info","ts":"2026-06-10T13:27:16.103934Z","caller":"traceutil/trace.go:171","msg":"trace[1877928004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16039; }","duration":"823.054748ms","start":"2026-06-10T13:27:15.280872Z","end":"2026-06-10T13:27:16.103926Z","steps":["trace[1877928004] 'agreement among raft nodes before linearized reading' (duration: 822.965955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:16.105318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:15.280857Z","time spent":"824.407815ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T13:27:16.105792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.644826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-55kjf\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-06-10T13:27:16.105854Z","caller":"traceutil/trace.go:171","msg":"trace[125684150] range","detail":"{range_begin:/registry/pods/openstack/keepalived-55kjf; range_end:; response_count:1; response_revision:16039; }","duration":"360.741248ms","start":"2026-06-10T13:27:15.745095Z","end":"2026-06-10T13:27:16.105837Z","steps":["trace[125684150] 'agreement among raft nodes before linearized reading' (duration: 359.732351ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:16.105922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:15.745077Z","time spent":"360.832231ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6544,"request content":"key:\"/registry/pods/openstack/keepalived-55kjf\" "} {"level":"info","ts":"2026-06-10T13:27:16.102979Z","caller":"traceutil/trace.go:171","msg":"trace[947156556] transaction","detail":"{read_only:false; response_revision:16038; number_of_response:1; }","duration":"1.058455675s","start":"2026-06-10T13:27:15.04451Z","end":"2026-06-10T13:27:16.102966Z","steps":["trace[947156556] 'process raft request' (duration: 1.0561298s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:27:16.106469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:27:15.044496Z","time spent":"1.06187699s","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:30:08.830944Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14685} {"level":"info","ts":"2026-06-10T13:30:08.90604Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14685,"took":"72.156918ms","hash":2189968492,"current-db-size-bytes":58437632,"current-db-size":"58 MB","current-db-size-in-use-bytes":32673792,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-10T13:30:08.906095Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2189968492,"revision":14685,"compact-revision":12660} {"level":"info","ts":"2026-06-10T13:32:59.421202Z","caller":"traceutil/trace.go:171","msg":"trace[878996247] transaction","detail":"{read_only:false; response_revision:18559; number_of_response:1; }","duration":"265.239217ms","start":"2026-06-10T13:32:59.155936Z","end":"2026-06-10T13:32:59.421175Z","steps":["trace[878996247] 'process raft request' (duration: 264.865617ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:32:59.434695Z","caller":"traceutil/trace.go:171","msg":"trace[1318594526] transaction","detail":"{read_only:false; response_revision:18560; number_of_response:1; }","duration":"206.276059ms","start":"2026-06-10T13:32:59.228397Z","end":"2026-06-10T13:32:59.434673Z","steps":["trace[1318594526] 'process raft request' (duration: 206.101003ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:33:00.11393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.408943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-10T13:33:00.11399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.389821ms","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-06-10T13:33:00.114029Z","caller":"traceutil/trace.go:171","msg":"trace[1543012401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18562; }","duration":"450.553327ms","start":"2026-06-10T13:32:59.663451Z","end":"2026-06-10T13:33:00.114004Z","steps":["trace[1543012401] 'range keys from in-memory index tree' (duration: 450.31875ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:33:00.114059Z","caller":"traceutil/trace.go:171","msg":"trace[797961345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18562; }","duration":"240.466663ms","start":"2026-06-10T13:32:59.873576Z","end":"2026-06-10T13:33:00.114043Z","steps":["trace[797961345] 'range keys from in-memory index tree' (duration: 240.284048ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:33:00.114073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:32:59.66343Z","time spent":"450.633948ms","remote":"127.0.0.1:53190","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-06-10T13:33:00.114121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.452385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T13:33:00.114193Z","caller":"traceutil/trace.go:171","msg":"trace[1725546870] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:18562; }","duration":"163.583149ms","start":"2026-06-10T13:32:59.950591Z","end":"2026-06-10T13:33:00.114174Z","steps":["trace[1725546870] 'count revisions from in-memory index tree' (duration: 163.336682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:33:00.114179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.482208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-10T13:33:00.114295Z","caller":"traceutil/trace.go:171","msg":"trace[1065163243] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18562; }","duration":"242.625903ms","start":"2026-06-10T13:32:59.871625Z","end":"2026-06-10T13:33:00.114251Z","steps":["trace[1065163243] 'range keys from in-memory index tree' (duration: 242.299403ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:33:00.113942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.093182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-10T13:33:00.11393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.837842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-06-10T13:33:00.114322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.886883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-10T13:33:00.114392Z","caller":"traceutil/trace.go:171","msg":"trace[459931341] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18562; }","duration":"363.003728ms","start":"2026-06-10T13:32:59.751371Z","end":"2026-06-10T13:33:00.114375Z","steps":["trace[459931341] 'range keys from in-memory index tree' (duration: 362.701339ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:33:00.114404Z","caller":"traceutil/trace.go:171","msg":"trace[1973817277] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18562; }","duration":"358.557546ms","start":"2026-06-10T13:32:59.755786Z","end":"2026-06-10T13:33:00.114343Z","steps":["trace[1973817277] 'range keys from in-memory index tree' (duration: 357.931028ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:33:00.114387Z","caller":"traceutil/trace.go:171","msg":"trace[1335927503] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18562; }","duration":"296.345167ms","start":"2026-06-10T13:32:59.818025Z","end":"2026-06-10T13:33:00.11437Z","steps":["trace[1335927503] 'range keys from in-memory index tree' (duration: 295.700279ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:33:00.114434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:32:59.751346Z","time spent":"363.08141ms","remote":"127.0.0.1:53420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T13:33:00.114446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:32:59.755769Z","time spent":"358.664529ms","remote":"127.0.0.1:53420","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":"warn","ts":"2026-06-10T13:33:00.11448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.941126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-10T13:33:00.114535Z","caller":"traceutil/trace.go:171","msg":"trace[1985889302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18562; }","duration":"241.011857ms","start":"2026-06-10T13:32:59.87351Z","end":"2026-06-10T13:33:00.114522Z","steps":["trace[1985889302] 'range keys from in-memory index tree' (duration: 240.201124ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:33:01.183203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.900751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-wlch6\" ","response":"range_response_count:1 size:22094"} {"level":"warn","ts":"2026-06-10T13:33:01.183235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.542633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-4tlp4\" ","response":"range_response_count:1 size:20850"} {"level":"info","ts":"2026-06-10T13:33:01.183298Z","caller":"traceutil/trace.go:171","msg":"trace[1230303595] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-wlch6; range_end:; response_count:1; response_revision:18571; }","duration":"381.036226ms","start":"2026-06-10T13:33:00.802241Z","end":"2026-06-10T13:33:01.183277Z","steps":["trace[1230303595] 'range keys from in-memory index tree' (duration: 380.729997ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:33:01.18334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:33:00.802222Z","time spent":"381.109108ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22116,"request content":"key:\"/registry/pods/openstack/nova-compute-default-wlch6\" "} {"level":"info","ts":"2026-06-10T13:33:01.183342Z","caller":"traceutil/trace.go:171","msg":"trace[1393547575] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-4tlp4; range_end:; response_count:1; response_revision:18571; }","duration":"283.679557ms","start":"2026-06-10T13:33:00.899638Z","end":"2026-06-10T13:33:01.183318Z","steps":["trace[1393547575] 'range keys from in-memory index tree' (duration: 283.385879ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:33:01.183369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.880828ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T13:33:01.183502Z","caller":"traceutil/trace.go:171","msg":"trace[526548390] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:18571; }","duration":"265.033612ms","start":"2026-06-10T13:33:00.918448Z","end":"2026-06-10T13:33:01.183481Z","steps":["trace[526548390] 'range keys from in-memory index tree' (duration: 264.857278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:33:01.458176Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185696866361546,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T13:33:01.577352Z","caller":"traceutil/trace.go:171","msg":"trace[197917718] transaction","detail":"{read_only:false; response_revision:18572; number_of_response:1; }","duration":"670.982406ms","start":"2026-06-10T13:33:00.906345Z","end":"2026-06-10T13:33:01.577327Z","steps":["trace[197917718] 'process raft request' (duration: 670.820722ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:33:01.577515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:33:00.906327Z","time spent":"671.101469ms","remote":"127.0.0.1:53244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":886,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T13:33:01.621052Z","caller":"traceutil/trace.go:171","msg":"trace[1200019474] transaction","detail":"{read_only:false; response_revision:18573; number_of_response:1; }","duration":"422.641575ms","start":"2026-06-10T13:33:01.198376Z","end":"2026-06-10T13:33:01.621018Z","steps":["trace[1200019474] 'process raft request' (duration: 417.554074ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:33:01.621189Z","caller":"traceutil/trace.go:171","msg":"trace[302014011] transaction","detail":"{read_only:false; response_revision:18574; number_of_response:1; }","duration":"181.362091ms","start":"2026-06-10T13:33:01.439804Z","end":"2026-06-10T13:33:01.621166Z","steps":["trace[302014011] 'process raft request' (duration: 181.117274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:33:01.621225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:33:01.198348Z","time spent":"422.7853ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20967,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T13:33:01.621375Z","caller":"traceutil/trace.go:171","msg":"trace[577320774] transaction","detail":"{read_only:false; response_revision:18575; number_of_response:1; }","duration":"180.813355ms","start":"2026-06-10T13:33:01.440548Z","end":"2026-06-10T13:33:01.621361Z","steps":["trace[577320774] 'process raft request' (duration: 180.412924ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:33:01.62147Z","caller":"traceutil/trace.go:171","msg":"trace[306497722] transaction","detail":"{read_only:false; response_revision:18576; number_of_response:1; }","duration":"112.080857ms","start":"2026-06-10T13:33:01.509382Z","end":"2026-06-10T13:33:01.621462Z","steps":["trace[306497722] 'process raft request' (duration: 111.744388ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:33:01.621367Z","caller":"traceutil/trace.go:171","msg":"trace[510784138] linearizableReadLoop","detail":"{readStateIndex:19216; appliedIndex:19212; }","duration":"663.301323ms","start":"2026-06-10T13:33:00.958057Z","end":"2026-06-10T13:33:01.621358Z","steps":["trace[510784138] 'read index received' (duration: 619.246406ms)","trace[510784138] 'applied index is now lower than readState.Index' (duration: 44.054157ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T13:33:01.621784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.718855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:703"} {"level":"info","ts":"2026-06-10T13:33:01.621855Z","caller":"traceutil/trace.go:171","msg":"trace[2134529405] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:18576; }","duration":"663.826958ms","start":"2026-06-10T13:33:00.958008Z","end":"2026-06-10T13:33:01.621835Z","steps":["trace[2134529405] 'agreement among raft nodes before linearized reading' (duration: 663.626402ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:33:01.621895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:33:00.957989Z","time spent":"663.896911ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":725,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-06-10T13:33:01.622312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.000232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:941301"} {"level":"info","ts":"2026-06-10T13:33:01.622352Z","caller":"traceutil/trace.go:171","msg":"trace[80819894] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:18576; }","duration":"433.090333ms","start":"2026-06-10T13:33:01.189253Z","end":"2026-06-10T13:33:01.622344Z","steps":["trace[80819894] 'agreement among raft nodes before linearized reading' (duration: 432.346073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:33:01.622377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T13:33:01.189235Z","time spent":"433.133255ms","remote":"127.0.0.1:53340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":95,"response size":941323,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-06-10T13:33:01.793301Z","caller":"traceutil/trace.go:171","msg":"trace[2138398548] linearizableReadLoop","detail":"{readStateIndex:19218; appliedIndex:19217; }","duration":"130.061172ms","start":"2026-06-10T13:33:01.66322Z","end":"2026-06-10T13:33:01.793281Z","steps":["trace[2138398548] 'read index received' (duration: 129.891878ms)","trace[2138398548] 'applied index is now lower than readState.Index' (duration: 168.464µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:33:01.793355Z","caller":"traceutil/trace.go:171","msg":"trace[267506184] transaction","detail":"{read_only:false; response_revision:18577; number_of_response:1; }","duration":"158.992442ms","start":"2026-06-10T13:33:01.634334Z","end":"2026-06-10T13:33:01.793327Z","steps":["trace[267506184] 'process raft request' (duration: 158.816037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T13:33:01.793407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.178396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-10T13:33:01.793431Z","caller":"traceutil/trace.go:171","msg":"trace[501427507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18577; }","duration":"130.256259ms","start":"2026-06-10T13:33:01.663167Z","end":"2026-06-10T13:33:01.793423Z","steps":["trace[501427507] 'agreement among raft nodes before linearized reading' (duration: 130.196386ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:33:01.934956Z","caller":"traceutil/trace.go:171","msg":"trace[596736559] transaction","detail":"{read_only:false; response_revision:18578; number_of_response:1; }","duration":"131.882704ms","start":"2026-06-10T13:33:01.803046Z","end":"2026-06-10T13:33:01.934929Z","steps":["trace[596736559] 'process raft request' (duration: 122.571346ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:33:21.947996Z","caller":"traceutil/trace.go:171","msg":"trace[1105063903] transaction","detail":"{read_only:false; response_revision:18770; number_of_response:1; }","duration":"201.793423ms","start":"2026-06-10T13:33:21.746183Z","end":"2026-06-10T13:33:21.947977Z","steps":["trace[1105063903] 'process raft request' (duration: 145.372326ms)","trace[1105063903] 'compare' (duration: 56.274672ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T13:33:21.948285Z","caller":"traceutil/trace.go:171","msg":"trace[681346585] transaction","detail":"{read_only:false; response_revision:18771; number_of_response:1; }","duration":"199.987763ms","start":"2026-06-10T13:33:21.748263Z","end":"2026-06-10T13:33:21.948251Z","steps":["trace[681346585] 'process raft request' (duration: 199.682695ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T13:35:04.873601Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"7c53a18098324dc9","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-10T13:35:04.879528Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-10T13:35:04.87978Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-10T13:35:08.837003Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17259} {"level":"info","ts":"2026-06-10T13:35:08.93744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17259,"took":"96.907107ms","hash":2119574169,"current-db-size-bytes":58437632,"current-db-size":"58 MB","current-db-size-in-use-bytes":30883840,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-10T13:35:08.937495Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2119574169,"revision":17259,"compact-revision":14685} {"level":"info","ts":"2026-06-10T13:40:08.84245Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19349} {"level":"info","ts":"2026-06-10T13:40:08.934388Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19349,"took":"88.479781ms","hash":468735270,"current-db-size-bytes":58437632,"current-db-size":"58 MB","current-db-size-in-use-bytes":25137152,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-06-10T13:40:08.934438Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":468735270,"revision":19349,"compact-revision":17259}