{"level":"warn","ts":"2026-07-16T00:23:22.294973Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T00:23:22.295164Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-16T00:23:22.295262Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T00:23:22.295272Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-07-16T00:23:22.295297Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-16T00:23:22.296018Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-07-16T00:23:22.2961Z","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.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-16T00:23:22.299358Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.848099ms"} {"level":"info","ts":"2026-07-16T00:23:22.305093Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-07-16T00:23:22.305217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T00:23:22.305247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-07-16T00:23:22.305267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T00:23:22.305281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-07-16T00:23:22.305326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-07-16T00:23:22.314736Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T00:23:22.316637Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T00:23:22.31786Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-16T00:23:22.321076Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T00:23:22.321257Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-16T00:23:22.321368Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-16T00:23:22.321383Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-16T00:23:22.321312Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T00:23:22.322859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-07-16T00:23:22.325076Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-07-16T00:23:22.327759Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-16T00:23:22.32788Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-07-16T00:23:22.327908Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-07-16T00:23:22.328156Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T00:23:22.328203Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T00:23:22.906435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T00:23:22.906491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T00:23:22.906525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-07-16T00:23:22.906545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-07-16T00:23:22.906556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-07-16T00:23:22.906572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-07-16T00:23:22.906585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-07-16T00:23:22.907646Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T00:23:22.907716Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:23:22.907797Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T00:23:22.907864Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T00:23:22.908846Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:23:22.908877Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T00:23:22.908968Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:23:22.908985Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-16T00:23:22.909004Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:23:22.911333Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-07-16T00:23:22.911951Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-16T00:23:38.830045Z","caller":"traceutil/trace.go:171","msg":"trace[500640418] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"145.584242ms","start":"2026-07-16T00:23:38.684438Z","end":"2026-07-16T00:23:38.830022Z","steps":["trace[500640418] 'process raft request' (duration: 145.459359ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:24:10.083771Z","caller":"traceutil/trace.go:171","msg":"trace[1340012305] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"131.53648ms","start":"2026-07-16T00:24:09.952211Z","end":"2026-07-16T00:24:10.083747Z","steps":["trace[1340012305] 'read index received' (duration: 130.777472ms)","trace[1340012305] 'applied index is now lower than readState.Index' (duration: 758.258µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:24:10.083858Z","caller":"traceutil/trace.go:171","msg":"trace[486655722] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"139.461285ms","start":"2026-07-16T00:24:09.944341Z","end":"2026-07-16T00:24:10.083802Z","steps":["trace[486655722] 'process raft request' (duration: 138.84968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:24:10.083996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.763416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-24dz9\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-16T00:24:10.084178Z","caller":"traceutil/trace.go:171","msg":"trace[1306283955] range","detail":"{range_begin:/registry/pods/kube-system/cilium-24dz9; range_end:; response_count:1; response_revision:644; }","duration":"131.994151ms","start":"2026-07-16T00:24:09.952168Z","end":"2026-07-16T00:24:10.084162Z","steps":["trace[1306283955] 'agreement among raft nodes before linearized reading' (duration: 131.706454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:24:10.549025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.977155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:24:10.549189Z","caller":"traceutil/trace.go:171","msg":"trace[1198768457] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:669; }","duration":"167.55094ms","start":"2026-07-16T00:24:10.381623Z","end":"2026-07-16T00:24:10.549174Z","steps":["trace[1198768457] 'read index received' (duration: 6.334946ms)","trace[1198768457] 'applied index is now lower than readState.Index' (duration: 161.215044ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:24:10.549268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.646052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:24:10.549263Z","caller":"traceutil/trace.go:171","msg":"trace[536340187] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"407.062845ms","start":"2026-07-16T00:24:10.142174Z","end":"2026-07-16T00:24:10.549237Z","steps":["trace[536340187] 'process raft request' (duration: 406.943982ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:24:10.549298Z","caller":"traceutil/trace.go:171","msg":"trace[560930929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"167.709484ms","start":"2026-07-16T00:24:10.38158Z","end":"2026-07-16T00:24:10.54929Z","steps":["trace[560930929] 'agreement among raft nodes before linearized reading' (duration: 167.643272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:24:10.549343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:24:10.142159Z","time spent":"407.148798ms","remote":"127.0.0.1:44632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:24:10.549513Z","caller":"traceutil/trace.go:171","msg":"trace[955433188] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"450.954337ms","start":"2026-07-16T00:24:10.098466Z","end":"2026-07-16T00:24:10.54942Z","steps":["trace[955433188] 'process raft request' (duration: 274.002418ms)","trace[955433188] 'compare' (duration: 175.835512ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:24:10.549929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:24:10.098434Z","time spent":"451.161442ms","remote":"127.0.0.1:44354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:24:54.431131Z","caller":"traceutil/trace.go:171","msg":"trace[1874469537] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1240; }","duration":"192.559092ms","start":"2026-07-16T00:24:54.238545Z","end":"2026-07-16T00:24:54.431104Z","steps":["trace[1874469537] 'read index received' (duration: 192.380958ms)","trace[1874469537] 'applied index is now lower than readState.Index' (duration: 176.884µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:24:54.431171Z","caller":"traceutil/trace.go:171","msg":"trace[1500403050] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"253.001959ms","start":"2026-07-16T00:24:54.17814Z","end":"2026-07-16T00:24:54.431142Z","steps":["trace[1500403050] 'process raft request' (duration: 252.796254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:24:54.431281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.714976ms","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":"info","ts":"2026-07-16T00:24:54.431317Z","caller":"traceutil/trace.go:171","msg":"trace[54076621] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1205; }","duration":"192.807148ms","start":"2026-07-16T00:24:54.238498Z","end":"2026-07-16T00:24:54.431305Z","steps":["trace[54076621] 'agreement among raft nodes before linearized reading' (duration: 192.726696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:25:40.067718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.557105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a6d1ac54-749a-48c7-9f8b-2def098b0847\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-16T00:25:40.06783Z","caller":"traceutil/trace.go:171","msg":"trace[1612249986] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a6d1ac54-749a-48c7-9f8b-2def098b0847; range_end:; response_count:1; response_revision:1879; }","duration":"224.720479ms","start":"2026-07-16T00:25:39.843082Z","end":"2026-07-16T00:25:40.067803Z","steps":["trace[1612249986] 'range keys from in-memory index tree' (duration: 224.315839ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:25:40.068109Z","caller":"traceutil/trace.go:171","msg":"trace[1869926924] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"129.015913ms","start":"2026-07-16T00:25:39.938727Z","end":"2026-07-16T00:25:40.067743Z","steps":["trace[1869926924] 'process raft request' (duration: 106.352448ms)","trace[1869926924] 'compare' (duration: 22.290136ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:25:40.096891Z","caller":"traceutil/trace.go:171","msg":"trace[143220752] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"157.970064ms","start":"2026-07-16T00:25:39.938891Z","end":"2026-07-16T00:25:40.096861Z","steps":["trace[143220752] 'process raft request' (duration: 157.82649ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:26:28.410901Z","caller":"traceutil/trace.go:171","msg":"trace[722366554] linearizableReadLoop","detail":"{readStateIndex:2274; appliedIndex:2273; }","duration":"112.178737ms","start":"2026-07-16T00:26:28.298698Z","end":"2026-07-16T00:26:28.410877Z","steps":["trace[722366554] 'read index received' (duration: 111.975172ms)","trace[722366554] 'applied index is now lower than readState.Index' (duration: 202.485µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:26:28.410977Z","caller":"traceutil/trace.go:171","msg":"trace[1765031577] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"147.710399ms","start":"2026-07-16T00:26:28.263236Z","end":"2026-07-16T00:26:28.410946Z","steps":["trace[1765031577] 'process raft request' (duration: 147.475134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:26:28.411145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.437262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-07-16T00:26:28.411225Z","caller":"traceutil/trace.go:171","msg":"trace[1451245949] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2192; }","duration":"112.567786ms","start":"2026-07-16T00:26:28.298639Z","end":"2026-07-16T00:26:28.411207Z","steps":["trace[1451245949] 'agreement among raft nodes before linearized reading' (duration: 112.34509ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:26:28.43051Z","caller":"traceutil/trace.go:171","msg":"trace[2070690256] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"122.746257ms","start":"2026-07-16T00:26:28.307742Z","end":"2026-07-16T00:26:28.430488Z","steps":["trace[2070690256] 'process raft request' (duration: 122.4835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:26:34.791011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.88567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:26:34.791156Z","caller":"traceutil/trace.go:171","msg":"trace[2025939165] linearizableReadLoop","detail":"{readStateIndex:2311; appliedIndex:2310; }","duration":"149.874604ms","start":"2026-07-16T00:26:34.64126Z","end":"2026-07-16T00:26:34.791134Z","steps":["trace[2025939165] 'read index received' (duration: 37.715557ms)","trace[2025939165] 'applied index is now lower than readState.Index' (duration: 112.157586ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:26:34.79127Z","caller":"traceutil/trace.go:171","msg":"trace[944839827] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"247.535582ms","start":"2026-07-16T00:26:34.543718Z","end":"2026-07-16T00:26:34.791254Z","steps":["trace[944839827] 'process raft request' (duration: 135.301335ms)","trace[944839827] 'compare' (duration: 111.704885ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:26:34.791353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.080328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:26:34.791434Z","caller":"traceutil/trace.go:171","msg":"trace[1433419183] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2227; }","duration":"150.202781ms","start":"2026-07-16T00:26:34.641215Z","end":"2026-07-16T00:26:34.791418Z","steps":["trace[1433419183] 'agreement among raft nodes before linearized reading' (duration: 150.076258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:26:34.791453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.672822ms","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-07-16T00:26:34.791531Z","caller":"traceutil/trace.go:171","msg":"trace[486609576] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2227; }","duration":"106.782174ms","start":"2026-07-16T00:26:34.684731Z","end":"2026-07-16T00:26:34.791513Z","steps":["trace[486609576] 'agreement among raft nodes before linearized reading' (duration: 106.575669ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:31:26.602903Z","caller":"traceutil/trace.go:171","msg":"trace[1773274919] linearizableReadLoop","detail":"{readStateIndex:4969; appliedIndex:4968; }","duration":"219.461628ms","start":"2026-07-16T00:31:26.383392Z","end":"2026-07-16T00:31:26.602854Z","steps":["trace[1773274919] 'read index received' (duration: 219.285664ms)","trace[1773274919] 'applied index is now lower than readState.Index' (duration: 174.904µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:31:26.602995Z","caller":"traceutil/trace.go:171","msg":"trace[1920815546] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"249.391259ms","start":"2026-07-16T00:31:26.353577Z","end":"2026-07-16T00:31:26.602968Z","steps":["trace[1920815546] 'process raft request' (duration: 249.122272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:31:26.603084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.672683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:31:26.603129Z","caller":"traceutil/trace.go:171","msg":"trace[1495105684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4784; }","duration":"219.771905ms","start":"2026-07-16T00:31:26.383345Z","end":"2026-07-16T00:31:26.603117Z","steps":["trace[1495105684] 'agreement among raft nodes before linearized reading' (duration: 219.676633ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:31:30.00113Z","caller":"traceutil/trace.go:171","msg":"trace[1455571592] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"135.947598ms","start":"2026-07-16T00:31:29.86515Z","end":"2026-07-16T00:31:30.001098Z","steps":["trace[1455571592] 'process raft request' (duration: 135.753944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:31:42.912754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.046977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:31:42.913064Z","caller":"traceutil/trace.go:171","msg":"trace[496889247] linearizableReadLoop","detail":"{readStateIndex:5379; appliedIndex:5375; }","duration":"144.290103ms","start":"2026-07-16T00:31:42.768757Z","end":"2026-07-16T00:31:42.913048Z","steps":["trace[496889247] 'read index received' (duration: 8.950678ms)","trace[496889247] 'applied index is now lower than readState.Index' (duration: 135.338565ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:31:42.913155Z","caller":"traceutil/trace.go:171","msg":"trace[253787723] transaction","detail":"{read_only:false; response_revision:5180; number_of_response:1; }","duration":"160.462468ms","start":"2026-07-16T00:31:42.752681Z","end":"2026-07-16T00:31:42.913143Z","steps":["trace[253787723] 'process raft request' (duration: 24.957ms)","trace[253787723] 'compare' (duration: 134.874663ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:31:42.913414Z","caller":"traceutil/trace.go:171","msg":"trace[1044241051] transaction","detail":"{read_only:false; response_revision:5183; number_of_response:1; }","duration":"152.825191ms","start":"2026-07-16T00:31:42.760555Z","end":"2026-07-16T00:31:42.91338Z","steps":["trace[1044241051] 'process raft request' (duration: 152.429901ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:31:42.913445Z","caller":"traceutil/trace.go:171","msg":"trace[1620763865] transaction","detail":"{read_only:false; response_revision:5181; number_of_response:1; }","duration":"160.444657ms","start":"2026-07-16T00:31:42.752963Z","end":"2026-07-16T00:31:42.913407Z","steps":["trace[1620763865] 'process raft request' (duration: 159.889934ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:31:42.913483Z","caller":"traceutil/trace.go:171","msg":"trace[1517945654] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"159.669598ms","start":"2026-07-16T00:31:42.753787Z","end":"2026-07-16T00:31:42.913457Z","steps":["trace[1517945654] 'process raft request' (duration: 159.152396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:31:42.913532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.751694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-16T00:31:42.913611Z","caller":"traceutil/trace.go:171","msg":"trace[1978884632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5183; }","duration":"144.860496ms","start":"2026-07-16T00:31:42.768732Z","end":"2026-07-16T00:31:42.913592Z","steps":["trace[1978884632] 'agreement among raft nodes before linearized reading' (duration: 144.63914ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:31:42.913744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.356159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-16T00:31:42.9139Z","caller":"traceutil/trace.go:171","msg":"trace[547678610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5183; }","duration":"134.580196ms","start":"2026-07-16T00:31:42.77928Z","end":"2026-07-16T00:31:42.913861Z","steps":["trace[547678610] 'agreement among raft nodes before linearized reading' (duration: 134.114404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:31:43.389712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.15591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:31:43.389779Z","caller":"traceutil/trace.go:171","msg":"trace[242596362] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:5189; }","duration":"142.279263ms","start":"2026-07-16T00:31:43.247486Z","end":"2026-07-16T00:31:43.389765Z","steps":["trace[242596362] 'count revisions from in-memory index tree' (duration: 142.095009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:31:43.390088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.829777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-zqntt\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-07-16T00:31:43.390188Z","caller":"traceutil/trace.go:171","msg":"trace[956953540] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-zqntt; range_end:; response_count:1; response_revision:5189; }","duration":"142.97651ms","start":"2026-07-16T00:31:43.247187Z","end":"2026-07-16T00:31:43.390163Z","steps":["trace[956953540] 'range keys from in-memory index tree' (duration: 142.626611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:31:51.509217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.218141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-zqntt\" ","response":"range_response_count:1 size:8067"} {"level":"info","ts":"2026-07-16T00:31:51.509427Z","caller":"traceutil/trace.go:171","msg":"trace[402999663] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-zqntt; range_end:; response_count:1; response_revision:5387; }","duration":"156.488087ms","start":"2026-07-16T00:31:51.352899Z","end":"2026-07-16T00:31:51.509387Z","steps":["trace[402999663] 'range keys from in-memory index tree' (duration: 155.954385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:31:51.769032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.716569ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-16T00:31:51.820346Z","caller":"traceutil/trace.go:171","msg":"trace[1231354076] linearizableReadLoop","detail":"{readStateIndex:5588; appliedIndex:5587; }","duration":"297.943248ms","start":"2026-07-16T00:31:51.522381Z","end":"2026-07-16T00:31:51.820324Z","steps":["trace[1231354076] 'read index received' (duration: 297.815435ms)","trace[1231354076] 'applied index is now lower than readState.Index' (duration: 125.743µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:31:51.820452Z","caller":"traceutil/trace.go:171","msg":"trace[92749502] transaction","detail":"{read_only:false; response_revision:5388; number_of_response:1; }","duration":"299.955947ms","start":"2026-07-16T00:31:51.520456Z","end":"2026-07-16T00:31:51.820412Z","steps":["trace[92749502] 'process raft request' (duration: 299.703621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:31:51.820545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.158243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a44e162a-e883-4a64-bfee-9e15040a4586\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-16T00:31:51.820575Z","caller":"traceutil/trace.go:171","msg":"trace[282033709] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a44e162a-e883-4a64-bfee-9e15040a4586; range_end:; response_count:1; response_revision:5388; }","duration":"298.229475ms","start":"2026-07-16T00:31:51.522337Z","end":"2026-07-16T00:31:51.820567Z","steps":["trace[282033709] 'agreement among raft nodes before linearized reading' (duration: 298.093552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:31:51.820677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:31:51.520404Z","time spent":"300.161871ms","remote":"127.0.0.1:44354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8456,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:31:51.950578Z","caller":"traceutil/trace.go:171","msg":"trace[103316602] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"341.153851ms","start":"2026-07-16T00:31:51.609402Z","end":"2026-07-16T00:31:51.950555Z","steps":["trace[103316602] 'process raft request' (duration: 261.726384ms)","trace[103316602] 'compare' (duration: 79.237283ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:31:51.95083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:31:51.609377Z","time spent":"341.261614ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:31:51.950816Z","caller":"traceutil/trace.go:171","msg":"trace[1253037867] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"331.787533ms","start":"2026-07-16T00:31:51.618992Z","end":"2026-07-16T00:31:51.95078Z","steps":["trace[1253037867] 'process raft request' (duration: 331.507207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:31:51.950904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.499792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-g6pjw\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-07-16T00:31:51.950978Z","caller":"traceutil/trace.go:171","msg":"trace[646149172] range","detail":"{range_begin:/registry/pods/openstack/keepalived-g6pjw; range_end:; response_count:1; response_revision:5390; }","duration":"371.671586ms","start":"2026-07-16T00:31:51.579293Z","end":"2026-07-16T00:31:51.950964Z","steps":["trace[646149172] 'agreement among raft nodes before linearized reading' (duration: 371.328718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:31:51.950993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:31:51.618968Z","time spent":"331.928766ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T00:31:51.951078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:31:51.579266Z","time spent":"371.79834ms","remote":"127.0.0.1:44354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-g6pjw\" "} {"level":"warn","ts":"2026-07-16T00:31:51.951056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.559118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-16T00:31:51.950577Z","caller":"traceutil/trace.go:171","msg":"trace[257876161] linearizableReadLoop","detail":"{readStateIndex:5590; appliedIndex:5588; }","duration":"130.147794ms","start":"2026-07-16T00:31:51.820406Z","end":"2026-07-16T00:31:51.950554Z","steps":["trace[257876161] 'read index received' (duration: 50.704036ms)","trace[257876161] 'applied index is now lower than readState.Index' (duration: 79.442638ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:31:51.951278Z","caller":"traceutil/trace.go:171","msg":"trace[808670058] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5390; }","duration":"249.775654ms","start":"2026-07-16T00:31:51.701416Z","end":"2026-07-16T00:31:51.951192Z","steps":["trace[808670058] 'agreement among raft nodes before linearized reading' (duration: 249.460216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:31:51.951416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.417856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a44e162a-e883-4a64-bfee-9e15040a4586\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-16T00:31:51.951633Z","caller":"traceutil/trace.go:171","msg":"trace[387945063] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a44e162a-e883-4a64-bfee-9e15040a4586; range_end:; response_count:1; response_revision:5390; }","duration":"124.558489ms","start":"2026-07-16T00:31:51.826888Z","end":"2026-07-16T00:31:51.951446Z","steps":["trace[387945063] 'agreement among raft nodes before linearized reading' (duration: 124.383645ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:33:22.940124Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3013} {"level":"info","ts":"2026-07-16T00:33:23.032484Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3013,"took":"90.849586ms","hash":3813705013,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":32256000,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-16T00:33:23.032593Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3813705013,"revision":3013,"compact-revision":-1} {"level":"info","ts":"2026-07-16T00:38:03.007106Z","caller":"traceutil/trace.go:171","msg":"trace[935985633] linearizableReadLoop","detail":"{readStateIndex:8492; appliedIndex:8491; }","duration":"248.437157ms","start":"2026-07-16T00:38:02.758641Z","end":"2026-07-16T00:38:03.007078Z","steps":["trace[935985633] 'read index received' (duration: 248.267503ms)","trace[935985633] 'applied index is now lower than readState.Index' (duration: 168.634µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:38:03.007174Z","caller":"traceutil/trace.go:171","msg":"trace[769684394] transaction","detail":"{read_only:false; response_revision:8204; number_of_response:1; }","duration":"280.252914ms","start":"2026-07-16T00:38:02.726878Z","end":"2026-07-16T00:38:03.007131Z","steps":["trace[769684394] 'process raft request' (duration: 280.040179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:38:03.00732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.671963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-16T00:38:03.007356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.42423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-16T00:38:03.007404Z","caller":"traceutil/trace.go:171","msg":"trace[1056772831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8204; }","duration":"244.539702ms","start":"2026-07-16T00:38:02.762853Z","end":"2026-07-16T00:38:03.007393Z","steps":["trace[1056772831] 'agreement among raft nodes before linearized reading' (duration: 244.374738ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:38:03.007406Z","caller":"traceutil/trace.go:171","msg":"trace[696962847] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8204; }","duration":"248.801106ms","start":"2026-07-16T00:38:02.758587Z","end":"2026-07-16T00:38:03.007388Z","steps":["trace[696962847] 'agreement among raft nodes before linearized reading' (duration: 248.600721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:38:03.007466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.557345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-16T00:38:03.007519Z","caller":"traceutil/trace.go:171","msg":"trace[825630000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8204; }","duration":"142.657658ms","start":"2026-07-16T00:38:02.864849Z","end":"2026-07-16T00:38:03.007507Z","steps":["trace[825630000] 'agreement among raft nodes before linearized reading' (duration: 142.428871ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:38:16.043098Z","caller":"traceutil/trace.go:171","msg":"trace[910632540] linearizableReadLoop","detail":"{readStateIndex:8565; appliedIndex:8564; }","duration":"112.37541ms","start":"2026-07-16T00:38:15.930695Z","end":"2026-07-16T00:38:16.04307Z","steps":["trace[910632540] 'read index received' (duration: 112.173355ms)","trace[910632540] 'applied index is now lower than readState.Index' (duration: 200.935µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:38:16.043149Z","caller":"traceutil/trace.go:171","msg":"trace[1838657529] transaction","detail":"{read_only:false; response_revision:8274; number_of_response:1; }","duration":"118.124689ms","start":"2026-07-16T00:38:15.924999Z","end":"2026-07-16T00:38:16.043123Z","steps":["trace[1838657529] 'process raft request' (duration: 117.921835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:38:16.043352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.618316ms","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":"info","ts":"2026-07-16T00:38:16.043465Z","caller":"traceutil/trace.go:171","msg":"trace[485530494] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8274; }","duration":"112.78903ms","start":"2026-07-16T00:38:15.930651Z","end":"2026-07-16T00:38:16.04344Z","steps":["trace[485530494] 'agreement among raft nodes before linearized reading' (duration: 112.576025ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:38:22.94587Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6146} {"level":"info","ts":"2026-07-16T00:38:23.167385Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6146,"took":"219.816581ms","hash":3834782400,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":23433216,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-16T00:38:23.167454Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3834782400,"revision":6146,"compact-revision":3013} {"level":"info","ts":"2026-07-16T00:38:43.425086Z","caller":"traceutil/trace.go:171","msg":"trace[716154946] linearizableReadLoop","detail":"{readStateIndex:8775; appliedIndex:8774; }","duration":"153.192675ms","start":"2026-07-16T00:38:43.271871Z","end":"2026-07-16T00:38:43.425064Z","steps":["trace[716154946] 'read index received' (duration: 153.039721ms)","trace[716154946] 'applied index is now lower than readState.Index' (duration: 151.694µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:38:43.425387Z","caller":"traceutil/trace.go:171","msg":"trace[1384478313] transaction","detail":"{read_only:false; response_revision:8475; number_of_response:1; }","duration":"232.823148ms","start":"2026-07-16T00:38:43.19255Z","end":"2026-07-16T00:38:43.425373Z","steps":["trace[1384478313] 'process raft request' (duration: 232.374907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:38:43.426051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.89878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T00:38:43.426102Z","caller":"traceutil/trace.go:171","msg":"trace[1432676437] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8475; }","duration":"143.982002ms","start":"2026-07-16T00:38:43.282109Z","end":"2026-07-16T00:38:43.426091Z","steps":["trace[1432676437] 'agreement among raft nodes before linearized reading' (duration: 143.827478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:38:43.426239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.367414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-16T00:38:43.426272Z","caller":"traceutil/trace.go:171","msg":"trace[1248336978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8475; }","duration":"154.400045ms","start":"2026-07-16T00:38:43.271865Z","end":"2026-07-16T00:38:43.426265Z","steps":["trace[1248336978] 'agreement among raft nodes before linearized reading' (duration: 154.321363ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:38:43.56634Z","caller":"traceutil/trace.go:171","msg":"trace[1779474038] transaction","detail":"{read_only:false; response_revision:8476; number_of_response:1; }","duration":"134.751426ms","start":"2026-07-16T00:38:43.431549Z","end":"2026-07-16T00:38:43.5663Z","steps":["trace[1779474038] 'process raft request' (duration: 134.605722ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:38:43.568916Z","caller":"traceutil/trace.go:171","msg":"trace[157966035] transaction","detail":"{read_only:false; response_revision:8477; number_of_response:1; }","duration":"137.336449ms","start":"2026-07-16T00:38:43.431559Z","end":"2026-07-16T00:38:43.568896Z","steps":["trace[157966035] 'process raft request' (duration: 136.957779ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:38:43.569013Z","caller":"traceutil/trace.go:171","msg":"trace[590775632] transaction","detail":"{read_only:false; response_revision:8478; number_of_response:1; }","duration":"137.344409ms","start":"2026-07-16T00:38:43.431623Z","end":"2026-07-16T00:38:43.568967Z","steps":["trace[590775632] 'process raft request' (duration: 136.993861ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:38:43.569104Z","caller":"traceutil/trace.go:171","msg":"trace[792515202] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"137.080573ms","start":"2026-07-16T00:38:43.432008Z","end":"2026-07-16T00:38:43.569088Z","steps":["trace[792515202] 'process raft request' (duration: 136.663173ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:38:43.569268Z","caller":"traceutil/trace.go:171","msg":"trace[1208465980] transaction","detail":"{read_only:false; response_revision:8480; number_of_response:1; }","duration":"118.349157ms","start":"2026-07-16T00:38:43.450888Z","end":"2026-07-16T00:38:43.569238Z","steps":["trace[1208465980] 'process raft request' (duration: 117.825724ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:38:43.569337Z","caller":"traceutil/trace.go:171","msg":"trace[1766512185] transaction","detail":"{read_only:false; response_revision:8481; number_of_response:1; }","duration":"116.460941ms","start":"2026-07-16T00:38:43.452865Z","end":"2026-07-16T00:38:43.569326Z","steps":["trace[1766512185] 'process raft request' (duration: 115.906787ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:38:56.184872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.755961ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-07-16T00:39:12.525376Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002391.wal"} {"level":"info","ts":"2026-07-16T00:40:58.259319Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-16T00:40:58.266077Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-16T00:40:58.26617Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-16T00:43:22.95111Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8318} {"level":"info","ts":"2026-07-16T00:43:23.028544Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8318,"took":"74.362301ms","hash":3940503786,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":23683072,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-16T00:43:23.028636Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3940503786,"revision":8318,"compact-revision":6146} {"level":"info","ts":"2026-07-16T00:45:49.424299Z","caller":"traceutil/trace.go:171","msg":"trace[1961969285] transaction","detail":"{read_only:false; response_revision:11758; number_of_response:1; }","duration":"169.956649ms","start":"2026-07-16T00:45:49.25432Z","end":"2026-07-16T00:45:49.424277Z","steps":["trace[1961969285] 'process raft request' (duration: 169.816196ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:48:22.95728Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10537} {"level":"info","ts":"2026-07-16T00:48:23.029168Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10537,"took":"69.662298ms","hash":4244545274,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":25903104,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-16T00:48:23.029225Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4244545274,"revision":10537,"compact-revision":8318} {"level":"info","ts":"2026-07-16T00:52:35.814957Z","caller":"traceutil/trace.go:171","msg":"trace[864870386] transaction","detail":"{read_only:false; response_revision:14583; number_of_response:1; }","duration":"198.516082ms","start":"2026-07-16T00:52:35.616417Z","end":"2026-07-16T00:52:35.814933Z","steps":["trace[864870386] 'process raft request' (duration: 198.369199ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:52:35.815001Z","caller":"traceutil/trace.go:171","msg":"trace[2091890356] linearizableReadLoop","detail":"{readStateIndex:15083; appliedIndex:15083; }","duration":"161.691096ms","start":"2026-07-16T00:52:35.653287Z","end":"2026-07-16T00:52:35.814978Z","steps":["trace[2091890356] 'read index received' (duration: 161.682805ms)","trace[2091890356] 'applied index is now lower than readState.Index' (duration: 7.061µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:52:35.815338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.020384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:415445"} {"level":"info","ts":"2026-07-16T00:52:35.815414Z","caller":"traceutil/trace.go:171","msg":"trace[1723247579] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:14583; }","duration":"162.143447ms","start":"2026-07-16T00:52:35.653253Z","end":"2026-07-16T00:52:35.815396Z","steps":["trace[1723247579] 'agreement among raft nodes before linearized reading' (duration: 161.832689ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:52:36.107572Z","caller":"traceutil/trace.go:171","msg":"trace[256582648] linearizableReadLoop","detail":"{readStateIndex:15084; appliedIndex:15083; }","duration":"292.49194ms","start":"2026-07-16T00:52:35.815057Z","end":"2026-07-16T00:52:36.107549Z","steps":["trace[256582648] 'read index received' (duration: 201.796192ms)","trace[256582648] 'applied index is now lower than readState.Index' (duration: 90.694928ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:52:36.107633Z","caller":"traceutil/trace.go:171","msg":"trace[105684076] transaction","detail":"{read_only:false; response_revision:14584; number_of_response:1; }","duration":"315.10925ms","start":"2026-07-16T00:52:35.792487Z","end":"2026-07-16T00:52:36.107597Z","steps":["trace[105684076] 'process raft request' (duration: 224.277879ms)","trace[105684076] 'compare' (duration: 90.657137ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:52:36.107759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.972769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-16T00:52:36.107767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:52:35.792466Z","time spent":"315.233903ms","remote":"127.0.0.1:44250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:52:36.107789Z","caller":"traceutil/trace.go:171","msg":"trace[1025591475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14584; }","duration":"426.04544ms","start":"2026-07-16T00:52:35.681737Z","end":"2026-07-16T00:52:36.107783Z","steps":["trace[1025591475] 'agreement among raft nodes before linearized reading' (duration: 425.909307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:52:36.107815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:52:35.681725Z","time spent":"426.083802ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T00:52:36.107822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.434339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-16T00:52:36.107904Z","caller":"traceutil/trace.go:171","msg":"trace[700066433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14584; }","duration":"421.550161ms","start":"2026-07-16T00:52:35.686335Z","end":"2026-07-16T00:52:36.107886Z","steps":["trace[700066433] 'agreement among raft nodes before linearized reading' (duration: 421.249354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:52:36.107953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:52:35.686329Z","time spent":"421.616253ms","remote":"127.0.0.1:44414","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-07-16T00:52:36.108008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.925333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-16T00:52:36.108034Z","caller":"traceutil/trace.go:171","msg":"trace[1816515353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14584; }","duration":"431.992635ms","start":"2026-07-16T00:52:35.676035Z","end":"2026-07-16T00:52:36.108027Z","steps":["trace[1816515353] 'agreement among raft nodes before linearized reading' (duration: 431.920303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:52:36.108053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:52:35.676009Z","time spent":"432.040336ms","remote":"127.0.0.1:44414","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-07-16T00:52:36.108142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.792854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:138"} {"level":"info","ts":"2026-07-16T00:52:36.108164Z","caller":"traceutil/trace.go:171","msg":"trace[1401778929] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:14584; }","duration":"114.859696ms","start":"2026-07-16T00:52:35.993299Z","end":"2026-07-16T00:52:36.108159Z","steps":["trace[1401778929] 'agreement among raft nodes before linearized reading' (duration: 114.800505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:52:36.108182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.133255ms","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-07-16T00:52:36.10825Z","caller":"traceutil/trace.go:171","msg":"trace[1091559504] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:14584; }","duration":"173.238087ms","start":"2026-07-16T00:52:35.934996Z","end":"2026-07-16T00:52:36.108234Z","steps":["trace[1091559504] 'agreement among raft nodes before linearized reading' (duration: 173.110184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:52:36.108193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.268261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-16T00:52:36.108322Z","caller":"traceutil/trace.go:171","msg":"trace[1035990177] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:14584; }","duration":"206.431005ms","start":"2026-07-16T00:52:35.901873Z","end":"2026-07-16T00:52:36.108304Z","steps":["trace[1035990177] 'agreement among raft nodes before linearized reading' (duration: 206.20921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:52:36.108592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.228078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-16T00:52:36.108775Z","caller":"traceutil/trace.go:171","msg":"trace[957710641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14584; }","duration":"422.477033ms","start":"2026-07-16T00:52:35.686279Z","end":"2026-07-16T00:52:36.108756Z","steps":["trace[957710641] 'agreement among raft nodes before linearized reading' (duration: 421.331326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:52:36.109023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:52:35.686267Z","time spent":"422.699499ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-16T00:53:22.963581Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12791} {"level":"info","ts":"2026-07-16T00:53:23.039154Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12791,"took":"72.074365ms","hash":4051894389,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":30859264,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-16T00:53:23.039228Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4051894389,"revision":12791,"compact-revision":10537} {"level":"info","ts":"2026-07-16T00:58:22.970946Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15313} {"level":"info","ts":"2026-07-16T00:58:23.064857Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15313,"took":"90.417854ms","hash":223972536,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":31170560,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-16T00:58:23.064938Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":223972536,"revision":15313,"compact-revision":12791} {"level":"warn","ts":"2026-07-16T01:00:00.317962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.432052ms","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-07-16T01:00:00.31805Z","caller":"traceutil/trace.go:171","msg":"trace[918561522] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18306; }","duration":"133.547366ms","start":"2026-07-16T01:00:00.184484Z","end":"2026-07-16T01:00:00.318032Z","steps":["trace[918561522] 'range keys from in-memory index tree' (duration: 133.31472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:00:00.318198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.499837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-6lspj\" ","response":"range_response_count:1 size:22094"} {"level":"info","ts":"2026-07-16T01:00:00.318252Z","caller":"traceutil/trace.go:171","msg":"trace[605115768] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-6lspj; range_end:; response_count:1; response_revision:18306; }","duration":"247.58908ms","start":"2026-07-16T01:00:00.070649Z","end":"2026-07-16T01:00:00.318238Z","steps":["trace[605115768] 'range keys from in-memory index tree' (duration: 247.389654ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:00:00.318377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.849255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-07-16T01:00:00.318432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.717018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:1 size:202"} {"level":"info","ts":"2026-07-16T01:00:00.318507Z","caller":"traceutil/trace.go:171","msg":"trace[1552809769] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:1; response_revision:18306; }","duration":"215.838491ms","start":"2026-07-16T01:00:00.102653Z","end":"2026-07-16T01:00:00.318491Z","steps":["trace[1552809769] 'range keys from in-memory index tree' (duration: 215.570374ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:00:00.318455Z","caller":"traceutil/trace.go:171","msg":"trace[1375696143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18306; }","duration":"136.933887ms","start":"2026-07-16T01:00:00.181503Z","end":"2026-07-16T01:00:00.318436Z","steps":["trace[1375696143] 'range keys from in-memory index tree' (duration: 136.738133ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:00:00.393103Z","caller":"traceutil/trace.go:171","msg":"trace[1253579982] transaction","detail":"{read_only:false; response_revision:18310; number_of_response:1; }","duration":"100.360762ms","start":"2026-07-16T01:00:00.29272Z","end":"2026-07-16T01:00:00.393081Z","steps":["trace[1253579982] 'process raft request' (duration: 100.289211ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:00:00.393141Z","caller":"traceutil/trace.go:171","msg":"trace[786168081] transaction","detail":"{read_only:false; response_revision:18308; number_of_response:1; }","duration":"120.762056ms","start":"2026-07-16T01:00:00.272317Z","end":"2026-07-16T01:00:00.393079Z","steps":["trace[786168081] 'process raft request' (duration: 120.530411ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:00:00.393162Z","caller":"traceutil/trace.go:171","msg":"trace[1287194218] transaction","detail":"{read_only:false; response_revision:18309; number_of_response:1; }","duration":"100.486925ms","start":"2026-07-16T01:00:00.292634Z","end":"2026-07-16T01:00:00.393121Z","steps":["trace[1287194218] 'process raft request' (duration: 100.336722ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:00:00.393461Z","caller":"traceutil/trace.go:171","msg":"trace[1707519256] linearizableReadLoop","detail":"{readStateIndex:18931; appliedIndex:18929; }","duration":"111.976484ms","start":"2026-07-16T01:00:00.281467Z","end":"2026-07-16T01:00:00.393444Z","steps":["trace[1707519256] 'read index received' (duration: 60.245771ms)","trace[1707519256] 'applied index is now lower than readState.Index' (duration: 51.729743ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:00:00.39379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.306141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-fqwgn\" ","response":"range_response_count:1 size:9792"} {"level":"info","ts":"2026-07-16T01:00:00.393869Z","caller":"traceutil/trace.go:171","msg":"trace[463151373] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-fqwgn; range_end:; response_count:1; response_revision:18314; }","duration":"112.424714ms","start":"2026-07-16T01:00:00.281429Z","end":"2026-07-16T01:00:00.393854Z","steps":["trace[463151373] 'agreement among raft nodes before linearized reading' (duration: 112.106296ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:00:00.393811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.267893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-16T01:00:00.394063Z","caller":"traceutil/trace.go:171","msg":"trace[1658381984] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:18314; }","duration":"108.544469ms","start":"2026-07-16T01:00:00.285492Z","end":"2026-07-16T01:00:00.394036Z","steps":["trace[1658381984] 'agreement among raft nodes before linearized reading' (duration: 108.068728ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:00:00.515743Z","caller":"traceutil/trace.go:171","msg":"trace[2019018991] transaction","detail":"{read_only:false; response_revision:18316; number_of_response:1; }","duration":"109.3575ms","start":"2026-07-16T01:00:00.406352Z","end":"2026-07-16T01:00:00.51571Z","steps":["trace[2019018991] 'process raft request' (duration: 109.222297ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:01:46.788681Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-16T01:01:46.795808Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-16T01:01:46.796038Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-16T01:03:22.977373Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17538} {"level":"info","ts":"2026-07-16T01:03:23.064267Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17538,"took":"84.530218ms","hash":4133358860,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":34615296,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-07-16T01:03:23.064321Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4133358860,"revision":17538,"compact-revision":15313} {"level":"info","ts":"2026-07-16T01:04:13.222933Z","caller":"traceutil/trace.go:171","msg":"trace[780050825] linearizableReadLoop","detail":"{readStateIndex:21095; appliedIndex:21094; }","duration":"110.758804ms","start":"2026-07-16T01:04:13.112118Z","end":"2026-07-16T01:04:13.222877Z","steps":["trace[780050825] 'read index received' (duration: 109.73049ms)","trace[780050825] 'applied index is now lower than readState.Index' (duration: 1.025924ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:04:13.223048Z","caller":"traceutil/trace.go:171","msg":"trace[63802754] transaction","detail":"{read_only:false; response_revision:20406; number_of_response:1; }","duration":"140.515655ms","start":"2026-07-16T01:04:13.082502Z","end":"2026-07-16T01:04:13.223018Z","steps":["trace[63802754] 'process raft request' (duration: 139.332386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:04:13.223379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.227516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/heat-api-5cc56f89f8-kc2fz.18c29f86a7675c34\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-07-16T01:04:13.223446Z","caller":"traceutil/trace.go:171","msg":"trace[793784967] range","detail":"{range_begin:/registry/events/openstack/heat-api-5cc56f89f8-kc2fz.18c29f86a7675c34; range_end:; response_count:1; response_revision:20406; }","duration":"111.352558ms","start":"2026-07-16T01:04:13.112073Z","end":"2026-07-16T01:04:13.223426Z","steps":["trace[793784967] 'agreement among raft nodes before linearized reading' (duration: 111.065271ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:05:25.56255Z","caller":"traceutil/trace.go:171","msg":"trace[2111985923] transaction","detail":"{read_only:false; response_revision:20887; number_of_response:1; }","duration":"116.667238ms","start":"2026-07-16T01:05:25.445854Z","end":"2026-07-16T01:05:25.562521Z","steps":["trace[2111985923] 'process raft request' (duration: 116.487253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:05:31.571088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.608833ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-16T01:05:31.57148Z","caller":"traceutil/trace.go:171","msg":"trace[174929924] linearizableReadLoop","detail":"{readStateIndex:21625; appliedIndex:21624; }","duration":"188.135438ms","start":"2026-07-16T01:05:31.383295Z","end":"2026-07-16T01:05:31.571431Z","steps":["trace[174929924] 'read index received' (duration: 22.088395ms)","trace[174929924] 'applied index is now lower than readState.Index' (duration: 166.045133ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:05:31.571587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.282922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:05:31.571626Z","caller":"traceutil/trace.go:171","msg":"trace[1516777883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20919; }","duration":"188.331343ms","start":"2026-07-16T01:05:31.383281Z","end":"2026-07-16T01:05:31.571612Z","steps":["trace[1516777883] 'agreement among raft nodes before linearized reading' (duration: 188.244121ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:05:31.751673Z","caller":"traceutil/trace.go:171","msg":"trace[1809899758] transaction","detail":"{read_only:false; response_revision:20920; number_of_response:1; }","duration":"162.661261ms","start":"2026-07-16T01:05:31.58899Z","end":"2026-07-16T01:05:31.751651Z","steps":["trace[1809899758] 'process raft request' (duration: 162.528498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:05:32.043472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.245314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:05:32.043677Z","caller":"traceutil/trace.go:171","msg":"trace[602998663] linearizableReadLoop","detail":"{readStateIndex:21629; appliedIndex:21626; }","duration":"160.393496ms","start":"2026-07-16T01:05:31.883267Z","end":"2026-07-16T01:05:32.043661Z","steps":["trace[602998663] 'read index received' (duration: 13.807914ms)","trace[602998663] 'applied index is now lower than readState.Index' (duration: 146.584382ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:05:32.043854Z","caller":"traceutil/trace.go:171","msg":"trace[258586352] transaction","detail":"{read_only:false; response_revision:20922; number_of_response:1; }","duration":"452.912265ms","start":"2026-07-16T01:05:31.590895Z","end":"2026-07-16T01:05:32.043808Z","steps":["trace[258586352] 'process raft request' (duration: 452.682079ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:05:32.043895Z","caller":"traceutil/trace.go:171","msg":"trace[142665958] transaction","detail":"{read_only:false; response_revision:20923; number_of_response:1; }","duration":"452.25556ms","start":"2026-07-16T01:05:31.591592Z","end":"2026-07-16T01:05:32.043848Z","steps":["trace[142665958] 'process raft request' (duration: 452.030823ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:05:32.043879Z","caller":"traceutil/trace.go:171","msg":"trace[1023902791] transaction","detail":"{read_only:false; response_revision:20921; number_of_response:1; }","duration":"454.414973ms","start":"2026-07-16T01:05:31.589426Z","end":"2026-07-16T01:05:32.043841Z","steps":["trace[1023902791] 'process raft request' (duration: 307.717177ms)","trace[1023902791] 'compare' (duration: 146.09985ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:05:32.043985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:05:31.591586Z","time spent":"452.354432ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:05:32.04399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.734635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-16T01:05:32.043999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:05:31.590888Z","time spent":"453.037649ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:05:32.044053Z","caller":"traceutil/trace.go:171","msg":"trace[1062012149] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20923; }","duration":"160.813447ms","start":"2026-07-16T01:05:31.88322Z","end":"2026-07-16T01:05:32.044033Z","steps":["trace[1062012149] 'agreement among raft nodes before linearized reading' (duration: 160.606521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:05:32.044163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:05:31.589414Z","time spent":"454.633627ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:05:32.179532Z","caller":"traceutil/trace.go:171","msg":"trace[2132427068] transaction","detail":"{read_only:false; response_revision:20925; number_of_response:1; }","duration":"130.887352ms","start":"2026-07-16T01:05:32.048615Z","end":"2026-07-16T01:05:32.179502Z","steps":["trace[2132427068] 'process raft request' (duration: 109.073613ms)","trace[2132427068] 'compare' (duration: 21.667995ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:05:32.179715Z","caller":"traceutil/trace.go:171","msg":"trace[183976244] linearizableReadLoop","detail":"{readStateIndex:21631; appliedIndex:21630; }","duration":"112.295841ms","start":"2026-07-16T01:05:32.067408Z","end":"2026-07-16T01:05:32.179704Z","steps":["trace[183976244] 'read index received' (duration: 90.273488ms)","trace[183976244] 'applied index is now lower than readState.Index' (duration: 21.787898ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:05:32.179813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.389694ms","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-07-16T01:05:32.179886Z","caller":"traceutil/trace.go:171","msg":"trace[805643292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20925; }","duration":"112.508676ms","start":"2026-07-16T01:05:32.067364Z","end":"2026-07-16T01:05:32.179873Z","steps":["trace[805643292] 'agreement among raft nodes before linearized reading' (duration: 112.307092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:05:32.17999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.324042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-16T01:05:32.180029Z","caller":"traceutil/trace.go:171","msg":"trace[1098297007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20925; }","duration":"112.443245ms","start":"2026-07-16T01:05:32.067575Z","end":"2026-07-16T01:05:32.180018Z","steps":["trace[1098297007] 'agreement among raft nodes before linearized reading' (duration: 112.22008ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:05:32.342427Z","caller":"traceutil/trace.go:171","msg":"trace[1307361258] transaction","detail":"{read_only:false; response_revision:20926; number_of_response:1; }","duration":"157.917557ms","start":"2026-07-16T01:05:32.184478Z","end":"2026-07-16T01:05:32.342396Z","steps":["trace[1307361258] 'process raft request' (duration: 157.124878ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:05:32.356345Z","caller":"traceutil/trace.go:171","msg":"trace[622308267] transaction","detail":"{read_only:false; response_revision:20927; number_of_response:1; }","duration":"171.6996ms","start":"2026-07-16T01:05:32.184623Z","end":"2026-07-16T01:05:32.356322Z","steps":["trace[622308267] 'process raft request' (duration: 171.478655ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:08:22.985504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19979} {"level":"info","ts":"2026-07-16T01:08:23.105088Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19979,"took":"116.065134ms","hash":3155997064,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":30765056,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-16T01:08:23.105156Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3155997064,"revision":19979,"compact-revision":17538} {"level":"info","ts":"2026-07-16T01:09:33.125386Z","caller":"traceutil/trace.go:171","msg":"trace[339893987] transaction","detail":"{read_only:false; response_revision:23393; number_of_response:1; }","duration":"215.744198ms","start":"2026-07-16T01:09:32.909612Z","end":"2026-07-16T01:09:33.125356Z","steps":["trace[339893987] 'process raft request' (duration: 196.676075ms)","trace[339893987] 'compare' (duration: 18.810487ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:09:33.179205Z","caller":"traceutil/trace.go:171","msg":"trace[2003515002] transaction","detail":"{read_only:false; response_revision:23394; number_of_response:1; }","duration":"252.384519ms","start":"2026-07-16T01:09:32.926779Z","end":"2026-07-16T01:09:33.179163Z","steps":["trace[2003515002] 'process raft request' (duration: 252.211885ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:09:33.179241Z","caller":"traceutil/trace.go:171","msg":"trace[1149306770] transaction","detail":"{read_only:false; response_revision:23395; number_of_response:1; }","duration":"226.344501ms","start":"2026-07-16T01:09:32.952849Z","end":"2026-07-16T01:09:33.179193Z","steps":["trace[1149306770] 'process raft request' (duration: 226.228808ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:11:36.033838Z","caller":"traceutil/trace.go:171","msg":"trace[1339801889] transaction","detail":"{read_only:false; response_revision:24703; number_of_response:1; }","duration":"168.194938ms","start":"2026-07-16T01:11:35.865624Z","end":"2026-07-16T01:11:36.033819Z","steps":["trace[1339801889] 'process raft request' (duration: 168.081156ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:11:36.034213Z","caller":"traceutil/trace.go:171","msg":"trace[953755843] linearizableReadLoop","detail":"{readStateIndex:25511; appliedIndex:25511; }","duration":"103.91736ms","start":"2026-07-16T01:11:35.93029Z","end":"2026-07-16T01:11:36.034207Z","steps":["trace[953755843] 'read index received' (duration: 103.91402ms)","trace[953755843] 'applied index is now lower than readState.Index' (duration: 2.88µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:11:36.034361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.068804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:215"} {"level":"info","ts":"2026-07-16T01:11:36.034407Z","caller":"traceutil/trace.go:171","msg":"trace[1206861696] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:24703; }","duration":"104.161976ms","start":"2026-07-16T01:11:35.930239Z","end":"2026-07-16T01:11:36.034401Z","steps":["trace[1206861696] 'agreement among raft nodes before linearized reading' (duration: 104.033873ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:11:43.806154Z","caller":"traceutil/trace.go:171","msg":"trace[1511735582] transaction","detail":"{read_only:false; response_revision:24758; number_of_response:1; }","duration":"131.088947ms","start":"2026-07-16T01:11:43.67505Z","end":"2026-07-16T01:11:43.806139Z","steps":["trace[1511735582] 'process raft request' (duration: 130.887041ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:11:43.806392Z","caller":"traceutil/trace.go:171","msg":"trace[362437994] transaction","detail":"{read_only:false; response_revision:24759; number_of_response:1; }","duration":"130.934433ms","start":"2026-07-16T01:11:43.675436Z","end":"2026-07-16T01:11:43.80637Z","steps":["trace[362437994] 'process raft request' (duration: 130.663597ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:11:43.80656Z","caller":"traceutil/trace.go:171","msg":"trace[1619910296] transaction","detail":"{read_only:false; response_revision:24760; number_of_response:1; }","duration":"128.022964ms","start":"2026-07-16T01:11:43.678507Z","end":"2026-07-16T01:11:43.80653Z","steps":["trace[1619910296] 'process raft request' (duration: 127.773128ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:11:43.806007Z","caller":"traceutil/trace.go:171","msg":"trace[1421334491] transaction","detail":"{read_only:false; response_revision:24757; number_of_response:1; }","duration":"153.429537ms","start":"2026-07-16T01:11:43.65255Z","end":"2026-07-16T01:11:43.805979Z","steps":["trace[1421334491] 'process raft request' (duration: 119.565162ms)","trace[1421334491] 'compare' (duration: 33.711961ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:12:50.208567Z","caller":"traceutil/trace.go:171","msg":"trace[2104478726] transaction","detail":"{read_only:false; response_revision:25421; number_of_response:1; }","duration":"117.975234ms","start":"2026-07-16T01:12:50.090577Z","end":"2026-07-16T01:12:50.208553Z","steps":["trace[2104478726] 'process raft request' (duration: 117.892272ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:50.658429Z","caller":"traceutil/trace.go:171","msg":"trace[1000085375] linearizableReadLoop","detail":"{readStateIndex:26254; appliedIndex:26250; }","duration":"449.996218ms","start":"2026-07-16T01:12:50.208413Z","end":"2026-07-16T01:12:50.65841Z","steps":["trace[1000085375] 'read index received' (duration: 449.69628ms)","trace[1000085375] 'applied index is now lower than readState.Index' (duration: 299.018µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:12:50.658503Z","caller":"traceutil/trace.go:171","msg":"trace[153011428] transaction","detail":"{read_only:false; response_revision:25423; number_of_response:1; }","duration":"566.522109ms","start":"2026-07-16T01:12:50.091946Z","end":"2026-07-16T01:12:50.658468Z","steps":["trace[153011428] 'process raft request' (duration: 566.366885ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:50.658508Z","caller":"traceutil/trace.go:171","msg":"trace[1144651535] transaction","detail":"{read_only:false; response_revision:25424; number_of_response:1; }","duration":"566.449727ms","start":"2026-07-16T01:12:50.092031Z","end":"2026-07-16T01:12:50.65848Z","steps":["trace[1144651535] 'process raft request' (duration: 566.307304ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:50.658573Z","caller":"traceutil/trace.go:171","msg":"trace[365542112] transaction","detail":"{read_only:false; response_revision:25425; number_of_response:1; }","duration":"532.064019ms","start":"2026-07-16T01:12:50.126474Z","end":"2026-07-16T01:12:50.658538Z","steps":["trace[365542112] 'process raft request' (duration: 531.894255ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:50.658646Z","caller":"traceutil/trace.go:171","msg":"trace[1291233286] transaction","detail":"{read_only:false; response_revision:25422; number_of_response:1; }","duration":"567.785689ms","start":"2026-07-16T01:12:50.09081Z","end":"2026-07-16T01:12:50.658595Z","steps":["trace[1291233286] 'process raft request' (duration: 567.346538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:50.658679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:50.09193Z","time spent":"566.653112ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:12:50.658685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.253044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-07-16T01:12:50.65882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:50.090787Z","time spent":"567.948392ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:12:50.658826Z","caller":"traceutil/trace.go:171","msg":"trace[764429621] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25425; }","duration":"450.422529ms","start":"2026-07-16T01:12:50.208386Z","end":"2026-07-16T01:12:50.658809Z","steps":["trace[764429621] 'agreement among raft nodes before linearized reading' (duration: 450.182483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:50.658838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:50.12645Z","time spent":"532.257933ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:12:50.658877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:50.208369Z","time spent":"450.49465ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-16T01:12:50.658755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:50.092022Z","time spent":"566.618811ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:12:50.722306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.523135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:12:50.722396Z","caller":"traceutil/trace.go:171","msg":"trace[1975802909] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25426; }","duration":"406.662388ms","start":"2026-07-16T01:12:50.315707Z","end":"2026-07-16T01:12:50.72237Z","steps":["trace[1975802909] 'agreement among raft nodes before linearized reading' (duration: 406.496284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:50.722378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.731377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-16T01:12:50.722456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:50.315688Z","time spent":"406.75297ms","remote":"127.0.0.1:52346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true "} {"level":"info","ts":"2026-07-16T01:12:50.72251Z","caller":"traceutil/trace.go:171","msg":"trace[1423180958] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:25426; }","duration":"175.900221ms","start":"2026-07-16T01:12:50.546574Z","end":"2026-07-16T01:12:50.722474Z","steps":["trace[1423180958] 'agreement among raft nodes before linearized reading' (duration: 175.697517ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:50.72268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.532718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-16T01:12:50.722824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.443692ms","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-07-16T01:12:50.722881Z","caller":"traceutil/trace.go:171","msg":"trace[1347928822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25426; }","duration":"236.522283ms","start":"2026-07-16T01:12:50.486344Z","end":"2026-07-16T01:12:50.722867Z","steps":["trace[1347928822] 'agreement among raft nodes before linearized reading' (duration: 236.3872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:50.72299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.541216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:12:50.723029Z","caller":"traceutil/trace.go:171","msg":"trace[249483503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25426; }","duration":"340.612258ms","start":"2026-07-16T01:12:50.382406Z","end":"2026-07-16T01:12:50.723019Z","steps":["trace[249483503] 'agreement among raft nodes before linearized reading' (duration: 340.547687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:50.723012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.306222ms","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-07-16T01:12:50.723052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:50.382389Z","time spent":"340.657259ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T01:12:50.723071Z","caller":"traceutil/trace.go:171","msg":"trace[1047046025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25426; }","duration":"282.395193ms","start":"2026-07-16T01:12:50.440668Z","end":"2026-07-16T01:12:50.723063Z","steps":["trace[1047046025] 'agreement among raft nodes before linearized reading' (duration: 282.235459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:50.723233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.611349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-16T01:12:50.723294Z","caller":"traceutil/trace.go:171","msg":"trace[810873672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25426; }","duration":"237.744532ms","start":"2026-07-16T01:12:50.485543Z","end":"2026-07-16T01:12:50.723288Z","steps":["trace[810873672] 'agreement among raft nodes before linearized reading' (duration: 237.620609ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:50.723411Z","caller":"traceutil/trace.go:171","msg":"trace[1920551836] transaction","detail":"{read_only:false; response_revision:25426; number_of_response:1; }","duration":"388.967057ms","start":"2026-07-16T01:12:50.334432Z","end":"2026-07-16T01:12:50.723399Z","steps":["trace[1920551836] 'process raft request' (duration: 387.626705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:50.723547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:50.33441Z","time spent":"389.05706ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:12:50.723426Z","caller":"traceutil/trace.go:171","msg":"trace[1619702716] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25426; }","duration":"232.722902ms","start":"2026-07-16T01:12:50.49013Z","end":"2026-07-16T01:12:50.722853Z","steps":["trace[1619702716] 'agreement among raft nodes before linearized reading' (duration: 232.100337ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:50.846904Z","caller":"traceutil/trace.go:171","msg":"trace[1972814526] linearizableReadLoop","detail":"{readStateIndex:26256; appliedIndex:26255; }","duration":"122.8535ms","start":"2026-07-16T01:12:50.724023Z","end":"2026-07-16T01:12:50.846876Z","steps":["trace[1972814526] 'read index received' (duration: 121.336234ms)","trace[1972814526] 'applied index is now lower than readState.Index' (duration: 1.515876ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:12:50.846951Z","caller":"traceutil/trace.go:171","msg":"trace[760597304] transaction","detail":"{read_only:false; response_revision:25427; number_of_response:1; }","duration":"184.443194ms","start":"2026-07-16T01:12:50.662483Z","end":"2026-07-16T01:12:50.846926Z","steps":["trace[760597304] 'process raft request' (duration: 182.867776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:50.847025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.985914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:12:50.847056Z","caller":"traceutil/trace.go:171","msg":"trace[983749951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25427; }","duration":"123.091556ms","start":"2026-07-16T01:12:50.723955Z","end":"2026-07-16T01:12:50.847047Z","steps":["trace[983749951] 'agreement among raft nodes before linearized reading' (duration: 123.010464ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:50.847744Z","caller":"traceutil/trace.go:171","msg":"trace[1572329124] transaction","detail":"{read_only:false; response_revision:25428; number_of_response:1; }","duration":"121.341364ms","start":"2026-07-16T01:12:50.726379Z","end":"2026-07-16T01:12:50.84772Z","steps":["trace[1572329124] 'process raft request' (duration: 121.190961ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:50.847992Z","caller":"traceutil/trace.go:171","msg":"trace[1214918091] transaction","detail":"{read_only:false; response_revision:25429; number_of_response:1; }","duration":"121.57009ms","start":"2026-07-16T01:12:50.726402Z","end":"2026-07-16T01:12:50.847972Z","steps":["trace[1214918091] 'process raft request' (duration: 121.281633ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:50.848065Z","caller":"traceutil/trace.go:171","msg":"trace[1949776761] transaction","detail":"{read_only:false; response_revision:25430; number_of_response:1; }","duration":"120.70641ms","start":"2026-07-16T01:12:50.727332Z","end":"2026-07-16T01:12:50.848038Z","steps":["trace[1949776761] 'process raft request' (duration: 120.550936ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:50.84813Z","caller":"traceutil/trace.go:171","msg":"trace[881589810] transaction","detail":"{read_only:false; response_revision:25431; number_of_response:1; }","duration":"120.075474ms","start":"2026-07-16T01:12:50.728035Z","end":"2026-07-16T01:12:50.84811Z","steps":["trace[881589810] 'process raft request' (duration: 119.925311ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:56.663129Z","caller":"traceutil/trace.go:171","msg":"trace[159078001] transaction","detail":"{read_only:false; response_revision:25479; number_of_response:1; }","duration":"524.949229ms","start":"2026-07-16T01:12:56.138151Z","end":"2026-07-16T01:12:56.6631Z","steps":["trace[159078001] 'process raft request' (duration: 482.087441ms)","trace[159078001] 'compare' (duration: 42.638153ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:12:56.663252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:56.138128Z","time spent":"525.065471ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:12:56.698247Z","caller":"traceutil/trace.go:171","msg":"trace[135386434] transaction","detail":"{read_only:false; response_revision:25480; number_of_response:1; }","duration":"469.158533ms","start":"2026-07-16T01:12:56.229063Z","end":"2026-07-16T01:12:56.698221Z","steps":["trace[135386434] 'process raft request' (duration: 468.926478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:56.698414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:56.229038Z","time spent":"469.289887ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:12:56.698475Z","caller":"traceutil/trace.go:171","msg":"trace[1086451886] linearizableReadLoop","detail":"{readStateIndex:26311; appliedIndex:26309; }","duration":"370.591871ms","start":"2026-07-16T01:12:56.327835Z","end":"2026-07-16T01:12:56.698426Z","steps":["trace[1086451886] 'read index received' (duration: 292.412823ms)","trace[1086451886] 'applied index is now lower than readState.Index' (duration: 78.176138ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:12:56.698509Z","caller":"traceutil/trace.go:171","msg":"trace[178929697] transaction","detail":"{read_only:false; response_revision:25481; number_of_response:1; }","duration":"368.749966ms","start":"2026-07-16T01:12:56.329721Z","end":"2026-07-16T01:12:56.698471Z","steps":["trace[178929697] 'process raft request' (duration: 368.431329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:56.698628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.780746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-16T01:12:56.698687Z","caller":"traceutil/trace.go:171","msg":"trace[2088403698] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:25481; }","duration":"370.881598ms","start":"2026-07-16T01:12:56.327793Z","end":"2026-07-16T01:12:56.698674Z","steps":["trace[2088403698] 'agreement among raft nodes before linearized reading' (duration: 370.763545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:56.698681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:56.329678Z","time spent":"368.91051ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:12:56.698729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:56.327771Z","time spent":"370.949209ms","remote":"127.0.0.1:44598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":19,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-07-16T01:12:57.124239Z","caller":"traceutil/trace.go:171","msg":"trace[1070151196] transaction","detail":"{read_only:false; response_revision:25482; number_of_response:1; }","duration":"446.346492ms","start":"2026-07-16T01:12:56.677859Z","end":"2026-07-16T01:12:57.124206Z","steps":["trace[1070151196] 'process raft request' (duration: 422.641787ms)","trace[1070151196] 'compare' (duration: 23.022008ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:12:57.124514Z","caller":"traceutil/trace.go:171","msg":"trace[1000270750] transaction","detail":"{read_only:false; response_revision:25483; number_of_response:1; }","duration":"446.019773ms","start":"2026-07-16T01:12:56.678468Z","end":"2026-07-16T01:12:57.124488Z","steps":["trace[1000270750] 'process raft request' (duration: 445.624714ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:57.124643Z","caller":"traceutil/trace.go:171","msg":"trace[1027205262] linearizableReadLoop","detail":"{readStateIndex:26315; appliedIndex:26312; }","duration":"426.074799ms","start":"2026-07-16T01:12:56.698551Z","end":"2026-07-16T01:12:57.124626Z","steps":["trace[1027205262] 'read index received' (duration: 401.959906ms)","trace[1027205262] 'applied index is now lower than readState.Index' (duration: 24.113943ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:12:57.124659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:56.678446Z","time spent":"446.138516ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:12:57.124681Z","caller":"traceutil/trace.go:171","msg":"trace[2124663413] transaction","detail":"{read_only:false; response_revision:25484; number_of_response:1; }","duration":"445.777908ms","start":"2026-07-16T01:12:56.678868Z","end":"2026-07-16T01:12:57.124646Z","steps":["trace[2124663413] 'process raft request' (duration: 445.524172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:57.124784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.370011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-07-16T01:12:57.124833Z","caller":"traceutil/trace.go:171","msg":"trace[481060798] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25484; }","duration":"459.457013ms","start":"2026-07-16T01:12:56.665363Z","end":"2026-07-16T01:12:57.12482Z","steps":["trace[481060798] 'agreement among raft nodes before linearized reading' (duration: 459.275549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:57.124841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:56.678844Z","time spent":"445.915701ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:12:57.124859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:56.665348Z","time spent":"459.505614ms","remote":"127.0.0.1:44332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-16T01:12:57.124877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.940409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:12:57.124976Z","caller":"traceutil/trace.go:171","msg":"trace[1732473997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25484; }","duration":"742.088022ms","start":"2026-07-16T01:12:56.382861Z","end":"2026-07-16T01:12:57.124949Z","steps":["trace[1732473997] 'agreement among raft nodes before linearized reading' (duration: 741.896448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:57.125029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:56.382838Z","time spent":"742.181155ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T01:12:57.125103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:56.677831Z","time spent":"446.567417ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:12:57.579559Z","caller":"traceutil/trace.go:171","msg":"trace[1631325689] transaction","detail":"{read_only:false; response_revision:25486; number_of_response:1; }","duration":"706.392884ms","start":"2026-07-16T01:12:56.873145Z","end":"2026-07-16T01:12:57.579538Z","steps":["trace[1631325689] 'process raft request' (duration: 706.290931ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:57.57962Z","caller":"traceutil/trace.go:171","msg":"trace[1360963096] transaction","detail":"{read_only:false; response_revision:25485; number_of_response:1; }","duration":"839.961688ms","start":"2026-07-16T01:12:56.7396Z","end":"2026-07-16T01:12:57.579562Z","steps":["trace[1360963096] 'process raft request' (duration: 782.181345ms)","trace[1360963096] 'compare' (duration: 57.298332ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:12:57.579755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:56.873127Z","time spent":"706.544427ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:12:57.579611Z","caller":"traceutil/trace.go:171","msg":"trace[1285813626] linearizableReadLoop","detail":"{readStateIndex:26318; appliedIndex:26315; }","duration":"454.898404ms","start":"2026-07-16T01:12:57.124689Z","end":"2026-07-16T01:12:57.579587Z","steps":["trace[1285813626] 'read index received' (duration: 397.106971ms)","trace[1285813626] 'applied index is now lower than readState.Index' (duration: 57.790323ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:12:57.579781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.586432ms","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-07-16T01:12:57.579816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:56.739573Z","time spent":"840.141673ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:12:57.579859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.060069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-16T01:12:57.579885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.69956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-16T01:12:57.57994Z","caller":"traceutil/trace.go:171","msg":"trace[144124382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25487; }","duration":"706.807493ms","start":"2026-07-16T01:12:56.873119Z","end":"2026-07-16T01:12:57.579926Z","steps":["trace[144124382] 'agreement among raft nodes before linearized reading' (duration: 706.66925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:57.579985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:56.873098Z","time spent":"706.874335ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-16T01:12:57.580077Z","caller":"traceutil/trace.go:171","msg":"trace[1927145263] transaction","detail":"{read_only:false; response_revision:25487; number_of_response:1; }","duration":"654.362466ms","start":"2026-07-16T01:12:56.925687Z","end":"2026-07-16T01:12:57.580049Z","steps":["trace[1927145263] 'process raft request' (duration: 653.818723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:57.580141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.121371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-07-16T01:12:57.580173Z","caller":"traceutil/trace.go:171","msg":"trace[1819791589] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:25487; }","duration":"670.176742ms","start":"2026-07-16T01:12:56.909987Z","end":"2026-07-16T01:12:57.580164Z","steps":["trace[1819791589] 'agreement among raft nodes before linearized reading' (duration: 670.05868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:57.5802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:56.909942Z","time spent":"670.253684ms","remote":"127.0.0.1:44370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":921,"request content":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" "} {"level":"warn","ts":"2026-07-16T01:12:57.580205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.214236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-16T01:12:57.580234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:56.925663Z","time spent":"654.507119ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:12:57.580236Z","caller":"traceutil/trace.go:171","msg":"trace[1920436746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25487; }","duration":"707.11065ms","start":"2026-07-16T01:12:56.872785Z","end":"2026-07-16T01:12:57.579895Z","steps":["trace[1920436746] 'agreement among raft nodes before linearized reading' (duration: 707.018188ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:57.580283Z","caller":"traceutil/trace.go:171","msg":"trace[1086807947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25487; }","duration":"708.327189ms","start":"2026-07-16T01:12:56.871937Z","end":"2026-07-16T01:12:57.580264Z","steps":["trace[1086807947] 'agreement among raft nodes before linearized reading' (duration: 708.103993ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:57.580323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:56.871921Z","time spent":"708.39389ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-16T01:12:57.58033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:56.872779Z","time spent":"707.53577ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-16T01:12:57.579859Z","caller":"traceutil/trace.go:171","msg":"trace[598072360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25487; }","duration":"710.698915ms","start":"2026-07-16T01:12:56.869146Z","end":"2026-07-16T01:12:57.579844Z","steps":["trace[598072360] 'agreement among raft nodes before linearized reading' (duration: 710.516581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:57.580422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.995101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-16T01:12:57.580423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:56.869129Z","time spent":"711.281069ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-16T01:12:57.580512Z","caller":"traceutil/trace.go:171","msg":"trace[2089709685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25487; }","duration":"708.103414ms","start":"2026-07-16T01:12:56.872401Z","end":"2026-07-16T01:12:57.580505Z","steps":["trace[2089709685] 'agreement among raft nodes before linearized reading' (duration: 707.484559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:57.580537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:56.872391Z","time spent":"708.141015ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-16T01:12:57.60015Z","caller":"traceutil/trace.go:171","msg":"trace[942043602] transaction","detail":"{read_only:false; response_revision:25488; number_of_response:1; }","duration":"270.127492ms","start":"2026-07-16T01:12:57.330007Z","end":"2026-07-16T01:12:57.600134Z","steps":["trace[942043602] 'process raft request' (duration: 270.015929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:57.600319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.723026ms","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:8"} {"level":"warn","ts":"2026-07-16T01:12:57.600348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.191143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-16T01:12:57.600401Z","caller":"traceutil/trace.go:171","msg":"trace[1101576814] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:25488; }","duration":"474.284096ms","start":"2026-07-16T01:12:57.126105Z","end":"2026-07-16T01:12:57.600389Z","steps":["trace[1101576814] 'agreement among raft nodes before linearized reading' (duration: 474.130742ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:57.600404Z","caller":"traceutil/trace.go:171","msg":"trace[1634488476] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25488; }","duration":"111.87593ms","start":"2026-07-16T01:12:57.488507Z","end":"2026-07-16T01:12:57.600383Z","steps":["trace[1634488476] 'agreement among raft nodes before linearized reading' (duration: 111.695835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:57.600431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:57.126089Z","time spent":"474.336677ms","remote":"127.0.0.1:44202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-07-16T01:12:57.600361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.357198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:12:57.600547Z","caller":"traceutil/trace.go:171","msg":"trace[158456729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25488; }","duration":"474.577112ms","start":"2026-07-16T01:12:57.125951Z","end":"2026-07-16T01:12:57.600528Z","steps":["trace[158456729] 'agreement among raft nodes before linearized reading' (duration: 474.343797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:57.600631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:57.125937Z","time spent":"474.678715ms","remote":"127.0.0.1:44168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T01:12:57.600541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.022433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-16T01:12:57.600725Z","caller":"traceutil/trace.go:171","msg":"trace[287957332] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:25488; }","duration":"390.278878ms","start":"2026-07-16T01:12:57.210426Z","end":"2026-07-16T01:12:57.600705Z","steps":["trace[287957332] 'agreement among raft nodes before linearized reading' (duration: 389.848138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:57.600785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:57.210413Z","time spent":"390.352951ms","remote":"127.0.0.1:44370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":242,"response size":32,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-07-16T01:12:58.076424Z","caller":"traceutil/trace.go:171","msg":"trace[696809131] transaction","detail":"{read_only:false; response_revision:25489; number_of_response:1; }","duration":"492.724163ms","start":"2026-07-16T01:12:57.583676Z","end":"2026-07-16T01:12:58.0764Z","steps":["trace[696809131] 'process raft request' (duration: 492.534919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:58.076561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:57.583655Z","time spent":"492.833786ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:12:58.076958Z","caller":"traceutil/trace.go:171","msg":"trace[1154766220] transaction","detail":"{read_only:false; response_revision:25490; number_of_response:1; }","duration":"492.644172ms","start":"2026-07-16T01:12:57.584299Z","end":"2026-07-16T01:12:58.076943Z","steps":["trace[1154766220] 'process raft request' (duration: 492.061428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:58.077028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:57.584278Z","time spent":"492.716563ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:12:58.077022Z","caller":"traceutil/trace.go:171","msg":"trace[1328879761] linearizableReadLoop","detail":"{readStateIndex:26324; appliedIndex:26319; }","duration":"476.839356ms","start":"2026-07-16T01:12:57.600159Z","end":"2026-07-16T01:12:58.076999Z","steps":["trace[1328879761] 'read index received' (duration: 475.986805ms)","trace[1328879761] 'applied index is now lower than readState.Index' (duration: 851.521µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:12:58.077231Z","caller":"traceutil/trace.go:171","msg":"trace[442307086] transaction","detail":"{read_only:false; response_revision:25491; number_of_response:1; }","duration":"492.212391ms","start":"2026-07-16T01:12:57.585005Z","end":"2026-07-16T01:12:58.077218Z","steps":["trace[442307086] 'process raft request' (duration: 491.7136ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:58.077227Z","caller":"traceutil/trace.go:171","msg":"trace[1933257424] transaction","detail":"{read_only:false; response_revision:25493; number_of_response:1; }","duration":"492.14726ms","start":"2026-07-16T01:12:57.585051Z","end":"2026-07-16T01:12:58.077199Z","steps":["trace[1933257424] 'process raft request' (duration: 491.902784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:58.077292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:57.584997Z","time spent":"492.264273ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:12:58.077369Z","caller":"traceutil/trace.go:171","msg":"trace[895847352] transaction","detail":"{read_only:false; response_revision:25492; number_of_response:1; }","duration":"492.354185ms","start":"2026-07-16T01:12:57.585007Z","end":"2026-07-16T01:12:58.077361Z","steps":["trace[895847352] 'process raft request' (duration: 491.907184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:58.07737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:57.585038Z","time spent":"492.238102ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:12:58.077382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.046203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-9swjq\" ","response":"range_response_count:1 size:3722"} {"level":"warn","ts":"2026-07-16T01:12:58.07741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:57.584986Z","time spent":"492.401316ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:12:58.077434Z","caller":"traceutil/trace.go:171","msg":"trace[1682821177] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-679c578f5-9swjq; range_end:; response_count:1; response_revision:25493; }","duration":"496.135615ms","start":"2026-07-16T01:12:57.581281Z","end":"2026-07-16T01:12:58.077416Z","steps":["trace[1682821177] 'agreement among raft nodes before linearized reading' (duration: 495.949441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:58.077522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:57.581258Z","time spent":"496.251627ms","remote":"127.0.0.1:44354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3745,"request content":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-9swjq\" "} {"level":"warn","ts":"2026-07-16T01:12:58.077594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.501565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-16T01:12:58.077595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.462384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-16T01:12:58.07762Z","caller":"traceutil/trace.go:171","msg":"trace[144303398] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:25493; }","duration":"475.590657ms","start":"2026-07-16T01:12:57.602023Z","end":"2026-07-16T01:12:58.077614Z","steps":["trace[144303398] 'agreement among raft nodes before linearized reading' (duration: 475.436433ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:58.077628Z","caller":"traceutil/trace.go:171","msg":"trace[1126015116] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25493; }","duration":"364.536856ms","start":"2026-07-16T01:12:57.713082Z","end":"2026-07-16T01:12:58.077619Z","steps":["trace[1126015116] 'agreement among raft nodes before linearized reading' (duration: 364.392272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:58.077637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:57.602009Z","time spent":"475.624887ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-07-16T01:12:58.077655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:57.713056Z","time spent":"364.593248ms","remote":"127.0.0.1:44354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-16T01:12:58.078045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.012379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:12:58.07815Z","caller":"traceutil/trace.go:171","msg":"trace[320655966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25493; }","duration":"469.224025ms","start":"2026-07-16T01:12:57.608902Z","end":"2026-07-16T01:12:58.078126Z","steps":["trace[320655966] 'agreement among raft nodes before linearized reading' (duration: 468.668002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:58.078213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:57.608889Z","time spent":"469.312836ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T01:12:58.808019Z","caller":"traceutil/trace.go:171","msg":"trace[1606253926] transaction","detail":"{read_only:false; response_revision:25494; number_of_response:1; }","duration":"105.216211ms","start":"2026-07-16T01:12:58.702782Z","end":"2026-07-16T01:12:58.807998Z","steps":["trace[1606253926] 'process raft request' (duration: 105.070668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:59.08064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.091991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:12:59.08079Z","caller":"traceutil/trace.go:171","msg":"trace[1725976589] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25494; }","duration":"156.315476ms","start":"2026-07-16T01:12:58.924452Z","end":"2026-07-16T01:12:59.080767Z","steps":["trace[1725976589] 'count revisions from in-memory index tree' (duration: 156.003919ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:59.08111Z","caller":"traceutil/trace.go:171","msg":"trace[1212927200] transaction","detail":"{read_only:false; response_revision:25495; number_of_response:1; }","duration":"152.010514ms","start":"2026-07-16T01:12:58.929065Z","end":"2026-07-16T01:12:59.081076Z","steps":["trace[1212927200] 'process raft request' (duration: 99.955397ms)","trace[1212927200] 'compare' (duration: 51.918304ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:12:59.397653Z","caller":"traceutil/trace.go:171","msg":"trace[1475907061] transaction","detail":"{read_only:false; response_revision:25496; number_of_response:1; }","duration":"268.452042ms","start":"2026-07-16T01:12:59.129175Z","end":"2026-07-16T01:12:59.397627Z","steps":["trace[1475907061] 'process raft request' (duration: 203.469168ms)","trace[1475907061] 'compare' (duration: 64.614596ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:12:59.701271Z","caller":"traceutil/trace.go:171","msg":"trace[1327723149] transaction","detail":"{read_only:false; response_revision:25497; number_of_response:1; }","duration":"571.428284ms","start":"2026-07-16T01:12:59.129826Z","end":"2026-07-16T01:12:59.701254Z","steps":["trace[1327723149] 'process raft request' (duration: 539.166807ms)","trace[1327723149] 'compare' (duration: 31.886897ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:12:59.701372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:59.129807Z","time spent":"571.508597ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:12:59.701492Z","caller":"traceutil/trace.go:171","msg":"trace[69759637] transaction","detail":"{read_only:false; response_revision:25498; number_of_response:1; }","duration":"571.374673ms","start":"2026-07-16T01:12:59.130091Z","end":"2026-07-16T01:12:59.701465Z","steps":["trace[69759637] 'process raft request' (duration: 571.100027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:59.701632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:59.130082Z","time spent":"571.477835ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:12:59.701727Z","caller":"traceutil/trace.go:171","msg":"trace[1960114348] transaction","detail":"{read_only:false; response_revision:25499; number_of_response:1; }","duration":"116.549991ms","start":"2026-07-16T01:12:59.58513Z","end":"2026-07-16T01:12:59.70168Z","steps":["trace[1960114348] 'process raft request' (duration: 116.319596ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:59.701601Z","caller":"traceutil/trace.go:171","msg":"trace[211549490] linearizableReadLoop","detail":"{readStateIndex:26329; appliedIndex:26327; }","duration":"317.605451ms","start":"2026-07-16T01:12:59.383894Z","end":"2026-07-16T01:12:59.701499Z","steps":["trace[211549490] 'read index received' (duration: 285.113628ms)","trace[211549490] 'applied index is now lower than readState.Index' (duration: 32.490833ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:12:59.701754Z","caller":"traceutil/trace.go:171","msg":"trace[643788461] transaction","detail":"{read_only:false; response_revision:25500; number_of_response:1; }","duration":"116.252684ms","start":"2026-07-16T01:12:59.585446Z","end":"2026-07-16T01:12:59.701699Z","steps":["trace[643788461] 'process raft request' (duration: 116.160891ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:12:59.70189Z","caller":"traceutil/trace.go:171","msg":"trace[682036834] transaction","detail":"{read_only:false; response_revision:25501; number_of_response:1; }","duration":"115.334712ms","start":"2026-07-16T01:12:59.58638Z","end":"2026-07-16T01:12:59.701715Z","steps":["trace[682036834] 'process raft request' (duration: 115.26861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:59.701945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.01569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:12:59.702047Z","caller":"traceutil/trace.go:171","msg":"trace[372279231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25502; }","duration":"318.179314ms","start":"2026-07-16T01:12:59.383852Z","end":"2026-07-16T01:12:59.701984Z","steps":["trace[372279231] 'agreement among raft nodes before linearized reading' (duration: 317.835216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:12:59.702093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:12:59.383835Z","time spent":"318.248887ms","remote":"127.0.0.1:44162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T01:12:59.702094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.787915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:12:59.704686Z","caller":"traceutil/trace.go:171","msg":"trace[970357098] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:25502; }","duration":"257.172884ms","start":"2026-07-16T01:12:59.44511Z","end":"2026-07-16T01:12:59.702283Z","steps":["trace[970357098] 'agreement among raft nodes before linearized reading' (duration: 256.791815ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:06.702352Z","caller":"traceutil/trace.go:171","msg":"trace[2085156010] transaction","detail":"{read_only:false; response_revision:25564; number_of_response:1; }","duration":"113.12048ms","start":"2026-07-16T01:13:06.589206Z","end":"2026-07-16T01:13:06.702327Z","steps":["trace[2085156010] 'process raft request' (duration: 112.973936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:07.008403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.834188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:13:07.008518Z","caller":"traceutil/trace.go:171","msg":"trace[1667303976] transaction","detail":"{read_only:false; response_revision:25565; number_of_response:1; }","duration":"298.586119ms","start":"2026-07-16T01:13:06.709908Z","end":"2026-07-16T01:13:07.008494Z","steps":["trace[1667303976] 'process raft request' (duration: 144.573507ms)","trace[1667303976] 'compare' (duration: 153.730385ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:13:07.38813Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305497419455058413,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-16T01:13:07.431274Z","caller":"traceutil/trace.go:171","msg":"trace[537416012] linearizableReadLoop","detail":"{readStateIndex:26399; appliedIndex:26398; }","duration":"544.140155ms","start":"2026-07-16T01:13:06.887114Z","end":"2026-07-16T01:13:07.431254Z","steps":["trace[537416012] 'read index received' (duration: 510.958876ms)","trace[537416012] 'applied index is now lower than readState.Index' (duration: 33.180129ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:13:07.431438Z","caller":"traceutil/trace.go:171","msg":"trace[1781116137] transaction","detail":"{read_only:false; response_revision:25566; number_of_response:1; }","duration":"599.964924ms","start":"2026-07-16T01:13:06.831335Z","end":"2026-07-16T01:13:07.4313Z","steps":["trace[1781116137] 'process raft request' (duration: 566.808865ms)","trace[1781116137] 'compare' (duration: 32.753189ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:13:07.431593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.466284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-07-16T01:13:07.431636Z","caller":"traceutil/trace.go:171","msg":"trace[1083061153] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:25566; }","duration":"544.563766ms","start":"2026-07-16T01:13:06.887063Z","end":"2026-07-16T01:13:07.431627Z","steps":["trace[1083061153] 'agreement among raft nodes before linearized reading' (duration: 544.276379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:07.431661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:06.887042Z","time spent":"544.612177ms","remote":"127.0.0.1:44268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":233,"response size":32,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-07-16T01:13:07.431734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:06.831311Z","time spent":"600.236369ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:13:07.504792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.940156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-16T01:13:07.504882Z","caller":"traceutil/trace.go:171","msg":"trace[1477199061] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:25567; }","duration":"397.08524ms","start":"2026-07-16T01:13:07.107779Z","end":"2026-07-16T01:13:07.504864Z","steps":["trace[1477199061] 'agreement among raft nodes before linearized reading' (duration: 396.918116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:07.504927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:07.107758Z","time spent":"397.159022ms","remote":"127.0.0.1:44410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-07-16T01:13:07.505065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.099782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:13:07.505148Z","caller":"traceutil/trace.go:171","msg":"trace[529178406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25567; }","duration":"122.222975ms","start":"2026-07-16T01:13:07.382903Z","end":"2026-07-16T01:13:07.505126Z","steps":["trace[529178406] 'agreement among raft nodes before linearized reading' (duration: 122.083062ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:07.838514Z","caller":"traceutil/trace.go:171","msg":"trace[1763192208] transaction","detail":"{read_only:false; response_revision:25568; number_of_response:1; }","duration":"114.361869ms","start":"2026-07-16T01:13:07.724106Z","end":"2026-07-16T01:13:07.838468Z","steps":["trace[1763192208] 'process raft request' (duration: 114.194145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:08.119335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.657339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:13:08.119593Z","caller":"traceutil/trace.go:171","msg":"trace[629409757] transaction","detail":"{read_only:false; response_revision:25569; number_of_response:1; }","duration":"392.8323ms","start":"2026-07-16T01:13:07.726726Z","end":"2026-07-16T01:13:08.119559Z","steps":["trace[629409757] 'process raft request' (duration: 255.847893ms)","trace[629409757] 'compare' (duration: 136.511625ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:13:08.11973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:07.726706Z","time spent":"392.947932ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:13:08.119763Z","caller":"traceutil/trace.go:171","msg":"trace[621850549] linearizableReadLoop","detail":"{readStateIndex:26406; appliedIndex:26401; }","duration":"376.529922ms","start":"2026-07-16T01:13:07.743212Z","end":"2026-07-16T01:13:08.119742Z","steps":["trace[621850549] 'read index received' (duration: 95.432589ms)","trace[621850549] 'applied index is now lower than readState.Index' (duration: 281.096093ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:13:08.11993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.712956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-16T01:13:08.119967Z","caller":"traceutil/trace.go:171","msg":"trace[161662904] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:25573; }","duration":"376.780508ms","start":"2026-07-16T01:13:07.743175Z","end":"2026-07-16T01:13:08.119955Z","steps":["trace[161662904] 'agreement among raft nodes before linearized reading' (duration: 376.632675ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:08.11999Z","caller":"traceutil/trace.go:171","msg":"trace[1407694419] transaction","detail":"{read_only:false; response_revision:25573; number_of_response:1; }","duration":"387.508284ms","start":"2026-07-16T01:13:07.732463Z","end":"2026-07-16T01:13:08.119971Z","steps":["trace[1407694419] 'process raft request' (duration: 387.227287ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:08.119975Z","caller":"traceutil/trace.go:171","msg":"trace[1439915666] transaction","detail":"{read_only:false; response_revision:25572; number_of_response:1; }","duration":"387.504413ms","start":"2026-07-16T01:13:07.732438Z","end":"2026-07-16T01:13:08.119943Z","steps":["trace[1439915666] 'process raft request' (duration: 387.198736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:08.12001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:07.743161Z","time spent":"376.836739ms","remote":"127.0.0.1:44580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":54,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-07-16T01:13:08.120019Z","caller":"traceutil/trace.go:171","msg":"trace[1446131989] transaction","detail":"{read_only:false; response_revision:25571; number_of_response:1; }","duration":"387.687348ms","start":"2026-07-16T01:13:07.732289Z","end":"2026-07-16T01:13:08.119977Z","steps":["trace[1446131989] 'process raft request' (duration: 387.225517ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:08.120015Z","caller":"traceutil/trace.go:171","msg":"trace[537657588] transaction","detail":"{read_only:false; response_revision:25570; number_of_response:1; }","duration":"387.978725ms","start":"2026-07-16T01:13:07.731998Z","end":"2026-07-16T01:13:08.119977Z","steps":["trace[537657588] 'process raft request' (duration: 387.443522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:08.120098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:07.73242Z","time spent":"387.612146ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:13:08.120101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:07.73242Z","time spent":"387.629026ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:13:08.120186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:07.731989Z","time spent":"388.103107ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:13:08.120797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:07.732283Z","time spent":"387.79702ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:13:08.270959Z","caller":"traceutil/trace.go:171","msg":"trace[871318531] linearizableReadLoop","detail":"{readStateIndex:26407; appliedIndex:26406; }","duration":"143.932433ms","start":"2026-07-16T01:13:08.126999Z","end":"2026-07-16T01:13:08.270931Z","steps":["trace[871318531] 'read index received' (duration: 130.312688ms)","trace[871318531] 'applied index is now lower than readState.Index' (duration: 13.618244ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:13:08.271032Z","caller":"traceutil/trace.go:171","msg":"trace[1786089985] transaction","detail":"{read_only:false; response_revision:25574; number_of_response:1; }","duration":"144.600888ms","start":"2026-07-16T01:13:08.126401Z","end":"2026-07-16T01:13:08.271002Z","steps":["trace[1786089985] 'process raft request' (duration: 130.884172ms)","trace[1786089985] 'compare' (duration: 13.333727ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:13:08.271214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.185318ms","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-07-16T01:13:08.271247Z","caller":"traceutil/trace.go:171","msg":"trace[1433851311] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25574; }","duration":"144.23922ms","start":"2026-07-16T01:13:08.126997Z","end":"2026-07-16T01:13:08.271236Z","steps":["trace[1433851311] 'agreement among raft nodes before linearized reading' (duration: 144.050465ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:08.408286Z","caller":"traceutil/trace.go:171","msg":"trace[962214467] linearizableReadLoop","detail":"{readStateIndex:26409; appliedIndex:26407; }","duration":"137.237082ms","start":"2026-07-16T01:13:08.271022Z","end":"2026-07-16T01:13:08.408259Z","steps":["trace[962214467] 'read index received' (duration: 136.976586ms)","trace[962214467] 'applied index is now lower than readState.Index' (duration: 259.286µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:13:08.408349Z","caller":"traceutil/trace.go:171","msg":"trace[1321088004] transaction","detail":"{read_only:false; response_revision:25576; number_of_response:1; }","duration":"280.221961ms","start":"2026-07-16T01:13:08.128097Z","end":"2026-07-16T01:13:08.408319Z","steps":["trace[1321088004] 'process raft request' (duration: 280.118699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:08.408505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.52866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:13:08.408452Z","caller":"traceutil/trace.go:171","msg":"trace[763081179] transaction","detail":"{read_only:false; response_revision:25575; number_of_response:1; }","duration":"280.468438ms","start":"2026-07-16T01:13:08.12796Z","end":"2026-07-16T01:13:08.408429Z","steps":["trace[763081179] 'process raft request' (duration: 280.077779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:08.408572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.965622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-16T01:13:08.408585Z","caller":"traceutil/trace.go:171","msg":"trace[243885767] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:25576; }","duration":"166.646182ms","start":"2026-07-16T01:13:08.241916Z","end":"2026-07-16T01:13:08.408562Z","steps":["trace[243885767] 'agreement among raft nodes before linearized reading' (duration: 166.449578ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:08.40857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.73218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-07-16T01:13:08.408641Z","caller":"traceutil/trace.go:171","msg":"trace[203817929] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25576; }","duration":"271.059614ms","start":"2026-07-16T01:13:08.137559Z","end":"2026-07-16T01:13:08.408619Z","steps":["trace[203817929] 'agreement among raft nodes before linearized reading' (duration: 270.834589ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:08.408654Z","caller":"traceutil/trace.go:171","msg":"trace[2085335710] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25576; }","duration":"157.866644ms","start":"2026-07-16T01:13:08.250766Z","end":"2026-07-16T01:13:08.408632Z","steps":["trace[2085335710] 'agreement among raft nodes before linearized reading' (duration: 157.565077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:08.419778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.145999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-16T01:13:08.419845Z","caller":"traceutil/trace.go:171","msg":"trace[973489204] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:25577; }","duration":"147.261821ms","start":"2026-07-16T01:13:08.272567Z","end":"2026-07-16T01:13:08.419828Z","steps":["trace[973489204] 'agreement among raft nodes before linearized reading' (duration: 147.121888ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:08.419697Z","caller":"traceutil/trace.go:171","msg":"trace[142132874] transaction","detail":"{read_only:false; response_revision:25577; number_of_response:1; }","duration":"144.418724ms","start":"2026-07-16T01:13:08.275263Z","end":"2026-07-16T01:13:08.419682Z","steps":["trace[142132874] 'process raft request' (duration: 144.28284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:08.680222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.866009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-16T01:13:08.680304Z","caller":"traceutil/trace.go:171","msg":"trace[770631143] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:25578; }","duration":"112.001353ms","start":"2026-07-16T01:13:08.568286Z","end":"2026-07-16T01:13:08.680287Z","steps":["trace[770631143] 'count revisions from in-memory index tree' (duration: 111.785818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:08.68033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.233056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:13:08.680393Z","caller":"traceutil/trace.go:171","msg":"trace[413053799] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25578; }","duration":"146.340859ms","start":"2026-07-16T01:13:08.534036Z","end":"2026-07-16T01:13:08.680377Z","steps":["trace[413053799] 'range keys from in-memory index tree' (duration: 146.161195ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:09.647214Z","caller":"traceutil/trace.go:171","msg":"trace[1735975024] transaction","detail":"{read_only:false; response_revision:25579; number_of_response:1; }","duration":"209.985622ms","start":"2026-07-16T01:13:09.437208Z","end":"2026-07-16T01:13:09.647193Z","steps":["trace[1735975024] 'process raft request' (duration: 209.837629ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:09.648075Z","caller":"traceutil/trace.go:171","msg":"trace[723717877] transaction","detail":"{read_only:false; response_revision:25580; number_of_response:1; }","duration":"138.175144ms","start":"2026-07-16T01:13:09.509874Z","end":"2026-07-16T01:13:09.648049Z","steps":["trace[723717877] 'process raft request' (duration: 137.99796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:15.110235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.877105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/operatorpolicies/\" range_end:\"/registry/rabbitmq.com/operatorpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:13:15.110325Z","caller":"traceutil/trace.go:171","msg":"trace[1519801589] range","detail":"{range_begin:/registry/rabbitmq.com/operatorpolicies/; range_end:/registry/rabbitmq.com/operatorpolicies0; response_count:0; response_revision:25629; }","duration":"283.008889ms","start":"2026-07-16T01:13:14.827295Z","end":"2026-07-16T01:13:15.110304Z","steps":["trace[1519801589] 'count revisions from in-memory index tree' (duration: 282.792633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:15.110531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.6019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-16T01:13:15.110561Z","caller":"traceutil/trace.go:171","msg":"trace[2019000582] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:25629; }","duration":"436.653541ms","start":"2026-07-16T01:13:14.673896Z","end":"2026-07-16T01:13:15.110549Z","steps":["trace[2019000582] 'count revisions from in-memory index tree' (duration: 436.549759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:15.11058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:14.67388Z","time spent":"436.693742ms","remote":"127.0.0.1:56330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-07-16T01:13:15.110656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.762305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-f7bf6fdd5-qcglr\" ","response":"range_response_count:1 size:8858"} {"level":"info","ts":"2026-07-16T01:13:15.110687Z","caller":"traceutil/trace.go:171","msg":"trace[255859153] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-f7bf6fdd5-qcglr; range_end:; response_count:1; response_revision:25629; }","duration":"267.854227ms","start":"2026-07-16T01:13:14.842822Z","end":"2026-07-16T01:13:15.110677Z","steps":["trace[255859153] 'range keys from in-memory index tree' (duration: 267.605271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:15.110857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.917265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-f7bf6fdd5-qcglr.18c2a0098743baec\" ","response":"range_response_count:1 size:887"} {"level":"info","ts":"2026-07-16T01:13:15.110914Z","caller":"traceutil/trace.go:171","msg":"trace[696721988] range","detail":"{range_begin:/registry/events/openstack/magnum-api-f7bf6fdd5-qcglr.18c2a0098743baec; range_end:; response_count:1; response_revision:25629; }","duration":"267.004817ms","start":"2026-07-16T01:13:14.843895Z","end":"2026-07-16T01:13:15.1109Z","steps":["trace[696721988] 'range keys from in-memory index tree' (duration: 266.661899ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:15.437953Z","caller":"traceutil/trace.go:171","msg":"trace[1073014195] transaction","detail":"{read_only:false; response_revision:25630; number_of_response:1; }","duration":"323.803788ms","start":"2026-07-16T01:13:15.114128Z","end":"2026-07-16T01:13:15.437932Z","steps":["trace[1073014195] 'process raft request' (duration: 323.678325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:15.438126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:15.114112Z","time spent":"323.91207ms","remote":"127.0.0.1:44250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":869,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:13:15.801658Z","caller":"traceutil/trace.go:171","msg":"trace[903026590] linearizableReadLoop","detail":"{readStateIndex:26465; appliedIndex:26463; }","duration":"585.685383ms","start":"2026-07-16T01:13:15.215928Z","end":"2026-07-16T01:13:15.801613Z","steps":["trace[903026590] 'read index received' (duration: 221.822123ms)","trace[903026590] 'applied index is now lower than readState.Index' (duration: 363.86195ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:13:15.80179Z","caller":"traceutil/trace.go:171","msg":"trace[257866682] transaction","detail":"{read_only:false; response_revision:25631; number_of_response:1; }","duration":"680.747363ms","start":"2026-07-16T01:13:15.121032Z","end":"2026-07-16T01:13:15.80178Z","steps":["trace[257866682] 'process raft request' (duration: 678.159872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:15.801881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:15.12102Z","time spent":"680.796455ms","remote":"127.0.0.1:44354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8881,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:13:15.801895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.95482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-16T01:13:15.801947Z","caller":"traceutil/trace.go:171","msg":"trace[1133450490] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25631; }","duration":"586.017782ms","start":"2026-07-16T01:13:15.215915Z","end":"2026-07-16T01:13:15.801933Z","steps":["trace[1133450490] 'agreement among raft nodes before linearized reading' (duration: 585.861508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:15.801982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:15.215898Z","time spent":"586.076783ms","remote":"127.0.0.1:44424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":92,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-07-16T01:13:15.842477Z","caller":"traceutil/trace.go:171","msg":"trace[337961931] transaction","detail":"{read_only:false; response_revision:25632; number_of_response:1; }","duration":"201.960072ms","start":"2026-07-16T01:13:15.640494Z","end":"2026-07-16T01:13:15.842454Z","steps":["trace[337961931] 'process raft request' (duration: 201.775237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:15.842508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.513765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:13:15.842529Z","caller":"traceutil/trace.go:171","msg":"trace[1127805701] transaction","detail":"{read_only:false; response_revision:25634; number_of_response:1; }","duration":"163.867116ms","start":"2026-07-16T01:13:15.678636Z","end":"2026-07-16T01:13:15.842503Z","steps":["trace[1127805701] 'process raft request' (duration: 163.779853ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:15.842562Z","caller":"traceutil/trace.go:171","msg":"trace[447048376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25634; }","duration":"462.616409ms","start":"2026-07-16T01:13:15.379935Z","end":"2026-07-16T01:13:15.842552Z","steps":["trace[447048376] 'agreement among raft nodes before linearized reading' (duration: 462.512905ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:15.842534Z","caller":"traceutil/trace.go:171","msg":"trace[267733933] transaction","detail":"{read_only:false; response_revision:25633; number_of_response:1; }","duration":"171.079608ms","start":"2026-07-16T01:13:15.671421Z","end":"2026-07-16T01:13:15.842501Z","steps":["trace[267733933] 'process raft request' (duration: 170.959664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:15.842585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:15.379917Z","time spent":"462.662959ms","remote":"127.0.0.1:44168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T01:13:16.349255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.167211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:13:16.349324Z","caller":"traceutil/trace.go:171","msg":"trace[431392522] linearizableReadLoop","detail":"{readStateIndex:26469; appliedIndex:26468; }","duration":"283.840897ms","start":"2026-07-16T01:13:16.065473Z","end":"2026-07-16T01:13:16.349314Z","steps":["trace[431392522] 'read index received' (duration: 76.918429ms)","trace[431392522] 'applied index is now lower than readState.Index' (duration: 206.921228ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:13:16.349369Z","caller":"traceutil/trace.go:171","msg":"trace[916565715] transaction","detail":"{read_only:false; response_revision:25635; number_of_response:1; }","duration":"409.015824ms","start":"2026-07-16T01:13:15.940326Z","end":"2026-07-16T01:13:16.349342Z","steps":["trace[916565715] 'process raft request' (duration: 202.122957ms)","trace[916565715] 'compare' (duration: 205.894164ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:13:16.349429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.95732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-16T01:13:16.349445Z","caller":"traceutil/trace.go:171","msg":"trace[1108091777] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:25635; }","duration":"284.000761ms","start":"2026-07-16T01:13:16.065438Z","end":"2026-07-16T01:13:16.349439Z","steps":["trace[1108091777] 'agreement among raft nodes before linearized reading' (duration: 283.919409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:16.349454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:15.940301Z","time spent":"409.116846ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:13:16.351445Z","caller":"traceutil/trace.go:171","msg":"trace[1688455784] transaction","detail":"{read_only:false; response_revision:25636; number_of_response:1; }","duration":"197.426873ms","start":"2026-07-16T01:13:16.153999Z","end":"2026-07-16T01:13:16.351426Z","steps":["trace[1688455784] 'process raft request' (duration: 197.327441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:16.352683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.561519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-16T01:13:16.352732Z","caller":"traceutil/trace.go:171","msg":"trace[1292365186] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:25640; }","duration":"208.6152ms","start":"2026-07-16T01:13:16.144105Z","end":"2026-07-16T01:13:16.35272Z","steps":["trace[1292365186] 'agreement among raft nodes before linearized reading' (duration: 208.542689ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:16.352953Z","caller":"traceutil/trace.go:171","msg":"trace[681265130] transaction","detail":"{read_only:false; response_revision:25637; number_of_response:1; }","duration":"198.884298ms","start":"2026-07-16T01:13:16.154045Z","end":"2026-07-16T01:13:16.352929Z","steps":["trace[681265130] 'process raft request' (duration: 198.17449ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:16.353337Z","caller":"traceutil/trace.go:171","msg":"trace[311409160] transaction","detail":"{read_only:false; response_revision:25638; number_of_response:1; }","duration":"174.869898ms","start":"2026-07-16T01:13:16.178455Z","end":"2026-07-16T01:13:16.353325Z","steps":["trace[311409160] 'process raft request' (duration: 173.848373ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:16.353528Z","caller":"traceutil/trace.go:171","msg":"trace[1411779520] transaction","detail":"{read_only:false; response_revision:25639; number_of_response:1; }","duration":"172.646205ms","start":"2026-07-16T01:13:16.18086Z","end":"2026-07-16T01:13:16.353506Z","steps":["trace[1411779520] 'process raft request' (duration: 171.60353ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:16.353668Z","caller":"traceutil/trace.go:171","msg":"trace[789282281] transaction","detail":"{read_only:false; response_revision:25640; number_of_response:1; }","duration":"167.976403ms","start":"2026-07-16T01:13:16.185685Z","end":"2026-07-16T01:13:16.353662Z","steps":["trace[789282281] 'process raft request' (duration: 166.829566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:16.354019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.50881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:13:16.354053Z","caller":"traceutil/trace.go:171","msg":"trace[1990012760] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:25640; }","duration":"193.565391ms","start":"2026-07-16T01:13:16.160479Z","end":"2026-07-16T01:13:16.354044Z","steps":["trace[1990012760] 'agreement among raft nodes before linearized reading' (duration: 193.50919ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:21.838881Z","caller":"traceutil/trace.go:171","msg":"trace[765705098] transaction","detail":"{read_only:false; response_revision:25687; number_of_response:1; }","duration":"114.652045ms","start":"2026-07-16T01:13:21.724206Z","end":"2026-07-16T01:13:21.838858Z","steps":["trace[765705098] 'process raft request' (duration: 114.491782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:22.265609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.607482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:13:22.265719Z","caller":"traceutil/trace.go:171","msg":"trace[714812432] transaction","detail":"{read_only:false; response_revision:25688; number_of_response:1; }","duration":"398.619076ms","start":"2026-07-16T01:13:21.867069Z","end":"2026-07-16T01:13:22.265688Z","steps":["trace[714812432] 'process raft request' (duration: 260.858062ms)","trace[714812432] 'compare' (duration: 137.433087ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:13:22.265766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:21.86705Z","time spent":"398.694518ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:13:22.267896Z","caller":"traceutil/trace.go:171","msg":"trace[789108647] transaction","detail":"{read_only:false; response_revision:25689; number_of_response:1; }","duration":"399.311522ms","start":"2026-07-16T01:13:21.868552Z","end":"2026-07-16T01:13:22.267864Z","steps":["trace[789108647] 'process raft request' (duration: 399.092097ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:22.267876Z","caller":"traceutil/trace.go:171","msg":"trace[581350291] transaction","detail":"{read_only:false; response_revision:25690; number_of_response:1; }","duration":"241.180684ms","start":"2026-07-16T01:13:22.026671Z","end":"2026-07-16T01:13:22.267852Z","steps":["trace[581350291] 'process raft request' (duration: 241.110182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:22.268055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:21.868531Z","time spent":"399.442945ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:13:22.570207Z","caller":"traceutil/trace.go:171","msg":"trace[67995895] transaction","detail":"{read_only:false; response_revision:25691; number_of_response:1; }","duration":"198.99142ms","start":"2026-07-16T01:13:22.37119Z","end":"2026-07-16T01:13:22.570182Z","steps":["trace[67995895] 'process raft request' (duration: 198.801366ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:22.57521Z","caller":"traceutil/trace.go:171","msg":"trace[1656724174] transaction","detail":"{read_only:false; response_revision:25692; number_of_response:1; }","duration":"203.673383ms","start":"2026-07-16T01:13:22.371513Z","end":"2026-07-16T01:13:22.575186Z","steps":["trace[1656724174] 'process raft request' (duration: 203.464948ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:22.575481Z","caller":"traceutil/trace.go:171","msg":"trace[292811053] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"202.747889ms","start":"2026-07-16T01:13:22.372721Z","end":"2026-07-16T01:13:22.575469Z","steps":["trace[292811053] 'process raft request' (duration: 202.640967ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:22.575501Z","caller":"traceutil/trace.go:171","msg":"trace[216477307] transaction","detail":"{read_only:false; response_revision:25693; number_of_response:1; }","duration":"203.923578ms","start":"2026-07-16T01:13:22.371546Z","end":"2026-07-16T01:13:22.57547Z","steps":["trace[216477307] 'process raft request' (duration: 203.59061ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:22.57551Z","caller":"traceutil/trace.go:171","msg":"trace[2044173335] transaction","detail":"{read_only:false; response_revision:25695; number_of_response:1; }","duration":"189.161648ms","start":"2026-07-16T01:13:22.386332Z","end":"2026-07-16T01:13:22.575493Z","steps":["trace[2044173335] 'process raft request' (duration: 189.099006ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:22.575589Z","caller":"traceutil/trace.go:171","msg":"trace[1372603254] transaction","detail":"{read_only:false; response_revision:25696; number_of_response:1; }","duration":"188.088672ms","start":"2026-07-16T01:13:22.387491Z","end":"2026-07-16T01:13:22.575579Z","steps":["trace[1372603254] 'process raft request' (duration: 187.977439ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:22.575556Z","caller":"traceutil/trace.go:171","msg":"trace[2078322215] linearizableReadLoop","detail":"{readStateIndex:26530; appliedIndex:26526; }","duration":"194.510074ms","start":"2026-07-16T01:13:22.381034Z","end":"2026-07-16T01:13:22.575544Z","steps":["trace[2078322215] 'read index received' (duration: 188.974882ms)","trace[2078322215] 'applied index is now lower than readState.Index' (duration: 5.534592ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:13:22.575647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.599986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:13:22.575748Z","caller":"traceutil/trace.go:171","msg":"trace[427683900] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25696; }","duration":"194.730559ms","start":"2026-07-16T01:13:22.381006Z","end":"2026-07-16T01:13:22.575736Z","steps":["trace[427683900] 'agreement among raft nodes before linearized reading' (duration: 194.577456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:22.575825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.360761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-16T01:13:22.57586Z","caller":"traceutil/trace.go:171","msg":"trace[1661848728] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25696; }","duration":"181.414483ms","start":"2026-07-16T01:13:22.394439Z","end":"2026-07-16T01:13:22.575854Z","steps":["trace[1661848728] 'agreement among raft nodes before linearized reading' (duration: 181.311871ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:22.99375Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22247} {"level":"info","ts":"2026-07-16T01:13:23.119673Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22247,"took":"119.88675ms","hash":2461738280,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":45506560,"current-db-size-in-use":"46 MB"} {"level":"info","ts":"2026-07-16T01:13:23.119744Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2461738280,"revision":22247,"compact-revision":19979} {"level":"info","ts":"2026-07-16T01:13:28.41524Z","caller":"traceutil/trace.go:171","msg":"trace[751306718] transaction","detail":"{read_only:false; response_revision:25793; number_of_response:1; }","duration":"259.620042ms","start":"2026-07-16T01:13:28.15559Z","end":"2026-07-16T01:13:28.41521Z","steps":["trace[751306718] 'process raft request' (duration: 259.429176ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:28.480678Z","caller":"traceutil/trace.go:171","msg":"trace[314250283] transaction","detail":"{read_only:false; response_revision:25795; number_of_response:1; }","duration":"193.647814ms","start":"2026-07-16T01:13:28.28699Z","end":"2026-07-16T01:13:28.480638Z","steps":["trace[314250283] 'process raft request' (duration: 193.568942ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:28.480711Z","caller":"traceutil/trace.go:171","msg":"trace[1719881425] transaction","detail":"{read_only:false; response_revision:25794; number_of_response:1; }","duration":"195.648611ms","start":"2026-07-16T01:13:28.285044Z","end":"2026-07-16T01:13:28.480692Z","steps":["trace[1719881425] 'process raft request' (duration: 195.433006ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:30.733648Z","caller":"traceutil/trace.go:171","msg":"trace[1982155524] linearizableReadLoop","detail":"{readStateIndex:26649; appliedIndex:26648; }","duration":"124.649893ms","start":"2026-07-16T01:13:30.608974Z","end":"2026-07-16T01:13:30.733624Z","steps":["trace[1982155524] 'read index received' (duration: 105.799075ms)","trace[1982155524] 'applied index is now lower than readState.Index' (duration: 18.849558ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:13:30.733743Z","caller":"traceutil/trace.go:171","msg":"trace[1139929755] transaction","detail":"{read_only:false; response_revision:25811; number_of_response:1; }","duration":"142.837196ms","start":"2026-07-16T01:13:30.590846Z","end":"2026-07-16T01:13:30.733683Z","steps":["trace[1139929755] 'process raft request' (duration: 124.013508ms)","trace[1139929755] 'compare' (duration: 18.653544ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:13:30.733859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.798007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-16T01:13:30.734Z","caller":"traceutil/trace.go:171","msg":"trace[1789673546] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:25811; }","duration":"125.045223ms","start":"2026-07-16T01:13:30.608927Z","end":"2026-07-16T01:13:30.733973Z","steps":["trace[1789673546] 'agreement among raft nodes before linearized reading' (duration: 124.787147ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:30.763733Z","caller":"traceutil/trace.go:171","msg":"trace[1842163451] transaction","detail":"{read_only:false; response_revision:25812; number_of_response:1; }","duration":"152.056015ms","start":"2026-07-16T01:13:30.611662Z","end":"2026-07-16T01:13:30.763718Z","steps":["trace[1842163451] 'process raft request' (duration: 151.918792ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:30.764055Z","caller":"traceutil/trace.go:171","msg":"trace[1047492896] transaction","detail":"{read_only:false; response_revision:25813; number_of_response:1; }","duration":"142.1788ms","start":"2026-07-16T01:13:30.621845Z","end":"2026-07-16T01:13:30.764024Z","steps":["trace[1047492896] 'process raft request' (duration: 141.840912ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:30.764366Z","caller":"traceutil/trace.go:171","msg":"trace[1192550135] transaction","detail":"{read_only:false; response_revision:25814; number_of_response:1; }","duration":"140.668294ms","start":"2026-07-16T01:13:30.62367Z","end":"2026-07-16T01:13:30.764338Z","steps":["trace[1192550135] 'process raft request' (duration: 140.251675ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:30.764465Z","caller":"traceutil/trace.go:171","msg":"trace[1104479459] transaction","detail":"{read_only:false; response_revision:25815; number_of_response:1; }","duration":"140.660414ms","start":"2026-07-16T01:13:30.623779Z","end":"2026-07-16T01:13:30.764439Z","steps":["trace[1104479459] 'process raft request' (duration: 140.48125ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:30.764662Z","caller":"traceutil/trace.go:171","msg":"trace[1029473429] transaction","detail":"{read_only:false; response_revision:25816; number_of_response:1; }","duration":"140.774547ms","start":"2026-07-16T01:13:30.623878Z","end":"2026-07-16T01:13:30.764652Z","steps":["trace[1029473429] 'process raft request' (duration: 140.437959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:30.765574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.307792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-16T01:13:30.765623Z","caller":"traceutil/trace.go:171","msg":"trace[1934360269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25816; }","duration":"131.394613ms","start":"2026-07-16T01:13:30.634217Z","end":"2026-07-16T01:13:30.765612Z","steps":["trace[1934360269] 'agreement among raft nodes before linearized reading' (duration: 130.230705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:30.765761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.183827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-16T01:13:30.765806Z","caller":"traceutil/trace.go:171","msg":"trace[1998554508] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25816; }","duration":"120.26361ms","start":"2026-07-16T01:13:30.645532Z","end":"2026-07-16T01:13:30.765796Z","steps":["trace[1998554508] 'agreement among raft nodes before linearized reading' (duration: 118.922697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:30.766075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.806435ms","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-07-16T01:13:30.766106Z","caller":"traceutil/trace.go:171","msg":"trace[921888603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25816; }","duration":"126.877097ms","start":"2026-07-16T01:13:30.639221Z","end":"2026-07-16T01:13:30.766098Z","steps":["trace[921888603] 'agreement among raft nodes before linearized reading' (duration: 125.562465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:30.766146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.918101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-16T01:13:30.766188Z","caller":"traceutil/trace.go:171","msg":"trace[1932257805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25816; }","duration":"127.989173ms","start":"2026-07-16T01:13:30.638189Z","end":"2026-07-16T01:13:30.766178Z","steps":["trace[1932257805] 'agreement among raft nodes before linearized reading' (duration: 126.545969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:30.766456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.207945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-16T01:13:30.766489Z","caller":"traceutil/trace.go:171","msg":"trace[543799143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25816; }","duration":"127.270297ms","start":"2026-07-16T01:13:30.639212Z","end":"2026-07-16T01:13:30.766482Z","steps":["trace[543799143] 'agreement among raft nodes before linearized reading' (duration: 125.266439ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:34.408765Z","caller":"traceutil/trace.go:171","msg":"trace[1975669555] linearizableReadLoop","detail":"{readStateIndex:26698; appliedIndex:26697; }","duration":"120.698529ms","start":"2026-07-16T01:13:34.288039Z","end":"2026-07-16T01:13:34.408738Z","steps":["trace[1975669555] 'read index received' (duration: 120.483824ms)","trace[1975669555] 'applied index is now lower than readState.Index' (duration: 213.425µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:13:34.408825Z","caller":"traceutil/trace.go:171","msg":"trace[110059125] transaction","detail":"{read_only:false; response_revision:25858; number_of_response:1; }","duration":"127.298755ms","start":"2026-07-16T01:13:34.281495Z","end":"2026-07-16T01:13:34.408794Z","steps":["trace[110059125] 'process raft request' (duration: 127.07767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:34.409026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.975256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-16T01:13:34.409094Z","caller":"traceutil/trace.go:171","msg":"trace[477312912] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25858; }","duration":"121.120769ms","start":"2026-07-16T01:13:34.287955Z","end":"2026-07-16T01:13:34.409076Z","steps":["trace[477312912] 'agreement among raft nodes before linearized reading' (duration: 120.924344ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:35.467503Z","caller":"traceutil/trace.go:171","msg":"trace[234378639] linearizableReadLoop","detail":"{readStateIndex:26718; appliedIndex:26717; }","duration":"154.81993ms","start":"2026-07-16T01:13:35.312622Z","end":"2026-07-16T01:13:35.467442Z","steps":["trace[234378639] 'read index received' (duration: 154.640776ms)","trace[234378639] 'applied index is now lower than readState.Index' (duration: 177.724µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:13:35.467563Z","caller":"traceutil/trace.go:171","msg":"trace[2021217699] transaction","detail":"{read_only:false; response_revision:25878; number_of_response:1; }","duration":"159.01004ms","start":"2026-07-16T01:13:35.308516Z","end":"2026-07-16T01:13:35.467526Z","steps":["trace[2021217699] 'process raft request' (duration: 158.750624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:35.467912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.53352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"warn","ts":"2026-07-16T01:13:35.46792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.240341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-cluster-api-proxy-d7jq4\" ","response":"range_response_count:1 size:5327"} {"level":"info","ts":"2026-07-16T01:13:35.467984Z","caller":"traceutil/trace.go:171","msg":"trace[1037720240] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25878; }","duration":"132.650563ms","start":"2026-07-16T01:13:35.335315Z","end":"2026-07-16T01:13:35.467966Z","steps":["trace[1037720240] 'agreement among raft nodes before linearized reading' (duration: 132.369957ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:35.468028Z","caller":"traceutil/trace.go:171","msg":"trace[830847011] range","detail":"{range_begin:/registry/pods/openstack/magnum-cluster-api-proxy-d7jq4; range_end:; response_count:1; response_revision:25878; }","duration":"155.422384ms","start":"2026-07-16T01:13:35.31258Z","end":"2026-07-16T01:13:35.468002Z","steps":["trace[830847011] 'agreement among raft nodes before linearized reading' (duration: 155.040225ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:35.731325Z","caller":"traceutil/trace.go:171","msg":"trace[1365298020] transaction","detail":"{read_only:false; response_revision:25879; number_of_response:1; }","duration":"253.23073ms","start":"2026-07-16T01:13:35.478077Z","end":"2026-07-16T01:13:35.731307Z","steps":["trace[1365298020] 'process raft request' (duration: 252.482282ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:35.874004Z","caller":"traceutil/trace.go:171","msg":"trace[1686110486] transaction","detail":"{read_only:false; response_revision:25880; number_of_response:1; }","duration":"131.103997ms","start":"2026-07-16T01:13:35.742801Z","end":"2026-07-16T01:13:35.873905Z","steps":["trace[1686110486] 'process raft request' (duration: 130.413091ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:36.139367Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006864.wal"} {"level":"info","ts":"2026-07-16T01:13:41.052989Z","caller":"traceutil/trace.go:171","msg":"trace[850202069] transaction","detail":"{read_only:false; response_revision:25926; number_of_response:1; }","duration":"178.684678ms","start":"2026-07-16T01:13:40.874282Z","end":"2026-07-16T01:13:41.052967Z","steps":["trace[850202069] 'process raft request' (duration: 178.511504ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:41.053219Z","caller":"traceutil/trace.go:171","msg":"trace[319922276] transaction","detail":"{read_only:false; response_revision:25927; number_of_response:1; }","duration":"176.880305ms","start":"2026-07-16T01:13:40.876329Z","end":"2026-07-16T01:13:41.053209Z","steps":["trace[319922276] 'process raft request' (duration: 176.602248ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:41.053458Z","caller":"traceutil/trace.go:171","msg":"trace[1996433349] transaction","detail":"{read_only:false; response_revision:25928; number_of_response:1; }","duration":"177.040909ms","start":"2026-07-16T01:13:40.876383Z","end":"2026-07-16T01:13:41.053424Z","steps":["trace[1996433349] 'process raft request' (duration: 176.773352ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:41.053717Z","caller":"traceutil/trace.go:171","msg":"trace[1894921934] transaction","detail":"{read_only:false; response_revision:25929; number_of_response:1; }","duration":"177.179191ms","start":"2026-07-16T01:13:40.876493Z","end":"2026-07-16T01:13:41.053673Z","steps":["trace[1894921934] 'process raft request' (duration: 176.853864ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:41.053886Z","caller":"traceutil/trace.go:171","msg":"trace[96901938] transaction","detail":"{read_only:false; response_revision:25930; number_of_response:1; }","duration":"176.67627ms","start":"2026-07-16T01:13:40.877198Z","end":"2026-07-16T01:13:41.053874Z","steps":["trace[96901938] 'process raft request' (duration: 176.376893ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:41.054036Z","caller":"traceutil/trace.go:171","msg":"trace[942640182] linearizableReadLoop","detail":"{readStateIndex:26771; appliedIndex:26765; }","duration":"151.8866ms","start":"2026-07-16T01:13:40.902033Z","end":"2026-07-16T01:13:41.05392Z","steps":["trace[942640182] 'read index received' (duration: 17.567787ms)","trace[942640182] 'applied index is now lower than readState.Index' (duration: 134.235621ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:13:41.054249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.195537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-16T01:13:41.054297Z","caller":"traceutil/trace.go:171","msg":"trace[1082641110] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25930; }","duration":"152.29025ms","start":"2026-07-16T01:13:40.901992Z","end":"2026-07-16T01:13:41.054282Z","steps":["trace[1082641110] 'agreement among raft nodes before linearized reading' (duration: 152.132376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:41.054555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.676034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-16T01:13:41.054588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.579662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-16T01:13:41.054653Z","caller":"traceutil/trace.go:171","msg":"trace[1403086563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25930; }","duration":"148.719105ms","start":"2026-07-16T01:13:40.905916Z","end":"2026-07-16T01:13:41.054635Z","steps":["trace[1403086563] 'agreement among raft nodes before linearized reading' (duration: 148.51464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:41.054769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.759786ms","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-07-16T01:13:41.054857Z","caller":"traceutil/trace.go:171","msg":"trace[600075929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25930; }","duration":"148.876339ms","start":"2026-07-16T01:13:40.90596Z","end":"2026-07-16T01:13:41.054837Z","steps":["trace[600075929] 'agreement among raft nodes before linearized reading' (duration: 148.621343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:41.054552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.547411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-16T01:13:41.055184Z","caller":"traceutil/trace.go:171","msg":"trace[1668090291] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25930; }","duration":"149.225578ms","start":"2026-07-16T01:13:40.90594Z","end":"2026-07-16T01:13:41.055166Z","steps":["trace[1668090291] 'agreement among raft nodes before linearized reading' (duration: 148.48671ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:41.055151Z","caller":"traceutil/trace.go:171","msg":"trace[260539182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25930; }","duration":"148.795427ms","start":"2026-07-16T01:13:40.905816Z","end":"2026-07-16T01:13:41.054611Z","steps":["trace[260539182] 'agreement among raft nodes before linearized reading' (duration: 148.587022ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:41.17073Z","caller":"traceutil/trace.go:171","msg":"trace[1592324596] transaction","detail":"{read_only:false; response_revision:25931; number_of_response:1; }","duration":"112.205658ms","start":"2026-07-16T01:13:41.058503Z","end":"2026-07-16T01:13:41.170708Z","steps":["trace[1592324596] 'process raft request' (duration: 111.311727ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:41.171389Z","caller":"traceutil/trace.go:171","msg":"trace[227649433] transaction","detail":"{read_only:false; response_revision:25932; number_of_response:1; }","duration":"111.243225ms","start":"2026-07-16T01:13:41.060136Z","end":"2026-07-16T01:13:41.17138Z","steps":["trace[227649433] 'process raft request' (duration: 110.820475ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:41.171523Z","caller":"traceutil/trace.go:171","msg":"trace[1259734039] transaction","detail":"{read_only:false; response_revision:25933; number_of_response:1; }","duration":"111.221745ms","start":"2026-07-16T01:13:41.060274Z","end":"2026-07-16T01:13:41.171495Z","steps":["trace[1259734039] 'process raft request' (duration: 110.836616ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:41.17217Z","caller":"traceutil/trace.go:171","msg":"trace[2044854003] transaction","detail":"{read_only:false; response_revision:25934; number_of_response:1; }","duration":"111.847899ms","start":"2026-07-16T01:13:41.060309Z","end":"2026-07-16T01:13:41.172157Z","steps":["trace[2044854003] 'process raft request' (duration: 110.842605ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:41.172551Z","caller":"traceutil/trace.go:171","msg":"trace[777322140] transaction","detail":"{read_only:false; response_revision:25935; number_of_response:1; }","duration":"112.210759ms","start":"2026-07-16T01:13:41.060325Z","end":"2026-07-16T01:13:41.172536Z","steps":["trace[777322140] 'process raft request' (duration: 112.103845ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:51.246409Z","caller":"traceutil/trace.go:171","msg":"trace[1097161369] transaction","detail":"{read_only:false; response_revision:26008; number_of_response:1; }","duration":"295.421644ms","start":"2026-07-16T01:13:50.950969Z","end":"2026-07-16T01:13:51.246391Z","steps":["trace[1097161369] 'process raft request' (duration: 295.313141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:51.494759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.1778ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:13:51.495224Z","caller":"traceutil/trace.go:171","msg":"trace[6135453] transaction","detail":"{read_only:false; response_revision:26009; number_of_response:1; }","duration":"411.79666ms","start":"2026-07-16T01:13:51.083404Z","end":"2026-07-16T01:13:51.495201Z","steps":["trace[6135453] 'process raft request' (duration: 285.079358ms)","trace[6135453] 'compare' (duration: 125.996045ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:13:51.49534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:51.083385Z","time spent":"411.894812ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:13:51.495868Z","caller":"traceutil/trace.go:171","msg":"trace[1178532694] transaction","detail":"{read_only:false; response_revision:26010; number_of_response:1; }","duration":"410.753716ms","start":"2026-07-16T01:13:51.085081Z","end":"2026-07-16T01:13:51.495834Z","steps":["trace[1178532694] 'process raft request' (duration: 410.168691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:51.496027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:51.08506Z","time spent":"410.92557ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:13:51.496068Z","caller":"traceutil/trace.go:171","msg":"trace[521038000] transaction","detail":"{read_only:false; response_revision:26011; number_of_response:1; }","duration":"409.629239ms","start":"2026-07-16T01:13:51.086427Z","end":"2026-07-16T01:13:51.496056Z","steps":["trace[521038000] 'process raft request' (duration: 409.352583ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:51.496246Z","caller":"traceutil/trace.go:171","msg":"trace[914989175] linearizableReadLoop","detail":"{readStateIndex:26857; appliedIndex:26851; }","duration":"352.733226ms","start":"2026-07-16T01:13:51.143487Z","end":"2026-07-16T01:13:51.49622Z","steps":["trace[914989175] 'read index received' (duration: 102.796584ms)","trace[914989175] 'applied index is now lower than readState.Index' (duration: 249.935232ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:13:51.496304Z","caller":"traceutil/trace.go:171","msg":"trace[564971436] transaction","detail":"{read_only:false; response_revision:26013; number_of_response:1; }","duration":"409.149207ms","start":"2026-07-16T01:13:51.087146Z","end":"2026-07-16T01:13:51.496295Z","steps":["trace[564971436] 'process raft request' (duration: 408.956872ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:51.49627Z","caller":"traceutil/trace.go:171","msg":"trace[168697530] transaction","detail":"{read_only:false; response_revision:26012; number_of_response:1; }","duration":"409.476365ms","start":"2026-07-16T01:13:51.086749Z","end":"2026-07-16T01:13:51.496225Z","steps":["trace[168697530] 'process raft request' (duration: 409.210689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:51.496362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:51.087135Z","time spent":"409.198019ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:13:51.496446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.143871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T01:13:51.496447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:51.086739Z","time spent":"409.616708ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:13:51.496467Z","caller":"traceutil/trace.go:171","msg":"trace[547363004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26013; }","duration":"113.208133ms","start":"2026-07-16T01:13:51.383253Z","end":"2026-07-16T01:13:51.496461Z","steps":["trace[547363004] 'agreement among raft nodes before linearized reading' (duration: 113.159312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:51.496447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.940241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-16T01:13:51.496519Z","caller":"traceutil/trace.go:171","msg":"trace[2132353931] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:26013; }","duration":"353.050864ms","start":"2026-07-16T01:13:51.14345Z","end":"2026-07-16T01:13:51.496501Z","steps":["trace[2132353931] 'agreement among raft nodes before linearized reading' (duration: 352.8822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:51.496552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:51.143436Z","time spent":"353.109375ms","remote":"127.0.0.1:60054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":37,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-07-16T01:13:51.496542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.382127ms","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-07-16T01:13:51.496637Z","caller":"traceutil/trace.go:171","msg":"trace[1477939336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26013; }","duration":"275.48104ms","start":"2026-07-16T01:13:51.221099Z","end":"2026-07-16T01:13:51.49658Z","steps":["trace[1477939336] 'agreement among raft nodes before linearized reading' (duration: 275.292365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:51.49664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.940336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-16T01:13:51.49662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.830862ms","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":"warn","ts":"2026-07-16T01:13:51.496663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.434578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-07-16T01:13:51.496694Z","caller":"traceutil/trace.go:171","msg":"trace[1872524185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26013; }","duration":"272.011727ms","start":"2026-07-16T01:13:51.224668Z","end":"2026-07-16T01:13:51.496679Z","steps":["trace[1872524185] 'agreement among raft nodes before linearized reading' (duration: 271.827323ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:51.496717Z","caller":"traceutil/trace.go:171","msg":"trace[1596977737] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26013; }","duration":"269.003945ms","start":"2026-07-16T01:13:51.227691Z","end":"2026-07-16T01:13:51.496695Z","steps":["trace[1596977737] 'agreement among raft nodes before linearized reading' (duration: 268.714099ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:51.496726Z","caller":"traceutil/trace.go:171","msg":"trace[1302883246] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26013; }","duration":"243.52598ms","start":"2026-07-16T01:13:51.253184Z","end":"2026-07-16T01:13:51.49671Z","steps":["trace[1302883246] 'agreement among raft nodes before linearized reading' (duration: 243.294374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:51.496652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.620066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-16T01:13:51.496792Z","caller":"traceutil/trace.go:171","msg":"trace[279531] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26013; }","duration":"273.798629ms","start":"2026-07-16T01:13:51.222984Z","end":"2026-07-16T01:13:51.496783Z","steps":["trace[279531] 'agreement among raft nodes before linearized reading' (duration: 273.546313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:51.496965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.274083ms","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-07-16T01:13:51.497003Z","caller":"traceutil/trace.go:171","msg":"trace[1836463292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26013; }","duration":"272.348825ms","start":"2026-07-16T01:13:51.224641Z","end":"2026-07-16T01:13:51.49699Z","steps":["trace[1836463292] 'agreement among raft nodes before linearized reading' (duration: 271.806133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:51.497892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:51.086403Z","time spent":"409.755463ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:13:51.798281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.832663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:13:51.798486Z","caller":"traceutil/trace.go:171","msg":"trace[1386466868] transaction","detail":"{read_only:false; response_revision:26014; number_of_response:1; }","duration":"298.407624ms","start":"2026-07-16T01:13:51.500039Z","end":"2026-07-16T01:13:51.798447Z","steps":["trace[1386466868] 'process raft request' (duration: 145.338065ms)","trace[1386466868] 'compare' (duration: 152.644058ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:13:51.798525Z","caller":"traceutil/trace.go:171","msg":"trace[171576230] transaction","detail":"{read_only:false; response_revision:26015; number_of_response:1; }","duration":"297.832711ms","start":"2026-07-16T01:13:51.500665Z","end":"2026-07-16T01:13:51.798498Z","steps":["trace[171576230] 'process raft request' (duration: 297.710388ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:51.798555Z","caller":"traceutil/trace.go:171","msg":"trace[2118067207] transaction","detail":"{read_only:false; response_revision:26017; number_of_response:1; }","duration":"296.920729ms","start":"2026-07-16T01:13:51.501616Z","end":"2026-07-16T01:13:51.798537Z","steps":["trace[2118067207] 'process raft request' (duration: 296.855328ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:51.798556Z","caller":"traceutil/trace.go:171","msg":"trace[28321478] transaction","detail":"{read_only:false; response_revision:26016; number_of_response:1; }","duration":"297.311438ms","start":"2026-07-16T01:13:51.501212Z","end":"2026-07-16T01:13:51.798523Z","steps":["trace[28321478] 'process raft request' (duration: 297.219726ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:51.798648Z","caller":"traceutil/trace.go:171","msg":"trace[2065927520] transaction","detail":"{read_only:false; response_revision:26018; number_of_response:1; }","duration":"296.464868ms","start":"2026-07-16T01:13:51.50216Z","end":"2026-07-16T01:13:51.798625Z","steps":["trace[2065927520] 'process raft request' (duration: 296.348056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:51.966184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.580863ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-16T01:13:51.967164Z","caller":"traceutil/trace.go:171","msg":"trace[913663667] linearizableReadLoop","detail":"{readStateIndex:26864; appliedIndex:26862; }","duration":"124.406618ms","start":"2026-07-16T01:13:51.842733Z","end":"2026-07-16T01:13:51.96714Z","steps":["trace[913663667] 'read index received' (duration: 32.447152ms)","trace[913663667] 'applied index is now lower than readState.Index' (duration: 91.957816ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:13:51.967194Z","caller":"traceutil/trace.go:171","msg":"trace[1371712060] transaction","detail":"{read_only:false; response_revision:26019; number_of_response:1; }","duration":"200.158899ms","start":"2026-07-16T01:13:51.767007Z","end":"2026-07-16T01:13:51.967166Z","steps":["trace[1371712060] 'process raft request' (duration: 199.309199ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:52.141565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.803024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/magnum-api-f7bf6fdd5-qcglr.18c2a0098743baec\" ","response":"range_response_count:1 size:887"} {"level":"warn","ts":"2026-07-16T01:13:52.141814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.783887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:13:52.141921Z","caller":"traceutil/trace.go:171","msg":"trace[648546439] linearizableReadLoop","detail":"{readStateIndex:26865; appliedIndex:26864; }","duration":"174.677562ms","start":"2026-07-16T01:13:51.967227Z","end":"2026-07-16T01:13:52.141905Z","steps":["trace[648546439] 'read index received' (duration: 62.70166ms)","trace[648546439] 'applied index is now lower than readState.Index' (duration: 111.973452ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:13:52.141928Z","caller":"traceutil/trace.go:171","msg":"trace[1271769579] transaction","detail":"{read_only:false; response_revision:26020; number_of_response:1; }","duration":"245.202128ms","start":"2026-07-16T01:13:51.896704Z","end":"2026-07-16T01:13:52.141906Z","steps":["trace[1271769579] 'process raft request' (duration: 133.236277ms)","trace[1271769579] 'compare' (duration: 111.312576ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:13:52.141981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.58894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-16T01:13:52.142041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.74736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:13:52.142054Z","caller":"traceutil/trace.go:171","msg":"trace[1874303000] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:26020; }","duration":"219.702022ms","start":"2026-07-16T01:13:51.92233Z","end":"2026-07-16T01:13:52.142032Z","steps":["trace[1874303000] 'agreement among raft nodes before linearized reading' (duration: 219.58048ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:52.142087Z","caller":"traceutil/trace.go:171","msg":"trace[454112423] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:26020; }","duration":"279.800211ms","start":"2026-07-16T01:13:51.862276Z","end":"2026-07-16T01:13:52.142077Z","steps":["trace[454112423] 'agreement among raft nodes before linearized reading' (duration: 279.71901ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:52.142383Z","caller":"traceutil/trace.go:171","msg":"trace[302516640] range","detail":"{range_begin:/registry/events/openstack/magnum-api-f7bf6fdd5-qcglr.18c2a0098743baec; range_end:; response_count:1; response_revision:26019; }","duration":"298.952547ms","start":"2026-07-16T01:13:51.842692Z","end":"2026-07-16T01:13:52.141644Z","steps":["trace[302516640] 'agreement among raft nodes before linearized reading' (duration: 124.526541ms)","trace[302516640] 'range keys from in-memory index tree' (duration: 174.178851ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:13:52.384498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.729037ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:13:52.384608Z","caller":"traceutil/trace.go:171","msg":"trace[990222154] transaction","detail":"{read_only:false; response_revision:26021; number_of_response:1; }","duration":"240.441206ms","start":"2026-07-16T01:13:52.14415Z","end":"2026-07-16T01:13:52.384591Z","steps":["trace[990222154] 'process raft request' (duration: 112.516255ms)","trace[990222154] 'compare' (duration: 127.44264ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:13:52.460584Z","caller":"traceutil/trace.go:171","msg":"trace[1743431413] transaction","detail":"{read_only:false; response_revision:26022; number_of_response:1; }","duration":"153.891338ms","start":"2026-07-16T01:13:52.306667Z","end":"2026-07-16T01:13:52.460558Z","steps":["trace[1743431413] 'process raft request' (duration: 153.744465ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:52.985828Z","caller":"traceutil/trace.go:171","msg":"trace[982361975] linearizableReadLoop","detail":"{readStateIndex:26869; appliedIndex:26868; }","duration":"280.807526ms","start":"2026-07-16T01:13:52.705001Z","end":"2026-07-16T01:13:52.985808Z","steps":["trace[982361975] 'read index received' (duration: 21.335868ms)","trace[982361975] 'applied index is now lower than readState.Index' (duration: 259.470808ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:13:52.986176Z","caller":"traceutil/trace.go:171","msg":"trace[2073907818] transaction","detail":"{read_only:false; response_revision:26024; number_of_response:1; }","duration":"339.874841ms","start":"2026-07-16T01:13:52.646286Z","end":"2026-07-16T01:13:52.98616Z","steps":["trace[2073907818] 'process raft request' (duration: 323.374058ms)","trace[2073907818] 'compare' (duration: 16.011ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:13:52.986173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.953012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-07-16T01:13:52.986232Z","caller":"traceutil/trace.go:171","msg":"trace[367048912] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26024; }","duration":"210.059494ms","start":"2026-07-16T01:13:52.776157Z","end":"2026-07-16T01:13:52.986217Z","steps":["trace[367048912] 'agreement among raft nodes before linearized reading' (duration: 209.87691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:52.986278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:52.646278Z","time spent":"339.931002ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:13:52.9863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.284197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10854"} {"level":"info","ts":"2026-07-16T01:13:52.986372Z","caller":"traceutil/trace.go:171","msg":"trace[1100180559] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26024; }","duration":"281.40835ms","start":"2026-07-16T01:13:52.704947Z","end":"2026-07-16T01:13:52.986355Z","steps":["trace[1100180559] 'agreement among raft nodes before linearized reading' (duration: 281.178305ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:53.309474Z","caller":"traceutil/trace.go:171","msg":"trace[1976396167] transaction","detail":"{read_only:false; response_revision:26025; number_of_response:1; }","duration":"312.897529ms","start":"2026-07-16T01:13:52.996549Z","end":"2026-07-16T01:13:53.309447Z","steps":["trace[1976396167] 'process raft request' (duration: 312.249864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:53.309614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:52.996517Z","time spent":"313.026332ms","remote":"127.0.0.1:44354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10656,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:13:53.30983Z","caller":"traceutil/trace.go:171","msg":"trace[1000549256] linearizableReadLoop","detail":"{readStateIndex:26870; appliedIndex:26869; }","duration":"313.234387ms","start":"2026-07-16T01:13:52.996581Z","end":"2026-07-16T01:13:53.309815Z","steps":["trace[1000549256] 'read index received' (duration: 312.136751ms)","trace[1000549256] 'applied index is now lower than readState.Index' (duration: 1.096145ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:13:53.30998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.39214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-7ae1c35d-e1ee-4ccd-aefd-a257ef1bf7fc\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-07-16T01:13:53.310021Z","caller":"traceutil/trace.go:171","msg":"trace[1453886124] range","detail":"{range_begin:/registry/persistentvolumes/pvc-7ae1c35d-e1ee-4ccd-aefd-a257ef1bf7fc; range_end:; response_count:1; response_revision:26026; }","duration":"313.461812ms","start":"2026-07-16T01:13:52.996546Z","end":"2026-07-16T01:13:53.310008Z","steps":["trace[1453886124] 'agreement among raft nodes before linearized reading' (duration: 313.333729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:53.310055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:13:52.996533Z","time spent":"313.515923ms","remote":"127.0.0.1:44322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1342,"request content":"key:\"/registry/persistentvolumes/pvc-7ae1c35d-e1ee-4ccd-aefd-a257ef1bf7fc\" "} {"level":"info","ts":"2026-07-16T01:13:58.882147Z","caller":"traceutil/trace.go:171","msg":"trace[512112039] transaction","detail":"{read_only:false; response_revision:26085; number_of_response:1; }","duration":"140.91432ms","start":"2026-07-16T01:13:58.741166Z","end":"2026-07-16T01:13:58.88208Z","steps":["trace[512112039] 'process raft request' (duration: 140.441339ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:59.945241Z","caller":"traceutil/trace.go:171","msg":"trace[2095365679] linearizableReadLoop","detail":"{readStateIndex:26940; appliedIndex:26939; }","duration":"108.94589ms","start":"2026-07-16T01:13:59.836264Z","end":"2026-07-16T01:13:59.945209Z","steps":["trace[2095365679] 'read index received' (duration: 105.210221ms)","trace[2095365679] 'applied index is now lower than readState.Index' (duration: 3.734009ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:13:59.945262Z","caller":"traceutil/trace.go:171","msg":"trace[2121794384] transaction","detail":"{read_only:false; response_revision:26094; number_of_response:1; }","duration":"109.080763ms","start":"2026-07-16T01:13:59.836153Z","end":"2026-07-16T01:13:59.945234Z","steps":["trace[2121794384] 'process raft request' (duration: 105.294973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:59.945393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.109724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-16T01:13:59.945592Z","caller":"traceutil/trace.go:171","msg":"trace[20151884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26094; }","duration":"109.333349ms","start":"2026-07-16T01:13:59.836221Z","end":"2026-07-16T01:13:59.945555Z","steps":["trace[20151884] 'agreement among raft nodes before linearized reading' (duration: 109.063623ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:59.958649Z","caller":"traceutil/trace.go:171","msg":"trace[1372801679] transaction","detail":"{read_only:false; response_revision:26095; number_of_response:1; }","duration":"122.064172ms","start":"2026-07-16T01:13:59.836545Z","end":"2026-07-16T01:13:59.958609Z","steps":["trace[1372801679] 'process raft request' (duration: 121.874157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:13:59.958721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.293557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-16T01:13:59.958761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.304028ms","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-07-16T01:13:59.958804Z","caller":"traceutil/trace.go:171","msg":"trace[345724405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26095; }","duration":"122.401661ms","start":"2026-07-16T01:13:59.83638Z","end":"2026-07-16T01:13:59.958782Z","steps":["trace[345724405] 'agreement among raft nodes before linearized reading' (duration: 122.167814ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:13:59.958818Z","caller":"traceutil/trace.go:171","msg":"trace[2027166090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26095; }","duration":"122.40226ms","start":"2026-07-16T01:13:59.836401Z","end":"2026-07-16T01:13:59.958803Z","steps":["trace[2027166090] 'agreement among raft nodes before linearized reading' (duration: 122.244736ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:10.205922Z","caller":"traceutil/trace.go:171","msg":"trace[800327056] transaction","detail":"{read_only:false; response_revision:26187; number_of_response:1; }","duration":"153.784535ms","start":"2026-07-16T01:14:10.052117Z","end":"2026-07-16T01:14:10.205901Z","steps":["trace[800327056] 'process raft request' (duration: 153.754784ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:10.206041Z","caller":"traceutil/trace.go:171","msg":"trace[1015523009] transaction","detail":"{read_only:false; response_revision:26184; number_of_response:1; }","duration":"155.036216ms","start":"2026-07-16T01:14:10.050999Z","end":"2026-07-16T01:14:10.206035Z","steps":["trace[1015523009] 'process raft request' (duration: 64.616686ms)","trace[1015523009] 'compare' (duration: 90.063801ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T01:14:10.206082Z","caller":"traceutil/trace.go:171","msg":"trace[1305723755] transaction","detail":"{read_only:false; response_revision:26185; number_of_response:1; }","duration":"154.153984ms","start":"2026-07-16T01:14:10.051924Z","end":"2026-07-16T01:14:10.206078Z","steps":["trace[1305723755] 'process raft request' (duration: 153.868357ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:10.206122Z","caller":"traceutil/trace.go:171","msg":"trace[276163484] transaction","detail":"{read_only:false; response_revision:26186; number_of_response:1; }","duration":"154.034752ms","start":"2026-07-16T01:14:10.052064Z","end":"2026-07-16T01:14:10.206099Z","steps":["trace[276163484] 'process raft request' (duration: 153.756504ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:21.2336Z","caller":"traceutil/trace.go:171","msg":"trace[513024816] transaction","detail":"{read_only:false; response_revision:26266; number_of_response:1; }","duration":"111.301386ms","start":"2026-07-16T01:14:21.122268Z","end":"2026-07-16T01:14:21.233569Z","steps":["trace[513024816] 'process raft request' (duration: 96.143936ms)","trace[513024816] 'compare' (duration: 14.151617ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:14:21.484231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.392327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:14:21.484316Z","caller":"traceutil/trace.go:171","msg":"trace[1194721855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26266; }","duration":"103.52311ms","start":"2026-07-16T01:14:21.380775Z","end":"2026-07-16T01:14:21.484298Z","steps":["trace[1194721855] 'range keys from in-memory index tree' (duration: 103.253194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:21.823723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.197439ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-16T01:14:21.824052Z","caller":"traceutil/trace.go:171","msg":"trace[56423521] transaction","detail":"{read_only:false; response_revision:26268; number_of_response:1; }","duration":"182.978351ms","start":"2026-07-16T01:14:21.641054Z","end":"2026-07-16T01:14:21.824033Z","steps":["trace[56423521] 'process raft request' (duration: 182.750875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:21.987457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.340373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-16T01:14:21.9878Z","caller":"traceutil/trace.go:171","msg":"trace[488434801] linearizableReadLoop","detail":"{readStateIndex:27123; appliedIndex:27119; }","duration":"164.645454ms","start":"2026-07-16T01:14:21.823133Z","end":"2026-07-16T01:14:21.987779Z","steps":["trace[488434801] 'read index received' (duration: 14.352932ms)","trace[488434801] 'applied index is now lower than readState.Index' (duration: 150.291332ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:14:21.987932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.798398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:14:21.987985Z","caller":"traceutil/trace.go:171","msg":"trace[801549748] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:26272; }","duration":"164.90774ms","start":"2026-07-16T01:14:21.823062Z","end":"2026-07-16T01:14:21.98797Z","steps":["trace[801549748] 'agreement among raft nodes before linearized reading' (duration: 164.793448ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:21.987966Z","caller":"traceutil/trace.go:171","msg":"trace[741744757] transaction","detail":"{read_only:false; response_revision:26270; number_of_response:1; }","duration":"307.944291ms","start":"2026-07-16T01:14:21.680003Z","end":"2026-07-16T01:14:21.987947Z","steps":["trace[741744757] 'process raft request' (duration: 307.592143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:21.988138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:21.679994Z","time spent":"308.067194ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:14:21.988252Z","caller":"traceutil/trace.go:171","msg":"trace[1691460290] transaction","detail":"{read_only:false; response_revision:26271; number_of_response:1; }","duration":"304.201852ms","start":"2026-07-16T01:14:21.684037Z","end":"2026-07-16T01:14:21.988238Z","steps":["trace[1691460290] 'process raft request' (duration: 303.643349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:21.988334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:21.684026Z","time spent":"304.268163ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:14:21.988447Z","caller":"traceutil/trace.go:171","msg":"trace[10835750] transaction","detail":"{read_only:false; response_revision:26272; number_of_response:1; }","duration":"304.016137ms","start":"2026-07-16T01:14:21.684417Z","end":"2026-07-16T01:14:21.988433Z","steps":["trace[10835750] 'process raft request' (duration: 303.312641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:21.988515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:21.684409Z","time spent":"304.072028ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T01:14:21.98864Z","caller":"traceutil/trace.go:171","msg":"trace[1592696681] transaction","detail":"{read_only:false; response_revision:26269; number_of_response:1; }","duration":"311.034936ms","start":"2026-07-16T01:14:21.677587Z","end":"2026-07-16T01:14:21.988622Z","steps":["trace[1592696681] 'process raft request' (duration: 146.436163ms)","trace[1592696681] 'compare' (duration: 162.757309ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T01:14:21.98881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T01:14:21.677564Z","time spent":"311.195689ms","remote":"127.0.0.1:44414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T01:14:22.294254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.6802ms","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-07-16T01:14:22.294314Z","caller":"traceutil/trace.go:171","msg":"trace[964654691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26272; }","duration":"197.775882ms","start":"2026-07-16T01:14:22.096523Z","end":"2026-07-16T01:14:22.294299Z","steps":["trace[964654691] 'range keys from in-memory index tree' (duration: 197.416213ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:14:25.97971Z","caller":"traceutil/trace.go:171","msg":"trace[1501194915] transaction","detail":"{read_only:false; response_revision:26297; number_of_response:1; }","duration":"144.7467ms","start":"2026-07-16T01:14:25.834931Z","end":"2026-07-16T01:14:25.979677Z","steps":["trace[1501194915] 'process raft request' (duration: 144.568006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T01:14:31.54914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.833692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-16T01:14:31.549208Z","caller":"traceutil/trace.go:171","msg":"trace[663954841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26344; }","duration":"165.940185ms","start":"2026-07-16T01:14:31.383252Z","end":"2026-07-16T01:14:31.549192Z","steps":["trace[663954841] 'range keys from in-memory index tree' (duration: 165.772881ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T01:18:22.999019Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25704} {"level":"info","ts":"2026-07-16T01:18:23.156314Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25704,"took":"149.393632ms","hash":3589734230,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":36622336,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-07-16T01:18:23.156416Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3589734230,"revision":25704,"compact-revision":22247} {"level":"info","ts":"2026-07-16T01:19:07.563837Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-16T01:19:07.568426Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-07-16T01:19:07.568532Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-07-16T01:23:23.00409Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28723} {"level":"info","ts":"2026-07-16T01:23:23.140372Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28723,"took":"132.257604ms","hash":1561785295,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":32587776,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-07-16T01:23:23.140429Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1561785295,"revision":28723,"compact-revision":25704} {"level":"info","ts":"2026-07-16T01:28:23.012326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30994} {"level":"info","ts":"2026-07-16T01:28:23.143138Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30994,"took":"126.481588ms","hash":3911622607,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":30904320,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-16T01:28:23.143208Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3911622607,"revision":30994,"compact-revision":28723} {"level":"info","ts":"2026-07-16T01:33:23.021149Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33262} {"level":"info","ts":"2026-07-16T01:33:23.159182Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33262,"took":"133.948437ms","hash":4062026516,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":31027200,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-16T01:33:23.159267Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4062026516,"revision":33262,"compact-revision":30994} {"level":"info","ts":"2026-07-16T01:38:23.044679Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35571} {"level":"info","ts":"2026-07-16T01:38:23.176184Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35571,"took":"127.802877ms","hash":317792830,"current-db-size-bytes":59248640,"current-db-size":"59 MB","current-db-size-in-use-bytes":30564352,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-16T01:38:23.176242Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":317792830,"revision":35571,"compact-revision":33262} {"level":"info","ts":"2026-07-16T01:40:23.733743Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-16T01:40:23.740033Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-07-16T01:40:23.740299Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}