{"level":"warn","ts":"2026-09-08T15:48:32.495374Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T15:48:32.495485Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.85: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://162.253.55.85:2380","--initial-cluster=instance=https://162.253.55.85:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.85:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.85: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-09-08T15:48:32.495573Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-08T15:48:32.495587Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.85:2380"]} {"level":"info","ts":"2026-09-08T15:48:32.495616Z","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-09-08T15:48:32.496451Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"]} {"level":"info","ts":"2026-09-08T15:48:32.496579Z","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://162.253.55.85:2380"],"listen-peer-urls":["https://162.253.55.85:2380"],"advertise-client-urls":["https://162.253.55.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.85: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-09-08T15:48:32.510736Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"13.788865ms"} {"level":"info","ts":"2026-09-08T15:48:32.536498Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"427150911cb3975a","cluster-id":"f76f6804bd8187de"} {"level":"info","ts":"2026-09-08T15:48:32.53664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=()"} {"level":"info","ts":"2026-09-08T15:48:32.536685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became follower at term 0"} {"level":"info","ts":"2026-09-08T15:48:32.536712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 427150911cb3975a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-08T15:48:32.536741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became follower at term 1"} {"level":"info","ts":"2026-09-08T15:48:32.536817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=(4787696463053559642)"} {"level":"warn","ts":"2026-09-08T15:48:32.548494Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-08T15:48:32.553361Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-08T15:48:32.55609Z","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-09-08T15:48:32.559136Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"427150911cb3975a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-08T15:48:32.559334Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"427150911cb3975a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-08T15:48:32.559422Z","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-09-08T15:48:32.559524Z","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-09-08T15:48:32.559536Z","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-09-08T15:48:32.56195Z","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-09-08T15:48:32.562066Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.85:2380"} {"level":"info","ts":"2026-09-08T15:48:32.562114Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.85:2380"} {"level":"info","ts":"2026-09-08T15:48:32.562294Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"427150911cb3975a","initial-advertise-peer-urls":["https://162.253.55.85:2380"],"listen-peer-urls":["https://162.253.55.85:2380"],"advertise-client-urls":["https://162.253.55.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-08T15:48:32.562356Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-08T15:48:32.562554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=(4787696463053559642)"} {"level":"info","ts":"2026-09-08T15:48:32.562797Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f76f6804bd8187de","local-member-id":"427150911cb3975a","added-peer-id":"427150911cb3975a","added-peer-peer-urls":["https://162.253.55.85:2380"]} {"level":"info","ts":"2026-09-08T15:48:32.93719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a is starting a new election at term 1"} {"level":"info","ts":"2026-09-08T15:48:32.937267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became pre-candidate at term 1"} {"level":"info","ts":"2026-09-08T15:48:32.937313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a received MsgPreVoteResp from 427150911cb3975a at term 1"} {"level":"info","ts":"2026-09-08T15:48:32.937339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became candidate at term 2"} {"level":"info","ts":"2026-09-08T15:48:32.937354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a received MsgVoteResp from 427150911cb3975a at term 2"} {"level":"info","ts":"2026-09-08T15:48:32.937375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became leader at term 2"} {"level":"info","ts":"2026-09-08T15:48:32.937394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 427150911cb3975a elected leader 427150911cb3975a at term 2"} {"level":"info","ts":"2026-09-08T15:48:32.938445Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:48:32.939954Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:48:32.939942Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"427150911cb3975a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.85:2379]}","request-path":"/0/members/427150911cb3975a/attributes","cluster-id":"f76f6804bd8187de","publish-timeout":"7s"} {"level":"info","ts":"2026-09-08T15:48:32.93998Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-08T15:48:32.940252Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-08T15:48:32.940273Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-08T15:48:32.942789Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f76f6804bd8187de","local-member-id":"427150911cb3975a","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:48:32.942898Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:48:32.942929Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-08T15:48:32.943202Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.85:2379"} {"level":"info","ts":"2026-09-08T15:48:32.944816Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-08T15:48:42.667274Z","caller":"traceutil/trace.go:171","msg":"trace[832294100] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:326; }","duration":"360.273639ms","start":"2026-09-08T15:48:42.306977Z","end":"2026-09-08T15:48:42.667251Z","steps":["trace[832294100] 'read index received' (duration: 1.48709ms)","trace[832294100] 'applied index is now lower than readState.Index' (duration: 358.785569ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:48:42.667417Z","caller":"traceutil/trace.go:171","msg":"trace[2063261914] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"385.120762ms","start":"2026-09-08T15:48:42.282242Z","end":"2026-09-08T15:48:42.667363Z","steps":["trace[2063261914] 'process raft request' (duration: 384.962449ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:42.66748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.43334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-09-08T15:48:42.667477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.481584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-09-08T15:48:42.667553Z","caller":"traceutil/trace.go:171","msg":"trace[2034035850] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:316; }","duration":"310.528272ms","start":"2026-09-08T15:48:42.357013Z","end":"2026-09-08T15:48:42.667541Z","steps":["trace[2034035850] 'agreement among raft nodes before linearized reading' (duration: 310.396699ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:42.667593Z","caller":"traceutil/trace.go:171","msg":"trace[107830877] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:316; }","duration":"360.638216ms","start":"2026-09-08T15:48:42.30693Z","end":"2026-09-08T15:48:42.667568Z","steps":["trace[107830877] 'agreement among raft nodes before linearized reading' (duration: 360.464273ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:42.667597Z","caller":"traceutil/trace.go:171","msg":"trace[1292327757] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"386.840437ms","start":"2026-09-08T15:48:42.280733Z","end":"2026-09-08T15:48:42.667573Z","steps":["trace[1292327757] 'process raft request' (duration: 386.278035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:42.667589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:42.356979Z","time spent":"310.601923ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-09-08T15:48:42.667748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:42.306916Z","time spent":"360.734249ms","remote":"127.0.0.1:52994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-09-08T15:48:42.667772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.414413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-09-08T15:48:42.667964Z","caller":"traceutil/trace.go:171","msg":"trace[1444758978] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:316; }","duration":"160.629228ms","start":"2026-09-08T15:48:42.507309Z","end":"2026-09-08T15:48:42.667938Z","steps":["trace[1444758978] 'agreement among raft nodes before linearized reading' (duration: 160.295031ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:42.667844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.840904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-09-08T15:48:42.668192Z","caller":"traceutil/trace.go:171","msg":"trace[1754226524] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:316; }","duration":"211.216251ms","start":"2026-09-08T15:48:42.456956Z","end":"2026-09-08T15:48:42.668172Z","steps":["trace[1754226524] 'agreement among raft nodes before linearized reading' (duration: 210.746102ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:42.667878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.252053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-09-08T15:48:42.668318Z","caller":"traceutil/trace.go:171","msg":"trace[2143452387] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:316; }","duration":"259.711973ms","start":"2026-09-08T15:48:42.408588Z","end":"2026-09-08T15:48:42.6683Z","steps":["trace[2143452387] 'agreement among raft nodes before linearized reading' (duration: 259.236593ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:42.668464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:42.280711Z","time spent":"386.965619ms","remote":"127.0.0.1:53136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:48:42.668563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:42.282223Z","time spent":"385.292066ms","remote":"127.0.0.1:53136","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:48:51.199851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.608023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-08T15:48:51.199938Z","caller":"traceutil/trace.go:171","msg":"trace[58224578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:374; }","duration":"124.741045ms","start":"2026-09-08T15:48:51.075179Z","end":"2026-09-08T15:48:51.19992Z","steps":["trace[58224578] 'range keys from in-memory index tree' (duration: 124.48164ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:51.383642Z","caller":"traceutil/trace.go:171","msg":"trace[196548197] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"179.596988ms","start":"2026-09-08T15:48:51.204019Z","end":"2026-09-08T15:48:51.383616Z","steps":["trace[196548197] 'process raft request' (duration: 179.383084ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:52.404608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.909719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:48:52.404741Z","caller":"traceutil/trace.go:171","msg":"trace[297224594] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"1.016494458s","start":"2026-09-08T15:48:51.38823Z","end":"2026-09-08T15:48:52.404724Z","steps":["trace[297224594] 'process raft request' (duration: 643.187629ms)","trace[297224594] 'compare' (duration: 372.794077ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:48:52.404798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:51.388208Z","time spent":"1.016562249s","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:48:53.115844Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.084389409s","expected-duration":"1s"} {"level":"warn","ts":"2026-09-08T15:48:53.484596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.371086ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:48:53.484788Z","caller":"traceutil/trace.go:171","msg":"trace[1490035103] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"2.092437762s","start":"2026-09-08T15:48:51.392309Z","end":"2026-09-08T15:48:53.484747Z","steps":["trace[1490035103] 'process raft request' (duration: 1.723860992s)","trace[1490035103] 'compare' (duration: 368.233803ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:48:53.484899Z","caller":"traceutil/trace.go:171","msg":"trace[1946812131] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:390; }","duration":"1.642932941s","start":"2026-09-08T15:48:51.841943Z","end":"2026-09-08T15:48:53.484876Z","steps":["trace[1946812131] 'read index received' (duration: 189.476832ms)","trace[1946812131] 'applied index is now lower than readState.Index' (duration: 1.453454329s)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:48:53.484992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:51.392284Z","time spent":"2.092603935s","remote":"127.0.0.1:52972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:48:53.912169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.438105ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-09-08T15:48:53.912243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.070278764s","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-09-08T15:48:53.912298Z","caller":"traceutil/trace.go:171","msg":"trace[1250452108] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:392; }","duration":"427.329873ms","start":"2026-09-08T15:48:53.484944Z","end":"2026-09-08T15:48:53.912274Z","steps":["trace[1250452108] 'read index received' (duration: 44.501µs)","trace[1250452108] 'applied index is now lower than readState.Index' (duration: 427.283642ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:48:53.912318Z","caller":"traceutil/trace.go:171","msg":"trace[1245842889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:377; }","duration":"2.070391767s","start":"2026-09-08T15:48:51.841904Z","end":"2026-09-08T15:48:53.912296Z","steps":["trace[1245842889] 'agreement among raft nodes before linearized reading' (duration: 1.643084634s)","trace[1245842889] 'range keys from in-memory index tree' (duration: 427.18607ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:48:53.912358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:51.841885Z","time spent":"2.070464968s","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-08T15:48:53.912377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.723139ms","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-09-08T15:48:53.91245Z","caller":"traceutil/trace.go:171","msg":"trace[857380906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:377; }","duration":"965.861131ms","start":"2026-09-08T15:48:52.946568Z","end":"2026-09-08T15:48:53.912429Z","steps":["trace[857380906] 'agreement among raft nodes before linearized reading' (duration: 965.738779ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:53.912449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.462869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-09-08T15:48:53.91249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:52.946519Z","time spent":"965.964463ms","remote":"127.0.0.1:53054","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-09-08T15:48:53.912477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.7252124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T15:48:53.912484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.192978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-09-08T15:48:53.912527Z","caller":"traceutil/trace.go:171","msg":"trace[149512172] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"524.582842ms","start":"2026-09-08T15:48:53.387919Z","end":"2026-09-08T15:48:53.912502Z","steps":["trace[149512172] 'agreement among raft nodes before linearized reading' (duration: 524.380518ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:53.91253Z","caller":"traceutil/trace.go:171","msg":"trace[1970266724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"1.725272501s","start":"2026-09-08T15:48:52.187243Z","end":"2026-09-08T15:48:53.912516Z","steps":["trace[1970266724] 'agreement among raft nodes before linearized reading' (duration: 1.725149349s)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:53.912567Z","caller":"traceutil/trace.go:171","msg":"trace[690476709] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:377; }","duration":"424.309501ms","start":"2026-09-08T15:48:53.488237Z","end":"2026-09-08T15:48:53.912547Z","steps":["trace[690476709] 'agreement among raft nodes before linearized reading' (duration: 424.178798ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:53.912575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:53.387899Z","time spent":"524.662103ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-08T15:48:53.912579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:52.187187Z","time spent":"1.725381203s","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-08T15:48:53.912608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:53.488219Z","time spent":"424.377552ms","remote":"127.0.0.1:52972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4345,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-09-08T15:48:54.048918Z","caller":"traceutil/trace.go:171","msg":"trace[663687484] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"131.589617ms","start":"2026-09-08T15:48:53.9173Z","end":"2026-09-08T15:48:54.048889Z","steps":["trace[663687484] 'process raft request' (duration: 131.383483ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:54.221099Z","caller":"traceutil/trace.go:171","msg":"trace[1403834598] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:393; }","duration":"295.192145ms","start":"2026-09-08T15:48:53.925887Z","end":"2026-09-08T15:48:54.221079Z","steps":["trace[1403834598] 'read index received' (duration: 122.832856ms)","trace[1403834598] 'applied index is now lower than readState.Index' (duration: 172.358259ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:48:54.221207Z","caller":"traceutil/trace.go:171","msg":"trace[709939372] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"299.732948ms","start":"2026-09-08T15:48:53.92145Z","end":"2026-09-08T15:48:54.221183Z","steps":["trace[709939372] 'process raft request' (duration: 299.558464ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:54.221239Z","caller":"traceutil/trace.go:171","msg":"trace[1980735557] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"302.762901ms","start":"2026-09-08T15:48:53.918464Z","end":"2026-09-08T15:48:54.221227Z","steps":["trace[1980735557] 'process raft request' (duration: 302.500115ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:54.221234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.329847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:48:54.221279Z","caller":"traceutil/trace.go:171","msg":"trace[1164571273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:381; }","duration":"295.40902ms","start":"2026-09-08T15:48:53.925855Z","end":"2026-09-08T15:48:54.221264Z","steps":["trace[1164571273] 'agreement among raft nodes before linearized reading' (duration: 295.333338ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:48:54.221101Z","caller":"traceutil/trace.go:171","msg":"trace[1830049572] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"302.999946ms","start":"2026-09-08T15:48:53.918075Z","end":"2026-09-08T15:48:54.221075Z","steps":["trace[1830049572] 'process raft request' (duration: 302.747131ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:48:54.221335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:53.918282Z","time spent":"303.008746ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:48:54.221456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:48:53.918044Z","time spent":"303.321583ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:49:00.42205Z","caller":"traceutil/trace.go:171","msg":"trace[1795576668] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"279.339323ms","start":"2026-09-08T15:49:00.142685Z","end":"2026-09-08T15:49:00.422024Z","steps":["trace[1795576668] 'process raft request' (duration: 279.154939ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:00.972025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10906205926577577113,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-08T15:49:01.239939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.494114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:49:01.240081Z","caller":"traceutil/trace.go:171","msg":"trace[2009786577] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"813.734649ms","start":"2026-09-08T15:49:00.426322Z","end":"2026-09-08T15:49:01.240057Z","steps":["trace[2009786577] 'process raft request' (duration: 634.001891ms)","trace[2009786577] 'compare' (duration: 179.115866ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:01.240164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:00.426299Z","time spent":"813.821451ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:49:01.279255Z","caller":"traceutil/trace.go:171","msg":"trace[1735438388] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:411; }","duration":"808.165901ms","start":"2026-09-08T15:49:00.471061Z","end":"2026-09-08T15:49:01.279227Z","steps":["trace[1735438388] 'read index received' (duration: 589.337066ms)","trace[1735438388] 'applied index is now lower than readState.Index' (duration: 218.827985ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:01.279391Z","caller":"traceutil/trace.go:171","msg":"trace[2092790158] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"852.916617ms","start":"2026-09-08T15:49:00.426433Z","end":"2026-09-08T15:49:01.279349Z","steps":["trace[2092790158] 'process raft request' (duration: 852.715153ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:01.279493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.381616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-09-08T15:49:01.279505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:00.426406Z","time spent":"853.04673ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:49:01.279555Z","caller":"traceutil/trace.go:171","msg":"trace[513251865] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:396; }","duration":"808.52109ms","start":"2026-09-08T15:49:00.471016Z","end":"2026-09-08T15:49:01.279537Z","steps":["trace[513251865] 'agreement among raft nodes before linearized reading' (duration: 808.332535ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:01.279696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:00.470995Z","time spent":"808.635052ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-08T15:49:04.761957Z","caller":"traceutil/trace.go:171","msg":"trace[1923990175] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"102.515177ms","start":"2026-09-08T15:49:04.659418Z","end":"2026-09-08T15:49:04.761933Z","steps":["trace[1923990175] 'process raft request' (duration: 102.472507ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:04.762153Z","caller":"traceutil/trace.go:171","msg":"trace[1727935539] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"103.787924ms","start":"2026-09-08T15:49:04.658338Z","end":"2026-09-08T15:49:04.762126Z","steps":["trace[1727935539] 'process raft request' (duration: 103.453818ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:04.762193Z","caller":"traceutil/trace.go:171","msg":"trace[110976230] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"105.194874ms","start":"2026-09-08T15:49:04.656989Z","end":"2026-09-08T15:49:04.762184Z","steps":["trace[110976230] 'process raft request' (duration: 68.185151ms)","trace[110976230] 'compare' (duration: 36.467791ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:04.762212Z","caller":"traceutil/trace.go:171","msg":"trace[1948284062] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"103.647411ms","start":"2026-09-08T15:49:04.658545Z","end":"2026-09-08T15:49:04.762193Z","steps":["trace[1948284062] 'process raft request' (duration: 103.312264ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:04.9901Z","caller":"traceutil/trace.go:171","msg":"trace[902473750] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"222.984933ms","start":"2026-09-08T15:49:04.767093Z","end":"2026-09-08T15:49:04.990078Z","steps":["trace[902473750] 'process raft request' (duration: 154.261861ms)","trace[902473750] 'compare' (duration: 68.533658ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:04.99032Z","caller":"traceutil/trace.go:171","msg":"trace[2060327160] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"220.936471ms","start":"2026-09-08T15:49:04.769353Z","end":"2026-09-08T15:49:04.990289Z","steps":["trace[2060327160] 'read index received' (duration: 63.666926ms)","trace[2060327160] 'applied index is now lower than readState.Index' (duration: 157.266445ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:04.99054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.162215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-09-08T15:49:04.990706Z","caller":"traceutil/trace.go:171","msg":"trace[1922567306] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:416; }","duration":"221.365449ms","start":"2026-09-08T15:49:04.769322Z","end":"2026-09-08T15:49:04.990687Z","steps":["trace[1922567306] 'agreement among raft nodes before linearized reading' (duration: 221.117704ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:04.991045Z","caller":"traceutil/trace.go:171","msg":"trace[1424620200] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"221.153555ms","start":"2026-09-08T15:49:04.769874Z","end":"2026-09-08T15:49:04.991028Z","steps":["trace[1424620200] 'process raft request' (duration: 220.153704ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:05.21008Z","caller":"traceutil/trace.go:171","msg":"trace[182761605] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"185.539162ms","start":"2026-09-08T15:49:05.024519Z","end":"2026-09-08T15:49:05.210059Z","steps":["trace[182761605] 'process raft request' (duration: 185.463261ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:05.210114Z","caller":"traceutil/trace.go:171","msg":"trace[1786966759] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"189.776682ms","start":"2026-09-08T15:49:05.020298Z","end":"2026-09-08T15:49:05.210074Z","steps":["trace[1786966759] 'process raft request' (duration: 120.495927ms)","trace[1786966759] 'compare' (duration: 68.741482ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:05.238332Z","caller":"traceutil/trace.go:171","msg":"trace[709050219] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:437; }","duration":"148.773844ms","start":"2026-09-08T15:49:05.089535Z","end":"2026-09-08T15:49:05.238308Z","steps":["trace[709050219] 'read index received' (duration: 51.274303ms)","trace[709050219] 'applied index is now lower than readState.Index' (duration: 97.498231ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:05.238452Z","caller":"traceutil/trace.go:171","msg":"trace[1155479712] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"147.688272ms","start":"2026-09-08T15:49:05.090751Z","end":"2026-09-08T15:49:05.23844Z","steps":["trace[1155479712] 'process raft request' (duration: 147.501008ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:05.238457Z","caller":"traceutil/trace.go:171","msg":"trace[1364495385] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"151.41446ms","start":"2026-09-08T15:49:05.087006Z","end":"2026-09-08T15:49:05.23842Z","steps":["trace[1364495385] 'process raft request' (duration: 151.118374ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:05.239044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.468869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:49:05.23913Z","caller":"traceutil/trace.go:171","msg":"trace[1746721589] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:423; }","duration":"149.610212ms","start":"2026-09-08T15:49:05.089497Z","end":"2026-09-08T15:49:05.239107Z","steps":["trace[1746721589] 'agreement among raft nodes before linearized reading' (duration: 148.900047ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:05.398439Z","caller":"traceutil/trace.go:171","msg":"trace[1874483524] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"181.276232ms","start":"2026-09-08T15:49:05.217097Z","end":"2026-09-08T15:49:05.398373Z","steps":["trace[1874483524] 'process raft request' (duration: 96.004529ms)","trace[1874483524] 'compare' (duration: 85.0982ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:05.409231Z","caller":"traceutil/trace.go:171","msg":"trace[1164512428] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"167.744176ms","start":"2026-09-08T15:49:05.241458Z","end":"2026-09-08T15:49:05.409202Z","steps":["trace[1164512428] 'process raft request' (duration: 167.486011ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:05.409287Z","caller":"traceutil/trace.go:171","msg":"trace[365793598] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"161.226178ms","start":"2026-09-08T15:49:05.248024Z","end":"2026-09-08T15:49:05.40925Z","steps":["trace[365793598] 'process raft request' (duration: 161.067935ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:05.409443Z","caller":"traceutil/trace.go:171","msg":"trace[1492769656] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:441; }","duration":"141.701465ms","start":"2026-09-08T15:49:05.2677Z","end":"2026-09-08T15:49:05.409401Z","steps":["trace[1492769656] 'read index received' (duration: 45.194225ms)","trace[1492769656] 'applied index is now lower than readState.Index' (duration: 96.50453ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:05.409537Z","caller":"traceutil/trace.go:171","msg":"trace[590039747] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"137.328843ms","start":"2026-09-08T15:49:05.272176Z","end":"2026-09-08T15:49:05.409504Z","steps":["trace[590039747] 'process raft request' (duration: 136.997826ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:05.409702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.959051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-08T15:49:05.409829Z","caller":"traceutil/trace.go:171","msg":"trace[1103251853] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:427; }","duration":"142.202276ms","start":"2026-09-08T15:49:05.267608Z","end":"2026-09-08T15:49:05.409811Z","steps":["trace[1103251853] 'agreement among raft nodes before linearized reading' (duration: 141.965581ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:05.409742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.630207ms","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-09-08T15:49:05.409968Z","caller":"traceutil/trace.go:171","msg":"trace[655176559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:427; }","duration":"108.901532ms","start":"2026-09-08T15:49:05.301044Z","end":"2026-09-08T15:49:05.409945Z","steps":["trace[655176559] 'agreement among raft nodes before linearized reading' (duration: 108.570155ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:05.603168Z","caller":"traceutil/trace.go:171","msg":"trace[876673866] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"188.933994ms","start":"2026-09-08T15:49:05.414196Z","end":"2026-09-08T15:49:05.60313Z","steps":["trace[876673866] 'process raft request' (duration: 160.286629ms)","trace[876673866] 'compare' (duration: 28.290048ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:05.603215Z","caller":"traceutil/trace.go:171","msg":"trace[1319101658] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"188.920812ms","start":"2026-09-08T15:49:05.414274Z","end":"2026-09-08T15:49:05.603194Z","steps":["trace[1319101658] 'process raft request' (duration: 188.859641ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:05.603223Z","caller":"traceutil/trace.go:171","msg":"trace[246321608] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"188.951514ms","start":"2026-09-08T15:49:05.414241Z","end":"2026-09-08T15:49:05.603192Z","steps":["trace[246321608] 'process raft request' (duration: 188.807291ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:05.603582Z","caller":"traceutil/trace.go:171","msg":"trace[2032430460] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"189.122997ms","start":"2026-09-08T15:49:05.41444Z","end":"2026-09-08T15:49:05.603563Z","steps":["trace[2032430460] 'process raft request' (duration: 188.970534ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:05.603593Z","caller":"traceutil/trace.go:171","msg":"trace[2019875529] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"189.155337ms","start":"2026-09-08T15:49:05.414368Z","end":"2026-09-08T15:49:05.603524Z","steps":["trace[2019875529] 'process raft request' (duration: 188.79201ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:05.6037Z","caller":"traceutil/trace.go:171","msg":"trace[913005744] linearizableReadLoop","detail":"{readStateIndex:452; appliedIndex:445; }","duration":"186.229545ms","start":"2026-09-08T15:49:05.417448Z","end":"2026-09-08T15:49:05.603678Z","steps":["trace[913005744] 'read index received' (duration: 75.841523ms)","trace[913005744] 'applied index is now lower than readState.Index' (duration: 110.386792ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:05.60372Z","caller":"traceutil/trace.go:171","msg":"trace[20950338] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"186.341478ms","start":"2026-09-08T15:49:05.417365Z","end":"2026-09-08T15:49:05.603706Z","steps":["trace[20950338] 'process raft request' (duration: 186.153274ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:05.603834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.362029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-09-08T15:49:05.603908Z","caller":"traceutil/trace.go:171","msg":"trace[1394380849] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:434; }","duration":"186.491942ms","start":"2026-09-08T15:49:05.417395Z","end":"2026-09-08T15:49:05.603887Z","steps":["trace[1394380849] 'agreement among raft nodes before linearized reading' (duration: 186.348699ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:05.785528Z","caller":"traceutil/trace.go:171","msg":"trace[905554476] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"174.225142ms","start":"2026-09-08T15:49:05.611269Z","end":"2026-09-08T15:49:05.785494Z","steps":["trace[905554476] 'process raft request' (duration: 134.558494ms)","trace[905554476] 'compare' (duration: 39.512145ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:26.052999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.749439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:49:26.053339Z","caller":"traceutil/trace.go:171","msg":"trace[1422124755] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:631; }","duration":"457.110782ms","start":"2026-09-08T15:49:25.596206Z","end":"2026-09-08T15:49:26.053317Z","steps":["trace[1422124755] 'read index received' (duration: 146.963356ms)","trace[1422124755] 'applied index is now lower than readState.Index' (duration: 310.146236ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:26.053361Z","caller":"traceutil/trace.go:171","msg":"trace[2039353950] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"511.541653ms","start":"2026-09-08T15:49:25.541786Z","end":"2026-09-08T15:49:26.053328Z","steps":["trace[2039353950] 'process raft request' (duration: 511.38517ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:26.053491Z","caller":"traceutil/trace.go:171","msg":"trace[1766505644] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"518.229034ms","start":"2026-09-08T15:49:25.535246Z","end":"2026-09-08T15:49:26.053475Z","steps":["trace[1766505644] 'process raft request' (duration: 30.943855ms)","trace[1766505644] 'compare' (duration: 486.627205ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:26.053526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:25.541759Z","time spent":"511.680955ms","remote":"127.0.0.1:52972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:49:26.053572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:25.535226Z","time spent":"518.320525ms","remote":"127.0.0.1:53328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-08T15:49:26.053573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.168786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-08T15:49:26.053617Z","caller":"traceutil/trace.go:171","msg":"trace[663944824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:609; }","duration":"354.258128ms","start":"2026-09-08T15:49:25.699346Z","end":"2026-09-08T15:49:26.053604Z","steps":["trace[663944824] 'agreement among raft nodes before linearized reading' (duration: 354.152735ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:26.053686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:25.699322Z","time spent":"354.351569ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-08T15:49:26.053683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.437288ms","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-09-08T15:49:26.053844Z","caller":"traceutil/trace.go:171","msg":"trace[189137771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"457.570151ms","start":"2026-09-08T15:49:25.596162Z","end":"2026-09-08T15:49:26.053732Z","steps":["trace[189137771] 'agreement among raft nodes before linearized reading' (duration: 457.278365ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:26.053925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:25.596143Z","time spent":"457.750785ms","remote":"127.0.0.1:53054","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-09-08T15:49:26.054878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.374822ms","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-09-08T15:49:26.054944Z","caller":"traceutil/trace.go:171","msg":"trace[4308564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"347.499015ms","start":"2026-09-08T15:49:25.707427Z","end":"2026-09-08T15:49:26.054926Z","steps":["trace[4308564] 'agreement among raft nodes before linearized reading' (duration: 346.377571ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:26.054984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:25.707394Z","time spent":"347.580626ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-08T15:49:26.225835Z","caller":"traceutil/trace.go:171","msg":"trace[170230399] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"166.254955ms","start":"2026-09-08T15:49:26.05956Z","end":"2026-09-08T15:49:26.225815Z","steps":["trace[170230399] 'process raft request' (duration: 165.867176ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:26.227079Z","caller":"traceutil/trace.go:171","msg":"trace[2089232984] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:634; }","duration":"160.776098ms","start":"2026-09-08T15:49:26.066272Z","end":"2026-09-08T15:49:26.227048Z","steps":["trace[2089232984] 'read index received' (duration: 159.166493ms)","trace[2089232984] 'applied index is now lower than readState.Index' (duration: 1.608395ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:26.227158Z","caller":"traceutil/trace.go:171","msg":"trace[1155326506] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"167.4462ms","start":"2026-09-08T15:49:26.059685Z","end":"2026-09-08T15:49:26.227131Z","steps":["trace[1155326506] 'process raft request' (duration: 167.193044ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:26.227212Z","caller":"traceutil/trace.go:171","msg":"trace[782492320] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"167.47163ms","start":"2026-09-08T15:49:26.059726Z","end":"2026-09-08T15:49:26.227198Z","steps":["trace[782492320] 'process raft request' (duration: 167.273546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:26.227436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.116115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:49:26.227563Z","caller":"traceutil/trace.go:171","msg":"trace[218732639] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:612; }","duration":"161.326359ms","start":"2026-09-08T15:49:26.066213Z","end":"2026-09-08T15:49:26.227539Z","steps":["trace[218732639] 'agreement among raft nodes before linearized reading' (duration: 161.117195ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:39.968483Z","caller":"traceutil/trace.go:171","msg":"trace[1132042547] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"202.378997ms","start":"2026-09-08T15:49:39.766077Z","end":"2026-09-08T15:49:39.968456Z","steps":["trace[1132042547] 'process raft request' (duration: 202.236484ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:44.600232Z","caller":"traceutil/trace.go:171","msg":"trace[1070016158] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"223.489614ms","start":"2026-09-08T15:49:44.376719Z","end":"2026-09-08T15:49:44.600209Z","steps":["trace[1070016158] 'read index received' (duration: 223.315471ms)","trace[1070016158] 'applied index is now lower than readState.Index' (duration: 173.203µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:49:44.600362Z","caller":"traceutil/trace.go:171","msg":"trace[2036711756] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"235.540188ms","start":"2026-09-08T15:49:44.364813Z","end":"2026-09-08T15:49:44.600353Z","steps":["trace[2036711756] 'process raft request' (duration: 235.219942ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:44.600856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.106857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-09-08T15:49:44.600971Z","caller":"traceutil/trace.go:171","msg":"trace[771815293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:782; }","duration":"224.25197ms","start":"2026-09-08T15:49:44.3767Z","end":"2026-09-08T15:49:44.600952Z","steps":["trace[771815293] 'agreement among raft nodes before linearized reading' (duration: 223.920903ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:44.601698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.577153ms","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-09-08T15:49:44.601765Z","caller":"traceutil/trace.go:171","msg":"trace[2145703609] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:782; }","duration":"203.668855ms","start":"2026-09-08T15:49:44.39808Z","end":"2026-09-08T15:49:44.601749Z","steps":["trace[2145703609] 'agreement among raft nodes before linearized reading' (duration: 202.378599ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:44.911166Z","caller":"traceutil/trace.go:171","msg":"trace[1323568594] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"305.756303ms","start":"2026-09-08T15:49:44.605385Z","end":"2026-09-08T15:49:44.911141Z","steps":["trace[1323568594] 'process raft request' (duration: 285.529555ms)","trace[1323568594] 'compare' (duration: 20.116056ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:49:44.911311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:44.605358Z","time spent":"305.884527ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:49:44.913715Z","caller":"traceutil/trace.go:171","msg":"trace[779581709] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"223.487383ms","start":"2026-09-08T15:49:44.690211Z","end":"2026-09-08T15:49:44.913699Z","steps":["trace[779581709] 'process raft request' (duration: 223.402282ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:49:44.914161Z","caller":"traceutil/trace.go:171","msg":"trace[915808735] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"308.292296ms","start":"2026-09-08T15:49:44.605848Z","end":"2026-09-08T15:49:44.91414Z","steps":["trace[915808735] 'process raft request' (duration: 307.670952ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:49:44.914338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:44.605823Z","time spent":"308.46074ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:49:53.08668Z","caller":"traceutil/trace.go:171","msg":"trace[925627748] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"104.55244ms","start":"2026-09-08T15:49:52.982089Z","end":"2026-09-08T15:49:53.086642Z","steps":["trace[925627748] 'process raft request' (duration: 104.398627ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:04.556379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.685523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:51:04.556567Z","caller":"traceutil/trace.go:171","msg":"trace[609815686] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1928; }","duration":"263.198688ms","start":"2026-09-08T15:51:04.293344Z","end":"2026-09-08T15:51:04.556543Z","steps":["trace[609815686] 'read index received' (duration: 2.169216ms)","trace[609815686] 'applied index is now lower than readState.Index' (duration: 261.026692ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:51:04.556628Z","caller":"traceutil/trace.go:171","msg":"trace[86015320] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"307.521761ms","start":"2026-09-08T15:51:04.249062Z","end":"2026-09-08T15:51:04.556584Z","steps":["trace[86015320] 'process raft request' (duration: 46.482849ms)","trace[86015320] 'compare' (duration: 260.05993ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:51:04.556907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:51:04.249031Z","time spent":"307.727756ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:51:04.556909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.545315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ebb05169-5fbf-4814-afbb-085f3c42b6f6\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-08T15:51:04.556982Z","caller":"traceutil/trace.go:171","msg":"trace[568112034] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ebb05169-5fbf-4814-afbb-085f3c42b6f6; range_end:; response_count:1; response_revision:1855; }","duration":"263.686828ms","start":"2026-09-08T15:51:04.293275Z","end":"2026-09-08T15:51:04.556962Z","steps":["trace[568112034] 'agreement among raft nodes before linearized reading' (duration: 263.441413ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:04.557041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.241169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-08T15:51:04.557077Z","caller":"traceutil/trace.go:171","msg":"trace[1017899657] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1855; }","duration":"203.408473ms","start":"2026-09-08T15:51:04.353657Z","end":"2026-09-08T15:51:04.557065Z","steps":["trace[1017899657] 'agreement among raft nodes before linearized reading' (duration: 203.24655ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:04.557109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.79407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-09-08T15:51:04.557174Z","caller":"traceutil/trace.go:171","msg":"trace[123064408] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1855; }","duration":"209.937233ms","start":"2026-09-08T15:51:04.347216Z","end":"2026-09-08T15:51:04.557153Z","steps":["trace[123064408] 'agreement among raft nodes before linearized reading' (duration: 209.514954ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:18.698068Z","caller":"traceutil/trace.go:171","msg":"trace[534274760] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"143.435833ms","start":"2026-09-08T15:51:18.554614Z","end":"2026-09-08T15:51:18.69805Z","steps":["trace[534274760] 'process raft request' (duration: 143.30501ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:51:54.307163Z","caller":"traceutil/trace.go:171","msg":"trace[1816216863] linearizableReadLoop","detail":"{readStateIndex:2267; appliedIndex:2266; }","duration":"118.562599ms","start":"2026-09-08T15:51:54.188575Z","end":"2026-09-08T15:51:54.307138Z","steps":["trace[1816216863] 'read index received' (duration: 118.282103ms)","trace[1816216863] 'applied index is now lower than readState.Index' (duration: 279.506µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:51:54.307229Z","caller":"traceutil/trace.go:171","msg":"trace[1263177400] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"179.804901ms","start":"2026-09-08T15:51:54.127403Z","end":"2026-09-08T15:51:54.307208Z","steps":["trace[1263177400] 'process raft request' (duration: 179.560356ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:54.307331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.721752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T15:51:54.307378Z","caller":"traceutil/trace.go:171","msg":"trace[1680156207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2182; }","duration":"118.839045ms","start":"2026-09-08T15:51:54.188527Z","end":"2026-09-08T15:51:54.307366Z","steps":["trace[1680156207] 'agreement among raft nodes before linearized reading' (duration: 118.735143ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:54.630154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.817709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:51:54.630276Z","caller":"traceutil/trace.go:171","msg":"trace[1344481653] linearizableReadLoop","detail":"{readStateIndex:2268; appliedIndex:2267; }","duration":"209.414742ms","start":"2026-09-08T15:51:54.42084Z","end":"2026-09-08T15:51:54.630255Z","steps":["trace[1344481653] 'read index received' (duration: 66.286636ms)","trace[1344481653] 'applied index is now lower than readState.Index' (duration: 143.126536ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:51:54.630579Z","caller":"traceutil/trace.go:171","msg":"trace[193714053] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"304.672631ms","start":"2026-09-08T15:51:54.325761Z","end":"2026-09-08T15:51:54.630434Z","steps":["trace[193714053] 'process raft request' (duration: 161.447173ms)","trace[193714053] 'compare' (duration: 142.696556ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:51:54.630658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.80946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-09-08T15:51:54.63071Z","caller":"traceutil/trace.go:171","msg":"trace[1843411701] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2183; }","duration":"209.919573ms","start":"2026-09-08T15:51:54.420779Z","end":"2026-09-08T15:51:54.630699Z","steps":["trace[1843411701] 'agreement among raft nodes before linearized reading' (duration: 209.534694ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:51:54.630796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:51:54.325724Z","time spent":"304.989548ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:51:54.829809Z","caller":"traceutil/trace.go:171","msg":"trace[1447124055] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"194.54004ms","start":"2026-09-08T15:51:54.635236Z","end":"2026-09-08T15:51:54.829776Z","steps":["trace[1447124055] 'process raft request' (duration: 189.551339ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:52:45.318981Z","caller":"traceutil/trace.go:171","msg":"trace[933157503] transaction","detail":"{read_only:false; response_revision:2629; number_of_response:1; }","duration":"129.309988ms","start":"2026-09-08T15:52:45.189646Z","end":"2026-09-08T15:52:45.318956Z","steps":["trace[933157503] 'process raft request' (duration: 129.063383ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:53:18.555969Z","caller":"traceutil/trace.go:171","msg":"trace[1870177914] transaction","detail":"{read_only:false; response_revision:2830; number_of_response:1; }","duration":"218.693968ms","start":"2026-09-08T15:53:18.337227Z","end":"2026-09-08T15:53:18.555921Z","steps":["trace[1870177914] 'process raft request' (duration: 139.383533ms)","trace[1870177914] 'compare' (duration: 78.976428ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:56:36.252546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.015667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-09-08T15:56:36.252654Z","caller":"traceutil/trace.go:171","msg":"trace[1198986435] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4069; }","duration":"248.167721ms","start":"2026-09-08T15:56:36.004465Z","end":"2026-09-08T15:56:36.252633Z","steps":["trace[1198986435] 'range keys from in-memory index tree' (duration: 247.834243ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:56:36.253298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.200481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:56:36.253407Z","caller":"traceutil/trace.go:171","msg":"trace[1116913593] transaction","detail":"{read_only:false; response_revision:4070; number_of_response:1; }","duration":"159.292176ms","start":"2026-09-08T15:56:36.094071Z","end":"2026-09-08T15:56:36.253363Z","steps":["trace[1116913593] 'process raft request' (duration: 17.942422ms)","trace[1116913593] 'compare' (duration: 140.632569ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:58:03.537242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.557212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2dbbb2aa-9232-49a5-a258-9d51def05d4b\" ","response":"range_response_count:1 size:4066"} {"level":"info","ts":"2026-09-08T15:58:03.537355Z","caller":"traceutil/trace.go:171","msg":"trace[716052598] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2dbbb2aa-9232-49a5-a258-9d51def05d4b; range_end:; response_count:1; response_revision:5069; }","duration":"138.693125ms","start":"2026-09-08T15:58:03.398642Z","end":"2026-09-08T15:58:03.537336Z","steps":["trace[716052598] 'range keys from in-memory index tree' (duration: 138.379319ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:09.467369Z","caller":"traceutil/trace.go:171","msg":"trace[165716736] transaction","detail":"{read_only:false; response_revision:5288; number_of_response:1; }","duration":"182.134315ms","start":"2026-09-08T15:58:09.285205Z","end":"2026-09-08T15:58:09.467339Z","steps":["trace[165716736] 'process raft request' (duration: 181.89702ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:09.628939Z","caller":"traceutil/trace.go:171","msg":"trace[95803934] linearizableReadLoop","detail":"{readStateIndex:5492; appliedIndex:5490; }","duration":"255.776902ms","start":"2026-09-08T15:58:09.373135Z","end":"2026-09-08T15:58:09.628912Z","steps":["trace[95803934] 'read index received' (duration: 93.950689ms)","trace[95803934] 'applied index is now lower than readState.Index' (duration: 161.824963ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:58:09.629167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.013327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a39c3e0c-72e2-420a-90d1-a8c4085855c5\" ","response":"range_response_count:1 size:3720"} {"level":"info","ts":"2026-09-08T15:58:09.629223Z","caller":"traceutil/trace.go:171","msg":"trace[193441638] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a39c3e0c-72e2-420a-90d1-a8c4085855c5; range_end:; response_count:1; response_revision:5289; }","duration":"256.115959ms","start":"2026-09-08T15:58:09.373091Z","end":"2026-09-08T15:58:09.629207Z","steps":["trace[193441638] 'agreement among raft nodes before linearized reading' (duration: 255.909335ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:09.629505Z","caller":"traceutil/trace.go:171","msg":"trace[394708036] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"333.179345ms","start":"2026-09-08T15:58:09.296309Z","end":"2026-09-08T15:58:09.629489Z","steps":["trace[394708036] 'process raft request' (duration: 278.567104ms)","trace[394708036] 'compare' (duration: 53.57398ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:58:09.629596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:58:09.296283Z","time spent":"333.247246ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T15:58:22.571934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.945015ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-08T15:58:22.572144Z","caller":"traceutil/trace.go:171","msg":"trace[1507109138] linearizableReadLoop","detail":"{readStateIndex:5745; appliedIndex:5744; }","duration":"463.955903ms","start":"2026-09-08T15:58:22.108171Z","end":"2026-09-08T15:58:22.572127Z","steps":["trace[1507109138] 'read index received' (duration: 13.749202ms)","trace[1507109138] 'applied index is now lower than readState.Index' (duration: 450.205761ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:58:22.572333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.163707ms","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-09-08T15:58:22.572365Z","caller":"traceutil/trace.go:171","msg":"trace[526690713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5533; }","duration":"464.247249ms","start":"2026-09-08T15:58:22.108108Z","end":"2026-09-08T15:58:22.572355Z","steps":["trace[526690713] 'agreement among raft nodes before linearized reading' (duration: 464.125477ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:58:22.572403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:58:22.108083Z","time spent":"464.30945ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-08T15:58:22.572608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.704464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-nt2dx\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-09-08T15:58:22.572677Z","caller":"traceutil/trace.go:171","msg":"trace[631543435] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-nt2dx; range_end:; response_count:1; response_revision:5534; }","duration":"144.807036ms","start":"2026-09-08T15:58:22.427857Z","end":"2026-09-08T15:58:22.572664Z","steps":["trace[631543435] 'agreement among raft nodes before linearized reading' (duration: 144.618442ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:22.572746Z","caller":"traceutil/trace.go:171","msg":"trace[1563333536] transaction","detail":"{read_only:false; response_revision:5534; number_of_response:1; }","duration":"108.093839ms","start":"2026-09-08T15:58:22.46464Z","end":"2026-09-08T15:58:22.572734Z","steps":["trace[1563333536] 'process raft request' (duration: 107.396476ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:30.844964Z","caller":"traceutil/trace.go:171","msg":"trace[161877703] transaction","detail":"{read_only:false; response_revision:5696; number_of_response:1; }","duration":"310.68188ms","start":"2026-09-08T15:58:30.534254Z","end":"2026-09-08T15:58:30.844936Z","steps":["trace[161877703] 'process raft request' (duration: 310.518507ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:58:30.845125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:58:30.534237Z","time spent":"310.808492ms","remote":"127.0.0.1:52972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-08T15:58:30.901953Z","caller":"traceutil/trace.go:171","msg":"trace[1214856211] linearizableReadLoop","detail":"{readStateIndex:5912; appliedIndex:5910; }","duration":"242.005921ms","start":"2026-09-08T15:58:30.659921Z","end":"2026-09-08T15:58:30.901926Z","steps":["trace[1214856211] 'read index received' (duration: 184.92ms)","trace[1214856211] 'applied index is now lower than readState.Index' (duration: 57.084521ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:58:30.902059Z","caller":"traceutil/trace.go:171","msg":"trace[348507152] transaction","detail":"{read_only:false; response_revision:5697; number_of_response:1; }","duration":"245.892468ms","start":"2026-09-08T15:58:30.656122Z","end":"2026-09-08T15:58:30.902015Z","steps":["trace[348507152] 'process raft request' (duration: 245.666313ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:58:30.902146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.204885ms","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-09-08T15:58:30.902187Z","caller":"traceutil/trace.go:171","msg":"trace[703968679] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5697; }","duration":"242.290566ms","start":"2026-09-08T15:58:30.659887Z","end":"2026-09-08T15:58:30.902177Z","steps":["trace[703968679] 'agreement among raft nodes before linearized reading' (duration: 242.142353ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T15:58:31.06095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.410715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T15:58:31.061308Z","caller":"traceutil/trace.go:171","msg":"trace[2091386721] transaction","detail":"{read_only:false; response_revision:5699; number_of_response:1; }","duration":"151.158402ms","start":"2026-09-08T15:58:30.910111Z","end":"2026-09-08T15:58:31.06127Z","steps":["trace[2091386721] 'process raft request' (duration: 150.944998ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:31.061308Z","caller":"traceutil/trace.go:171","msg":"trace[478233138] transaction","detail":"{read_only:false; response_revision:5698; number_of_response:1; }","duration":"154.628421ms","start":"2026-09-08T15:58:30.906641Z","end":"2026-09-08T15:58:31.06127Z","steps":["trace[478233138] 'process raft request' (duration: 40.816648ms)","trace[478233138] 'compare' (duration: 113.290782ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:58:32.9731Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2910} {"level":"info","ts":"2026-09-08T15:58:33.055448Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2910,"took":"80.974372ms","hash":724352866,"current-db-size-bytes":55525376,"current-db-size":"56 MB","current-db-size-in-use-bytes":31838208,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-08T15:58:33.055521Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":724352866,"revision":2910,"compact-revision":-1} {"level":"info","ts":"2026-09-08T15:58:41.815085Z","caller":"traceutil/trace.go:171","msg":"trace[1637900922] transaction","detail":"{read_only:false; response_revision:5844; number_of_response:1; }","duration":"129.799822ms","start":"2026-09-08T15:58:41.68526Z","end":"2026-09-08T15:58:41.815059Z","steps":["trace[1637900922] 'process raft request' (duration: 129.648179ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:41.815103Z","caller":"traceutil/trace.go:171","msg":"trace[1992791571] linearizableReadLoop","detail":"{readStateIndex:6063; appliedIndex:6063; }","duration":"126.286547ms","start":"2026-09-08T15:58:41.688775Z","end":"2026-09-08T15:58:41.815062Z","steps":["trace[1992791571] 'read index received' (duration: 126.270476ms)","trace[1992791571] 'applied index is now lower than readState.Index' (duration: 13.231µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T15:58:41.815448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.603623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-08T15:58:41.815518Z","caller":"traceutil/trace.go:171","msg":"trace[932617503] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5844; }","duration":"126.760358ms","start":"2026-09-08T15:58:41.688738Z","end":"2026-09-08T15:58:41.815498Z","steps":["trace[932617503] 'agreement among raft nodes before linearized reading' (duration: 126.467591ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:41.957018Z","caller":"traceutil/trace.go:171","msg":"trace[1307884635] transaction","detail":"{read_only:false; response_revision:5846; number_of_response:1; }","duration":"135.024594ms","start":"2026-09-08T15:58:41.821975Z","end":"2026-09-08T15:58:41.957Z","steps":["trace[1307884635] 'process raft request' (duration: 69.516955ms)","trace[1307884635] 'compare' (duration: 65.358335ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T15:58:41.957349Z","caller":"traceutil/trace.go:171","msg":"trace[774869260] transaction","detail":"{read_only:false; response_revision:5848; number_of_response:1; }","duration":"134.504063ms","start":"2026-09-08T15:58:41.822837Z","end":"2026-09-08T15:58:41.957341Z","steps":["trace[774869260] 'process raft request' (duration: 134.295908ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:41.957374Z","caller":"traceutil/trace.go:171","msg":"trace[657144759] transaction","detail":"{read_only:false; response_revision:5849; number_of_response:1; }","duration":"134.284408ms","start":"2026-09-08T15:58:41.823068Z","end":"2026-09-08T15:58:41.957352Z","steps":["trace[657144759] 'process raft request' (duration: 134.167455ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T15:58:41.957476Z","caller":"traceutil/trace.go:171","msg":"trace[1067522539] transaction","detail":"{read_only:false; response_revision:5847; number_of_response:1; }","duration":"134.744218ms","start":"2026-09-08T15:58:41.822696Z","end":"2026-09-08T15:58:41.95744Z","steps":["trace[1067522539] 'process raft request' (duration: 134.272918ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:03:32.980486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5730} {"level":"info","ts":"2026-09-08T16:03:33.070987Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5730,"took":"88.793932ms","hash":1584369675,"current-db-size-bytes":55525376,"current-db-size":"56 MB","current-db-size-in-use-bytes":26685440,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-08T16:03:33.071065Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1584369675,"revision":5730,"compact-revision":2910} {"level":"warn","ts":"2026-09-08T16:04:44.912714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.667979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T16:04:44.912832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.498093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.912886Z","caller":"traceutil/trace.go:171","msg":"trace[1207790783] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8578; }","duration":"282.837962ms","start":"2026-09-08T16:04:44.629969Z","end":"2026-09-08T16:04:44.912807Z","steps":["trace[1207790783] 'range keys from in-memory index tree' (duration: 282.549246ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:44.912948Z","caller":"traceutil/trace.go:171","msg":"trace[775322924] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8578; }","duration":"257.655306ms","start":"2026-09-08T16:04:44.655261Z","end":"2026-09-08T16:04:44.912916Z","steps":["trace[775322924] 'range keys from in-memory index tree' (duration: 257.424281ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.913085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.445181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T16:04:44.913089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.408833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.913154Z","caller":"traceutil/trace.go:171","msg":"trace[1513915022] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8578; }","duration":"258.565006ms","start":"2026-09-08T16:04:44.654571Z","end":"2026-09-08T16:04:44.913136Z","steps":["trace[1513915022] 'range keys from in-memory index tree' (duration: 258.281199ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.913184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.16563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T16:04:44.913238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.594862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.913308Z","caller":"traceutil/trace.go:171","msg":"trace[1249546967] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8578; }","duration":"350.718694ms","start":"2026-09-08T16:04:44.562571Z","end":"2026-09-08T16:04:44.91329Z","steps":["trace[1249546967] 'count revisions from in-memory index tree' (duration: 350.503659ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.913345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:44.562557Z","time spent":"350.777885ms","remote":"127.0.0.1:34286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true "} {"level":"warn","ts":"2026-09-08T16:04:44.913311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.763206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T16:04:44.913433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.745025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T16:04:44.913436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.789888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.913493Z","caller":"traceutil/trace.go:171","msg":"trace[2040387755] linearizableReadLoop","detail":"{readStateIndex:8886; appliedIndex:8885; }","duration":"238.797967ms","start":"2026-09-08T16:04:44.674681Z","end":"2026-09-08T16:04:44.913479Z","steps":["trace[2040387755] 'read index received' (duration: 156.769889ms)","trace[2040387755] 'applied index is now lower than readState.Index' (duration: 82.027238ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:04:44.913496Z","caller":"traceutil/trace.go:171","msg":"trace[837517008] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8578; }","duration":"350.853186ms","start":"2026-09-08T16:04:44.562627Z","end":"2026-09-08T16:04:44.91348Z","steps":["trace[837517008] 'range keys from in-memory index tree' (duration: 350.683454ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:44.913521Z","caller":"traceutil/trace.go:171","msg":"trace[59037543] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8578; }","duration":"328.902971ms","start":"2026-09-08T16:04:44.584603Z","end":"2026-09-08T16:04:44.913506Z","steps":["trace[59037543] 'range keys from in-memory index tree' (duration: 328.677606ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.913546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:44.562572Z","time spent":"350.959019ms","remote":"127.0.0.1:34286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 "} {"level":"warn","ts":"2026-09-08T16:04:44.913563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:44.584589Z","time spent":"328.959772ms","remote":"127.0.0.1:34290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:10000 "} {"level":"info","ts":"2026-09-08T16:04:44.913581Z","caller":"traceutil/trace.go:171","msg":"trace[886272106] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"255.929368ms","start":"2026-09-08T16:04:44.657636Z","end":"2026-09-08T16:04:44.913565Z","steps":["trace[886272106] 'process raft request' (duration: 173.8706ms)","trace[886272106] 'compare' (duration: 81.884984ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:04:44.913195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.145009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T16:04:44.913644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.47527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.913673Z","caller":"traceutil/trace.go:171","msg":"trace[1094265376] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8578; }","duration":"283.644899ms","start":"2026-09-08T16:04:44.630018Z","end":"2026-09-08T16:04:44.913663Z","steps":["trace[1094265376] 'count revisions from in-memory index tree' (duration: 283.094838ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:44.913694Z","caller":"traceutil/trace.go:171","msg":"trace[312583806] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8579; }","duration":"238.549962ms","start":"2026-09-08T16:04:44.675133Z","end":"2026-09-08T16:04:44.913683Z","steps":["trace[312583806] 'agreement among raft nodes before linearized reading' (duration: 238.45211ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.913693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.644664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T16:04:44.913705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.017932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.913737Z","caller":"traceutil/trace.go:171","msg":"trace[157475375] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8579; }","duration":"220.709935ms","start":"2026-09-08T16:04:44.693013Z","end":"2026-09-08T16:04:44.913723Z","steps":["trace[157475375] 'agreement among raft nodes before linearized reading' (duration: 220.621384ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:44.913753Z","caller":"traceutil/trace.go:171","msg":"trace[1149249194] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8579; }","duration":"239.086813ms","start":"2026-09-08T16:04:44.674651Z","end":"2026-09-08T16:04:44.913737Z","steps":["trace[1149249194] 'agreement among raft nodes before linearized reading' (duration: 239.022761ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:44.91346Z","caller":"traceutil/trace.go:171","msg":"trace[2135856640] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8578; }","duration":"327.97229ms","start":"2026-09-08T16:04:44.585448Z","end":"2026-09-08T16:04:44.913421Z","steps":["trace[2135856640] 'range keys from in-memory index tree' (duration: 327.677914ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.91364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.539891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T16:04:44.913811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.520941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.91382Z","caller":"traceutil/trace.go:171","msg":"trace[1084276216] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8579; }","duration":"220.761426ms","start":"2026-09-08T16:04:44.693046Z","end":"2026-09-08T16:04:44.913807Z","steps":["trace[1084276216] 'agreement among raft nodes before linearized reading' (duration: 220.541292ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:44.91325Z","caller":"traceutil/trace.go:171","msg":"trace[436855783] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:8578; }","duration":"283.277482ms","start":"2026-09-08T16:04:44.629955Z","end":"2026-09-08T16:04:44.913232Z","steps":["trace[436855783] 'range keys from in-memory index tree' (duration: 283.095598ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:44.913892Z","caller":"traceutil/trace.go:171","msg":"trace[1236054413] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8579; }","duration":"238.584543ms","start":"2026-09-08T16:04:44.675256Z","end":"2026-09-08T16:04:44.913841Z","steps":["trace[1236054413] 'agreement among raft nodes before linearized reading' (duration: 238.519242ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.91274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.054595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-08T16:04:44.913917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.763656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.913945Z","caller":"traceutil/trace.go:171","msg":"trace[501382708] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:8578; }","duration":"259.356313ms","start":"2026-09-08T16:04:44.654579Z","end":"2026-09-08T16:04:44.913935Z","steps":["trace[501382708] 'count revisions from in-memory index tree' (duration: 257.900401ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.913817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:44.585438Z","time spent":"328.362389ms","remote":"127.0.0.1:34290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" limit:500 "} {"level":"info","ts":"2026-09-08T16:04:44.913966Z","caller":"traceutil/trace.go:171","msg":"trace[844309426] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:8579; }","duration":"220.835497ms","start":"2026-09-08T16:04:44.693117Z","end":"2026-09-08T16:04:44.913953Z","steps":["trace[844309426] 'agreement among raft nodes before linearized reading' (duration: 220.731685ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.913355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.775805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:04:44.914093Z","caller":"traceutil/trace.go:171","msg":"trace[1678556473] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:8578; }","duration":"351.562442ms","start":"2026-09-08T16:04:44.56252Z","end":"2026-09-08T16:04:44.914083Z","steps":["trace[1678556473] 'range keys from in-memory index tree' (duration: 350.671212ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.914127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:44.562503Z","time spent":"351.610133ms","remote":"127.0.0.1:34286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 "} {"level":"info","ts":"2026-09-08T16:04:44.914292Z","caller":"traceutil/trace.go:171","msg":"trace[1374000082] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:8578; }","duration":"328.514083ms","start":"2026-09-08T16:04:44.584602Z","end":"2026-09-08T16:04:44.913116Z","steps":["trace[1374000082] 'count revisions from in-memory index tree' (duration: 328.311438ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:44.914377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:04:44.584588Z","time spent":"329.770499ms","remote":"127.0.0.1:34290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true "} {"level":"info","ts":"2026-09-08T16:04:56.181738Z","caller":"traceutil/trace.go:171","msg":"trace[1542213296] linearizableReadLoop","detail":"{readStateIndex:8947; appliedIndex:8946; }","duration":"116.872903ms","start":"2026-09-08T16:04:56.064841Z","end":"2026-09-08T16:04:56.181714Z","steps":["trace[1542213296] 'read index received' (duration: 116.682199ms)","trace[1542213296] 'applied index is now lower than readState.Index' (duration: 189.704µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:04:56.181769Z","caller":"traceutil/trace.go:171","msg":"trace[835021482] transaction","detail":"{read_only:false; response_revision:8638; number_of_response:1; }","duration":"184.796187ms","start":"2026-09-08T16:04:55.996943Z","end":"2026-09-08T16:04:56.18174Z","steps":["trace[835021482] 'process raft request' (duration: 184.614293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:56.181975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.058118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T16:04:56.182011Z","caller":"traceutil/trace.go:171","msg":"trace[1593971324] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:8638; }","duration":"117.16634ms","start":"2026-09-08T16:04:56.064835Z","end":"2026-09-08T16:04:56.182001Z","steps":["trace[1593971324] 'agreement among raft nodes before linearized reading' (duration: 117.006156ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:57.373064Z","caller":"traceutil/trace.go:171","msg":"trace[76995406] transaction","detail":"{read_only:false; response_revision:8644; number_of_response:1; }","duration":"150.796569ms","start":"2026-09-08T16:04:57.22224Z","end":"2026-09-08T16:04:57.373036Z","steps":["trace[76995406] 'process raft request' (duration: 150.598865ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:04:57.402364Z","caller":"traceutil/trace.go:171","msg":"trace[1231196547] transaction","detail":"{read_only:false; response_revision:8645; number_of_response:1; }","duration":"102.597055ms","start":"2026-09-08T16:04:57.299734Z","end":"2026-09-08T16:04:57.402331Z","steps":["trace[1231196547] 'process raft request' (duration: 102.410171ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:04:57.57988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.548891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T16:04:57.580059Z","caller":"traceutil/trace.go:171","msg":"trace[1989513403] transaction","detail":"{read_only:false; response_revision:8646; number_of_response:1; }","duration":"173.177534ms","start":"2026-09-08T16:04:57.406846Z","end":"2026-09-08T16:04:57.580024Z","steps":["trace[1989513403] 'process raft request' (duration: 47.388728ms)","trace[1989513403] 'compare' (duration: 125.331187ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:05:26.022116Z","caller":"traceutil/trace.go:171","msg":"trace[1606587833] transaction","detail":"{read_only:false; response_revision:8851; number_of_response:1; }","duration":"334.181065ms","start":"2026-09-08T16:05:25.687912Z","end":"2026-09-08T16:05:26.022093Z","steps":["trace[1606587833] 'process raft request' (duration: 331.301843ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:26.022206Z","caller":"traceutil/trace.go:171","msg":"trace[1767224711] linearizableReadLoop","detail":"{readStateIndex:9168; appliedIndex:9167; }","duration":"308.984139ms","start":"2026-09-08T16:05:25.713198Z","end":"2026-09-08T16:05:26.022182Z","steps":["trace[1767224711] 'read index received' (duration: 305.947113ms)","trace[1767224711] 'applied index is now lower than readState.Index' (duration: 3.033586ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:05:26.022251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:25.687889Z","time spent":"334.292168ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T16:05:26.022286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.076121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:05:26.022319Z","caller":"traceutil/trace.go:171","msg":"trace[434646344] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:8852; }","duration":"309.144412ms","start":"2026-09-08T16:05:25.713164Z","end":"2026-09-08T16:05:26.022309Z","steps":["trace[434646344] 'agreement among raft nodes before linearized reading' (duration: 309.06812ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:26.022339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:25.713148Z","time spent":"309.185313ms","remote":"127.0.0.1:55312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-09-08T16:05:26.022529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.363983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9r8f8\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-09-08T16:05:26.022562Z","caller":"traceutil/trace.go:171","msg":"trace[543840242] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9r8f8; range_end:; response_count:1; response_revision:8852; }","duration":"217.439185ms","start":"2026-09-08T16:05:25.805113Z","end":"2026-09-08T16:05:26.022552Z","steps":["trace[543840242] 'agreement among raft nodes before linearized reading' (duration: 217.209459ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:26.022508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.481798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-08T16:05:26.022653Z","caller":"traceutil/trace.go:171","msg":"trace[167766877] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8852; }","duration":"302.667433ms","start":"2026-09-08T16:05:25.719961Z","end":"2026-09-08T16:05:26.022628Z","steps":["trace[167766877] 'agreement among raft nodes before linearized reading' (duration: 302.335645ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:26.022706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:25.719944Z","time spent":"302.747834ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-08T16:05:33.701552Z","caller":"traceutil/trace.go:171","msg":"trace[2066012570] linearizableReadLoop","detail":"{readStateIndex:9212; appliedIndex:9211; }","duration":"101.971151ms","start":"2026-09-08T16:05:33.599552Z","end":"2026-09-08T16:05:33.701523Z","steps":["trace[2066012570] 'read index received' (duration: 101.788688ms)","trace[2066012570] 'applied index is now lower than readState.Index' (duration: 181.003µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:05:33.701752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.177187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-09-08T16:05:33.701803Z","caller":"traceutil/trace.go:171","msg":"trace[1434267847] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8892; }","duration":"102.296859ms","start":"2026-09-08T16:05:33.599491Z","end":"2026-09-08T16:05:33.701788Z","steps":["trace[1434267847] 'agreement among raft nodes before linearized reading' (duration: 102.132266ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:33.70182Z","caller":"traceutil/trace.go:171","msg":"trace[540844705] transaction","detail":"{read_only:false; response_revision:8892; number_of_response:1; }","duration":"138.981445ms","start":"2026-09-08T16:05:33.562783Z","end":"2026-09-08T16:05:33.701764Z","steps":["trace[540844705] 'process raft request' (duration: 138.567746ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:33.839965Z","caller":"traceutil/trace.go:171","msg":"trace[1494551324] transaction","detail":"{read_only:false; response_revision:8893; number_of_response:1; }","duration":"132.82832ms","start":"2026-09-08T16:05:33.707105Z","end":"2026-09-08T16:05:33.839933Z","steps":["trace[1494551324] 'process raft request' (duration: 132.548944ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:38.813496Z","caller":"traceutil/trace.go:171","msg":"trace[685212905] transaction","detail":"{read_only:false; response_revision:8939; number_of_response:1; }","duration":"104.301142ms","start":"2026-09-08T16:05:38.709176Z","end":"2026-09-08T16:05:38.813477Z","steps":["trace[685212905] 'process raft request' (duration: 104.160789ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:38.967142Z","caller":"traceutil/trace.go:171","msg":"trace[2024681528] linearizableReadLoop","detail":"{readStateIndex:9264; appliedIndex:9262; }","duration":"153.656451ms","start":"2026-09-08T16:05:38.813453Z","end":"2026-09-08T16:05:38.967109Z","steps":["trace[2024681528] 'read index received' (duration: 143.899721ms)","trace[2024681528] 'applied index is now lower than readState.Index' (duration: 9.75517ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:05:38.967342Z","caller":"traceutil/trace.go:171","msg":"trace[1989157261] transaction","detail":"{read_only:false; response_revision:8940; number_of_response:1; }","duration":"171.810865ms","start":"2026-09-08T16:05:38.795498Z","end":"2026-09-08T16:05:38.967309Z","steps":["trace[1989157261] 'process raft request' (duration: 161.904391ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:38.967361Z","caller":"traceutil/trace.go:171","msg":"trace[250230050] transaction","detail":"{read_only:false; response_revision:8941; number_of_response:1; }","duration":"166.491889ms","start":"2026-09-08T16:05:38.800836Z","end":"2026-09-08T16:05:38.967328Z","steps":["trace[250230050] 'process raft request' (duration: 166.109381ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:38.967653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.614505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-48qmg\" ","response":"range_response_count:1 size:4649"} {"level":"info","ts":"2026-09-08T16:05:38.967748Z","caller":"traceutil/trace.go:171","msg":"trace[213117896] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-48qmg; range_end:; response_count:1; response_revision:8941; }","duration":"215.750857ms","start":"2026-09-08T16:05:38.751969Z","end":"2026-09-08T16:05:38.96772Z","steps":["trace[213117896] 'agreement among raft nodes before linearized reading' (duration: 215.352728ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:39.057794Z","caller":"traceutil/trace.go:171","msg":"trace[1405558067] transaction","detail":"{read_only:false; response_revision:8942; number_of_response:1; }","duration":"242.439847ms","start":"2026-09-08T16:05:38.815323Z","end":"2026-09-08T16:05:39.057763Z","steps":["trace[1405558067] 'process raft request' (duration: 242.259863ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:39.241348Z","caller":"traceutil/trace.go:171","msg":"trace[1243819246] transaction","detail":"{read_only:false; response_revision:8943; number_of_response:1; }","duration":"220.467369ms","start":"2026-09-08T16:05:39.020849Z","end":"2026-09-08T16:05:39.241317Z","steps":["trace[1243819246] 'process raft request' (duration: 152.254061ms)","trace[1243819246] 'compare' (duration: 67.602595ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:05:39.3905Z","caller":"traceutil/trace.go:171","msg":"trace[1073292137] transaction","detail":"{read_only:false; response_revision:8945; number_of_response:1; }","duration":"144.38617ms","start":"2026-09-08T16:05:39.246081Z","end":"2026-09-08T16:05:39.390467Z","steps":["trace[1073292137] 'process raft request' (duration: 144.146765ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:39.390902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.899253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-08T16:05:39.390976Z","caller":"traceutil/trace.go:171","msg":"trace[1172948750] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:8946; }","duration":"146.049246ms","start":"2026-09-08T16:05:39.244909Z","end":"2026-09-08T16:05:39.390958Z","steps":["trace[1172948750] 'agreement among raft nodes before linearized reading' (duration: 145.812671ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:39.390977Z","caller":"traceutil/trace.go:171","msg":"trace[2075405810] transaction","detail":"{read_only:false; response_revision:8946; number_of_response:1; }","duration":"138.437042ms","start":"2026-09-08T16:05:39.252526Z","end":"2026-09-08T16:05:39.390963Z","steps":["trace[2075405810] 'process raft request' (duration: 137.854359ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:39.39101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.032285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-09-08T16:05:39.391168Z","caller":"traceutil/trace.go:171","msg":"trace[338898165] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:8946; }","duration":"145.240609ms","start":"2026-09-08T16:05:39.245892Z","end":"2026-09-08T16:05:39.391132Z","steps":["trace[338898165] 'agreement among raft nodes before linearized reading' (duration: 144.685487ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:05:39.391379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.702921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/rook-ceph-rgw-ceph-certs\" ","response":"range_response_count:1 size:2361"} {"level":"info","ts":"2026-09-08T16:05:39.391441Z","caller":"traceutil/trace.go:171","msg":"trace[637584143] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/rook-ceph-rgw-ceph-certs; range_end:; response_count:1; response_revision:8946; }","duration":"140.845254ms","start":"2026-09-08T16:05:39.250573Z","end":"2026-09-08T16:05:39.391418Z","steps":["trace[637584143] 'agreement among raft nodes before linearized reading' (duration: 140.63891ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:05:53.501099Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024c2.wal"} {"level":"info","ts":"2026-09-08T16:06:46.337449Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"427150911cb3975a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-08T16:06:46.344096Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-08T16:06:46.344231Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-09-08T16:07:04.29203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.060261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:07:04.292126Z","caller":"traceutil/trace.go:171","msg":"trace[727297868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9760; }","duration":"103.252444ms","start":"2026-09-08T16:07:04.188849Z","end":"2026-09-08T16:07:04.292102Z","steps":["trace[727297868] 'range keys from in-memory index tree' (duration: 102.933158ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:08:32.986825Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7967} {"level":"info","ts":"2026-09-08T16:08:33.058175Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7967,"took":"68.971284ms","hash":3911196684,"current-db-size-bytes":55525376,"current-db-size":"56 MB","current-db-size-in-use-bytes":28880896,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-09-08T16:08:33.058218Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3911196684,"revision":7967,"compact-revision":5730} {"level":"info","ts":"2026-09-08T16:13:32.992928Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10387} {"level":"info","ts":"2026-09-08T16:13:33.084742Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10387,"took":"89.133485ms","hash":1501996463,"current-db-size-bytes":55525376,"current-db-size":"56 MB","current-db-size-in-use-bytes":27660288,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-08T16:13:33.084815Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1501996463,"revision":10387,"compact-revision":7967} {"level":"info","ts":"2026-09-08T16:17:00.0327Z","caller":"traceutil/trace.go:171","msg":"trace[821835481] transaction","detail":"{read_only:false; response_revision:14119; number_of_response:1; }","duration":"120.368604ms","start":"2026-09-08T16:16:59.912311Z","end":"2026-09-08T16:17:00.032679Z","steps":["trace[821835481] 'process raft request' (duration: 120.246011ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:17:08.62902Z","caller":"traceutil/trace.go:171","msg":"trace[245861182] linearizableReadLoop","detail":"{readStateIndex:14656; appliedIndex:14655; }","duration":"100.515057ms","start":"2026-09-08T16:17:08.528489Z","end":"2026-09-08T16:17:08.629004Z","steps":["trace[245861182] 'read index received' (duration: 100.367434ms)","trace[245861182] 'applied index is now lower than readState.Index' (duration: 147.003µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:17:08.629165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.66254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-08T16:17:08.629195Z","caller":"traceutil/trace.go:171","msg":"trace[378644408] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:14166; }","duration":"100.708761ms","start":"2026-09-08T16:17:08.528475Z","end":"2026-09-08T16:17:08.629184Z","steps":["trace[378644408] 'agreement among raft nodes before linearized reading' (duration: 100.591219ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:17:08.629169Z","caller":"traceutil/trace.go:171","msg":"trace[175094771] transaction","detail":"{read_only:false; response_revision:14166; number_of_response:1; }","duration":"110.851189ms","start":"2026-09-08T16:17:08.518285Z","end":"2026-09-08T16:17:08.629137Z","steps":["trace[175094771] 'process raft request' (duration: 110.593804ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:17.369206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.757747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-08T16:17:17.36932Z","caller":"traceutil/trace.go:171","msg":"trace[2009634623] linearizableReadLoop","detail":"{readStateIndex:14717; appliedIndex:14716; }","duration":"187.541935ms","start":"2026-09-08T16:17:17.181762Z","end":"2026-09-08T16:17:17.369304Z","steps":["trace[2009634623] 'read index received' (duration: 38.721µs)","trace[2009634623] 'applied index is now lower than readState.Index' (duration: 187.501734ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:17:17.369383Z","caller":"traceutil/trace.go:171","msg":"trace[1088566298] transaction","detail":"{read_only:false; response_revision:14224; number_of_response:1; }","duration":"438.688404ms","start":"2026-09-08T16:17:16.930656Z","end":"2026-09-08T16:17:17.369344Z","steps":["trace[1088566298] 'process raft request' (duration: 218.707723ms)","trace[1088566298] 'compare' (duration: 219.44936ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:17:17.369438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.688127ms","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-09-08T16:17:17.369466Z","caller":"traceutil/trace.go:171","msg":"trace[1251887886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14224; }","duration":"187.752589ms","start":"2026-09-08T16:17:17.181705Z","end":"2026-09-08T16:17:17.369457Z","steps":["trace[1251887886] 'agreement among raft nodes before linearized reading' (duration: 187.638926ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:17.36954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:16.930627Z","time spent":"438.827487ms","remote":"127.0.0.1:53054","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-09-08T16:17:28.622823Z","caller":"traceutil/trace.go:171","msg":"trace[360484585] linearizableReadLoop","detail":"{readStateIndex:14896; appliedIndex:14895; }","duration":"126.292921ms","start":"2026-09-08T16:17:28.496504Z","end":"2026-09-08T16:17:28.622797Z","steps":["trace[360484585] 'read index received' (duration: 126.020935ms)","trace[360484585] 'applied index is now lower than readState.Index' (duration: 270.985µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:17:28.622937Z","caller":"traceutil/trace.go:171","msg":"trace[1753303648] transaction","detail":"{read_only:false; response_revision:14399; number_of_response:1; }","duration":"298.017925ms","start":"2026-09-08T16:17:28.324893Z","end":"2026-09-08T16:17:28.622911Z","steps":["trace[1753303648] 'process raft request' (duration: 297.687158ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:28.623128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.609217ms","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-09-08T16:17:28.623187Z","caller":"traceutil/trace.go:171","msg":"trace[104788579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14399; }","duration":"126.719349ms","start":"2026-09-08T16:17:28.496452Z","end":"2026-09-08T16:17:28.623171Z","steps":["trace[104788579] 'agreement among raft nodes before linearized reading' (duration: 126.512335ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:17:28.623203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.692285ms","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-09-08T16:17:28.623295Z","caller":"traceutil/trace.go:171","msg":"trace[344352351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14399; }","duration":"124.850569ms","start":"2026-09-08T16:17:28.498423Z","end":"2026-09-08T16:17:28.623274Z","steps":["trace[344352351] 'agreement among raft nodes before linearized reading' (duration: 124.564953ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:18:32.998907Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12709} {"level":"info","ts":"2026-09-08T16:18:33.091375Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12709,"took":"89.906479ms","hash":686070233,"current-db-size-bytes":55525376,"current-db-size":"56 MB","current-db-size-in-use-bytes":31387648,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-08T16:18:33.091485Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":686070233,"revision":12709,"compact-revision":10387} {"level":"info","ts":"2026-09-08T16:19:08.209101Z","caller":"traceutil/trace.go:171","msg":"trace[497215884] linearizableReadLoop","detail":"{readStateIndex:16027; appliedIndex:16026; }","duration":"127.438793ms","start":"2026-09-08T16:19:08.081616Z","end":"2026-09-08T16:19:08.209055Z","steps":["trace[497215884] 'read index received' (duration: 99.950266ms)","trace[497215884] 'applied index is now lower than readState.Index' (duration: 27.487607ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:19:08.209617Z","caller":"traceutil/trace.go:171","msg":"trace[243873346] transaction","detail":"{read_only:false; response_revision:15494; number_of_response:1; }","duration":"132.002991ms","start":"2026-09-08T16:19:08.077234Z","end":"2026-09-08T16:19:08.209237Z","steps":["trace[243873346] 'process raft request' (duration: 104.496793ms)","trace[243873346] 'compare' (duration: 27.186352ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:19:08.209695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.053016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19566"} {"level":"info","ts":"2026-09-08T16:19:08.209913Z","caller":"traceutil/trace.go:171","msg":"trace[203511801] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:15494; }","duration":"128.297372ms","start":"2026-09-08T16:19:08.081591Z","end":"2026-09-08T16:19:08.209888Z","steps":["trace[203511801] 'agreement among raft nodes before linearized reading' (duration: 127.933984ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:19:13.399863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.022769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-09-08T16:19:13.399967Z","caller":"traceutil/trace.go:171","msg":"trace[679704828] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15519; }","duration":"101.157532ms","start":"2026-09-08T16:19:13.298784Z","end":"2026-09-08T16:19:13.399942Z","steps":["trace[679704828] 'range keys from in-memory index tree' (duration: 100.869756ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:19:13.400308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.656096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-08T16:19:13.400762Z","caller":"traceutil/trace.go:171","msg":"trace[926395674] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15519; }","duration":"104.176136ms","start":"2026-09-08T16:19:13.296556Z","end":"2026-09-08T16:19:13.400732Z","steps":["trace[926395674] 'range keys from in-memory index tree' (duration: 103.527203ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:19:14.175561Z","caller":"traceutil/trace.go:171","msg":"trace[1669092086] transaction","detail":"{read_only:false; response_revision:15521; number_of_response:1; }","duration":"126.401961ms","start":"2026-09-08T16:19:14.04914Z","end":"2026-09-08T16:19:14.175542Z","steps":["trace[1669092086] 'process raft request' (duration: 126.149366ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:19:14.175537Z","caller":"traceutil/trace.go:171","msg":"trace[1261075536] linearizableReadLoop","detail":"{readStateIndex:16055; appliedIndex:16054; }","duration":"114.450676ms","start":"2026-09-08T16:19:14.061039Z","end":"2026-09-08T16:19:14.17549Z","steps":["trace[1261075536] 'read index received' (duration: 114.15115ms)","trace[1261075536] 'applied index is now lower than readState.Index' (duration: 298.636µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:19:14.175798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.736762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-f2plv\" ","response":"range_response_count:1 size:20649"} {"level":"info","ts":"2026-09-08T16:19:14.176125Z","caller":"traceutil/trace.go:171","msg":"trace[498836754] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-f2plv; range_end:; response_count:1; response_revision:15521; }","duration":"115.09676ms","start":"2026-09-08T16:19:14.061014Z","end":"2026-09-08T16:19:14.17611Z","steps":["trace[498836754] 'agreement among raft nodes before linearized reading' (duration: 114.594439ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:23:33.006307Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15309} {"level":"info","ts":"2026-09-08T16:23:33.115602Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15309,"took":"106.396431ms","hash":179588326,"current-db-size-bytes":55525376,"current-db-size":"56 MB","current-db-size-in-use-bytes":31485952,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-08T16:23:33.115702Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":179588326,"revision":15309,"compact-revision":12709} {"level":"warn","ts":"2026-09-08T16:24:52.258686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.373198ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-08T16:24:52.600914Z","caller":"traceutil/trace.go:171","msg":"trace[1071077591] linearizableReadLoop","detail":"{readStateIndex:18592; appliedIndex:18591; }","duration":"146.232952ms","start":"2026-09-08T16:24:52.454646Z","end":"2026-09-08T16:24:52.600879Z","steps":["trace[1071077591] 'read index received' (duration: 145.992387ms)","trace[1071077591] 'applied index is now lower than readState.Index' (duration: 239.655µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:24:52.601166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.509917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-08T16:24:52.601343Z","caller":"traceutil/trace.go:171","msg":"trace[180491080] transaction","detail":"{read_only:false; response_revision:17974; number_of_response:1; }","duration":"157.821129ms","start":"2026-09-08T16:24:52.443485Z","end":"2026-09-08T16:24:52.601306Z","steps":["trace[180491080] 'process raft request' (duration: 157.233267ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:24:52.601451Z","caller":"traceutil/trace.go:171","msg":"trace[463031038] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17974; }","duration":"146.641301ms","start":"2026-09-08T16:24:52.454562Z","end":"2026-09-08T16:24:52.601204Z","steps":["trace[463031038] 'agreement among raft nodes before linearized reading' (duration: 146.468707ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:24:53.180924Z","caller":"traceutil/trace.go:171","msg":"trace[602321046] linearizableReadLoop","detail":"{readStateIndex:18595; appliedIndex:18594; }","duration":"140.411588ms","start":"2026-09-08T16:24:53.040492Z","end":"2026-09-08T16:24:53.180904Z","steps":["trace[602321046] 'read index received' (duration: 140.267895ms)","trace[602321046] 'applied index is now lower than readState.Index' (duration: 142.643µs)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:24:53.181032Z","caller":"traceutil/trace.go:171","msg":"trace[1887035743] transaction","detail":"{read_only:false; response_revision:17977; number_of_response:1; }","duration":"166.116097ms","start":"2026-09-08T16:24:53.014885Z","end":"2026-09-08T16:24:53.181001Z","steps":["trace[1887035743] 'process raft request' (duration: 165.867452ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:24:53.181187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.684663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-09-08T16:24:53.181235Z","caller":"traceutil/trace.go:171","msg":"trace[597098784] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:17977; }","duration":"140.780966ms","start":"2026-09-08T16:24:53.040444Z","end":"2026-09-08T16:24:53.181225Z","steps":["trace[597098784] 'agreement among raft nodes before linearized reading' (duration: 140.570141ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:24:53.24581Z","caller":"traceutil/trace.go:171","msg":"trace[1045372072] transaction","detail":"{read_only:false; response_revision:17978; number_of_response:1; }","duration":"111.51177ms","start":"2026-09-08T16:24:53.134276Z","end":"2026-09-08T16:24:53.245788Z","steps":["trace[1045372072] 'process raft request' (duration: 99.3279ms)","trace[1045372072] 'compare' (duration: 11.774041ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:24:53.246362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.803223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-08T16:24:53.246433Z","caller":"traceutil/trace.go:171","msg":"trace[1348434180] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:17980; }","duration":"167.925775ms","start":"2026-09-08T16:24:53.078488Z","end":"2026-09-08T16:24:53.246414Z","steps":["trace[1348434180] 'agreement among raft nodes before linearized reading' (duration: 167.770241ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:25:01.407849Z","caller":"traceutil/trace.go:171","msg":"trace[1988148800] transaction","detail":"{read_only:false; response_revision:18132; number_of_response:1; }","duration":"119.595844ms","start":"2026-09-08T16:25:01.288219Z","end":"2026-09-08T16:25:01.407815Z","steps":["trace[1988148800] 'process raft request' (duration: 115.641509ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:25:01.407959Z","caller":"traceutil/trace.go:171","msg":"trace[1680218457] transaction","detail":"{read_only:false; response_revision:18133; number_of_response:1; }","duration":"119.301307ms","start":"2026-09-08T16:25:01.288648Z","end":"2026-09-08T16:25:01.40795Z","steps":["trace[1680218457] 'process raft request' (duration: 118.914789ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:25:01.408243Z","caller":"traceutil/trace.go:171","msg":"trace[2100180047] transaction","detail":"{read_only:false; response_revision:18134; number_of_response:1; }","duration":"118.808736ms","start":"2026-09-08T16:25:01.289375Z","end":"2026-09-08T16:25:01.408183Z","steps":["trace[2100180047] 'process raft request' (duration: 118.249265ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:25:01.408368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.829948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-08T16:25:01.408428Z","caller":"traceutil/trace.go:171","msg":"trace[254504827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18134; }","duration":"104.92857ms","start":"2026-09-08T16:25:01.303485Z","end":"2026-09-08T16:25:01.408414Z","steps":["trace[254504827] 'agreement among raft nodes before linearized reading' (duration: 104.768047ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:25:01.408437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.333734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/openstack/libvirt-libvirt-default-f2plv-api\" ","response":"range_response_count:1 size:1499"} {"level":"warn","ts":"2026-09-08T16:25:01.408485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.203672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-09-08T16:25:01.408545Z","caller":"traceutil/trace.go:171","msg":"trace[2053077450] range","detail":"{range_begin:/registry/cert-manager.io/certificates/openstack/libvirt-libvirt-default-f2plv-api; range_end:; response_count:1; response_revision:18134; }","duration":"124.482317ms","start":"2026-09-08T16:25:01.284038Z","end":"2026-09-08T16:25:01.40852Z","steps":["trace[2053077450] 'agreement among raft nodes before linearized reading' (duration: 124.289573ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:25:01.40857Z","caller":"traceutil/trace.go:171","msg":"trace[304239486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18134; }","duration":"117.315635ms","start":"2026-09-08T16:25:01.291237Z","end":"2026-09-08T16:25:01.408552Z","steps":["trace[304239486] 'agreement among raft nodes before linearized reading' (duration: 117.024199ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:25:01.407889Z","caller":"traceutil/trace.go:171","msg":"trace[1257070550] transaction","detail":"{read_only:false; number_of_response:1; response_revision:18134; }","duration":"115.407484ms","start":"2026-09-08T16:25:01.292455Z","end":"2026-09-08T16:25:01.407862Z","steps":["trace[1257070550] 'process raft request' (duration: 115.24651ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:27:23.818443Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"427150911cb3975a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-08T16:27:23.824815Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-08T16:27:23.824959Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-08T16:28:33.013898Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17439} {"level":"info","ts":"2026-09-08T16:28:33.122843Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17439,"took":"105.100604ms","hash":30988778,"current-db-size-bytes":55525376,"current-db-size":"56 MB","current-db-size-in-use-bytes":34406400,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-08T16:28:33.122925Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":30988778,"revision":17439,"compact-revision":15309} {"level":"warn","ts":"2026-09-08T16:30:56.290354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.494491ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-08T16:30:56.290532Z","caller":"traceutil/trace.go:171","msg":"trace[915379858] linearizableReadLoop","detail":"{readStateIndex:21479; appliedIndex:21478; }","duration":"101.691622ms","start":"2026-09-08T16:30:56.188818Z","end":"2026-09-08T16:30:56.290509Z","steps":["trace[915379858] 'read index received' (duration: 38.161µs)","trace[915379858] 'applied index is now lower than readState.Index' (duration: 101.651661ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:30:56.290551Z","caller":"traceutil/trace.go:171","msg":"trace[809799845] transaction","detail":"{read_only:false; response_revision:20771; number_of_response:1; }","duration":"330.873864ms","start":"2026-09-08T16:30:55.959642Z","end":"2026-09-08T16:30:56.290516Z","steps":["trace[809799845] 'process raft request' (duration: 26.098927ms)","trace[809799845] 'compare' (duration: 304.252796ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:30:56.290697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.872846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-08T16:30:56.290737Z","caller":"traceutil/trace.go:171","msg":"trace[1233920707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20771; }","duration":"101.937907ms","start":"2026-09-08T16:30:56.188787Z","end":"2026-09-08T16:30:56.290724Z","steps":["trace[1233920707] 'agreement among raft nodes before linearized reading' (duration: 101.857985ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:56.290747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:30:55.959625Z","time spent":"331.032968ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-08T16:30:56.291107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.335593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-08T16:30:56.29117Z","caller":"traceutil/trace.go:171","msg":"trace[192542541] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:20771; }","duration":"101.432566ms","start":"2026-09-08T16:30:56.189722Z","end":"2026-09-08T16:30:56.291155Z","steps":["trace[192542541] 'agreement among raft nodes before linearized reading' (duration: 101.311973ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:30:56.899811Z","caller":"traceutil/trace.go:171","msg":"trace[2015718819] linearizableReadLoop","detail":"{readStateIndex:21480; appliedIndex:21479; }","duration":"168.641981ms","start":"2026-09-08T16:30:56.731135Z","end":"2026-09-08T16:30:56.899777Z","steps":["trace[2015718819] 'read index received' (duration: 168.422006ms)","trace[2015718819] 'applied index is now lower than readState.Index' (duration: 218.814µs)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:30:56.900031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.890965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-09-08T16:30:56.900129Z","caller":"traceutil/trace.go:171","msg":"trace[820060826] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:20772; }","duration":"169.027548ms","start":"2026-09-08T16:30:56.731081Z","end":"2026-09-08T16:30:56.900109Z","steps":["trace[820060826] 'agreement among raft nodes before linearized reading' (duration: 168.823364ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:30:56.899873Z","caller":"traceutil/trace.go:171","msg":"trace[2138107810] transaction","detail":"{read_only:false; response_revision:20772; number_of_response:1; }","duration":"252.632434ms","start":"2026-09-08T16:30:56.647214Z","end":"2026-09-08T16:30:56.899846Z","steps":["trace[2138107810] 'process raft request' (duration: 252.386649ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:57.159622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.195931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-08T16:30:57.1597Z","caller":"traceutil/trace.go:171","msg":"trace[1903781242] linearizableReadLoop","detail":"{readStateIndex:21481; appliedIndex:21480; }","duration":"259.802317ms","start":"2026-09-08T16:30:56.899887Z","end":"2026-09-08T16:30:57.159689Z","steps":["trace[1903781242] 'read index received' (duration: 114.339941ms)","trace[1903781242] 'applied index is now lower than readState.Index' (duration: 145.461256ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:30:57.159781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.064552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-09-08T16:30:57.1598Z","caller":"traceutil/trace.go:171","msg":"trace[967525855] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20773; }","duration":"378.118543ms","start":"2026-09-08T16:30:56.781675Z","end":"2026-09-08T16:30:57.159793Z","steps":["trace[967525855] 'agreement among raft nodes before linearized reading' (duration: 378.039972ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:57.159817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:30:56.781652Z","time spent":"378.161474ms","remote":"127.0.0.1:53054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-09-08T16:30:57.160134Z","caller":"traceutil/trace.go:171","msg":"trace[1336379729] transaction","detail":"{read_only:false; response_revision:20773; number_of_response:1; }","duration":"354.937848ms","start":"2026-09-08T16:30:56.805168Z","end":"2026-09-08T16:30:57.160106Z","steps":["trace[1336379729] 'process raft request' (duration: 209.074523ms)","trace[1336379729] 'compare' (duration: 144.936835ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:30:57.160568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:30:56.805146Z","time spent":"355.347227ms","remote":"127.0.0.1:53054","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-09-08T16:30:57.16014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.458232ms","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-09-08T16:30:57.160807Z","caller":"traceutil/trace.go:171","msg":"trace[1105097928] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:20773; }","duration":"366.130557ms","start":"2026-09-08T16:30:56.794636Z","end":"2026-09-08T16:30:57.160766Z","steps":["trace[1105097928] 'agreement among raft nodes before linearized reading' (duration: 365.435592ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:57.160932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:30:56.794626Z","time spent":"366.265669ms","remote":"127.0.0.1:58172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":31,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"warn","ts":"2026-09-08T16:30:57.160203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.820582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-09-08T16:30:57.160165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.35641ms","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":"info","ts":"2026-09-08T16:30:57.161053Z","caller":"traceutil/trace.go:171","msg":"trace[1003018874] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:20773; }","duration":"345.671131ms","start":"2026-09-08T16:30:56.815344Z","end":"2026-09-08T16:30:57.161015Z","steps":["trace[1003018874] 'agreement among raft nodes before linearized reading' (duration: 344.634278ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:30:57.161158Z","caller":"traceutil/trace.go:171","msg":"trace[1387395280] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20773; }","duration":"314.363972ms","start":"2026-09-08T16:30:56.846754Z","end":"2026-09-08T16:30:57.161118Z","steps":["trace[1387395280] 'agreement among raft nodes before linearized reading' (duration: 313.322479ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:57.161188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:30:56.81533Z","time spent":"345.817114ms","remote":"127.0.0.1:41728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":134,"response size":32,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-09-08T16:30:57.160208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.2463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.85\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-09-08T16:30:57.161217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:30:56.846743Z","time spent":"314.455935ms","remote":"127.0.0.1:52124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-09-08T16:30:57.161265Z","caller":"traceutil/trace.go:171","msg":"trace[931172027] range","detail":"{range_begin:/registry/masterleases/162.253.55.85; range_end:; response_count:1; response_revision:20773; }","duration":"323.343434ms","start":"2026-09-08T16:30:56.837899Z","end":"2026-09-08T16:30:57.161243Z","steps":["trace[931172027] 'agreement among raft nodes before linearized reading' (duration: 322.107247ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:57.161355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:30:56.837888Z","time spent":"323.451157ms","remote":"127.0.0.1:52848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":160,"request content":"key:\"/registry/masterleases/162.253.55.85\" "} {"level":"info","ts":"2026-09-08T16:30:57.170062Z","caller":"traceutil/trace.go:171","msg":"trace[2096835635] transaction","detail":"{read_only:false; response_revision:20774; number_of_response:1; }","duration":"264.862146ms","start":"2026-09-08T16:30:56.905179Z","end":"2026-09-08T16:30:57.170041Z","steps":["trace[2096835635] 'process raft request' (duration: 264.754363ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:30:57.223995Z","caller":"traceutil/trace.go:171","msg":"trace[71359956] transaction","detail":"{read_only:false; response_revision:20775; number_of_response:1; }","duration":"196.214749ms","start":"2026-09-08T16:30:57.027752Z","end":"2026-09-08T16:30:57.223967Z","steps":["trace[71359956] 'process raft request' (duration: 196.020375ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:30:57.482861Z","caller":"traceutil/trace.go:171","msg":"trace[807762096] linearizableReadLoop","detail":"{readStateIndex:21486; appliedIndex:21485; }","duration":"202.901431ms","start":"2026-09-08T16:30:57.279935Z","end":"2026-09-08T16:30:57.482836Z","steps":["trace[807762096] 'read index received' (duration: 112.4135ms)","trace[807762096] 'applied index is now lower than readState.Index' (duration: 90.486841ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:30:57.482879Z","caller":"traceutil/trace.go:171","msg":"trace[1904147535] transaction","detail":"{read_only:false; response_revision:20777; number_of_response:1; }","duration":"257.505178ms","start":"2026-09-08T16:30:57.225346Z","end":"2026-09-08T16:30:57.482851Z","steps":["trace[1904147535] 'process raft request' (duration: 167.052898ms)","trace[1904147535] 'compare' (duration: 90.313297ms)"],"step_count":2} {"level":"warn","ts":"2026-09-08T16:30:57.483112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.662616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-09-08T16:30:57.483053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.113836ms","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-09-08T16:30:57.483237Z","caller":"traceutil/trace.go:171","msg":"trace[61313812] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20777; }","duration":"203.330441ms","start":"2026-09-08T16:30:57.279886Z","end":"2026-09-08T16:30:57.483216Z","steps":["trace[61313812] 'agreement among raft nodes before linearized reading' (duration: 203.043574ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:30:57.483146Z","caller":"traceutil/trace.go:171","msg":"trace[1446927137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20777; }","duration":"150.750948ms","start":"2026-09-08T16:30:57.332388Z","end":"2026-09-08T16:30:57.483139Z","steps":["trace[1446927137] 'agreement among raft nodes before linearized reading' (duration: 150.691116ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:57.483205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.5265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-08T16:30:57.483454Z","caller":"traceutil/trace.go:171","msg":"trace[1642078480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20777; }","duration":"147.758304ms","start":"2026-09-08T16:30:57.335674Z","end":"2026-09-08T16:30:57.483432Z","steps":["trace[1642078480] 'agreement among raft nodes before linearized reading' (duration: 147.479949ms)"],"step_count":1} {"level":"warn","ts":"2026-09-08T16:30:57.712956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.681558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-08T16:30:57.713172Z","caller":"traceutil/trace.go:171","msg":"trace[1854633993] transaction","detail":"{read_only:false; response_revision:20780; number_of_response:1; }","duration":"224.37249ms","start":"2026-09-08T16:30:57.48878Z","end":"2026-09-08T16:30:57.713153Z","steps":["trace[1854633993] 'process raft request' (duration: 224.270038ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:30:57.713341Z","caller":"traceutil/trace.go:171","msg":"trace[2097000322] transaction","detail":"{read_only:false; response_revision:20779; number_of_response:1; }","duration":"224.525404ms","start":"2026-09-08T16:30:57.488781Z","end":"2026-09-08T16:30:57.713307Z","steps":["trace[2097000322] 'process raft request' (duration: 95.396847ms)","trace[2097000322] 'compare' (duration: 128.134235ms)"],"step_count":2} {"level":"info","ts":"2026-09-08T16:33:33.021388Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19803} {"level":"info","ts":"2026-09-08T16:33:33.142935Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19803,"took":"118.161932ms","hash":34512893,"current-db-size-bytes":55525376,"current-db-size":"56 MB","current-db-size-in-use-bytes":30334976,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-08T16:33:33.143003Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":34512893,"revision":19803,"compact-revision":17439} {"level":"warn","ts":"2026-09-08T16:34:57.214792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.991196ms","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-09-08T16:34:57.214793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.921804ms","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-09-08T16:34:57.214927Z","caller":"traceutil/trace.go:171","msg":"trace[274348484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23226; }","duration":"234.123598ms","start":"2026-09-08T16:34:56.980785Z","end":"2026-09-08T16:34:57.214909Z","steps":["trace[274348484] 'range keys from in-memory index tree' (duration: 233.71917ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:34:57.214903Z","caller":"traceutil/trace.go:171","msg":"trace[854032631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23226; }","duration":"182.147279ms","start":"2026-09-08T16:34:57.03273Z","end":"2026-09-08T16:34:57.214877Z","steps":["trace[854032631] 'range keys from in-memory index tree' (duration: 181.823933ms)"],"step_count":1} {"level":"info","ts":"2026-09-08T16:38:33.029318Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21983} {"level":"info","ts":"2026-09-08T16:38:33.141885Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21983,"took":"108.823426ms","hash":3213808722,"current-db-size-bytes":56369152,"current-db-size":"56 MB","current-db-size-in-use-bytes":42516480,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-08T16:38:33.141956Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3213808722,"revision":21983,"compact-revision":19803} {"level":"info","ts":"2026-09-08T16:43:33.037006Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25149} {"level":"info","ts":"2026-09-08T16:43:33.183058Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25149,"took":"142.740828ms","hash":442766020,"current-db-size-bytes":56369152,"current-db-size":"56 MB","current-db-size-in-use-bytes":30507008,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-08T16:43:33.183145Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":442766020,"revision":25149,"compact-revision":21983} {"level":"info","ts":"2026-09-08T16:44:20.181095Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006fd6.wal"}