{"level":"warn","ts":"2026-09-08T15:48:43.290122Z","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:43.29023Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.124: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.124:2380","--initial-cluster=instance=https://162.253.55.124:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.124:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.124: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:43.290328Z","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:43.290348Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.124:2380"]}
{"level":"info","ts":"2026-09-08T15:48:43.290372Z","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:43.291239Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.124:2379"]}
{"level":"info","ts":"2026-09-08T15:48:43.291356Z","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.124:2380"],"listen-peer-urls":["https://162.253.55.124:2380"],"advertise-client-urls":["https://162.253.55.124:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.124:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.124: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:43.29806Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.06538ms"}
{"level":"info","ts":"2026-09-08T15:48:43.305636Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9ad1df4dc3f3835d","cluster-id":"5f6d28152804218"}
{"level":"info","ts":"2026-09-08T15:48:43.305731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d switched to configuration voters=()"}
{"level":"info","ts":"2026-09-08T15:48:43.305751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d became follower at term 0"}
{"level":"info","ts":"2026-09-08T15:48:43.305765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9ad1df4dc3f3835d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-09-08T15:48:43.305774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d became follower at term 1"}
{"level":"info","ts":"2026-09-08T15:48:43.305814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d switched to configuration voters=(11155943277066421085)"}
{"level":"warn","ts":"2026-09-08T15:48:43.31479Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-09-08T15:48:43.316734Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-09-08T15:48:43.318235Z","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:43.320815Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9ad1df4dc3f3835d","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-09-08T15:48:43.32097Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9ad1df4dc3f3835d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-09-08T15:48:43.321141Z","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:43.321358Z","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:43.321382Z","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:43.32311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d switched to configuration voters=(11155943277066421085)"}
{"level":"info","ts":"2026-09-08T15:48:43.324051Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f6d28152804218","local-member-id":"9ad1df4dc3f3835d","added-peer-id":"9ad1df4dc3f3835d","added-peer-peer-urls":["https://162.253.55.124:2380"]}
{"level":"info","ts":"2026-09-08T15:48:43.326524Z","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:43.326585Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.124:2380"}
{"level":"info","ts":"2026-09-08T15:48:43.32661Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.124:2380"}
{"level":"info","ts":"2026-09-08T15:48:43.326898Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9ad1df4dc3f3835d","initial-advertise-peer-urls":["https://162.253.55.124:2380"],"listen-peer-urls":["https://162.253.55.124:2380"],"advertise-client-urls":["https://162.253.55.124:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.124:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-09-08T15:48:43.326958Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-09-08T15:48:44.306545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d is starting a new election at term 1"}
{"level":"info","ts":"2026-09-08T15:48:44.306622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d became pre-candidate at term 1"}
{"level":"info","ts":"2026-09-08T15:48:44.306663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d received MsgPreVoteResp from 9ad1df4dc3f3835d at term 1"}
{"level":"info","ts":"2026-09-08T15:48:44.306684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d became candidate at term 2"}
{"level":"info","ts":"2026-09-08T15:48:44.306695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d received MsgVoteResp from 9ad1df4dc3f3835d at term 2"}
{"level":"info","ts":"2026-09-08T15:48:44.306712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ad1df4dc3f3835d became leader at term 2"}
{"level":"info","ts":"2026-09-08T15:48:44.306726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9ad1df4dc3f3835d elected leader 9ad1df4dc3f3835d at term 2"}
{"level":"info","ts":"2026-09-08T15:48:44.308927Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9ad1df4dc3f3835d","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.124:2379]}","request-path":"/0/members/9ad1df4dc3f3835d/attributes","cluster-id":"5f6d28152804218","publish-timeout":"7s"}
{"level":"info","ts":"2026-09-08T15:48:44.308939Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-08T15:48:44.308965Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-09-08T15:48:44.309151Z","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:44.309394Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-09-08T15:48:44.309533Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-09-08T15:48:44.310847Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f6d28152804218","local-member-id":"9ad1df4dc3f3835d","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-08T15:48:44.310964Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-08T15:48:44.310998Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-09-08T15:48:44.312487Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.124:2379"}
{"level":"info","ts":"2026-09-08T15:48:44.312668Z","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:49:23.712474Z","caller":"traceutil/trace.go:171","msg":"trace[275249926] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"180.594542ms","start":"2026-09-08T15:49:23.531843Z","end":"2026-09-08T15:49:23.712438Z","steps":["trace[275249926] 'process raft request'  (duration: 180.323735ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:23.834514Z","caller":"traceutil/trace.go:171","msg":"trace[2073299908] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"284.951152ms","start":"2026-09-08T15:49:23.549501Z","end":"2026-09-08T15:49:23.834452Z","steps":["trace[2073299908] 'process raft request'  (duration: 284.433378ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:23.834585Z","caller":"traceutil/trace.go:171","msg":"trace[1709009502] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:510; }","duration":"285.575508ms","start":"2026-09-08T15:49:23.548983Z","end":"2026-09-08T15:49:23.834558Z","steps":["trace[1709009502] 'read index received'  (duration: 163.492373ms)","trace[1709009502] 'applied index is now lower than readState.Index'  (duration: 122.082035ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T15:49:23.834658Z","caller":"traceutil/trace.go:171","msg":"trace[578580902] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"283.518595ms","start":"2026-09-08T15:49:23.551124Z","end":"2026-09-08T15:49:23.834643Z","steps":["trace[578580902] 'process raft request'  (duration: 282.875348ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:49:23.834757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.751643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T15:49:23.834846Z","caller":"traceutil/trace.go:171","msg":"trace[538534542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"285.892107ms","start":"2026-09-08T15:49:23.548942Z","end":"2026-09-08T15:49:23.834834Z","steps":["trace[538534542] 'agreement among raft nodes before linearized reading'  (duration: 285.722912ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:23.834836Z","caller":"traceutil/trace.go:171","msg":"trace[794990046] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"282.577899ms","start":"2026-09-08T15:49:23.552225Z","end":"2026-09-08T15:49:23.834803Z","steps":["trace[794990046] 'process raft request'  (duration: 281.829559ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:23.834877Z","caller":"traceutil/trace.go:171","msg":"trace[788834299] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"248.589627ms","start":"2026-09-08T15:49:23.586258Z","end":"2026-09-08T15:49:23.834847Z","steps":["trace[788834299] 'process raft request'  (duration: 248.203337ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:23.835003Z","caller":"traceutil/trace.go:171","msg":"trace[123204060] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"260.818569ms","start":"2026-09-08T15:49:23.574168Z","end":"2026-09-08T15:49:23.834987Z","steps":["trace[123204060] 'process raft request'  (duration: 260.210223ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:23.835028Z","caller":"traceutil/trace.go:171","msg":"trace[1274296263] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"210.413685ms","start":"2026-09-08T15:49:23.624535Z","end":"2026-09-08T15:49:23.834949Z","steps":["trace[1274296263] 'process raft request'  (duration: 209.975803ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:23.835114Z","caller":"traceutil/trace.go:171","msg":"trace[1922926401] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"296.68355ms","start":"2026-09-08T15:49:23.538379Z","end":"2026-09-08T15:49:23.835062Z","steps":["trace[1922926401] 'process raft request'  (duration: 295.496338ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:23.834592Z","caller":"traceutil/trace.go:171","msg":"trace[1550733652] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"280.102065ms","start":"2026-09-08T15:49:23.554461Z","end":"2026-09-08T15:49:23.834563Z","steps":["trace[1550733652] 'process raft request'  (duration: 279.771166ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:23.835279Z","caller":"traceutil/trace.go:171","msg":"trace[1143854312] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"296.981978ms","start":"2026-09-08T15:49:23.538222Z","end":"2026-09-08T15:49:23.835204Z","steps":["trace[1143854312] 'process raft request'  (duration: 295.506319ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:23.834986Z","caller":"traceutil/trace.go:171","msg":"trace[1432040579] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"281.525051ms","start":"2026-09-08T15:49:23.553442Z","end":"2026-09-08T15:49:23.834967Z","steps":["trace[1432040579] 'process raft request'  (duration: 280.7262ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:23.84002Z","caller":"traceutil/trace.go:171","msg":"trace[1173357117] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"117.267409ms","start":"2026-09-08T15:49:23.72263Z","end":"2026-09-08T15:49:23.839897Z","steps":["trace[1173357117] 'process raft request'  (duration: 116.487519ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:23.840265Z","caller":"traceutil/trace.go:171","msg":"trace[557599997] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"117.431433ms","start":"2026-09-08T15:49:23.722793Z","end":"2026-09-08T15:49:23.840224Z","steps":["trace[557599997] 'process raft request'  (duration: 116.467399ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:23.840497Z","caller":"traceutil/trace.go:171","msg":"trace[1010979712] transaction","detail":"{read_only:false; number_of_response:1; response_revision:501; }","duration":"117.742571ms","start":"2026-09-08T15:49:23.722746Z","end":"2026-09-08T15:49:23.840489Z","steps":["trace[1010979712] 'process raft request'  (duration: 116.472898ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:23.840749Z","caller":"traceutil/trace.go:171","msg":"trace[802389718] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"107.918223ms","start":"2026-09-08T15:49:23.732823Z","end":"2026-09-08T15:49:23.840742Z","steps":["trace[802389718] 'process raft request'  (duration: 106.768662ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:31.158495Z","caller":"traceutil/trace.go:171","msg":"trace[2033832214] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"153.217043ms","start":"2026-09-08T15:49:31.005233Z","end":"2026-09-08T15:49:31.15845Z","steps":["trace[2033832214] 'read index received'  (duration: 98.465266ms)","trace[2033832214] 'applied index is now lower than readState.Index'  (duration: 54.750477ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T15:49:31.158575Z","caller":"traceutil/trace.go:171","msg":"trace[1751940533] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"293.892367ms","start":"2026-09-08T15:49:30.864651Z","end":"2026-09-08T15:49:31.158543Z","steps":["trace[1751940533] 'process raft request'  (duration: 239.140329ms)","trace[1751940533] 'compare'  (duration: 54.426409ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T15:49:31.158711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.460119ms","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:49:31.158753Z","caller":"traceutil/trace.go:171","msg":"trace[807828440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"153.559802ms","start":"2026-09-08T15:49:31.005181Z","end":"2026-09-08T15:49:31.158741Z","steps":["trace[807828440] 'agreement among raft nodes before linearized reading'  (duration: 153.423728ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:31.387788Z","caller":"traceutil/trace.go:171","msg":"trace[930648416] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"216.349851ms","start":"2026-09-08T15:49:31.171399Z","end":"2026-09-08T15:49:31.387749Z","steps":["trace[930648416] 'read index received'  (duration: 172.376086ms)","trace[930648416] 'applied index is now lower than readState.Index'  (duration: 43.972745ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T15:49:31.387994Z","caller":"traceutil/trace.go:171","msg":"trace[800295968] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"224.29504ms","start":"2026-09-08T15:49:31.163597Z","end":"2026-09-08T15:49:31.387892Z","steps":["trace[800295968] 'process raft request'  (duration: 180.349246ms)","trace[800295968] 'compare'  (duration: 43.658416ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T15:49:31.387949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.529146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T15:49:31.388217Z","caller":"traceutil/trace.go:171","msg":"trace[496990464] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:594; }","duration":"216.881754ms","start":"2026-09-08T15:49:31.171313Z","end":"2026-09-08T15:49:31.388195Z","steps":["trace[496990464] 'agreement among raft nodes before linearized reading'  (duration: 216.567435ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:49:31.876188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.434967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wr8z6\" ","response":"range_response_count:1 size:16965"}
{"level":"info","ts":"2026-09-08T15:49:31.876303Z","caller":"traceutil/trace.go:171","msg":"trace[309716269] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wr8z6; range_end:; response_count:1; response_revision:594; }","duration":"156.602971ms","start":"2026-09-08T15:49:31.719674Z","end":"2026-09-08T15:49:31.876277Z","steps":["trace[309716269] 'range keys from in-memory index tree'  (duration: 156.294873ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:49:31.876309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.904994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T15:49:31.876414Z","caller":"traceutil/trace.go:171","msg":"trace[1013202949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"327.088158ms","start":"2026-09-08T15:49:31.549306Z","end":"2026-09-08T15:49:31.876394Z","steps":["trace[1013202949] 'range keys from in-memory index tree'  (duration: 326.807912ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:49:31.876459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:31.549282Z","time spent":"327.16827ms","remote":"127.0.0.1:49184","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:49:32.617355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.809165ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T15:49:32.617493Z","caller":"traceutil/trace.go:171","msg":"trace[136666523] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:595; }","duration":"456.986469ms","start":"2026-09-08T15:49:32.160484Z","end":"2026-09-08T15:49:32.61747Z","steps":["trace[136666523] 'range keys from in-memory index tree'  (duration: 456.789154ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:32.618296Z","caller":"traceutil/trace.go:171","msg":"trace[449342421] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"723.368564ms","start":"2026-09-08T15:49:31.894879Z","end":"2026-09-08T15:49:32.618247Z","steps":["trace[449342421] 'process raft request'  (duration: 634.276495ms)","trace[449342421] 'compare'  (duration: 88.542905ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T15:49:32.618413Z","caller":"traceutil/trace.go:171","msg":"trace[1141154390] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:618; }","duration":"386.084137ms","start":"2026-09-08T15:49:32.232285Z","end":"2026-09-08T15:49:32.61837Z","steps":["trace[1141154390] 'read index received'  (duration: 296.824304ms)","trace[1141154390] 'applied index is now lower than readState.Index'  (duration: 89.254613ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T15:49:32.618744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.446307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"warn","ts":"2026-09-08T15:49:32.618748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.44148ms","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:32.618799Z","caller":"traceutil/trace.go:171","msg":"trace[336570217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"386.575419ms","start":"2026-09-08T15:49:32.23221Z","end":"2026-09-08T15:49:32.618786Z","steps":["trace[336570217] 'agreement among raft nodes before linearized reading'  (duration: 386.238701ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:32.618447Z","caller":"traceutil/trace.go:171","msg":"trace[1602461391] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"594.338466ms","start":"2026-09-08T15:49:32.024014Z","end":"2026-09-08T15:49:32.618352Z","steps":["trace[1602461391] 'process raft request'  (duration: 594.12183ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:32.618814Z","caller":"traceutil/trace.go:171","msg":"trace[12520755] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"225.564043ms","start":"2026-09-08T15:49:32.393233Z","end":"2026-09-08T15:49:32.618797Z","steps":["trace[12520755] 'agreement among raft nodes before linearized reading'  (duration: 225.332758ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:49:32.618851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:32.232183Z","time spent":"386.636581ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-09-08T15:49:32.618887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.848154ms","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:32.618963Z","caller":"traceutil/trace.go:171","msg":"trace[921669025] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"262.0152ms","start":"2026-09-08T15:49:32.356929Z","end":"2026-09-08T15:49:32.618944Z","steps":["trace[921669025] 'agreement among raft nodes before linearized reading'  (duration: 261.61847ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:49:32.619061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:31.894832Z","time spent":"723.625671ms","remote":"127.0.0.1:45248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-wr8z6\" mod_revision:480 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-wr8z6\" value_size:17085 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-wr8z6\" > >"}
{"level":"warn","ts":"2026-09-08T15:49:32.619034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:32.023985Z","time spent":"594.867749ms","remote":"127.0.0.1:45398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" mod_revision:0 > success:<request_put:<key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" value_size:958 >> failure:<>"}
{"level":"warn","ts":"2026-09-08T15:49:48.884205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.894344ms","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:48.88426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.066638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T15:49:48.884319Z","caller":"traceutil/trace.go:171","msg":"trace[2032714604] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:763; }","duration":"148.063378ms","start":"2026-09-08T15:49:48.73623Z","end":"2026-09-08T15:49:48.884294Z","steps":["trace[2032714604] 'range keys from in-memory index tree'  (duration: 147.636567ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:49:48.884386Z","caller":"traceutil/trace.go:171","msg":"trace[1107581468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"335.244462ms","start":"2026-09-08T15:49:48.549111Z","end":"2026-09-08T15:49:48.884355Z","steps":["trace[1107581468] 'range keys from in-memory index tree'  (duration: 334.905383ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:49:48.8844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.687846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"}
{"level":"warn","ts":"2026-09-08T15:49:48.884454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:49:48.549089Z","time spent":"335.347145ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-09-08T15:49:48.884466Z","caller":"traceutil/trace.go:171","msg":"trace[640478831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"166.787679ms","start":"2026-09-08T15:49:48.717659Z","end":"2026-09-08T15:49:48.884446Z","steps":["trace[640478831] 'range keys from in-memory index tree'  (duration: 166.508952ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:49:48.884538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.419479ms","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:48.884659Z","caller":"traceutil/trace.go:171","msg":"trace[1903172727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:763; }","duration":"158.578224ms","start":"2026-09-08T15:49:48.726053Z","end":"2026-09-08T15:49:48.884632Z","steps":["trace[1903172727] 'range keys from in-memory index tree'  (duration: 158.256195ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:50:19.416828Z","caller":"traceutil/trace.go:171","msg":"trace[1275144828] linearizableReadLoop","detail":"{readStateIndex:1211; appliedIndex:1210; }","duration":"257.400376ms","start":"2026-09-08T15:50:19.159399Z","end":"2026-09-08T15:50:19.4168Z","steps":["trace[1275144828] 'read index received'  (duration: 257.130319ms)","trace[1275144828] 'applied index is now lower than readState.Index'  (duration: 269.227µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T15:50:19.416985Z","caller":"traceutil/trace.go:171","msg":"trace[795810237] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"298.836425ms","start":"2026-09-08T15:50:19.118097Z","end":"2026-09-08T15:50:19.416933Z","steps":["trace[795810237] 'process raft request'  (duration: 298.542077ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:50:19.41708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.669793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"}
{"level":"info","ts":"2026-09-08T15:50:19.417118Z","caller":"traceutil/trace.go:171","msg":"trace[1801372176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1176; }","duration":"257.782146ms","start":"2026-09-08T15:50:19.159324Z","end":"2026-09-08T15:50:19.417106Z","steps":["trace[1801372176] 'agreement among raft nodes before linearized reading'  (duration: 257.632802ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:50:19.41726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.458627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T15:50:19.41748Z","caller":"traceutil/trace.go:171","msg":"trace[1284677984] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1176; }","duration":"144.754345ms","start":"2026-09-08T15:50:19.272679Z","end":"2026-09-08T15:50:19.417433Z","steps":["trace[1284677984] 'agreement among raft nodes before linearized reading'  (duration: 144.430326ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:50:19.417665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.7151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-09-08T15:50:19.41775Z","caller":"traceutil/trace.go:171","msg":"trace[1707939649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1176; }","duration":"250.809062ms","start":"2026-09-08T15:50:19.16692Z","end":"2026-09-08T15:50:19.417729Z","steps":["trace[1707939649] 'agreement among raft nodes before linearized reading'  (duration: 250.148774ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:51:10.264241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.312333ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9465898470751909917 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:1804 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-08T15:51:10.264358Z","caller":"traceutil/trace.go:171","msg":"trace[1883374656] linearizableReadLoop","detail":"{readStateIndex:1944; appliedIndex:1943; }","duration":"212.168642ms","start":"2026-09-08T15:51:10.052173Z","end":"2026-09-08T15:51:10.264342Z","steps":["trace[1883374656] 'read index received'  (duration: 59.971µs)","trace[1883374656] 'applied index is now lower than readState.Index'  (duration: 212.106331ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T15:51:10.264429Z","caller":"traceutil/trace.go:171","msg":"trace[1287861201] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"378.15968ms","start":"2026-09-08T15:51:09.886216Z","end":"2026-09-08T15:51:10.264376Z","steps":["trace[1287861201] 'process raft request'  (duration: 153.372294ms)","trace[1287861201] 'compare'  (duration: 223.904333ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T15:51:10.264565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:51:09.88618Z","time spent":"378.322484ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:1804 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-09-08T15:51:10.264659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.49297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-09-08T15:51:10.264703Z","caller":"traceutil/trace.go:171","msg":"trace[2044500864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1871; }","duration":"212.587843ms","start":"2026-09-08T15:51:10.052103Z","end":"2026-09-08T15:51:10.264691Z","steps":["trace[2044500864] 'agreement among raft nodes before linearized reading'  (duration: 212.396088ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:51:10.264927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.954118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-da288b9b-8bde-4f3c-b707-8ae00e4b22ae\" ","response":"range_response_count:1 size:3627"}
{"level":"info","ts":"2026-09-08T15:51:10.265058Z","caller":"traceutil/trace.go:171","msg":"trace[977003296] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-da288b9b-8bde-4f3c-b707-8ae00e4b22ae; range_end:; response_count:1; response_revision:1871; }","duration":"177.161974ms","start":"2026-09-08T15:51:10.087864Z","end":"2026-09-08T15:51:10.265026Z","steps":["trace[977003296] 'agreement among raft nodes before linearized reading'  (duration: 176.720762ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:51:10.422972Z","caller":"traceutil/trace.go:171","msg":"trace[953244950] linearizableReadLoop","detail":"{readStateIndex:1946; appliedIndex:1945; }","duration":"123.307378ms","start":"2026-09-08T15:51:10.299632Z","end":"2026-09-08T15:51:10.42294Z","steps":["trace[953244950] 'read index received'  (duration: 113.757774ms)","trace[953244950] 'applied index is now lower than readState.Index'  (duration: 9.548394ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T15:51:10.423077Z","caller":"traceutil/trace.go:171","msg":"trace[1649165211] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"130.339094ms","start":"2026-09-08T15:51:10.292691Z","end":"2026-09-08T15:51:10.42303Z","steps":["trace[1649165211] 'process raft request'  (duration: 120.725159ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:51:10.423243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.598165ms","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:10.423307Z","caller":"traceutil/trace.go:171","msg":"trace[992008896] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1873; }","duration":"123.735649ms","start":"2026-09-08T15:51:10.29955Z","end":"2026-09-08T15:51:10.423286Z","steps":["trace[992008896] 'agreement among raft nodes before linearized reading'  (duration: 123.519073ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:51:10.64853Z","caller":"traceutil/trace.go:171","msg":"trace[1990134917] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"163.06369ms","start":"2026-09-08T15:51:10.485429Z","end":"2026-09-08T15:51:10.648493Z","steps":["trace[1990134917] 'process raft request'  (duration: 121.391606ms)","trace[1990134917] 'compare'  (duration: 41.09143ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T15:51:24.099818Z","caller":"traceutil/trace.go:171","msg":"trace[1264356632] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"144.811188ms","start":"2026-09-08T15:51:23.954974Z","end":"2026-09-08T15:51:24.099785Z","steps":["trace[1264356632] 'process raft request'  (duration: 144.002596ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:51:56.12278Z","caller":"traceutil/trace.go:171","msg":"trace[1439427990] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"132.09083ms","start":"2026-09-08T15:51:55.99066Z","end":"2026-09-08T15:51:56.122751Z","steps":["trace[1439427990] 'process raft request'  (duration: 131.906085ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:52:01.500022Z","caller":"traceutil/trace.go:171","msg":"trace[1150102445] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"141.497268ms","start":"2026-09-08T15:52:01.358492Z","end":"2026-09-08T15:52:01.49999Z","steps":["trace[1150102445] 'process raft request'  (duration: 141.307803ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:52:51.039322Z","caller":"traceutil/trace.go:171","msg":"trace[817744839] transaction","detail":"{read_only:false; response_revision:2644; number_of_response:1; }","duration":"214.870351ms","start":"2026-09-08T15:52:50.824422Z","end":"2026-09-08T15:52:51.039293Z","steps":["trace[817744839] 'process raft request'  (duration: 214.742027ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:52:51.040084Z","caller":"traceutil/trace.go:171","msg":"trace[1029162857] transaction","detail":"{read_only:false; response_revision:2645; number_of_response:1; }","duration":"213.205127ms","start":"2026-09-08T15:52:50.826858Z","end":"2026-09-08T15:52:51.040063Z","steps":["trace[1029162857] 'process raft request'  (duration: 213.112485ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:53:24.656531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.536755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T15:53:24.656617Z","caller":"traceutil/trace.go:171","msg":"trace[524282095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2847; }","duration":"106.693339ms","start":"2026-09-08T15:53:24.549905Z","end":"2026-09-08T15:53:24.656598Z","steps":["trace[524282095] 'range keys from in-memory index tree'  (duration: 106.421742ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:53:24.829238Z","caller":"traceutil/trace.go:171","msg":"trace[1800598394] transaction","detail":"{read_only:false; response_revision:2848; number_of_response:1; }","duration":"168.222486ms","start":"2026-09-08T15:53:24.660971Z","end":"2026-09-08T15:53:24.829194Z","steps":["trace[1800598394] 'process raft request'  (duration: 167.870127ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:53:25.195423Z","caller":"traceutil/trace.go:171","msg":"trace[1375498024] linearizableReadLoop","detail":"{readStateIndex:2959; appliedIndex:2958; }","duration":"203.377142ms","start":"2026-09-08T15:53:24.992014Z","end":"2026-09-08T15:53:25.195391Z","steps":["trace[1375498024] 'read index received'  (duration: 203.160456ms)","trace[1375498024] 'applied index is now lower than readState.Index'  (duration: 215.686µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T15:53:25.195487Z","caller":"traceutil/trace.go:171","msg":"trace[1415404465] transaction","detail":"{read_only:false; response_revision:2849; number_of_response:1; }","duration":"360.688149ms","start":"2026-09-08T15:53:24.83476Z","end":"2026-09-08T15:53:25.195448Z","steps":["trace[1415404465] 'process raft request'  (duration: 360.38554ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:53:25.195689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.639729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-08T15:53:25.195818Z","caller":"traceutil/trace.go:171","msg":"trace[2025269415] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2849; }","duration":"203.835304ms","start":"2026-09-08T15:53:24.991951Z","end":"2026-09-08T15:53:25.195787Z","steps":["trace[2025269415] 'agreement among raft nodes before linearized reading'  (duration: 203.568067ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:53:25.195839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:53:24.834722Z","time spent":"360.989887ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:2838 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-09-08T15:53:25.204792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.606931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-08T15:53:25.204877Z","caller":"traceutil/trace.go:171","msg":"trace[2056314749] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2850; }","duration":"149.742745ms","start":"2026-09-08T15:53:25.055111Z","end":"2026-09-08T15:53:25.204854Z","steps":["trace[2056314749] 'agreement among raft nodes before linearized reading'  (duration: 149.587631ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:53:25.204836Z","caller":"traceutil/trace.go:171","msg":"trace[528851865] transaction","detail":"{read_only:false; response_revision:2850; number_of_response:1; }","duration":"139.549924ms","start":"2026-09-08T15:53:25.065212Z","end":"2026-09-08T15:53:25.204762Z","steps":["trace[528851865] 'process raft request'  (duration: 139.356238ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:53:25.205182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.110261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"}
{"level":"info","ts":"2026-09-08T15:53:25.205267Z","caller":"traceutil/trace.go:171","msg":"trace[301834196] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2850; }","duration":"145.252695ms","start":"2026-09-08T15:53:25.059996Z","end":"2026-09-08T15:53:25.205249Z","steps":["trace[301834196] 'agreement among raft nodes before linearized reading'  (duration: 144.936726ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:17.123168Z","caller":"traceutil/trace.go:171","msg":"trace[1468845115] transaction","detail":"{read_only:false; response_revision:5142; number_of_response:1; }","duration":"144.965406ms","start":"2026-09-08T15:58:16.978176Z","end":"2026-09-08T15:58:17.123142Z","steps":["trace[1468845115] 'process raft request'  (duration: 144.908684ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:17.123184Z","caller":"traceutil/trace.go:171","msg":"trace[1954607576] transaction","detail":"{read_only:false; response_revision:5141; number_of_response:1; }","duration":"147.109633ms","start":"2026-09-08T15:58:16.976045Z","end":"2026-09-08T15:58:17.123155Z","steps":["trace[1954607576] 'process raft request'  (duration: 60.309829ms)","trace[1954607576] 'compare'  (duration: 86.580388ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T15:58:17.480102Z","caller":"traceutil/trace.go:171","msg":"trace[343586468] linearizableReadLoop","detail":"{readStateIndex:5340; appliedIndex:5339; }","duration":"299.128015ms","start":"2026-09-08T15:58:17.180954Z","end":"2026-09-08T15:58:17.480082Z","steps":["trace[343586468] 'read index received'  (duration: 299.037523ms)","trace[343586468] 'applied index is now lower than readState.Index'  (duration: 89.512µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T15:58:17.48019Z","caller":"traceutil/trace.go:171","msg":"trace[1421764600] transaction","detail":"{read_only:false; response_revision:5143; number_of_response:1; }","duration":"333.319198ms","start":"2026-09-08T15:58:17.146847Z","end":"2026-09-08T15:58:17.480167Z","steps":["trace[1421764600] 'process raft request'  (duration: 333.092322ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:58:17.480301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:58:17.146821Z","time spent":"333.41598ms","remote":"127.0.0.1:58558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7450,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" mod_revision:5133 > success:<request_put:<key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" value_size:7354 >> failure:<request_range:<key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" > >"}
{"level":"warn","ts":"2026-09-08T15:58:17.480307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.338881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-26bc1fb5-98a3-4d70-8ad9-a18eefd8ba5c\" ","response":"range_response_count:1 size:3774"}
{"level":"info","ts":"2026-09-08T15:58:17.480348Z","caller":"traceutil/trace.go:171","msg":"trace[1391641852] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-26bc1fb5-98a3-4d70-8ad9-a18eefd8ba5c; range_end:; response_count:1; response_revision:5143; }","duration":"299.425483ms","start":"2026-09-08T15:58:17.18091Z","end":"2026-09-08T15:58:17.480335Z","steps":["trace[1391641852] 'agreement among raft nodes before linearized reading'  (duration: 299.285429ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:17.53688Z","caller":"traceutil/trace.go:171","msg":"trace[1798679936] transaction","detail":"{read_only:false; response_revision:5144; number_of_response:1; }","duration":"353.762022ms","start":"2026-09-08T15:58:17.183095Z","end":"2026-09-08T15:58:17.536857Z","steps":["trace[1798679936] 'process raft request'  (duration: 353.3009ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:58:17.537017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.451188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16981"}
{"level":"warn","ts":"2026-09-08T15:58:17.537022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:58:17.183081Z","time spent":"353.874256ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:5064 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-09-08T15:58:17.537046Z","caller":"traceutil/trace.go:171","msg":"trace[506802520] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5146; }","duration":"154.483929ms","start":"2026-09-08T15:58:17.382555Z","end":"2026-09-08T15:58:17.537039Z","steps":["trace[506802520] 'agreement among raft nodes before linearized reading'  (duration: 154.384037ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:17.537211Z","caller":"traceutil/trace.go:171","msg":"trace[30526437] transaction","detail":"{read_only:false; response_revision:5145; number_of_response:1; }","duration":"297.355509ms","start":"2026-09-08T15:58:17.239848Z","end":"2026-09-08T15:58:17.537204Z","steps":["trace[30526437] 'process raft request'  (duration: 296.704221ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:58:17.537222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.691764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"info","ts":"2026-09-08T15:58:17.537249Z","caller":"traceutil/trace.go:171","msg":"trace[1530691474] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5146; }","duration":"328.745876ms","start":"2026-09-08T15:58:17.208494Z","end":"2026-09-08T15:58:17.53724Z","steps":["trace[1530691474] 'agreement among raft nodes before linearized reading'  (duration: 328.663964ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:58:17.537273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:58:17.208477Z","time spent":"328.788807ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-09-08T15:58:17.537919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.61337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2210296"}
{"level":"info","ts":"2026-09-08T15:58:17.537938Z","caller":"traceutil/trace.go:171","msg":"trace[67139911] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:5146; }","duration":"116.657561ms","start":"2026-09-08T15:58:17.421274Z","end":"2026-09-08T15:58:17.537932Z","steps":["trace[67139911] 'agreement among raft nodes before linearized reading'  (duration: 116.126097ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:58:17.647827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.385776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T15:58:17.647868Z","caller":"traceutil/trace.go:171","msg":"trace[128253191] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"101.392572ms","start":"2026-09-08T15:58:17.546438Z","end":"2026-09-08T15:58:17.647831Z","steps":["trace[128253191] 'process raft request'  (duration: 101.152527ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:17.647913Z","caller":"traceutil/trace.go:171","msg":"trace[1075031299] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5148; }","duration":"100.50846ms","start":"2026-09-08T15:58:17.547383Z","end":"2026-09-08T15:58:17.647892Z","steps":["trace[1075031299] 'agreement among raft nodes before linearized reading'  (duration: 100.338686ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:58:21.668206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.717208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b97344ce-2a73-499c-ab3b-5895ff6aa005\" ","response":"range_response_count:1 size:3601"}
{"level":"warn","ts":"2026-09-08T15:58:21.668196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.822847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-26bc1fb5-98a3-4d70-8ad9-a18eefd8ba5c\" ","response":"range_response_count:1 size:4079"}
{"level":"info","ts":"2026-09-08T15:58:21.668305Z","caller":"traceutil/trace.go:171","msg":"trace[1391750004] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b97344ce-2a73-499c-ab3b-5895ff6aa005; range_end:; response_count:1; response_revision:5340; }","duration":"129.863472ms","start":"2026-09-08T15:58:21.538422Z","end":"2026-09-08T15:58:21.668285Z","steps":["trace[1391750004] 'range keys from in-memory index tree'  (duration: 129.567304ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:21.66837Z","caller":"traceutil/trace.go:171","msg":"trace[1080430211] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-26bc1fb5-98a3-4d70-8ad9-a18eefd8ba5c; range_end:; response_count:1; response_revision:5340; }","duration":"164.067664ms","start":"2026-09-08T15:58:21.504273Z","end":"2026-09-08T15:58:21.668341Z","steps":["trace[1080430211] 'range keys from in-memory index tree'  (duration: 163.51147ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:58:21.668418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.051044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-8blvw.18d363b3b7623cc3\" ","response":"range_response_count:1 size:962"}
{"level":"info","ts":"2026-09-08T15:58:21.668512Z","caller":"traceutil/trace.go:171","msg":"trace[122583853] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-8blvw.18d363b3b7623cc3; range_end:; response_count:1; response_revision:5340; }","duration":"122.200708ms","start":"2026-09-08T15:58:21.546289Z","end":"2026-09-08T15:58:21.66849Z","steps":["trace[122583853] 'range keys from in-memory index tree'  (duration: 121.791337ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:58:21.668252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.425233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T15:58:21.668581Z","caller":"traceutil/trace.go:171","msg":"trace[667029557] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5340; }","duration":"119.776674ms","start":"2026-09-08T15:58:21.548792Z","end":"2026-09-08T15:58:21.668569Z","steps":["trace[667029557] 'range keys from in-memory index tree'  (duration: 119.342232ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:58:21.668611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.29668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786932"}
{"level":"info","ts":"2026-09-08T15:58:21.668662Z","caller":"traceutil/trace.go:171","msg":"trace[1085816039] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5340; }","duration":"125.385153ms","start":"2026-09-08T15:58:21.543265Z","end":"2026-09-08T15:58:21.66865Z","steps":["trace[1085816039] 'range keys from in-memory index tree'  (duration: 124.689354ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:21.671595Z","caller":"traceutil/trace.go:171","msg":"trace[614638361] linearizableReadLoop","detail":"{readStateIndex:5547; appliedIndex:5546; }","duration":"112.275974ms","start":"2026-09-08T15:58:21.559304Z","end":"2026-09-08T15:58:21.67158Z","steps":["trace[614638361] 'read index received'  (duration: 112.237703ms)","trace[614638361] 'applied index is now lower than readState.Index'  (duration: 37.831µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T15:58:21.671661Z","caller":"traceutil/trace.go:171","msg":"trace[862462708] transaction","detail":"{read_only:false; response_revision:5341; number_of_response:1; }","duration":"122.826825ms","start":"2026-09-08T15:58:21.548825Z","end":"2026-09-08T15:58:21.671652Z","steps":["trace[862462708] 'process raft request'  (duration: 122.672701ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:58:21.671888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.576072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b97344ce-2a73-499c-ab3b-5895ff6aa005\" ","response":"range_response_count:1 size:3601"}
{"level":"info","ts":"2026-09-08T15:58:21.671918Z","caller":"traceutil/trace.go:171","msg":"trace[556796945] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b97344ce-2a73-499c-ab3b-5895ff6aa005; range_end:; response_count:1; response_revision:5341; }","duration":"112.652044ms","start":"2026-09-08T15:58:21.559258Z","end":"2026-09-08T15:58:21.67191Z","steps":["trace[556796945] 'agreement among raft nodes before linearized reading'  (duration: 112.542151ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:23.789857Z","caller":"traceutil/trace.go:171","msg":"trace[309710520] transaction","detail":"{read_only:false; response_revision:5376; number_of_response:1; }","duration":"107.926258ms","start":"2026-09-08T15:58:23.681895Z","end":"2026-09-08T15:58:23.789822Z","steps":["trace[309710520] 'process raft request'  (duration: 107.62568ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:24.115667Z","caller":"traceutil/trace.go:171","msg":"trace[1732661967] linearizableReadLoop","detail":"{readStateIndex:5588; appliedIndex:5586; }","duration":"130.122029ms","start":"2026-09-08T15:58:23.98552Z","end":"2026-09-08T15:58:24.115642Z","steps":["trace[1732661967] 'read index received'  (duration: 1.920961ms)","trace[1732661967] 'applied index is now lower than readState.Index'  (duration: 128.200098ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T15:58:24.115799Z","caller":"traceutil/trace.go:171","msg":"trace[1762538224] transaction","detail":"{read_only:false; response_revision:5380; number_of_response:1; }","duration":"173.875006ms","start":"2026-09-08T15:58:23.941884Z","end":"2026-09-08T15:58:24.115759Z","steps":["trace[1762538224] 'process raft request'  (duration: 91.155331ms)","trace[1762538224] 'compare'  (duration: 82.14563ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T15:58:24.115941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.412457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"}
{"level":"info","ts":"2026-09-08T15:58:24.115975Z","caller":"traceutil/trace.go:171","msg":"trace[376906585] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5380; }","duration":"130.498589ms","start":"2026-09-08T15:58:23.985467Z","end":"2026-09-08T15:58:24.115965Z","steps":["trace[376906585] 'agreement among raft nodes before linearized reading'  (duration: 130.322914ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:35.124872Z","caller":"traceutil/trace.go:171","msg":"trace[2139756094] transaction","detail":"{read_only:false; response_revision:5579; number_of_response:1; }","duration":"114.143846ms","start":"2026-09-08T15:58:35.010707Z","end":"2026-09-08T15:58:35.124851Z","steps":["trace[2139756094] 'process raft request'  (duration: 75.556716ms)","trace[2139756094] 'compare'  (duration: 38.22989ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T15:58:35.913994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.390765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12975"}
{"level":"info","ts":"2026-09-08T15:58:35.914104Z","caller":"traceutil/trace.go:171","msg":"trace[1751971080] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5587; }","duration":"225.531799ms","start":"2026-09-08T15:58:35.688535Z","end":"2026-09-08T15:58:35.914067Z","steps":["trace[1751971080] 'range keys from in-memory index tree'  (duration: 225.211309ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:35.914267Z","caller":"traceutil/trace.go:171","msg":"trace[107170256] transaction","detail":"{read_only:false; response_revision:5588; number_of_response:1; }","duration":"209.444778ms","start":"2026-09-08T15:58:35.704779Z","end":"2026-09-08T15:58:35.914223Z","steps":["trace[107170256] 'process raft request'  (duration: 200.272443ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:35.914342Z","caller":"traceutil/trace.go:171","msg":"trace[1411788484] linearizableReadLoop","detail":"{readStateIndex:5801; appliedIndex:5800; }","duration":"170.11477ms","start":"2026-09-08T15:58:35.744206Z","end":"2026-09-08T15:58:35.91432Z","steps":["trace[1411788484] 'read index received'  (duration: 160.869173ms)","trace[1411788484] 'applied index is now lower than readState.Index'  (duration: 9.243507ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T15:58:35.914539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.334606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"}
{"level":"info","ts":"2026-09-08T15:58:35.914582Z","caller":"traceutil/trace.go:171","msg":"trace[1819025500] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5589; }","duration":"170.380097ms","start":"2026-09-08T15:58:35.744188Z","end":"2026-09-08T15:58:35.914568Z","steps":["trace[1819025500] 'agreement among raft nodes before linearized reading'  (duration: 170.222903ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:44.694273Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2959}
{"level":"warn","ts":"2026-09-08T15:58:44.695101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.36749ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9465898470751919452 username:\"kube-apiserver-etcd-client\" auth_revision:1 > compaction:<revision:2959 > ","response":"size:5"}
{"level":"info","ts":"2026-09-08T15:58:44.695226Z","caller":"traceutil/trace.go:171","msg":"trace[12110487] linearizableReadLoop","detail":"{readStateIndex:5986; appliedIndex:5985; }","duration":"146.437608ms","start":"2026-09-08T15:58:44.54877Z","end":"2026-09-08T15:58:44.695208Z","steps":["trace[12110487] 'read index received'  (duration: 37.921µs)","trace[12110487] 'applied index is now lower than readState.Index'  (duration: 146.398197ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T15:58:44.695309Z","caller":"traceutil/trace.go:171","msg":"trace[174290452] compact","detail":"{revision:2959; response_revision:5770; }","duration":"301.654439ms","start":"2026-09-08T15:58:44.393646Z","end":"2026-09-08T15:58:44.6953Z","steps":["trace[174290452] 'process raft request'  (duration: 97.982045ms)","trace[174290452] 'check and update compact revision'  (duration: 202.200176ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T15:58:44.695344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T15:58:44.393622Z","time spent":"301.7188ms","remote":"127.0.0.1:49198","response type":"/etcdserverpb.KV/Compact","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"warn","ts":"2026-09-08T15:58:44.695462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.685914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T15:58:44.695543Z","caller":"traceutil/trace.go:171","msg":"trace[212540106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5770; }","duration":"146.807436ms","start":"2026-09-08T15:58:44.548712Z","end":"2026-09-08T15:58:44.69552Z","steps":["trace[212540106] 'agreement among raft nodes before linearized reading'  (duration: 146.676503ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:44.788403Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2959,"took":"93.034852ms","hash":1699423395,"current-db-size-bytes":56283136,"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:44.788487Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1699423395,"revision":2959,"compact-revision":-1}
{"level":"info","ts":"2026-09-08T15:58:46.001418Z","caller":"traceutil/trace.go:171","msg":"trace[1350482261] transaction","detail":"{read_only:false; response_revision:5796; number_of_response:1; }","duration":"127.839762ms","start":"2026-09-08T15:58:45.873548Z","end":"2026-09-08T15:58:46.001388Z","steps":["trace[1350482261] 'process raft request'  (duration: 83.657781ms)","trace[1350482261] 'compare'  (duration: 44.000586ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T15:58:46.004116Z","caller":"traceutil/trace.go:171","msg":"trace[212138448] transaction","detail":"{read_only:false; response_revision:5798; number_of_response:1; }","duration":"130.316247ms","start":"2026-09-08T15:58:45.873774Z","end":"2026-09-08T15:58:46.00409Z","steps":["trace[212138448] 'process raft request'  (duration: 130.205274ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:46.004244Z","caller":"traceutil/trace.go:171","msg":"trace[660704172] linearizableReadLoop","detail":"{readStateIndex:6018; appliedIndex:6012; }","duration":"125.806157ms","start":"2026-09-08T15:58:45.878415Z","end":"2026-09-08T15:58:46.004221Z","steps":["trace[660704172] 'read index received'  (duration: 78.803492ms)","trace[660704172] 'applied index is now lower than readState.Index'  (duration: 47.001715ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T15:58:46.004374Z","caller":"traceutil/trace.go:171","msg":"trace[628925867] transaction","detail":"{read_only:false; response_revision:5797; number_of_response:1; }","duration":"130.640357ms","start":"2026-09-08T15:58:45.873717Z","end":"2026-09-08T15:58:46.004357Z","steps":["trace[628925867] 'process raft request'  (duration: 130.152143ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:46.004636Z","caller":"traceutil/trace.go:171","msg":"trace[1996445341] transaction","detail":"{read_only:false; response_revision:5799; number_of_response:1; }","duration":"130.770139ms","start":"2026-09-08T15:58:45.873839Z","end":"2026-09-08T15:58:46.004609Z","steps":["trace[1996445341] 'process raft request'  (duration: 130.171674ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:46.004958Z","caller":"traceutil/trace.go:171","msg":"trace[130889151] transaction","detail":"{read_only:false; response_revision:5800; number_of_response:1; }","duration":"131.112098ms","start":"2026-09-08T15:58:45.873828Z","end":"2026-09-08T15:58:46.00494Z","steps":["trace[130889151] 'process raft request'  (duration: 130.220825ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:46.005134Z","caller":"traceutil/trace.go:171","msg":"trace[1316273394] transaction","detail":"{read_only:false; response_revision:5801; number_of_response:1; }","duration":"130.648965ms","start":"2026-09-08T15:58:45.874473Z","end":"2026-09-08T15:58:46.005122Z","steps":["trace[1316273394] 'process raft request'  (duration: 129.608048ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:58:46.00531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.871604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"}
{"level":"info","ts":"2026-09-08T15:58:46.005364Z","caller":"traceutil/trace.go:171","msg":"trace[788340437] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:5803; }","duration":"126.940686ms","start":"2026-09-08T15:58:45.878409Z","end":"2026-09-08T15:58:46.005349Z","steps":["trace[788340437] 'agreement among raft nodes before linearized reading'  (duration: 126.731061ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:58:46.005395Z","caller":"traceutil/trace.go:171","msg":"trace[775025349] transaction","detail":"{read_only:false; response_revision:5802; number_of_response:1; }","duration":"122.134418ms","start":"2026-09-08T15:58:45.883246Z","end":"2026-09-08T15:58:46.00538Z","steps":["trace[775025349] 'process raft request'  (duration: 120.867455ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:58:46.005673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.404863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-66075e4f-46df-4555-bb33-b4bd2b838363\" ","response":"range_response_count:1 size:3939"}
{"level":"info","ts":"2026-09-08T15:58:46.005744Z","caller":"traceutil/trace.go:171","msg":"trace[787718725] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-66075e4f-46df-4555-bb33-b4bd2b838363; range_end:; response_count:1; response_revision:5803; }","duration":"123.503476ms","start":"2026-09-08T15:58:45.882228Z","end":"2026-09-08T15:58:46.005732Z","steps":["trace[787718725] 'agreement among raft nodes before linearized reading'  (duration: 123.363412ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T15:59:21.57661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.106145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:12 size:382229"}
{"level":"info","ts":"2026-09-08T15:59:21.576749Z","caller":"traceutil/trace.go:171","msg":"trace[963824583] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:12; response_revision:6110; }","duration":"117.28459ms","start":"2026-09-08T15:59:21.459443Z","end":"2026-09-08T15:59:21.576728Z","steps":["trace[963824583] 'range keys from in-memory index tree'  (duration: 116.828857ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:59:35.987627Z","caller":"traceutil/trace.go:171","msg":"trace[155107666] transaction","detail":"{read_only:false; response_revision:6286; number_of_response:1; }","duration":"133.230726ms","start":"2026-09-08T15:59:35.854349Z","end":"2026-09-08T15:59:35.98758Z","steps":["trace[155107666] 'process raft request'  (duration: 84.956858ms)","trace[155107666] 'compare'  (duration: 47.943669ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T15:59:36.139733Z","caller":"traceutil/trace.go:171","msg":"trace[1912305470] transaction","detail":"{read_only:false; response_revision:6288; number_of_response:1; }","duration":"138.761952ms","start":"2026-09-08T15:59:36.000927Z","end":"2026-09-08T15:59:36.139689Z","steps":["trace[1912305470] 'process raft request'  (duration: 117.619048ms)","trace[1912305470] 'compare'  (duration: 20.964219ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T15:59:36.139983Z","caller":"traceutil/trace.go:171","msg":"trace[736475391] transaction","detail":"{read_only:false; response_revision:6289; number_of_response:1; }","duration":"138.968748ms","start":"2026-09-08T15:59:36.001004Z","end":"2026-09-08T15:59:36.139973Z","steps":["trace[736475391] 'process raft request'  (duration: 138.642679ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:59:36.140038Z","caller":"traceutil/trace.go:171","msg":"trace[437198991] transaction","detail":"{read_only:false; response_revision:6290; number_of_response:1; }","duration":"138.811153ms","start":"2026-09-08T15:59:36.001209Z","end":"2026-09-08T15:59:36.14002Z","steps":["trace[437198991] 'process raft request'  (duration: 138.67907ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:59:36.140373Z","caller":"traceutil/trace.go:171","msg":"trace[970570712] transaction","detail":"{read_only:false; response_revision:6291; number_of_response:1; }","duration":"138.107605ms","start":"2026-09-08T15:59:36.002253Z","end":"2026-09-08T15:59:36.14036Z","steps":["trace[970570712] 'process raft request'  (duration: 137.731315ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:59:36.140477Z","caller":"traceutil/trace.go:171","msg":"trace[421304023] transaction","detail":"{read_only:false; response_revision:6292; number_of_response:1; }","duration":"137.52364ms","start":"2026-09-08T15:59:36.002928Z","end":"2026-09-08T15:59:36.140452Z","steps":["trace[421304023] 'process raft request'  (duration: 137.306574ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T15:59:36.140651Z","caller":"traceutil/trace.go:171","msg":"trace[633831963] transaction","detail":"{read_only:false; response_revision:6293; number_of_response:1; }","duration":"133.663636ms","start":"2026-09-08T15:59:36.006963Z","end":"2026-09-08T15:59:36.140627Z","steps":["trace[633831963] 'process raft request'  (duration: 133.362668ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:03:44.703671Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5770}
{"level":"info","ts":"2026-09-08T16:03:44.802938Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5770,"took":"95.817024ms","hash":2374811414,"current-db-size-bytes":56283136,"current-db-size":"56 MB","current-db-size-in-use-bytes":26357760,"current-db-size-in-use":"26 MB"}
{"level":"info","ts":"2026-09-08T16:03:44.803037Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2374811414,"revision":5770,"compact-revision":2959}
{"level":"info","ts":"2026-09-08T16:04:50.146135Z","caller":"traceutil/trace.go:171","msg":"trace[1695878892] transaction","detail":"{read_only:false; response_revision:8612; number_of_response:1; }","duration":"174.950442ms","start":"2026-09-08T16:04:49.971122Z","end":"2026-09-08T16:04:50.146073Z","steps":["trace[1695878892] 'process raft request'  (duration: 174.799718ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:04:50.40431Z","caller":"traceutil/trace.go:171","msg":"trace[1541492227] linearizableReadLoop","detail":"{readStateIndex:8920; appliedIndex:8919; }","duration":"173.913196ms","start":"2026-09-08T16:04:50.230366Z","end":"2026-09-08T16:04:50.404279Z","steps":["trace[1541492227] 'read index received'  (duration: 173.750201ms)","trace[1541492227] 'applied index is now lower than readState.Index'  (duration: 162.115µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:04:50.404369Z","caller":"traceutil/trace.go:171","msg":"trace[1026590658] transaction","detail":"{read_only:false; response_revision:8613; number_of_response:1; }","duration":"229.634611ms","start":"2026-09-08T16:04:50.174712Z","end":"2026-09-08T16:04:50.404347Z","steps":["trace[1026590658] 'process raft request'  (duration: 229.419785ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:04:50.404507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.123851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T16:04:50.404564Z","caller":"traceutil/trace.go:171","msg":"trace[1704466930] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:8613; }","duration":"174.254475ms","start":"2026-09-08T16:04:50.230293Z","end":"2026-09-08T16:04:50.404548Z","steps":["trace[1704466930] 'agreement among raft nodes before linearized reading'  (duration: 174.127872ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:04:50.405353Z","caller":"traceutil/trace.go:171","msg":"trace[1150802464] transaction","detail":"{read_only:false; response_revision:8614; number_of_response:1; }","duration":"110.559518ms","start":"2026-09-08T16:04:50.294756Z","end":"2026-09-08T16:04:50.405316Z","steps":["trace[1150802464] 'process raft request'  (duration: 110.319171ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:04:50.40546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.279709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"}
{"level":"info","ts":"2026-09-08T16:04:50.405591Z","caller":"traceutil/trace.go:171","msg":"trace[2079069284] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8614; }","duration":"143.439894ms","start":"2026-09-08T16:04:50.262098Z","end":"2026-09-08T16:04:50.405538Z","steps":["trace[2079069284] 'agreement among raft nodes before linearized reading'  (duration: 143.131245ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:05:02.89844Z","caller":"traceutil/trace.go:171","msg":"trace[1740120055] linearizableReadLoop","detail":"{readStateIndex:8987; appliedIndex:8986; }","duration":"165.972592ms","start":"2026-09-08T16:05:02.732441Z","end":"2026-09-08T16:05:02.898414Z","steps":["trace[1740120055] 'read index received'  (duration: 79.277282ms)","trace[1740120055] 'applied index is now lower than readState.Index'  (duration: 86.69394ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:05:02.898454Z","caller":"traceutil/trace.go:171","msg":"trace[854371526] transaction","detail":"{read_only:false; response_revision:8677; number_of_response:1; }","duration":"388.315227ms","start":"2026-09-08T16:05:02.510102Z","end":"2026-09-08T16:05:02.898418Z","steps":["trace[854371526] 'process raft request'  (duration: 301.646578ms)","trace[854371526] 'compare'  (duration: 86.506854ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T16:05:02.898603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:02.510072Z","time spent":"388.442969ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:8668 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:464 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-08T16:05:02.898654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.213018ms","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:05:02.89869Z","caller":"traceutil/trace.go:171","msg":"trace[1967776718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8677; }","duration":"166.324621ms","start":"2026-09-08T16:05:02.732355Z","end":"2026-09-08T16:05:02.89868Z","steps":["trace[1967776718] 'agreement among raft nodes before linearized reading'  (duration: 166.184957ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:05:33.488801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.794599ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9465898470751927959 > lease_revoke:<id:035da081b514fcee>","response":"size:28"}
{"level":"info","ts":"2026-09-08T16:05:33.488946Z","caller":"traceutil/trace.go:171","msg":"trace[2077899975] linearizableReadLoop","detail":"{readStateIndex:9210; appliedIndex:9209; }","duration":"428.806314ms","start":"2026-09-08T16:05:33.060128Z","end":"2026-09-08T16:05:33.488934Z","steps":["trace[2077899975] 'read index received'  (duration: 66.877401ms)","trace[2077899975] 'applied index is now lower than readState.Index'  (duration: 361.928363ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T16:05:33.48906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.927597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"}
{"level":"info","ts":"2026-09-08T16:05:33.489081Z","caller":"traceutil/trace.go:171","msg":"trace[182593806] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8891; }","duration":"428.987909ms","start":"2026-09-08T16:05:33.060086Z","end":"2026-09-08T16:05:33.489074Z","steps":["trace[182593806] 'agreement among raft nodes before linearized reading'  (duration: 428.910847ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:05:33.489098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:33.060067Z","time spent":"429.027231ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"info","ts":"2026-09-08T16:05:33.489263Z","caller":"traceutil/trace.go:171","msg":"trace[1443896493] transaction","detail":"{read_only:false; response_revision:8892; number_of_response:1; }","duration":"348.844375ms","start":"2026-09-08T16:05:33.140402Z","end":"2026-09-08T16:05:33.489247Z","steps":["trace[1443896493] 'process raft request'  (duration: 348.476485ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:05:33.489344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:33.140366Z","time spent":"348.928817ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" mod_revision:8807 > success:<request_put:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" value_size:415 >> failure:<request_range:<key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" > >"}
{"level":"warn","ts":"2026-09-08T16:05:33.675218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.23899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z4krk\" ","response":"range_response_count:1 size:6530"}
{"level":"info","ts":"2026-09-08T16:05:33.675323Z","caller":"traceutil/trace.go:171","msg":"trace[1883273519] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z4krk; range_end:; response_count:1; response_revision:8892; }","duration":"473.423364ms","start":"2026-09-08T16:05:33.201874Z","end":"2026-09-08T16:05:33.675297Z","steps":["trace[1883273519] 'agreement among raft nodes before linearized reading'  (duration: 287.375827ms)","trace[1883273519] 'range keys from in-memory index tree'  (duration: 185.76366ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T16:05:33.675344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.280619ms","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-08T16:05:33.675369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:33.201845Z","time spent":"473.513137ms","remote":"127.0.0.1:45248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-z4krk\" "}
{"level":"info","ts":"2026-09-08T16:05:33.675347Z","caller":"traceutil/trace.go:171","msg":"trace[1700867988] linearizableReadLoop","detail":"{readStateIndex:9212; appliedIndex:9211; }","duration":"127.255981ms","start":"2026-09-08T16:05:33.548068Z","end":"2026-09-08T16:05:33.675324Z","steps":["trace[1700867988] 'read index received'  (duration: 88.242002ms)","trace[1700867988] 'applied index is now lower than readState.Index'  (duration: 39.013069ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T16:05:33.675349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.977254ms","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-08T16:05:33.675405Z","caller":"traceutil/trace.go:171","msg":"trace[506308722] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8892; }","duration":"539.373732ms","start":"2026-09-08T16:05:33.136012Z","end":"2026-09-08T16:05:33.675385Z","steps":["trace[506308722] 'agreement among raft nodes before linearized reading'  (duration: 353.247932ms)","trace[506308722] 'range keys from in-memory index tree'  (duration: 185.988326ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T16:05:33.67544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:33.135991Z","time spent":"539.441374ms","remote":"127.0.0.1:45332","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-08T16:05:33.675452Z","caller":"traceutil/trace.go:171","msg":"trace[130151205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8892; }","duration":"583.121718ms","start":"2026-09-08T16:05:33.092302Z","end":"2026-09-08T16:05:33.675424Z","steps":["trace[130151205] 'agreement among raft nodes before linearized reading'  (duration: 396.926576ms)","trace[130151205] 'range keys from in-memory index tree'  (duration: 185.873813ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:05:33.675482Z","caller":"traceutil/trace.go:171","msg":"trace[1424038229] transaction","detail":"{read_only:false; response_revision:8893; number_of_response:1; }","duration":"182.681228ms","start":"2026-09-08T16:05:33.492787Z","end":"2026-09-08T16:05:33.675468Z","steps":["trace[1424038229] 'process raft request'  (duration: 143.573326ms)","trace[1424038229] 'compare'  (duration: 38.515406ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T16:05:33.675509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:05:33.092283Z","time spent":"583.2035ms","remote":"127.0.0.1:45332","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-08T16:05:33.675657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.587081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T16:05:33.675687Z","caller":"traceutil/trace.go:171","msg":"trace[870466494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8893; }","duration":"127.670853ms","start":"2026-09-08T16:05:33.548006Z","end":"2026-09-08T16:05:33.675677Z","steps":["trace[870466494] 'agreement among raft nodes before linearized reading'  (duration: 127.606541ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:05:33.675742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.282662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"}
{"level":"warn","ts":"2026-09-08T16:05:33.675788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.554573ms","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:05:33.675809Z","caller":"traceutil/trace.go:171","msg":"trace[341301174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8893; }","duration":"121.386825ms","start":"2026-09-08T16:05:33.554408Z","end":"2026-09-08T16:05:33.675795Z","steps":["trace[341301174] 'agreement among raft nodes before linearized reading'  (duration: 121.241921ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:05:33.675849Z","caller":"traceutil/trace.go:171","msg":"trace[1458781436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8893; }","duration":"123.671026ms","start":"2026-09-08T16:05:33.552162Z","end":"2026-09-08T16:05:33.675833Z","steps":["trace[1458781436] 'agreement among raft nodes before linearized reading'  (duration: 123.44588ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:05:33.822531Z","caller":"traceutil/trace.go:171","msg":"trace[1824693638] transaction","detail":"{read_only:false; response_revision:8896; number_of_response:1; }","duration":"142.049414ms","start":"2026-09-08T16:05:33.680453Z","end":"2026-09-08T16:05:33.822503Z","steps":["trace[1824693638] 'process raft request'  (duration: 98.819233ms)","trace[1824693638] 'compare'  (duration: 43.056507ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:05:33.822733Z","caller":"traceutil/trace.go:171","msg":"trace[1241271646] transaction","detail":"{read_only:false; response_revision:8897; number_of_response:1; }","duration":"142.069145ms","start":"2026-09-08T16:05:33.680638Z","end":"2026-09-08T16:05:33.822707Z","steps":["trace[1241271646] 'process raft request'  (duration: 141.823648ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:05:33.822962Z","caller":"traceutil/trace.go:171","msg":"trace[251294829] transaction","detail":"{read_only:false; response_revision:8898; number_of_response:1; }","duration":"140.618397ms","start":"2026-09-08T16:05:33.682333Z","end":"2026-09-08T16:05:33.822951Z","steps":["trace[251294829] 'process raft request'  (duration: 140.295139ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:05:33.822986Z","caller":"traceutil/trace.go:171","msg":"trace[1395841832] linearizableReadLoop","detail":"{readStateIndex:9215; appliedIndex:9212; }","duration":"142.406254ms","start":"2026-09-08T16:05:33.680541Z","end":"2026-09-08T16:05:33.822947Z","steps":["trace[1395841832] 'read index received'  (duration: 84.744988ms)","trace[1395841832] 'applied index is now lower than readState.Index'  (duration: 57.659286ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T16:05:33.823469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.905418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228548"}
{"level":"info","ts":"2026-09-08T16:05:33.823551Z","caller":"traceutil/trace.go:171","msg":"trace[1439111065] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8898; }","duration":"143.023751ms","start":"2026-09-08T16:05:33.680509Z","end":"2026-09-08T16:05:33.823532Z","steps":["trace[1439111065] 'agreement among raft nodes before linearized reading'  (duration: 142.536468ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:05:47.052243Z","caller":"traceutil/trace.go:171","msg":"trace[2057917956] transaction","detail":"{read_only:false; response_revision:9007; number_of_response:1; }","duration":"130.529978ms","start":"2026-09-08T16:05:46.921676Z","end":"2026-09-08T16:05:47.052206Z","steps":["trace[2057917956] 'process raft request'  (duration: 65.368332ms)","trace[2057917956] 'compare'  (duration: 65.007602ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T16:05:48.08289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.958262ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9465898470751928317 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/masterleases/162.253.55.124\" mod_revision:8917 > success:<request_put:<key:\"/registry/masterleases/162.253.55.124\" value_size:67 lease:242526433897152504 >> failure:<request_range:<key:\"/registry/masterleases/162.253.55.124\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-08T16:05:48.08321Z","caller":"traceutil/trace.go:171","msg":"trace[1259413412] transaction","detail":"{read_only:false; response_revision:9016; number_of_response:1; }","duration":"130.515757ms","start":"2026-09-08T16:05:47.952674Z","end":"2026-09-08T16:05:48.08319Z","steps":["trace[1259413412] 'process raft request'  (duration: 130.459226ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:05:48.083468Z","caller":"traceutil/trace.go:171","msg":"trace[432218528] transaction","detail":"{read_only:false; response_revision:9014; number_of_response:1; }","duration":"186.700344ms","start":"2026-09-08T16:05:47.896753Z","end":"2026-09-08T16:05:48.083453Z","steps":["trace[432218528] 'process raft request'  (duration: 65.046413ms)","trace[432218528] 'compare'  (duration: 120.799249ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:05:48.08379Z","caller":"traceutil/trace.go:171","msg":"trace[1863353076] transaction","detail":"{read_only:false; response_revision:9015; number_of_response:1; }","duration":"185.099172ms","start":"2026-09-08T16:05:47.898656Z","end":"2026-09-08T16:05:48.083755Z","steps":["trace[1863353076] 'process raft request'  (duration: 184.348482ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:05:48.083946Z","caller":"traceutil/trace.go:171","msg":"trace[1214306671] linearizableReadLoop","detail":"{readStateIndex:9339; appliedIndex:9337; }","duration":"137.832693ms","start":"2026-09-08T16:05:47.946099Z","end":"2026-09-08T16:05:48.083931Z","steps":["trace[1214306671] 'read index received'  (duration: 15.701749ms)","trace[1214306671] 'applied index is now lower than readState.Index'  (duration: 122.129264ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T16:05:48.084232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.290251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-09-08T16:05:48.084273Z","caller":"traceutil/trace.go:171","msg":"trace[58394631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9016; }","duration":"136.381464ms","start":"2026-09-08T16:05:47.947881Z","end":"2026-09-08T16:05:48.084263Z","steps":["trace[58394631] 'agreement among raft nodes before linearized reading'  (duration: 136.253581ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:05:48.084356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.785714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"}
{"level":"warn","ts":"2026-09-08T16:05:48.084406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.359527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/openstack/ceph\" ","response":"range_response_count:1 size:3672"}
{"level":"info","ts":"2026-09-08T16:05:48.084421Z","caller":"traceutil/trace.go:171","msg":"trace[1315529750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9016; }","duration":"175.911537ms","start":"2026-09-08T16:05:47.908497Z","end":"2026-09-08T16:05:48.084408Z","steps":["trace[1315529750] 'agreement among raft nodes before linearized reading'  (duration: 175.758163ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:05:48.084499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.812406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-z4krk\" ","response":"range_response_count:1 size:6530"}
{"level":"info","ts":"2026-09-08T16:05:48.08443Z","caller":"traceutil/trace.go:171","msg":"trace[1947180741] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/openstack/ceph; range_end:; response_count:1; response_revision:9016; }","duration":"111.419768ms","start":"2026-09-08T16:05:47.973002Z","end":"2026-09-08T16:05:48.084422Z","steps":["trace[1947180741] 'agreement among raft nodes before linearized reading'  (duration: 111.344436ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:05:48.084527Z","caller":"traceutil/trace.go:171","msg":"trace[1469805992] range","detail":"{range_begin:/registry/pods/openstack/keepalived-z4krk; range_end:; response_count:1; response_revision:9016; }","duration":"124.865618ms","start":"2026-09-08T16:05:47.959655Z","end":"2026-09-08T16:05:48.08452Z","steps":["trace[1469805992] 'agreement among raft nodes before linearized reading'  (duration: 124.792756ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:06:01.092222Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024f6.wal"}
{"level":"info","ts":"2026-09-08T16:06:50.165047Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9ad1df4dc3f3835d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-08T16:06:50.172333Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001}
{"level":"info","ts":"2026-09-08T16:06:50.172495Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001}
{"level":"warn","ts":"2026-09-08T16:07:07.448179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.03547ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9465898470751930463 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:9762 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >>","response":"size:16"}
{"level":"info","ts":"2026-09-08T16:07:07.448336Z","caller":"traceutil/trace.go:171","msg":"trace[876610927] linearizableReadLoop","detail":"{readStateIndex:10116; appliedIndex:10114; }","duration":"413.877232ms","start":"2026-09-08T16:07:07.034436Z","end":"2026-09-08T16:07:07.448313Z","steps":["trace[876610927] 'read index received'  (duration: 67.726424ms)","trace[876610927] 'applied index is now lower than readState.Index'  (duration: 346.149048ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:07:07.448707Z","caller":"traceutil/trace.go:171","msg":"trace[405047298] transaction","detail":"{read_only:false; response_revision:9772; number_of_response:1; }","duration":"431.995175ms","start":"2026-09-08T16:07:07.016672Z","end":"2026-09-08T16:07:07.448667Z","steps":["trace[405047298] 'process raft request'  (duration: 250.348618ms)","trace[405047298] 'compare'  (duration: 180.558938ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T16:07:07.448802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.324414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"info","ts":"2026-09-08T16:07:07.448874Z","caller":"traceutil/trace.go:171","msg":"trace[1272037299] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9772; }","duration":"414.459917ms","start":"2026-09-08T16:07:07.034398Z","end":"2026-09-08T16:07:07.448858Z","steps":["trace[1272037299] 'agreement among raft nodes before linearized reading'  (duration: 413.976224ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:07:07.448954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:07:07.016653Z","time spent":"432.150009ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:9762 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-08T16:07:07.449052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:07:07.034381Z","time spent":"414.579441ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"info","ts":"2026-09-08T16:07:07.801141Z","caller":"traceutil/trace.go:171","msg":"trace[456766221] linearizableReadLoop","detail":"{readStateIndex:10117; appliedIndex:10116; }","duration":"253.801219ms","start":"2026-09-08T16:07:07.547314Z","end":"2026-09-08T16:07:07.801116Z","steps":["trace[456766221] 'read index received'  (duration: 253.718337ms)","trace[456766221] 'applied index is now lower than readState.Index'  (duration: 81.512µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:07:07.801176Z","caller":"traceutil/trace.go:171","msg":"trace[1600499920] transaction","detail":"{read_only:false; response_revision:9773; number_of_response:1; }","duration":"347.540386ms","start":"2026-09-08T16:07:07.453608Z","end":"2026-09-08T16:07:07.801148Z","steps":["trace[1600499920] 'process raft request'  (duration: 347.33399ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:07:07.80127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.934083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-09-08T16:07:07.801311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:07:07.453559Z","time spent":"347.68301ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:9763 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:409 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-09-08T16:07:07.801347Z","caller":"traceutil/trace.go:171","msg":"trace[1402339712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9773; }","duration":"254.013455ms","start":"2026-09-08T16:07:07.547308Z","end":"2026-09-08T16:07:07.801322Z","steps":["trace[1402339712] 'agreement among raft nodes before linearized reading'  (duration: 253.893081ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:07:07.812009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.689385ms","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":"info","ts":"2026-09-08T16:07:07.812114Z","caller":"traceutil/trace.go:171","msg":"trace[1600760940] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:9774; }","duration":"262.807008ms","start":"2026-09-08T16:07:07.54928Z","end":"2026-09-08T16:07:07.812087Z","steps":["trace[1600760940] 'agreement among raft nodes before linearized reading'  (duration: 262.637434ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:07:07.81204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.581858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-08T16:07:07.812211Z","caller":"traceutil/trace.go:171","msg":"trace[604990180] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:9774; }","duration":"100.785484ms","start":"2026-09-08T16:07:07.711401Z","end":"2026-09-08T16:07:07.812186Z","steps":["trace[604990180] 'agreement among raft nodes before linearized reading'  (duration: 100.586959ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:08:44.712614Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8035}
{"level":"info","ts":"2026-09-08T16:08:44.803402Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8035,"took":"88.757633ms","hash":1118527150,"current-db-size-bytes":56283136,"current-db-size":"56 MB","current-db-size-in-use-bytes":25624576,"current-db-size-in-use":"26 MB"}
{"level":"info","ts":"2026-09-08T16:08:44.803486Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1118527150,"revision":8035,"compact-revision":5770}
{"level":"info","ts":"2026-09-08T16:09:32.949023Z","caller":"traceutil/trace.go:171","msg":"trace[1379865094] transaction","detail":"{read_only:false; response_revision:10850; number_of_response:1; }","duration":"123.059777ms","start":"2026-09-08T16:09:32.825923Z","end":"2026-09-08T16:09:32.948982Z","steps":["trace[1379865094] 'process raft request'  (duration: 76.155358ms)","trace[1379865094] 'compare'  (duration: 46.752475ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:09:32.951682Z","caller":"traceutil/trace.go:171","msg":"trace[1629606657] transaction","detail":"{read_only:false; response_revision:10851; number_of_response:1; }","duration":"125.688137ms","start":"2026-09-08T16:09:32.825971Z","end":"2026-09-08T16:09:32.951659Z","steps":["trace[1629606657] 'process raft request'  (duration: 125.516804ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:09:32.951919Z","caller":"traceutil/trace.go:171","msg":"trace[753216168] transaction","detail":"{read_only:false; response_revision:10852; number_of_response:1; }","duration":"117.805567ms","start":"2026-09-08T16:09:32.834075Z","end":"2026-09-08T16:09:32.951881Z","steps":["trace[753216168] 'process raft request'  (duration: 117.52887ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:13:44.720772Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10454}
{"level":"info","ts":"2026-09-08T16:13:44.813242Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10454,"took":"90.515128ms","hash":2192434679,"current-db-size-bytes":56283136,"current-db-size":"56 MB","current-db-size-in-use-bytes":27754496,"current-db-size-in-use":"28 MB"}
{"level":"info","ts":"2026-09-08T16:13:44.813316Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2192434679,"revision":10454,"compact-revision":8035}
{"level":"info","ts":"2026-09-08T16:17:09.849006Z","caller":"traceutil/trace.go:171","msg":"trace[206755144] linearizableReadLoop","detail":"{readStateIndex:14647; appliedIndex:14646; }","duration":"221.36346ms","start":"2026-09-08T16:17:09.627616Z","end":"2026-09-08T16:17:09.848979Z","steps":["trace[206755144] 'read index received'  (duration: 221.144415ms)","trace[206755144] 'applied index is now lower than readState.Index'  (duration: 218.105µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:17:09.849097Z","caller":"traceutil/trace.go:171","msg":"trace[1275115461] transaction","detail":"{read_only:false; response_revision:14159; number_of_response:1; }","duration":"254.518333ms","start":"2026-09-08T16:17:09.594545Z","end":"2026-09-08T16:17:09.849064Z","steps":["trace[1275115461] 'process raft request'  (duration: 254.290917ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:17:09.849181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.557946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-08T16:17:09.849214Z","caller":"traceutil/trace.go:171","msg":"trace[1610498033] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:14159; }","duration":"221.645939ms","start":"2026-09-08T16:17:09.627559Z","end":"2026-09-08T16:17:09.849205Z","steps":["trace[1610498033] 'agreement among raft nodes before linearized reading'  (duration: 221.560026ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:17:09.849379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.960869ms","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:09.84945Z","caller":"traceutil/trace.go:171","msg":"trace[29243150] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14159; }","duration":"180.087023ms","start":"2026-09-08T16:17:09.669343Z","end":"2026-09-08T16:17:09.84943Z","steps":["trace[29243150] 'agreement among raft nodes before linearized reading'  (duration: 179.831916ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:17:10.035407Z","caller":"traceutil/trace.go:171","msg":"trace[1879159194] linearizableReadLoop","detail":"{readStateIndex:14648; appliedIndex:14647; }","duration":"180.914025ms","start":"2026-09-08T16:17:09.854469Z","end":"2026-09-08T16:17:10.035383Z","steps":["trace[1879159194] 'read index received'  (duration: 180.663969ms)","trace[1879159194] 'applied index is now lower than readState.Index'  (duration: 249.225µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:17:10.03551Z","caller":"traceutil/trace.go:171","msg":"trace[1519603378] transaction","detail":"{read_only:false; response_revision:14160; number_of_response:1; }","duration":"182.027905ms","start":"2026-09-08T16:17:09.853472Z","end":"2026-09-08T16:17:10.0355Z","steps":["trace[1519603378] 'process raft request'  (duration: 181.730168ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:17:10.035756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.261584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-09-08T16:17:10.035811Z","caller":"traceutil/trace.go:171","msg":"trace[281661821] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14160; }","duration":"181.376357ms","start":"2026-09-08T16:17:09.854424Z","end":"2026-09-08T16:17:10.0358Z","steps":["trace[281661821] 'agreement among raft nodes before linearized reading'  (duration: 181.231913ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:17:10.036042Z","caller":"traceutil/trace.go:171","msg":"trace[925952483] transaction","detail":"{read_only:false; response_revision:14161; number_of_response:1; }","duration":"120.24282ms","start":"2026-09-08T16:17:09.915786Z","end":"2026-09-08T16:17:10.036028Z","steps":["trace[925952483] 'process raft request'  (duration: 120.075105ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:17:10.036128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.178765ms","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:17:10.036194Z","caller":"traceutil/trace.go:171","msg":"trace[290577273] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14161; }","duration":"174.285528ms","start":"2026-09-08T16:17:09.86189Z","end":"2026-09-08T16:17:10.036175Z","steps":["trace[290577273] 'agreement among raft nodes before linearized reading'  (duration: 174.086062ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:17:10.442093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.554202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"warn","ts":"2026-09-08T16:17:10.442091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-09-08T16:17:10.442191Z","caller":"traceutil/trace.go:171","msg":"trace[1658677699] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:14165; }","duration":"249.671565ms","start":"2026-09-08T16:17:10.192483Z","end":"2026-09-08T16:17:10.442154Z","steps":["trace[1658677699] 'count revisions from in-memory index tree'  (duration: 249.456269ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:17:10.442198Z","caller":"traceutil/trace.go:171","msg":"trace[1230711273] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:14165; }","duration":"112.873384ms","start":"2026-09-08T16:17:10.329304Z","end":"2026-09-08T16:17:10.442178Z","steps":["trace[1230711273] 'count revisions from in-memory index tree'  (duration: 112.614968ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:17:10.44219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.222138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"}
{"level":"info","ts":"2026-09-08T16:17:10.442344Z","caller":"traceutil/trace.go:171","msg":"trace[1266018290] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14165; }","duration":"144.403433ms","start":"2026-09-08T16:17:10.297893Z","end":"2026-09-08T16:17:10.442296Z","steps":["trace[1266018290] 'range keys from in-memory index tree'  (duration: 143.975001ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:17:28.505427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9465898470751943042,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-09-08T16:17:28.629183Z","caller":"traceutil/trace.go:171","msg":"trace[405811357] transaction","detail":"{read_only:false; response_revision:14273; number_of_response:1; }","duration":"624.732486ms","start":"2026-09-08T16:17:28.004419Z","end":"2026-09-08T16:17:28.629151Z","steps":["trace[405811357] 'process raft request'  (duration: 624.574851ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:17:28.629342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:28.004404Z","time spent":"624.852818ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/cert-manager/cert-manager-controller\" mod_revision:14179 > success:<request_put:<key:\"/registry/leases/cert-manager/cert-manager-controller\" value_size:454 >> failure:<request_range:<key:\"/registry/leases/cert-manager/cert-manager-controller\" > >"}
{"level":"info","ts":"2026-09-08T16:17:28.915323Z","caller":"traceutil/trace.go:171","msg":"trace[79478277] transaction","detail":"{read_only:false; response_revision:14274; number_of_response:1; }","duration":"896.501378ms","start":"2026-09-08T16:17:28.018797Z","end":"2026-09-08T16:17:28.915298Z","steps":["trace[79478277] 'process raft request'  (duration: 896.264021ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:17:28.915478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:28.01876Z","time spent":"896.640311ms","remote":"127.0.0.1:45248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12713,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/pods/openstack/openvswitch-4s8gz\" mod_revision:14228 > success:<request_put:<key:\"/registry/pods/openstack/openvswitch-4s8gz\" value_size:12663 >> failure:<request_range:<key:\"/registry/pods/openstack/openvswitch-4s8gz\" > >"}
{"level":"info","ts":"2026-09-08T16:17:28.915534Z","caller":"traceutil/trace.go:171","msg":"trace[1532553282] linearizableReadLoop","detail":"{readStateIndex:14768; appliedIndex:14766; }","duration":"910.193722ms","start":"2026-09-08T16:17:28.005317Z","end":"2026-09-08T16:17:28.915511Z","steps":["trace[1532553282] 'read index received'  (duration: 623.899443ms)","trace[1532553282] 'applied index is now lower than readState.Index'  (duration: 286.292619ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:17:28.915606Z","caller":"traceutil/trace.go:171","msg":"trace[1835125626] transaction","detail":"{read_only:false; response_revision:14275; number_of_response:1; }","duration":"810.211241ms","start":"2026-09-08T16:17:28.105362Z","end":"2026-09-08T16:17:28.915573Z","steps":["trace[1835125626] 'process raft request'  (duration: 809.877052ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:17:28.915777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:28.105335Z","time spent":"810.335154ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:14259 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:477 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-08T16:17:28.915917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.580701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"}
{"level":"info","ts":"2026-09-08T16:17:28.916Z","caller":"traceutil/trace.go:171","msg":"trace[708403306] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:14275; }","duration":"910.737976ms","start":"2026-09-08T16:17:28.005241Z","end":"2026-09-08T16:17:28.915979Z","steps":["trace[708403306] 'agreement among raft nodes before linearized reading'  (duration: 910.406077ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:17:28.916046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:28.005214Z","time spent":"910.821668ms","remote":"127.0.0.1:45344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "}
{"level":"warn","ts":"2026-09-08T16:17:28.916153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.630989ms","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-08T16:17:28.916218Z","caller":"traceutil/trace.go:171","msg":"trace[1632439911] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14276; }","duration":"802.742102ms","start":"2026-09-08T16:17:28.113458Z","end":"2026-09-08T16:17:28.9162Z","steps":["trace[1632439911] 'agreement among raft nodes before linearized reading'  (duration: 802.581558ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:17:28.916223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.849157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"}
{"level":"warn","ts":"2026-09-08T16:17:28.916258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:28.113438Z","time spent":"802.808014ms","remote":"127.0.0.1:45332","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":"info","ts":"2026-09-08T16:17:28.916276Z","caller":"traceutil/trace.go:171","msg":"trace[1124259766] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14276; }","duration":"763.923849ms","start":"2026-09-08T16:17:28.152336Z","end":"2026-09-08T16:17:28.91626Z","steps":["trace[1124259766] 'agreement among raft nodes before linearized reading'  (duration: 763.757624ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:17:28.91631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:28.152325Z","time spent":"763.978081ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "}
{"level":"warn","ts":"2026-09-08T16:17:28.916461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.797696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"warn","ts":"2026-09-08T16:17:28.916485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.262109ms","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-08T16:17:28.916495Z","caller":"traceutil/trace.go:171","msg":"trace[1801751757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14276; }","duration":"778.860167ms","start":"2026-09-08T16:17:28.137625Z","end":"2026-09-08T16:17:28.916485Z","steps":["trace[1801751757] 'agreement among raft nodes before linearized reading'  (duration: 778.755334ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:17:28.916516Z","caller":"traceutil/trace.go:171","msg":"trace[2105692531] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14276; }","duration":"799.324401ms","start":"2026-09-08T16:17:28.117182Z","end":"2026-09-08T16:17:28.916507Z","steps":["trace[2105692531] 'agreement among raft nodes before linearized reading'  (duration: 799.204288ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:17:28.916524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:28.137605Z","time spent":"778.912679ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-09-08T16:17:28.916519Z","caller":"traceutil/trace.go:171","msg":"trace[1426612817] transaction","detail":"{read_only:false; response_revision:14276; number_of_response:1; }","duration":"123.496346ms","start":"2026-09-08T16:17:28.793009Z","end":"2026-09-08T16:17:28.916505Z","steps":["trace[1426612817] 'process raft request'  (duration: 122.948941ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:17:28.916539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:28.117162Z","time spent":"799.371492ms","remote":"127.0.0.1:45332","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-08T16:17:28.916644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.219216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-09-08T16:17:28.916659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.282625ms","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-08T16:17:28.916674Z","caller":"traceutil/trace.go:171","msg":"trace[506306818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14276; }","duration":"369.283857ms","start":"2026-09-08T16:17:28.54738Z","end":"2026-09-08T16:17:28.916664Z","steps":["trace[506306818] 'agreement among raft nodes before linearized reading'  (duration: 369.239306ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:17:28.916708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:28.54736Z","time spent":"369.342298ms","remote":"127.0.0.1:49184","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-08T16:17:28.916719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.239348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-09-08T16:17:28.916716Z","caller":"traceutil/trace.go:171","msg":"trace[1290543535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14276; }","duration":"768.339496ms","start":"2026-09-08T16:17:28.14836Z","end":"2026-09-08T16:17:28.916699Z","steps":["trace[1290543535] 'agreement among raft nodes before linearized reading'  (duration: 768.185042ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:17:28.91682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:28.148311Z","time spent":"768.49467ms","remote":"127.0.0.1:45332","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":"info","ts":"2026-09-08T16:17:28.916775Z","caller":"traceutil/trace.go:171","msg":"trace[978264249] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:14276; }","duration":"773.32158ms","start":"2026-09-08T16:17:28.143442Z","end":"2026-09-08T16:17:28.916763Z","steps":["trace[978264249] 'agreement among raft nodes before linearized reading'  (duration: 773.243498ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:17:28.91696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:17:28.143431Z","time spent":"773.511885ms","remote":"127.0.0.1:55264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "}
{"level":"info","ts":"2026-09-08T16:18:44.727714Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12776}
{"level":"info","ts":"2026-09-08T16:18:44.812841Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12776,"took":"82.544436ms","hash":1583444052,"current-db-size-bytes":56283136,"current-db-size":"56 MB","current-db-size-in-use-bytes":30707712,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-09-08T16:18:44.812911Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1583444052,"revision":12776,"compact-revision":10454}
{"level":"info","ts":"2026-09-08T16:19:01.545535Z","caller":"traceutil/trace.go:171","msg":"trace[490248857] transaction","detail":"{read_only:false; response_revision:15312; number_of_response:1; }","duration":"111.689072ms","start":"2026-09-08T16:19:01.433819Z","end":"2026-09-08T16:19:01.545508Z","steps":["trace[490248857] 'process raft request'  (duration: 87.340084ms)","trace[490248857] 'compare'  (duration: 23.930647ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:19:01.545916Z","caller":"traceutil/trace.go:171","msg":"trace[1055023871] transaction","detail":"{read_only:false; response_revision:15313; number_of_response:1; }","duration":"111.519218ms","start":"2026-09-08T16:19:01.434383Z","end":"2026-09-08T16:19:01.545902Z","steps":["trace[1055023871] 'process raft request'  (duration: 110.836209ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:23:44.737646Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15223}
{"level":"info","ts":"2026-09-08T16:23:44.85Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15223,"took":"108.859162ms","hash":2805033624,"current-db-size-bytes":56283136,"current-db-size":"56 MB","current-db-size-in-use-bytes":32079872,"current-db-size-in-use":"32 MB"}
{"level":"info","ts":"2026-09-08T16:23:44.850148Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2805033624,"revision":15223,"compact-revision":12776}
{"level":"info","ts":"2026-09-08T16:24:40.731262Z","caller":"traceutil/trace.go:171","msg":"trace[907854875] transaction","detail":"{read_only:false; response_revision:17844; number_of_response:1; }","duration":"138.359237ms","start":"2026-09-08T16:24:40.592873Z","end":"2026-09-08T16:24:40.731232Z","steps":["trace[907854875] 'process raft request'  (duration: 138.227623ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:24:47.068965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.867411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/network.18d3651b9ede42d4\" ","response":"range_response_count:1 size:671"}
{"level":"info","ts":"2026-09-08T16:24:47.069044Z","caller":"traceutil/trace.go:171","msg":"trace[1067732400] range","detail":"{range_begin:/registry/events/openstack/network.18d3651b9ede42d4; range_end:; response_count:1; response_revision:17878; }","duration":"321.991484ms","start":"2026-09-08T16:24:46.747034Z","end":"2026-09-08T16:24:47.069025Z","steps":["trace[1067732400] 'range keys from in-memory index tree'  (duration: 321.687427ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:24:47.069082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:24:46.747017Z","time spent":"322.055787ms","remote":"127.0.0.1:45132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":694,"request content":"key:\"/registry/events/openstack/network.18d3651b9ede42d4\" "}
{"level":"info","ts":"2026-09-08T16:24:47.069197Z","caller":"traceutil/trace.go:171","msg":"trace[381880575] transaction","detail":"{read_only:false; response_revision:17879; number_of_response:1; }","duration":"285.261431ms","start":"2026-09-08T16:24:46.783899Z","end":"2026-09-08T16:24:47.06916Z","steps":["trace[381880575] 'process raft request'  (duration: 263.801103ms)","trace[381880575] 'compare'  (duration: 20.873703ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:24:47.153835Z","caller":"traceutil/trace.go:171","msg":"trace[407288038] linearizableReadLoop","detail":"{readStateIndex:18492; appliedIndex:18490; }","duration":"281.438499ms","start":"2026-09-08T16:24:46.872378Z","end":"2026-09-08T16:24:47.153817Z","steps":["trace[407288038] 'read index received'  (duration: 175.288746ms)","trace[407288038] 'applied index is now lower than readState.Index'  (duration: 106.149103ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:24:47.153982Z","caller":"traceutil/trace.go:171","msg":"trace[1365218607] transaction","detail":"{read_only:false; response_revision:17880; number_of_response:1; }","duration":"353.683045ms","start":"2026-09-08T16:24:46.800268Z","end":"2026-09-08T16:24:47.153951Z","steps":["trace[1365218607] 'process raft request'  (duration: 353.453339ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:24:47.154069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.688566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-db8sb\" ","response":"range_response_count:1 size:20763"}
{"level":"info","ts":"2026-09-08T16:24:47.154107Z","caller":"traceutil/trace.go:171","msg":"trace[1672767029] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-db8sb; range_end:; response_count:1; response_revision:17880; }","duration":"281.757658ms","start":"2026-09-08T16:24:46.872338Z","end":"2026-09-08T16:24:47.154096Z","steps":["trace[1672767029] 'agreement among raft nodes before linearized reading'  (duration: 281.608304ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:24:47.154101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:24:46.800241Z","time spent":"353.792348ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" mod_revision:17867 > success:<request_put:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" value_size:478 >> failure:<request_range:<key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-08T16:24:47.235775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.223808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-rt6p7\" ","response":"range_response_count:1 size:9800"}
{"level":"info","ts":"2026-09-08T16:24:47.235854Z","caller":"traceutil/trace.go:171","msg":"trace[536545467] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-rt6p7; range_end:; response_count:1; response_revision:17881; }","duration":"194.338652ms","start":"2026-09-08T16:24:47.041494Z","end":"2026-09-08T16:24:47.235833Z","steps":["trace[536545467] 'agreement among raft nodes before linearized reading'  (duration: 194.120066ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:24:47.23595Z","caller":"traceutil/trace.go:171","msg":"trace[658748163] transaction","detail":"{read_only:false; response_revision:17881; number_of_response:1; }","duration":"161.013118ms","start":"2026-09-08T16:24:47.074902Z","end":"2026-09-08T16:24:47.235915Z","steps":["trace[658748163] 'process raft request'  (duration: 160.383671ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:24:47.296564Z","caller":"traceutil/trace.go:171","msg":"trace[836878801] transaction","detail":"{read_only:false; response_revision:17882; number_of_response:1; }","duration":"116.736494ms","start":"2026-09-08T16:24:47.179793Z","end":"2026-09-08T16:24:47.29653Z","steps":["trace[836878801] 'process raft request'  (duration: 116.525468ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:24:59.32178Z","caller":"traceutil/trace.go:171","msg":"trace[632904122] transaction","detail":"{read_only:false; response_revision:18070; number_of_response:1; }","duration":"214.381663ms","start":"2026-09-08T16:24:59.107366Z","end":"2026-09-08T16:24:59.321748Z","steps":["trace[632904122] 'process raft request'  (duration: 140.71022ms)","trace[632904122] 'compare'  (duration: 73.510659ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:24:59.32178Z","caller":"traceutil/trace.go:171","msg":"trace[1385175241] linearizableReadLoop","detail":"{readStateIndex:18691; appliedIndex:18690; }","duration":"193.466839ms","start":"2026-09-08T16:24:59.128289Z","end":"2026-09-08T16:24:59.321756Z","steps":["trace[1385175241] 'read index received'  (duration: 119.790795ms)","trace[1385175241] 'applied index is now lower than readState.Index'  (duration: 73.675024ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:24:59.322001Z","caller":"traceutil/trace.go:171","msg":"trace[226494819] transaction","detail":"{read_only:false; response_revision:18071; number_of_response:1; }","duration":"120.282439ms","start":"2026-09-08T16:24:59.201707Z","end":"2026-09-08T16:24:59.32199Z","steps":["trace[226494819] 'process raft request'  (duration: 120.009402ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:24:59.322129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.972717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-08T16:24:59.322178Z","caller":"traceutil/trace.go:171","msg":"trace[1378521353] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:18071; }","duration":"187.081849ms","start":"2026-09-08T16:24:59.135081Z","end":"2026-09-08T16:24:59.322162Z","steps":["trace[1378521353] 'agreement among raft nodes before linearized reading'  (duration: 186.957356ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:24:59.322177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.879489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-71e60060-7465-4c07-9911-6dc3ce71bfe5\" ","response":"range_response_count:1 size:3640"}
{"level":"info","ts":"2026-09-08T16:24:59.322348Z","caller":"traceutil/trace.go:171","msg":"trace[1333807713] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-71e60060-7465-4c07-9911-6dc3ce71bfe5; range_end:; response_count:1; response_revision:18071; }","duration":"194.080615ms","start":"2026-09-08T16:24:59.128236Z","end":"2026-09-08T16:24:59.322316Z","steps":["trace[1333807713] 'agreement among raft nodes before linearized reading'  (duration: 193.54971ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:24:59.548519Z","caller":"traceutil/trace.go:171","msg":"trace[1641214936] transaction","detail":"{read_only:false; response_revision:18072; number_of_response:1; }","duration":"218.610985ms","start":"2026-09-08T16:24:59.329876Z","end":"2026-09-08T16:24:59.548487Z","steps":["trace[1641214936] 'process raft request'  (duration: 180.993788ms)","trace[1641214936] 'compare'  (duration: 37.407132ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:24:59.574807Z","caller":"traceutil/trace.go:171","msg":"trace[362826904] linearizableReadLoop","detail":"{readStateIndex:18694; appliedIndex:18692; }","duration":"132.660776ms","start":"2026-09-08T16:24:59.442128Z","end":"2026-09-08T16:24:59.574789Z","steps":["trace[362826904] 'read index received'  (duration: 68.819064ms)","trace[362826904] 'applied index is now lower than readState.Index'  (duration: 63.840712ms)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:24:59.574953Z","caller":"traceutil/trace.go:171","msg":"trace[109270747] transaction","detail":"{read_only:false; response_revision:18073; number_of_response:1; }","duration":"192.181583ms","start":"2026-09-08T16:24:59.382742Z","end":"2026-09-08T16:24:59.574924Z","steps":["trace[109270747] 'process raft request'  (duration: 191.931347ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:24:59.575165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.379584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-rt6p7\" ","response":"range_response_count:1 size:9800"}
{"level":"info","ts":"2026-09-08T16:24:59.575241Z","caller":"traceutil/trace.go:171","msg":"trace[1316167306] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-rt6p7; range_end:; response_count:1; response_revision:18073; }","duration":"122.495606ms","start":"2026-09-08T16:24:59.452726Z","end":"2026-09-08T16:24:59.575221Z","steps":["trace[1316167306] 'agreement among raft nodes before linearized reading'  (duration: 122.229219ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:24:59.575436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.296432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"}
{"level":"info","ts":"2026-09-08T16:24:59.575481Z","caller":"traceutil/trace.go:171","msg":"trace[22269514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18073; }","duration":"133.396206ms","start":"2026-09-08T16:24:59.442074Z","end":"2026-09-08T16:24:59.57547Z","steps":["trace[22269514] 'agreement among raft nodes before linearized reading'  (duration: 133.251792ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:24:59.575637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.787191ms","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:24:59.57572Z","caller":"traceutil/trace.go:171","msg":"trace[363996767] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18073; }","duration":"103.891533ms","start":"2026-09-08T16:24:59.471816Z","end":"2026-09-08T16:24:59.575707Z","steps":["trace[363996767] 'agreement among raft nodes before linearized reading'  (duration: 103.694128ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:24:59.949526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.97146ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9465898470751957236 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:18011 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-08T16:24:59.949948Z","caller":"traceutil/trace.go:171","msg":"trace[1503885357] transaction","detail":"{read_only:false; response_revision:18076; number_of_response:1; }","duration":"367.766358ms","start":"2026-09-08T16:24:59.58215Z","end":"2026-09-08T16:24:59.949916Z","steps":["trace[1503885357] 'process raft request'  (duration: 367.48726ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:24:59.950009Z","caller":"traceutil/trace.go:171","msg":"trace[746896800] transaction","detail":"{read_only:false; response_revision:18080; number_of_response:1; }","duration":"197.723801ms","start":"2026-09-08T16:24:59.752263Z","end":"2026-09-08T16:24:59.949987Z","steps":["trace[746896800] 'process raft request'  (duration: 197.571257ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:24:59.950062Z","caller":"traceutil/trace.go:171","msg":"trace[1196755571] transaction","detail":"{read_only:false; response_revision:18075; number_of_response:1; }","duration":"368.864637ms","start":"2026-09-08T16:24:59.581166Z","end":"2026-09-08T16:24:59.950031Z","steps":["trace[1196755571] 'process raft request'  (duration: 85.280411ms)","trace[1196755571] 'compare'  (duration: 282.405285ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T16:24:59.950119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:24:59.582102Z","time spent":"367.928302ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" mod_revision:18028 > success:<request_put:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" value_size:464 >> failure:<request_range:<key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" > >"}
{"level":"warn","ts":"2026-09-08T16:24:59.950211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:24:59.581141Z","time spent":"368.991131ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:18011 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-09-08T16:24:59.950275Z","caller":"traceutil/trace.go:171","msg":"trace[1298446227] transaction","detail":"{read_only:false; response_revision:18079; number_of_response:1; }","duration":"364.925582ms","start":"2026-09-08T16:24:59.585333Z","end":"2026-09-08T16:24:59.950258Z","steps":["trace[1298446227] 'process raft request'  (duration: 364.46104ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:24:59.950313Z","caller":"traceutil/trace.go:171","msg":"trace[1805922226] linearizableReadLoop","detail":"{readStateIndex:18696; appliedIndex:18694; }","duration":"368.937639ms","start":"2026-09-08T16:24:59.581345Z","end":"2026-09-08T16:24:59.950283Z","steps":["trace[1805922226] 'read index received'  (duration: 21.707295ms)","trace[1805922226] 'applied index is now lower than readState.Index'  (duration: 347.226894ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T16:24:59.95035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:24:59.585298Z","time spent":"365.008434ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:18031 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-09-08T16:24:59.950364Z","caller":"traceutil/trace.go:171","msg":"trace[1210590395] transaction","detail":"{read_only:false; response_revision:18077; number_of_response:1; }","duration":"368.158489ms","start":"2026-09-08T16:24:59.582192Z","end":"2026-09-08T16:24:59.950351Z","steps":["trace[1210590395] 'process raft request'  (duration: 367.536282ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:24:59.950416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:24:59.582176Z","time spent":"368.21077ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:18005 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-09-08T16:24:59.950459Z","caller":"traceutil/trace.go:171","msg":"trace[1086360505] transaction","detail":"{read_only:false; response_revision:18078; number_of_response:1; }","duration":"366.319219ms","start":"2026-09-08T16:24:59.584126Z","end":"2026-09-08T16:24:59.950445Z","steps":["trace[1086360505] 'process raft request'  (duration: 365.636861ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:24:59.950531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:24:59.584114Z","time spent":"366.382511ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:18010 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-09-08T16:24:59.950668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.114768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"}
{"level":"info","ts":"2026-09-08T16:24:59.950717Z","caller":"traceutil/trace.go:171","msg":"trace[670367462] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:18080; }","duration":"282.20254ms","start":"2026-09-08T16:24:59.668501Z","end":"2026-09-08T16:24:59.950703Z","steps":["trace[670367462] 'agreement among raft nodes before linearized reading'  (duration: 282.060786ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:24:59.950727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.71073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-71e60060-7465-4c07-9911-6dc3ce71bfe5\" ","response":"range_response_count:1 size:3760"}
{"level":"info","ts":"2026-09-08T16:24:59.950764Z","caller":"traceutil/trace.go:171","msg":"trace[209305481] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-71e60060-7465-4c07-9911-6dc3ce71bfe5; range_end:; response_count:1; response_revision:18080; }","duration":"197.786652ms","start":"2026-09-08T16:24:59.752967Z","end":"2026-09-08T16:24:59.950753Z","steps":["trace[209305481] 'agreement among raft nodes before linearized reading'  (duration: 197.605118ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:24:59.951525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.173363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:955661"}
{"level":"info","ts":"2026-09-08T16:24:59.951595Z","caller":"traceutil/trace.go:171","msg":"trace[1733106726] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:18080; }","duration":"370.265355ms","start":"2026-09-08T16:24:59.581313Z","end":"2026-09-08T16:24:59.951578Z","steps":["trace[1733106726] 'agreement among raft nodes before linearized reading'  (duration: 369.112674ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:24:59.951646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:24:59.581305Z","time spent":"370.324536ms","remote":"127.0.0.1:45248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":95,"response size":955684,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "}
{"level":"info","ts":"2026-09-08T16:25:24.285831Z","caller":"traceutil/trace.go:171","msg":"trace[1638865462] linearizableReadLoop","detail":"{readStateIndex:18874; appliedIndex:18873; }","duration":"102.900308ms","start":"2026-09-08T16:25:24.182897Z","end":"2026-09-08T16:25:24.285798Z","steps":["trace[1638865462] 'read index received'  (duration: 98.373638ms)","trace[1638865462] 'applied index is now lower than readState.Index'  (duration: 4.52576ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T16:25:24.286095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.177455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"}
{"level":"info","ts":"2026-09-08T16:25:24.286136Z","caller":"traceutil/trace.go:171","msg":"trace[792074035] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:18247; }","duration":"103.264627ms","start":"2026-09-08T16:25:24.182858Z","end":"2026-09-08T16:25:24.286123Z","steps":["trace[792074035] 'agreement among raft nodes before linearized reading'  (duration: 103.046402ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:25:24.286124Z","caller":"traceutil/trace.go:171","msg":"trace[233598975] transaction","detail":"{read_only:false; response_revision:18247; number_of_response:1; }","duration":"107.305164ms","start":"2026-09-08T16:25:24.178747Z","end":"2026-09-08T16:25:24.286052Z","steps":["trace[233598975] 'process raft request'  (duration: 102.678301ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:27:34.912284Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"9ad1df4dc3f3835d","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000}
{"level":"info","ts":"2026-09-08T16:27:34.920128Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002}
{"level":"info","ts":"2026-09-08T16:27:34.92036Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}
{"level":"info","ts":"2026-09-08T16:28:44.746775Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17450}
{"level":"info","ts":"2026-09-08T16:28:44.847215Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17450,"took":"96.915108ms","hash":3732637401,"current-db-size-bytes":56283136,"current-db-size":"56 MB","current-db-size-in-use-bytes":34320384,"current-db-size-in-use":"34 MB"}
{"level":"info","ts":"2026-09-08T16:28:44.847289Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3732637401,"revision":17450,"compact-revision":15223}
{"level":"info","ts":"2026-09-08T16:30:03.355925Z","caller":"traceutil/trace.go:171","msg":"trace[99125080] linearizableReadLoop","detail":"{readStateIndex:21084; appliedIndex:21083; }","duration":"147.574091ms","start":"2026-09-08T16:30:03.208322Z","end":"2026-09-08T16:30:03.355896Z","steps":["trace[99125080] 'read index received'  (duration: 147.253522ms)","trace[99125080] 'applied index is now lower than readState.Index'  (duration: 319.169µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:30:03.356039Z","caller":"traceutil/trace.go:171","msg":"trace[1067855047] transaction","detail":"{read_only:false; response_revision:20390; number_of_response:1; }","duration":"147.776877ms","start":"2026-09-08T16:30:03.208225Z","end":"2026-09-08T16:30:03.356002Z","steps":["trace[1067855047] 'process raft request'  (duration: 147.465578ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:30:03.356198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.843369ms","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:8"}
{"level":"info","ts":"2026-09-08T16:30:03.35626Z","caller":"traceutil/trace.go:171","msg":"trace[94498145] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:20390; }","duration":"147.951381ms","start":"2026-09-08T16:30:03.208291Z","end":"2026-09-08T16:30:03.356242Z","steps":["trace[94498145] 'agreement among raft nodes before linearized reading'  (duration: 147.753976ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:30:03.776899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.179405ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9465898470751963957 > lease_revoke:<id:035da081b5158a46>","response":"size:29"}
{"level":"info","ts":"2026-09-08T16:30:03.777013Z","caller":"traceutil/trace.go:171","msg":"trace[1746154336] linearizableReadLoop","detail":"{readStateIndex:21085; appliedIndex:21084; }","duration":"227.527801ms","start":"2026-09-08T16:30:03.549465Z","end":"2026-09-08T16:30:03.776993Z","steps":["trace[1746154336] 'read index received'  (duration: 58.272444ms)","trace[1746154336] 'applied index is now lower than readState.Index'  (duration: 169.253987ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T16:30:03.777146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.270663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:8"}
{"level":"info","ts":"2026-09-08T16:30:03.777197Z","caller":"traceutil/trace.go:171","msg":"trace[1733196883] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:20390; }","duration":"150.378966ms","start":"2026-09-08T16:30:03.6268Z","end":"2026-09-08T16:30:03.777178Z","steps":["trace[1733196883] 'agreement among raft nodes before linearized reading'  (duration: 150.286774ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:30:03.777155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.683695ms","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:03.777349Z","caller":"traceutil/trace.go:171","msg":"trace[1869657268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20390; }","duration":"227.926731ms","start":"2026-09-08T16:30:03.549399Z","end":"2026-09-08T16:30:03.777326Z","steps":["trace[1869657268] 'agreement among raft nodes before linearized reading'  (duration: 227.675375ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:30:03.777515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.262823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"}
{"level":"info","ts":"2026-09-08T16:30:03.777633Z","caller":"traceutil/trace.go:171","msg":"trace[39475383] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20390; }","duration":"121.423598ms","start":"2026-09-08T16:30:03.65619Z","end":"2026-09-08T16:30:03.777613Z","steps":["trace[39475383] 'agreement among raft nodes before linearized reading'  (duration: 121.14945ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:30:03.777643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.28399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"}
{"level":"info","ts":"2026-09-08T16:30:03.777706Z","caller":"traceutil/trace.go:171","msg":"trace[1978189473] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:20390; }","duration":"134.387613ms","start":"2026-09-08T16:30:03.643299Z","end":"2026-09-08T16:30:03.777687Z","steps":["trace[1978189473] 'agreement among raft nodes before linearized reading'  (duration: 134.158936ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:30:24.081695Z","caller":"traceutil/trace.go:171","msg":"trace[2094728789] linearizableReadLoop","detail":"{readStateIndex:21209; appliedIndex:21208; }","duration":"183.903425ms","start":"2026-09-08T16:30:23.897763Z","end":"2026-09-08T16:30:24.081666Z","steps":["trace[2094728789] 'read index received'  (duration: 183.753571ms)","trace[2094728789] 'applied index is now lower than readState.Index'  (duration: 148.694µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:30:24.081817Z","caller":"traceutil/trace.go:171","msg":"trace[1705237922] transaction","detail":"{read_only:false; response_revision:20510; number_of_response:1; }","duration":"188.712553ms","start":"2026-09-08T16:30:23.893072Z","end":"2026-09-08T16:30:24.081785Z","steps":["trace[1705237922] 'process raft request'  (duration: 188.400435ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:30:24.081966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.190253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"}
{"level":"info","ts":"2026-09-08T16:30:24.082012Z","caller":"traceutil/trace.go:171","msg":"trace[1267752484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20510; }","duration":"184.295756ms","start":"2026-09-08T16:30:23.897703Z","end":"2026-09-08T16:30:24.081999Z","steps":["trace[1267752484] 'agreement among raft nodes before linearized reading'  (duration: 184.122181ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:30:24.082149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.458524ms","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":"warn","ts":"2026-09-08T16:30:24.082161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.566571ms","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:24.08215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.120576ms","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:24.082197Z","caller":"traceutil/trace.go:171","msg":"trace[1685571873] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20510; }","duration":"174.535287ms","start":"2026-09-08T16:30:23.907646Z","end":"2026-09-08T16:30:24.082181Z","steps":["trace[1685571873] 'agreement among raft nodes before linearized reading'  (duration: 174.345942ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:30:24.082212Z","caller":"traceutil/trace.go:171","msg":"trace[501469860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:20510; }","duration":"176.697874ms","start":"2026-09-08T16:30:23.905499Z","end":"2026-09-08T16:30:24.082197Z","steps":["trace[501469860] 'agreement among raft nodes before linearized reading'  (duration: 176.402106ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:30:24.08223Z","caller":"traceutil/trace.go:171","msg":"trace[1539815696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20510; }","duration":"171.233908ms","start":"2026-09-08T16:30:23.910977Z","end":"2026-09-08T16:30:24.082211Z","steps":["trace[1539815696] 'agreement among raft nodes before linearized reading'  (duration: 170.991562ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:30:24.23647Z","caller":"traceutil/trace.go:171","msg":"trace[2068825395] transaction","detail":"{read_only:false; response_revision:20511; number_of_response:1; }","duration":"150.653633ms","start":"2026-09-08T16:30:24.085782Z","end":"2026-09-08T16:30:24.236436Z","steps":["trace[2068825395] 'process raft request'  (duration: 149.058281ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:30:24.242589Z","caller":"traceutil/trace.go:171","msg":"trace[2004942597] transaction","detail":"{read_only:false; response_revision:20512; number_of_response:1; }","duration":"154.864054ms","start":"2026-09-08T16:30:24.0877Z","end":"2026-09-08T16:30:24.242564Z","steps":["trace[2004942597] 'process raft request'  (duration: 154.686579ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:30:24.242781Z","caller":"traceutil/trace.go:171","msg":"trace[1264642200] transaction","detail":"{read_only:false; response_revision:20513; number_of_response:1; }","duration":"154.167356ms","start":"2026-09-08T16:30:24.08859Z","end":"2026-09-08T16:30:24.242758Z","steps":["trace[1264642200] 'process raft request'  (duration: 153.93123ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:30:24.242856Z","caller":"traceutil/trace.go:171","msg":"trace[1623445766] transaction","detail":"{read_only:false; response_revision:20514; number_of_response:1; }","duration":"150.868979ms","start":"2026-09-08T16:30:24.091963Z","end":"2026-09-08T16:30:24.242832Z","steps":["trace[1623445766] 'process raft request'  (duration: 150.756346ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:30:24.585333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.578063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-08T16:30:24.585436Z","caller":"traceutil/trace.go:171","msg":"trace[187346129] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:20514; }","duration":"227.760998ms","start":"2026-09-08T16:30:24.357645Z","end":"2026-09-08T16:30:24.585406Z","steps":["trace[187346129] 'count revisions from in-memory index tree'  (duration: 227.48071ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:30:24.585481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.808505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"}
{"level":"info","ts":"2026-09-08T16:30:24.585592Z","caller":"traceutil/trace.go:171","msg":"trace[1231611488] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:20514; }","duration":"211.927649ms","start":"2026-09-08T16:30:24.37362Z","end":"2026-09-08T16:30:24.585547Z","steps":["trace[1231611488] 'range keys from in-memory index tree'  (duration: 211.729753ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:30:24.888879Z","caller":"traceutil/trace.go:171","msg":"trace[2140536975] transaction","detail":"{read_only:false; response_revision:20515; number_of_response:1; }","duration":"155.628105ms","start":"2026-09-08T16:30:24.733216Z","end":"2026-09-08T16:30:24.888844Z","steps":["trace[2140536975] 'process raft request'  (duration: 155.417029ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:30:25.118751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.664772ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9465898470751964274 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:20507 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >>","response":"size:18"}
{"level":"info","ts":"2026-09-08T16:30:25.118859Z","caller":"traceutil/trace.go:171","msg":"trace[366232354] transaction","detail":"{read_only:false; response_revision:20516; number_of_response:1; }","duration":"381.629934ms","start":"2026-09-08T16:30:24.737207Z","end":"2026-09-08T16:30:25.118837Z","steps":["trace[366232354] 'process raft request'  (duration: 258.797369ms)","trace[366232354] 'compare'  (duration: 122.392903ms)"],"step_count":2}
{"level":"warn","ts":"2026-09-08T16:30:25.118916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-08T16:30:24.737193Z","time spent":"381.693877ms","remote":"127.0.0.1:45332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare:<target:MOD key:\"/registry/leases/openstack/08db1feb.percona.com\" mod_revision:20507 > success:<request_put:<key:\"/registry/leases/openstack/08db1feb.percona.com\" value_size:455 >> failure:<request_range:<key:\"/registry/leases/openstack/08db1feb.percona.com\" > >"}
{"level":"info","ts":"2026-09-08T16:30:25.414751Z","caller":"traceutil/trace.go:171","msg":"trace[228845718] transaction","detail":"{read_only:false; response_revision:20517; number_of_response:1; }","duration":"235.451881ms","start":"2026-09-08T16:30:25.17927Z","end":"2026-09-08T16:30:25.414721Z","steps":["trace[228845718] 'process raft request'  (duration: 232.04782ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:33:44.753675Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19847}
{"level":"info","ts":"2026-09-08T16:33:44.871111Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19847,"took":"114.767156ms","hash":4543212,"current-db-size-bytes":56283136,"current-db-size":"56 MB","current-db-size-in-use-bytes":30654464,"current-db-size-in-use":"31 MB"}
{"level":"info","ts":"2026-09-08T16:33:44.871202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4543212,"revision":19847,"compact-revision":17450}
{"level":"info","ts":"2026-09-08T16:34:41.607419Z","caller":"traceutil/trace.go:171","msg":"trace[1160107365] linearizableReadLoop","detail":"{readStateIndex:23878; appliedIndex:23877; }","duration":"208.194776ms","start":"2026-09-08T16:34:41.399207Z","end":"2026-09-08T16:34:41.607402Z","steps":["trace[1160107365] 'read index received'  (duration: 208.095413ms)","trace[1160107365] 'applied index is now lower than readState.Index'  (duration: 98.533µs)"],"step_count":2}
{"level":"info","ts":"2026-09-08T16:34:41.607636Z","caller":"traceutil/trace.go:171","msg":"trace[1218224405] transaction","detail":"{read_only:false; response_revision:23101; number_of_response:1; }","duration":"215.771146ms","start":"2026-09-08T16:34:41.39182Z","end":"2026-09-08T16:34:41.607591Z","steps":["trace[1218224405] 'process raft request'  (duration: 215.482608ms)"],"step_count":1}
{"level":"warn","ts":"2026-09-08T16:34:41.607688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.478593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"}
{"level":"warn","ts":"2026-09-08T16:34:41.60785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.107321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager-67b8b974d6-hqdw5\" ","response":"range_response_count:1 size:5293"}
{"level":"info","ts":"2026-09-08T16:34:41.607921Z","caller":"traceutil/trace.go:171","msg":"trace[150232584] range","detail":"{range_begin:/registry/pods/capi-kubeadm-bootstrap-system/capi-kubeadm-bootstrap-controller-manager-67b8b974d6-hqdw5; range_end:; response_count:1; response_revision:23101; }","duration":"168.239844ms","start":"2026-09-08T16:34:41.439662Z","end":"2026-09-08T16:34:41.607902Z","steps":["trace[150232584] 'agreement among raft nodes before linearized reading'  (duration: 167.879275ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:34:41.607901Z","caller":"traceutil/trace.go:171","msg":"trace[1772345522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23101; }","duration":"208.72627ms","start":"2026-09-08T16:34:41.399153Z","end":"2026-09-08T16:34:41.607879Z","steps":["trace[1772345522] 'agreement among raft nodes before linearized reading'  (duration: 208.35259ms)"],"step_count":1}
{"level":"info","ts":"2026-09-08T16:38:44.760165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22131}
{"level":"info","ts":"2026-09-08T16:38:44.870768Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22131,"took":"106.620419ms","hash":3828330083,"current-db-size-bytes":57126912,"current-db-size":"57 MB","current-db-size-in-use-bytes":41529344,"current-db-size-in-use":"42 MB"}
{"level":"info","ts":"2026-09-08T16:38:44.870832Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3828330083,"revision":22131,"compact-revision":19847}
{"level":"info","ts":"2026-09-08T16:43:44.766985Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25233}
{"level":"info","ts":"2026-09-08T16:43:44.918052Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25233,"took":"147.015367ms","hash":2913117845,"current-db-size-bytes":57126912,"current-db-size":"57 MB","current-db-size-in-use-bytes":29949952,"current-db-size-in-use":"30 MB"}
{"level":"info","ts":"2026-09-08T16:43:44.918101Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2913117845,"revision":25233,"compact-revision":22131}